2025-11-24 00:07:00.292510 | Job console starting...
2025-11-24 00:07:00.305296 | Updating repositories
2025-11-24 00:07:00.441576 | Preparing job workspace
2025-11-24 00:07:09.854404 | Running Ansible setup...
2025-11-24 00:07:19.129909 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-24 00:07:19.845037 | 
2025-11-24 00:07:19.845150 | PLAY [localhost]
2025-11-24 00:07:19.853586 | 
2025-11-24 00:07:19.853659 | TASK [Gathering Facts]
2025-11-24 00:07:20.824535 | localhost | ok
2025-11-24 00:07:20.838163 | 
2025-11-24 00:07:20.838242 | TASK [Setup log path fact]
2025-11-24 00:07:20.856247 | localhost | ok
2025-11-24 00:07:20.871083 | 
2025-11-24 00:07:20.871188 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 00:07:20.899063 | localhost | ok
2025-11-24 00:07:20.906610 | 
2025-11-24 00:07:20.906698 | TASK [emit-job-header : Print job information]
2025-11-24 00:07:20.967596 | # Job Information
2025-11-24 00:07:20.967844 | Ansible Version: 2.15.12
2025-11-24 00:07:20.967891 | Job: ansible-test-sanity-docker-stable-2.9
2025-11-24 00:07:20.967919 | Pipeline: periodic
2025-11-24 00:07:20.967944 | Executor: ze03.softwarefactory-project.io
2025-11-24 00:07:20.967967 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-24 00:07:20.967996 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/6a6/ansible/6a6c8dc374fe47d5b966364b2a120564/
2025-11-24 00:07:20.968022 | Event ID: db80f5515f954e34b34bdcccc30c1493
2025-11-24 00:07:20.974831 | 
2025-11-24 00:07:20.974898 | LOOP [emit-job-header : Print node information]
2025-11-24 00:07:21.098984 | localhost | ok:
2025-11-24 00:07:21.099230 | localhost | # Node Information
2025-11-24 00:07:21.099268 | localhost | Inventory Hostname: controller
2025-11-24 00:07:21.099307 | localhost | Hostname: np0005532865
2025-11-24 00:07:21.099343 | localhost | Username: zuul
2025-11-24 00:07:21.099378 | localhost | Distro: Fedora 37
2025-11-24 00:07:21.099409 | localhost | Provider: ansible-vexxhost-ams1
2025-11-24 00:07:21.099439 | localhost | Region: ams1
2025-11-24 00:07:21.099468 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-24 00:07:21.099496 | localhost | Product Name: OpenStack Nova
2025-11-24 00:07:21.099525 | localhost | Interface IP: 38.129.16.94
2025-11-24 00:07:21.108138 | 
2025-11-24 00:07:21.108263 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-24 00:07:21.538256 | localhost -> localhost | changed
2025-11-24 00:07:21.552885 | 
2025-11-24 00:07:21.553052 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-24 00:07:22.699573 | localhost -> localhost | changed
2025-11-24 00:07:22.720048 | 
2025-11-24 00:07:22.720145 | PLAY [all:!appliance*]
2025-11-24 00:07:22.736405 | 
2025-11-24 00:07:22.736500 | TASK [include_role : start-zuul-console]
2025-11-24 00:07:22.756703 | controller | ok
2025-11-24 00:07:22.770035 | 
2025-11-24 00:07:22.770099 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-24 00:07:23.741292 | controller | ok
2025-11-24 00:07:23.756269 | 
2025-11-24 00:07:23.756355 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-24 00:07:25.588063 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-24 00:07:25.603122 | 
2025-11-24 00:07:25.603261 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-24 00:07:25.977979 | controller | skipping: Conditional result was False
2025-11-24 00:07:25.993478 | 
2025-11-24 00:07:25.993656 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-24 00:07:26.021011 | controller | skipping: Conditional result was False
2025-11-24 00:07:26.035193 | 
2025-11-24 00:07:26.035383 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-24 00:07:26.072822 | controller | skipping: Conditional result was False
2025-11-24 00:07:26.088714 | 
2025-11-24 00:07:26.088940 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-24 00:07:26.127706 | controller | skipping: Conditional result was False
2025-11-24 00:07:26.143180 | 
2025-11-24 00:07:26.143374 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-24 00:07:26.172109 | controller | skipping: Conditional result was False
2025-11-24 00:07:26.189072 | 
2025-11-24 00:07:26.189331 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-24 00:07:26.217424 | controller | skipping: Conditional result was False
2025-11-24 00:07:26.244891 | 
2025-11-24 00:07:26.245037 | TASK [Disable Fedora Modular]
2025-11-24 00:07:27.392880 | controller | changed
2025-11-24 00:07:27.400712 | 
2025-11-24 00:07:27.400831 | TASK [Enable EPEL]
2025-11-24 00:07:27.426613 | controller | skipping: Conditional result was False
2025-11-24 00:07:27.441845 | 
2025-11-24 00:07:27.442021 | TASK [Register the RHEL node]
2025-11-24 00:07:27.865833 | 
2025-11-24 00:07:27.866009 | TASK [Show the subscription-manager status]
2025-11-24 00:07:28.250148 | controller | skipping: Conditional result was False
2025-11-24 00:07:28.264201 | 
2025-11-24 00:07:28.264391 | TASK [Enable EPEL on RHEL]
2025-11-24 00:07:28.666258 | controller | skipping: Conditional result was False
2025-11-24 00:07:28.680027 | 
2025-11-24 00:07:28.680202 | TASK [Install git and tox]
2025-11-24 00:09:09.561050 | controller | changed
2025-11-24 00:09:09.570089 | 
2025-11-24 00:09:09.570191 | TASK [include_role : prepare-workspace]
2025-11-24 00:09:09.593937 | controller | ok
2025-11-24 00:09:09.624490 | 
2025-11-24 00:09:09.624590 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-24 00:09:10.419604 | controller | ok
2025-11-24 00:09:10.435581 | 
2025-11-24 00:09:10.435964 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-24 00:09:30.004420 | controller | Output suppressed because no_log was given
2025-11-24 00:09:30.015903 | 
2025-11-24 00:09:30.016021 | TASK [include_role : prepare-workspace-openshift]
2025-11-24 00:09:30.041709 | controller | skipping: Conditional result was False
2025-11-24 00:09:30.077878 | 
2025-11-24 00:09:30.078055 | PLAY [all:!appliance]
2025-11-24 00:09:30.096037 | 
2025-11-24 00:09:30.096145 | TASK [Run add-build-sshkey role (RSA)]
2025-11-24 00:09:30.117316 | controller | ok
2025-11-24 00:09:30.132616 | 
2025-11-24 00:09:30.132697 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-24 00:09:30.370775 | controller -> localhost | ok
2025-11-24 00:09:30.377663 | 
2025-11-24 00:09:30.377751 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-24 00:09:30.407632 | controller | ok
2025-11-24 00:09:30.422959 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-24 00:09:30.428936 | 
2025-11-24 00:09:30.428995 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-24 00:09:30.980755 | controller -> localhost | Generating public/private rsa key pair.
2025-11-24 00:09:30.980969 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/6a6c8dc374fe47d5b966364b2a120564_id_rsa.
2025-11-24 00:09:30.981001 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/6a6c8dc374fe47d5b966364b2a120564_id_rsa.pub.
2025-11-24 00:09:30.981023 | controller -> localhost | The key fingerprint is:
2025-11-24 00:09:30.981044 | controller -> localhost | SHA256:HhFFn3YwMsCdmrRf94lc3CgTVOjwwqPIdJtnl//Xkpc zuul-build-sshkey
2025-11-24 00:09:30.981065 | controller -> localhost | The key's randomart image is:
2025-11-24 00:09:30.981086 | controller -> localhost | +---[RSA 2048]----+
2025-11-24 00:09:30.981106 | controller -> localhost | |       .o==o+o.  |
2025-11-24 00:09:30.981127 | controller -> localhost | |        o.++o+   |
2025-11-24 00:09:30.981147 | controller -> localhost | |       ..= ++.o..|
2025-11-24 00:09:30.981168 | controller -> localhost | |      . =.+.*.oo.|
2025-11-24 00:09:30.981187 | controller -> localhost | |     o oS= +.=o..|
2025-11-24 00:09:30.981215 | controller -> localhost | |      o.+.+ oo ..|
2025-11-24 00:09:30.981240 | controller -> localhost | |        .o . . .o|
2025-11-24 00:09:30.981263 | controller -> localhost | |              +E+|
2025-11-24 00:09:30.981283 | controller -> localhost | |               +o|
2025-11-24 00:09:30.981305 | controller -> localhost | +----[SHA256]-----+
2025-11-24 00:09:30.981357 | controller -> localhost | ok: Runtime: 0:00:00.107808
2025-11-24 00:09:30.988559 | 
2025-11-24 00:09:30.988640 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-24 00:09:31.059132 | controller | ok
2025-11-24 00:09:31.082829 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-24 00:09:31.105791 | 
2025-11-24 00:09:31.105971 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-24 00:09:31.134016 | controller | skipping: Conditional result was False
2025-11-24 00:09:31.147047 | 
2025-11-24 00:09:31.147171 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-24 00:09:32.181103 | controller | changed
2025-11-24 00:09:32.190176 | 
2025-11-24 00:09:32.190300 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-24 00:09:32.805556 | controller | ok
2025-11-24 00:09:32.813963 | 
2025-11-24 00:09:32.814046 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-24 00:09:35.738925 | controller | changed
2025-11-24 00:09:35.753182 | 
2025-11-24 00:09:35.753793 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-24 00:09:38.675567 | controller | changed
2025-11-24 00:09:38.684959 | 
2025-11-24 00:09:38.685080 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-24 00:09:38.710915 | controller | skipping: Conditional result was False
2025-11-24 00:09:38.725272 | 
2025-11-24 00:09:38.725448 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-24 00:09:39.132758 | controller -> localhost | changed
2025-11-24 00:09:39.177515 | 
2025-11-24 00:09:39.177885 | TASK [add-build-sshkey : Add back temp key]
2025-11-24 00:09:39.446865 | controller -> localhost | Identity added: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/6a6c8dc374fe47d5b966364b2a120564_id_rsa (zuul-build-sshkey)
2025-11-24 00:09:39.447056 | controller -> localhost | ok: Runtime: 0:00:00.007691
2025-11-24 00:09:39.454593 | 
2025-11-24 00:09:39.454661 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-24 00:09:40.327020 | controller | ok
2025-11-24 00:09:40.341809 | 
2025-11-24 00:09:40.341974 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-24 00:09:40.380523 | controller | skipping: Conditional result was False
2025-11-24 00:09:40.405259 | 
2025-11-24 00:09:40.405406 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-24 00:09:40.431959 | controller | ok
2025-11-24 00:09:40.454906 | 
2025-11-24 00:09:40.455032 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-24 00:09:40.746322 | controller -> localhost | ok
2025-11-24 00:09:40.757441 | 
2025-11-24 00:09:40.757588 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-24 00:09:40.791136 | controller | ok
2025-11-24 00:09:40.804107 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-24 00:09:40.810856 | 
2025-11-24 00:09:40.810973 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-24 00:09:41.101839 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-24 00:09:41.102112 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/6a6c8dc374fe47d5b966364b2a120564_id_ecdsa.
2025-11-24 00:09:41.102153 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/6a6c8dc374fe47d5b966364b2a120564_id_ecdsa.pub.
2025-11-24 00:09:41.102194 | controller -> localhost | The key fingerprint is:
2025-11-24 00:09:41.102223 | controller -> localhost | SHA256:XX5pvNeMIdOp3R5f+I5LtmkM40zVYji4a785B/j1TRc zuul-build-sshkey
2025-11-24 00:09:41.102249 | controller -> localhost | The key's randomart image is:
2025-11-24 00:09:41.102275 | controller -> localhost | +---[ECDSA 521]---+
2025-11-24 00:09:41.102300 | controller -> localhost | |                 |
2025-11-24 00:09:41.102325 | controller -> localhost | |                 |
2025-11-24 00:09:41.102349 | controller -> localhost | |           ... . |
2025-11-24 00:09:41.102372 | controller -> localhost | |         ..ooo+E.|
2025-11-24 00:09:41.102396 | controller -> localhost | |        S .o++O..|
2025-11-24 00:09:41.102420 | controller -> localhost | |          o =B.B+|
2025-11-24 00:09:41.102445 | controller -> localhost | |           *.*B+O|
2025-11-24 00:09:41.102469 | controller -> localhost | |          o =++B*|
2025-11-24 00:09:41.102494 | controller -> localhost | |         . .+==o=|
2025-11-24 00:09:41.102519 | controller -> localhost | +----[SHA256]-----+
2025-11-24 00:09:41.102597 | controller -> localhost | ok: Runtime: 0:00:00.008491
2025-11-24 00:09:41.121537 | 
2025-11-24 00:09:41.121659 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-24 00:09:41.144558 | controller | ok
2025-11-24 00:09:41.152750 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-24 00:09:41.162361 | 
2025-11-24 00:09:41.162452 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-24 00:09:41.187405 | controller | skipping: Conditional result was False
2025-11-24 00:09:41.194261 | 
2025-11-24 00:09:41.194579 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-24 00:09:42.049235 | controller | changed
2025-11-24 00:09:42.067802 | 
2025-11-24 00:09:42.067997 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-24 00:09:42.666963 | controller | ok
2025-11-24 00:09:42.676250 | 
2025-11-24 00:09:42.676413 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-24 00:09:45.501664 | controller | changed
2025-11-24 00:09:45.508637 | 
2025-11-24 00:09:45.508714 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-24 00:09:48.374402 | controller | changed
2025-11-24 00:09:48.388369 | 
2025-11-24 00:09:48.388547 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-24 00:09:48.427406 | controller | skipping: Conditional result was False
2025-11-24 00:09:48.436509 | 
2025-11-24 00:09:48.436639 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-24 00:09:48.698705 | controller -> localhost | changed
2025-11-24 00:09:48.710844 | 
2025-11-24 00:09:48.710950 | TASK [add-build-sshkey : Add back temp key]
2025-11-24 00:09:49.035771 | controller -> localhost | Identity added: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/6a6c8dc374fe47d5b966364b2a120564_id_ecdsa (zuul-build-sshkey)
2025-11-24 00:09:49.036094 | controller -> localhost | ok: Runtime: 0:00:00.007587
2025-11-24 00:09:49.050178 | 
2025-11-24 00:09:49.050338 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-24 00:09:49.729130 | controller | ok
2025-11-24 00:09:49.754265 | 
2025-11-24 00:09:49.754489 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-24 00:09:49.793442 | controller | skipping: Conditional result was False
2025-11-24 00:09:49.809317 | 
2025-11-24 00:09:49.809416 | TASK [include_role : remove-zuul-sshkey]
2025-11-24 00:09:49.834931 | controller | skipping: Conditional result was False
2025-11-24 00:09:49.843143 | 
2025-11-24 00:09:49.843236 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-24 00:09:50.457133 | controller | ok: "logs"
2025-11-24 00:09:50.457422 | controller | ok: All items complete
2025-11-24 00:09:50.457456 | 
2025-11-24 00:09:51.035013 | controller | ok: "artifacts"
2025-11-24 00:09:51.627754 | controller | ok: "docs"
2025-11-24 00:09:51.652891 | 
2025-11-24 00:09:51.653095 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-24 00:09:52.296529 | controller | changed: "logs"
2025-11-24 00:09:52.875043 | controller | changed: "artifacts"
2025-11-24 00:09:53.460932 | controller | changed: "docs"
2025-11-24 00:09:53.539887 | 
2025-11-24 00:09:53.540001 | PLAY RECAP
2025-11-24 00:09:53.540060 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-24 00:09:53.540098 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-24 00:09:53.540120 | 
2025-11-24 00:09:53.649604 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-24 00:09:53.650703 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-24 00:09:54.253755 | 
2025-11-24 00:09:54.253893 | PLAY [all]
2025-11-24 00:09:54.283292 | 
2025-11-24 00:09:54.283525 | TASK [Install binary dependencies]
2025-11-24 00:09:54.343281 | controller | ok
2025-11-24 00:09:54.364721 | 
2025-11-24 00:09:54.364866 | TASK [bindep : Include find tasks]
2025-11-24 00:09:54.393949 | controller | ok
2025-11-24 00:09:54.401374 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-24 00:09:54.407405 | 
2025-11-24 00:09:54.407468 | TASK [bindep : Look for bindep.txt]
2025-11-24 00:09:55.311824 | controller | ok
2025-11-24 00:09:55.340629 | 
2025-11-24 00:09:55.340843 | TASK [bindep : Define bindep_file fact]
2025-11-24 00:09:55.370334 | controller | skipping: Conditional result was False
2025-11-24 00:09:55.385398 | 
2025-11-24 00:09:55.385548 | TASK [bindep : Look for other-requirements.txt]
2025-11-24 00:09:55.987862 | controller | ok
2025-11-24 00:09:55.997897 | 
2025-11-24 00:09:55.998004 | TASK [bindep : Define bindep_file fact]
2025-11-24 00:09:56.024164 | controller | skipping: Conditional result was False
2025-11-24 00:09:56.035145 | 
2025-11-24 00:09:56.035309 | TASK [bindep : Look for bindep fallback file]
2025-11-24 00:09:56.063377 | controller | skipping: Conditional result was False
2025-11-24 00:09:56.073817 | 
2025-11-24 00:09:56.073948 | TASK [bindep : Define bindep_file fact]
2025-11-24 00:09:56.100500 | controller | skipping: Conditional result was False
2025-11-24 00:09:56.110809 | 
2025-11-24 00:09:56.110916 | TASK [bindep : Include bindep tasks]
2025-11-24 00:09:56.136690 | controller | skipping: Conditional result was False
2025-11-24 00:09:56.147071 | 
2025-11-24 00:09:56.147196 | TASK [bindep : Include install tasks]
2025-11-24 00:09:56.173123 | controller | skipping: Conditional result was False
2025-11-24 00:09:56.183015 | 
2025-11-24 00:09:56.183140 | LOOP [bindep : Include package tasks]
2025-11-24 00:09:56.257668 | 
2025-11-24 00:09:56.258016 | TASK [Run test-setup role]
2025-11-24 00:09:56.280981 | controller | ok
2025-11-24 00:09:56.305224 | 
2025-11-24 00:09:56.305375 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-24 00:09:57.003546 | controller | ok
2025-11-24 00:09:57.013417 | 
2025-11-24 00:09:57.013534 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-24 00:09:57.386247 | controller | skipping: Conditional result was False
2025-11-24 00:09:57.453895 | 
2025-11-24 00:09:57.454003 | PLAY RECAP
2025-11-24 00:09:57.454062 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-24 00:09:57.454092 | 
2025-11-24 00:09:57.564423 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-24 00:09:57.565315 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-24 00:09:58.226292 | 
2025-11-24 00:09:58.226435 | PLAY [controller]
2025-11-24 00:09:58.247436 | 
2025-11-24 00:09:58.247566 | TASK [Create the /root directory]
2025-11-24 00:09:59.248142 | controller | ok
2025-11-24 00:09:59.263087 | 
2025-11-24 00:09:59.263318 | TASK [Install glibc-langpack-en]
2025-11-24 00:10:06.351578 | controller | ok: Nothing to do
2025-11-24 00:10:06.365694 | 
2025-11-24 00:10:06.365893 | TASK [Ensure controller directory exists]
2025-11-24 00:10:07.164668 | controller | changed
2025-11-24 00:10:07.179149 | 
2025-11-24 00:10:07.179323 | TASK [Install container runtime]
2025-11-24 00:10:07.261429 | controller | ok
2025-11-24 00:10:07.331990 | 
2025-11-24 00:10:07.332111 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-24 00:10:07.363952 | controller | ok: "/var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-24 00:10:07.374367 | 
2025-11-24 00:10:07.374484 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-24 00:10:07.398591 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.405606 | 
2025-11-24 00:10:07.405867 | TASK [ensure-docker : Sanity check for distro install]
2025-11-24 00:10:07.430093 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.436961 | 
2025-11-24 00:10:07.437071 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-24 00:10:07.461317 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.469470 | 
2025-11-24 00:10:07.469590 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-24 00:10:07.493782 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.500410 | 
2025-11-24 00:10:07.500505 | TASK [ensure-docker : Include tasks for docker install]
2025-11-24 00:10:07.530739 | controller | ok
2025-11-24 00:10:07.540659 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-24 00:10:07.550077 | 
2025-11-24 00:10:07.550206 | TASK [ensure-docker : Not Implemented]
2025-11-24 00:10:07.565281 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.572950 | 
2025-11-24 00:10:07.573046 | TASK [Set up docker mirrors]
2025-11-24 00:10:07.593984 | controller | ok
2025-11-24 00:10:07.614825 | 
2025-11-24 00:10:07.614951 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-24 00:10:07.640155 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.649577 | 
2025-11-24 00:10:07.649687 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-24 00:10:07.676037 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.685445 | 
2025-11-24 00:10:07.685558 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-24 00:10:07.721959 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.731452 | 
2025-11-24 00:10:07.731573 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-24 00:10:07.757535 | controller | skipping: Conditional result was False
2025-11-24 00:10:07.808873 | 
2025-11-24 00:10:07.808985 | TASK [Add all repositories]
2025-11-24 00:10:07.829410 | controller | ok
2025-11-24 00:10:07.856346 | 
2025-11-24 00:10:07.856565 | LOOP [ensure-package-repositories : Install repositories]
2025-11-24 00:10:07.889170 | controller | ok: "/var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-24 00:10:07.889420 | controller | ok: All items complete
2025-11-24 00:10:07.889448 | 
2025-11-24 00:10:07.901610 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-24 00:10:07.909058 | 
2025-11-24 00:10:07.909165 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-24 00:10:08.802012 | controller | changed
2025-11-24 00:10:08.822026 | 
2025-11-24 00:10:08.822254 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-24 00:10:11.897948 | controller | changed:
2025-11-24 00:10:11.898225 | controller | {
2025-11-24 00:10:11.898282 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-24 00:10:11.898332 | controller | }
2025-11-24 00:10:11.914685 | 
2025-11-24 00:10:11.914901 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-24 00:10:13.083828 | controller | changed:
2025-11-24 00:10:13.084120 | controller | {
2025-11-24 00:10:13.084177 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-24 00:10:13.084227 | controller | }
2025-11-24 00:10:13.084284 | controller | changed: All items complete
2025-11-24 00:10:13.084329 | 
2025-11-24 00:10:13.099584 | 
2025-11-24 00:10:13.099818 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-24 00:10:13.752384 | controller | changed
2025-11-24 00:10:13.761773 | 
2025-11-24 00:10:13.761865 | LOOP [ensure-package-repositories : Add repositories]
2025-11-24 00:10:14.548061 | controller | changed:
2025-11-24 00:10:14.548349 | controller | {
2025-11-24 00:10:14.548407 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-24 00:10:14.548447 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-24 00:10:14.548484 | controller |   "gpgcheck": true,
2025-11-24 00:10:14.548519 | controller |   "name": "docker-ce-stable"
2025-11-24 00:10:14.548555 | controller | }
2025-11-24 00:10:14.579972 | 
2025-11-24 00:10:14.580411 | TASK [ensure-docker : Install pre-reqs]
2025-11-24 00:10:18.979537 | controller | ok: Nothing to do
2025-11-24 00:10:18.993954 | 
2025-11-24 00:10:18.994100 | TASK [ensure-docker : Remove packages]
2025-11-24 00:10:21.819073 | controller | ok: Nothing to do
2025-11-24 00:10:21.832345 | 
2025-11-24 00:10:21.832506 | TASK [ensure-docker : Install upstream docker using package]
2025-11-24 00:11:36.273562 | controller | changed
2025-11-24 00:11:36.282236 | 
2025-11-24 00:11:36.282368 | TASK [ensure-docker : Setup docker]
2025-11-24 00:11:36.309612 | controller | ok
2025-11-24 00:11:36.332142 | controller | included: /var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-24 00:11:36.353239 | 
2025-11-24 00:11:36.353386 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-24 00:11:37.305499 | controller | ok
2025-11-24 00:11:37.313499 | 
2025-11-24 00:11:37.313603 | TASK [ensure-docker : Add user to docker group]
2025-11-24 00:11:38.260196 | controller | changed
2025-11-24 00:11:38.269402 | 
2025-11-24 00:11:38.269526 | TASK [Update docker daemon proxy configuration]
2025-11-24 00:11:38.297196 | controller | skipping: Conditional result was False
2025-11-24 00:11:38.339360 | 
2025-11-24 00:11:38.339493 | TASK [Lower default MTU]
2025-11-24 00:11:38.364011 | controller | skipping: Conditional result was False
2025-11-24 00:11:38.374595 | 
2025-11-24 00:11:38.374781 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-24 00:11:39.218254 | controller | inactive
2025-11-24 00:11:39.740547 | controller | ok: Runtime: 0:00:00.014286
2025-11-24 00:11:39.754311 | 
2025-11-24 00:11:39.754512 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-24 00:11:40.484492 | controller | inactive
2025-11-24 00:11:40.636334 | controller | ok: Runtime: 0:00:00.017960
2025-11-24 00:11:40.651097 | 
2025-11-24 00:11:40.651322 | TASK [ensure-docker : Restart docker]
2025-11-24 00:11:40.713908 | Trigger a Docker restart
2025-11-24 00:11:40.728288 | 
2025-11-24 00:11:40.728484 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-24 00:11:40.783670 | 
2025-11-24 00:11:40.783988 | TASK [ensure-docker : Install docker-compose]
2025-11-24 00:11:40.813463 | controller | skipping: Conditional result was False
2025-11-24 00:11:40.830654 | 
2025-11-24 00:11:40.831099 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-24 00:11:40.851829 | 
2025-11-24 00:11:40.851998 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-24 00:11:44.028303 | controller | changed
2025-11-24 00:11:44.040749 | 
2025-11-24 00:11:44.040898 | TASK [ensure-docker : Assure docker service is running]
2025-11-24 00:11:47.685382 | controller | changed
2025-11-24 00:11:47.697410 | 
2025-11-24 00:11:47.697562 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-24 00:11:49.226952 | controller | ok
2025-11-24 00:11:49.247999 | 
2025-11-24 00:11:49.248185 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-24 00:11:50.067226 | controller | ok
2025-11-24 00:11:50.080470 | 
2025-11-24 00:11:50.080793 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-24 00:11:50.685759 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-24 00:11:50.951646 | controller | ok: Runtime: 0:00:00.033546
2025-11-24 00:11:50.966321 | 
2025-11-24 00:11:50.966467 | TASK [ensure-docker : Get version details]
2025-11-24 00:11:51.566130 | controller | Client: Docker Engine - Community
2025-11-24 00:11:51.566199 | controller |  Version:           24.0.7
2025-11-24 00:11:51.566209 | controller |  API version:       1.43
2025-11-24 00:11:51.566216 | controller |  Go version:        go1.20.10
2025-11-24 00:11:51.566232 | controller |  Git commit:        afdd53b
2025-11-24 00:11:51.566239 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-24 00:11:51.566246 | controller |  OS/Arch:           linux/amd64
2025-11-24 00:11:51.566254 | controller |  Context:           default
2025-11-24 00:11:51.566263 | controller |
2025-11-24 00:11:51.566269 | controller | Server: Docker Engine - Community
2025-11-24 00:11:51.566277 | controller |  Engine:
2025-11-24 00:11:51.566332 | controller |   Version:          24.0.7
2025-11-24 00:11:51.566341 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-24 00:11:51.566347 | controller |   Go version:       go1.20.10
2025-11-24 00:11:51.566355 | controller |   Git commit:       311b9ff
2025-11-24 00:11:51.566418 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-24 00:11:51.566427 | controller |   OS/Arch:          linux/amd64
2025-11-24 00:11:51.566433 | controller |   Experimental:     false
2025-11-24 00:11:51.566440 | controller |  containerd:
2025-11-24 00:11:51.566448 | controller |   Version:          1.6.25
2025-11-24 00:11:51.566491 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-24 00:11:51.566511 | controller |  runc:
2025-11-24 00:11:51.566517 | controller |   Version:          1.1.10
2025-11-24 00:11:51.566523 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-24 00:11:51.566531 | controller |  docker-init:
2025-11-24 00:11:51.566539 | controller |   Version:          0.19.0
2025-11-24 00:11:51.569282 | controller |   GitCommit:        de40ad0
2025-11-24 00:11:51.846300 | controller | ok: Runtime: 0:00:00.034462
2025-11-24 00:11:51.854458 | 
2025-11-24 00:11:51.854563 | TASK [ensure-docker : Dump installed docker details]
2025-11-24 00:11:51.886608 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-11-24 00:11:51.535156', 'end': '2025-11-24 00:11:51.569618', 'delta': '0:00:00.034462', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-a2a0-e204-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-11-24 00:11:51.893475 | 
2025-11-24 00:11:51.893686 | TASK [Use buildset registry]
2025-11-24 00:11:51.907823 | controller | skipping: Conditional result was False
2025-11-24 00:11:51.920593 | 
2025-11-24 00:11:51.920694 | TASK [Ensure python3.8 is present]
2025-11-24 00:11:51.935555 | controller | skipping: Conditional result was False
2025-11-24 00:11:51.981288 | 
2025-11-24 00:11:51.981409 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-24 00:11:52.001543 | controller | ok
2025-11-24 00:11:52.022703 | 
2025-11-24 00:11:52.022852 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-24 00:11:59.705036 | controller | ok: Nothing to do
2025-11-24 00:11:59.710847 | 
2025-11-24 00:11:59.710915 | TASK [our-ensure-python : Also install python3-devel]
2025-11-24 00:12:43.940551 | controller | changed
2025-11-24 00:12:43.951951 | 
2025-11-24 00:12:43.952029 | TASK [Run ensure-virtualenv role]
2025-11-24 00:12:43.972308 | controller | ok
2025-11-24 00:12:43.994397 | 
2025-11-24 00:12:43.994504 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-24 00:12:44.723883 | controller | /usr/bin/virtualenv
2025-11-24 00:12:44.852776 | controller | ok: Runtime: 0:00:00.006388
2025-11-24 00:12:44.861145 | 
2025-11-24 00:12:44.861287 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-24 00:12:44.889818 | controller | skipping: Conditional result was False
2025-11-24 00:12:44.890066 | controller | changed: All items complete
2025-11-24 00:12:44.890093 | 
2025-11-24 00:12:44.910191 | 
2025-11-24 00:12:44.910350 | TASK [Find the full path of the Python interpreter]
2025-11-24 00:12:45.491172 | controller | /usr/bin/python3.8
2025-11-24 00:12:45.769097 | controller | ok
2025-11-24 00:12:45.776911 | 
2025-11-24 00:12:45.777026 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-24 00:12:48.520465 | controller | created virtual environment CPython3.8.18.final.0-64 in 1312ms
2025-11-24 00:12:48.658452 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-24 00:12:48.658511 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-24 00:12:48.658530 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-24 00:12:48.658549 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-24 00:12:49.153422 | controller | changed
2025-11-24 00:12:49.164081 | 
2025-11-24 00:12:49.164211 | TASK [Set selinux package]
2025-11-24 00:12:49.200939 | controller | ok
2025-11-24 00:12:49.206683 | 
2025-11-24 00:12:49.206775 | TASK [Set selinux package (Fedora)]
2025-11-24 00:12:49.236687 | controller | ok
2025-11-24 00:12:49.241997 | 
2025-11-24 00:12:49.242065 | TASK [Install selinux into virtualenv]
2025-11-24 00:12:54.791010 | controller | Collecting selinux-please-lie-to-me
2025-11-24 00:12:54.946547 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-24 00:12:55.982586 | controller | Collecting setuptools<50.0.0
2025-11-24 00:12:55.989561 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-24 00:12:56.095715 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.1 MB/s eta 0:00:00
2025-11-24 00:12:56.211503 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-24 00:12:56.211882 | controller |   Attempting uninstall: setuptools
2025-11-24 00:12:56.213161 | controller |     Found existing installation: setuptools 62.6.0
2025-11-24 00:12:56.326499 | controller |     Uninstalling setuptools-62.6.0:
2025-11-24 00:12:56.342146 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-24 00:12:56.959454 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-24 00:12:57.142747 | controller |
2025-11-24 00:12:57.481435 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-24 00:12:57.481529 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-24 00:12:57.634580 | controller | ok: Runtime: 0:00:07.278788
2025-11-24 00:12:57.640863 | 
2025-11-24 00:12:57.640943 | TASK [Install pytest-forked into virtualenv]
2025-11-24 00:12:59.673686 | controller | Collecting pytest-forked
2025-11-24 00:12:59.724567 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-24 00:12:59.786175 | controller | Collecting py
2025-11-24 00:12:59.791091 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-24 00:12:59.825933 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.1 MB/s eta 0:00:00
2025-11-24 00:13:00.003156 | controller | Collecting pytest>=3.10
2025-11-24 00:13:00.008561 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-24 00:13:00.027785 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 22.5 MB/s eta 0:00:00
2025-11-24 00:13:00.095127 | controller | Collecting pluggy<2,>=1.5
2025-11-24 00:13:00.100391 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-24 00:13:00.166476 | controller | Collecting tomli>=1
2025-11-24 00:13:00.171893 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-24 00:13:00.222860 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-24 00:13:00.228218 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-11-24 00:13:00.292495 | controller | Collecting packaging
2025-11-24 00:13:00.297443 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-24 00:13:00.307187 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 9.2 MB/s eta 0:00:00
2025-11-24 00:13:00.334184 | controller | Collecting iniconfig
2025-11-24 00:13:00.339276 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-24 00:13:00.417320 | controller | Collecting typing-extensions>=4.6.0
2025-11-24 00:13:00.422797 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-24 00:13:00.431403 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 7.0 MB/s eta 0:00:00
2025-11-24 00:13:00.526569 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-24 00:13:01.276443 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-24 00:13:01.281584 | controller |
2025-11-24 00:13:01.496774 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-24 00:13:01.496826 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-24 00:13:02.021941 | controller | ok: Runtime: 0:00:02.723689
2025-11-24 00:13:02.038985 | 
2025-11-24 00:13:02.039167 | TASK [Update pip]
2025-11-24 00:13:04.754636 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-24 00:13:05.243159 | controller | Collecting pip
2025-11-24 00:13:05.367442 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-24 00:13:05.469812 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 19.8 MB/s eta 0:00:00
2025-11-24 00:13:05.702427 | controller | Installing collected packages: pip
2025-11-24 00:13:05.702641 | controller |   Attempting uninstall: pip
2025-11-24 00:13:05.703693 | controller |     Found existing installation: pip 22.2.2
2025-11-24 00:13:06.431496 | controller |     Uninstalling pip-22.2.2:
2025-11-24 00:13:06.505199 | controller |       Successfully uninstalled pip-22.2.2
2025-11-24 00:13:08.851175 | controller | Successfully installed pip-25.0.1
2025-11-24 00:13:09.444824 | controller | ok: Runtime: 0:00:05.855320
2025-11-24 00:13:09.458915 | 
2025-11-24 00:13:09.459086 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-24 00:13:10.568131 | controller | changed
2025-11-24 00:13:10.580374 | 
2025-11-24 00:13:10.580518 | TASK [Install ansible into virtualenv]
2025-11-24 00:13:12.431561 | controller | Processing ./src/github.com/ansible/ansible
2025-11-24 00:13:12.433025 | controller |   Preparing metadata (setup.py): started
2025-11-24 00:13:14.068879 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-24 00:13:14.139846 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-11-24 00:13:14.155438 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-24 00:13:14.238955 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-11-24 00:13:14.244536 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-11-24 00:13:14.711042 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-11-24 00:13:14.717270 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-24 00:13:14.941205 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-11-24 00:13:14.946000 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-24 00:13:14.954794 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2025-11-24 00:13:15.053312 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-11-24 00:13:15.059129 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-24 00:13:15.088930 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-11-24 00:13:15.093812 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-24 00:13:15.110039 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-24 00:13:15.163912 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 96.3 MB/s eta 0:00:00
2025-11-24 00:13:15.169081 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-24 00:13:15.180811 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-11-24 00:13:15.196262 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 51.4 MB/s eta 0:00:00
2025-11-24 00:13:15.202992 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-24 00:13:15.218165 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-24 00:13:15.227448 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-24 00:13:15.255218 | controller | Building wheels for collected packages: ansible
2025-11-24 00:13:15.256075 | controller |   Building wheel for ansible (setup.py): started
2025-11-24 00:13:24.976982 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-11-24 00:13:24.993553 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=d4d6ad4465c492f42a264f771d96daebf3607664fc6d2e8bf08e63ec24d7ce07
2025-11-24 00:13:24.993825 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-g4bap1xp/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-24 00:13:25.076751 | controller | Successfully built ansible
2025-11-24 00:13:25.313732 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-11-24 00:13:39.047601 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-11-24 00:13:39.472307 | controller | ok: Runtime: 0:00:27.677828
2025-11-24 00:13:39.478628 | 
2025-11-24 00:13:39.478694 | TASK [Setup download-artifact-fork role]
2025-11-24 00:13:39.498863 | controller | ok
2025-11-24 00:13:39.518354 | 
2025-11-24 00:13:39.518464 | TASK [download-artifact-fork : Parse inventory response]
2025-11-24 00:13:39.571647 | controller | ok
2025-11-24 00:13:39.580195 | 
2025-11-24 00:13:39.580279 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-24 00:13:39.606232 | controller | skipping: Conditional result was False
2025-11-24 00:13:39.613674 | 
2025-11-24 00:13:39.613791 | TASK [download-artifact-fork : Parse build response]
2025-11-24 00:13:39.650250 | controller | skipping: Conditional result was False
2025-11-24 00:13:39.660332 | 
2025-11-24 00:13:39.660445 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-24 00:13:40.454915 | controller | changed
2025-11-24 00:13:40.461096 | 
2025-11-24 00:13:40.461179 | LOOP [download-artifact-fork : Download archive by type]
2025-11-24 00:13:40.486366 | controller | skipping: Conditional result was False
2025-11-24 00:13:40.486745 | 
2025-11-24 00:13:42.749756 | controller | changed: OK (20199 bytes)
2025-11-24 00:13:42.780542 | 
2025-11-24 00:13:42.780786 | TASK [deploy-artifacts : define __collections]
2025-11-24 00:13:42.807022 | controller | ok
2025-11-24 00:13:42.814617 | 
2025-11-24 00:13:42.814741 | LOOP [deploy-artifacts : Create list of collections]
2025-11-24 00:13:42.840396 | controller | skipping: Conditional result was False
2025-11-24 00:13:42.850469 | controller | ok:
2025-11-24 00:13:42.850559 | controller | {
2025-11-24 00:13:42.850584 | controller |   "branch": "main",
2025-11-24 00:13:42.850653 | controller |   "job": "build-ansible-collection",
2025-11-24 00:13:42.850684 | controller |   "metadata": {
2025-11-24 00:13:42.850709 | controller |     "type": "ansible_collection",
2025-11-24 00:13:42.850779 | controller |     "version": "5.0.0"
2025-11-24 00:13:42.850803 | controller |   },
2025-11-24 00:13:42.850825 | controller |   "name": "ansible.network",
2025-11-24 00:13:42.850846 | controller |   "project": "ansible-collections/ansible.network",
2025-11-24 00:13:42.850866 | controller |   "url": "https://f988f1a100b7aa2f0ab1-c459d4f3d96a67ba6bc3740c634614b0.ssl.cf5.rackcdn.com/ansible/e7c3f52c20574f1caacb1ed6fe20637b/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-24 00:13:42.850895 | controller | }
2025-11-24 00:13:42.903180 | 
2025-11-24 00:13:42.903308 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-24 00:13:44.002395 | controller | Process install dependency map
2025-11-24 00:14:31.453593 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|Starting collection install process
2025-11-24 00:14:31.554509 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-24 00:14:31.554585 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-24 00:14:33.162352 | controller | /-\|/-\|/-\|/-\|Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-24 00:14:35.172268 | controller | /-\|/-\|/-\|/-\|/-\|Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-24 00:14:37.381661 | controller | /-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-24 00:14:40.499239 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-24 00:14:43.032676 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-24 00:14:46.916328 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-24 00:14:49.800260 | controller | ok: Runtime: 0:01:05.997043
2025-11-24 00:14:49.812785 | 
2025-11-24 00:14:49.813271 | TASK [podman may need iptables kernel modules]
2025-11-24 00:14:51.172760 | controller | ok: Runtime: 0:00:00.006583
2025-11-24 00:14:51.207193 | 
2025-11-24 00:14:51.207312 | PLAY RECAP
2025-11-24 00:14:51.207356 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-24 00:14:51.207380 | 
2025-11-24 00:14:51.357898 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-24 00:14:51.358938 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-24 00:14:52.043115 | 
2025-11-24 00:14:52.043247 | PLAY [controller]
2025-11-24 00:14:52.067517 | 
2025-11-24 00:14:52.067661 | TASK [Copy the galaxy.yml on the controller]
2025-11-24 00:14:55.880021 | controller | changed
2025-11-24 00:14:55.898100 | 
2025-11-24 00:14:55.898342 | TASK [Load information from galaxy.yml]
2025-11-24 00:14:55.963759 | controller | ok
2025-11-24 00:14:55.982517 | 
2025-11-24 00:14:55.982767 | LOOP [Enable FIPS mode]
2025-11-24 00:14:56.020275 | 
2025-11-24 00:14:56.020737 | LOOP [ansible-test : Include branch specific variables.]
2025-11-24 00:14:56.064357 | controller | ok: "/var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-24 00:14:56.064749 | 
2025-11-24 00:14:56.080985 | 
2025-11-24 00:14:56.081198 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-24 00:14:56.141839 | controller | ok
2025-11-24 00:14:56.148161 | 
2025-11-24 00:14:56.148255 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-24 00:14:56.175320 | controller | skipping: Conditional result was False
2025-11-24 00:14:56.185124 | 
2025-11-24 00:14:56.185271 | TASK [ansible-test : Enable --retry-on-error]
2025-11-24 00:14:56.211496 | controller | skipping: Conditional result was False
2025-11-24 00:14:56.220795 | 
2025-11-24 00:14:56.220918 | TASK [ansible-test : Enable --continue-on-error]
2025-11-24 00:14:56.267710 | controller | ok
2025-11-24 00:14:56.276429 | 
2025-11-24 00:14:56.276522 | TASK [ansible-test : Enable --requirements]
2025-11-24 00:14:56.303284 | controller | skipping: Conditional result was False
2025-11-24 00:14:56.311816 | 
2025-11-24 00:14:56.311919 | TASK [ansible-test : Adjust options for unit tests]
2025-11-24 00:14:56.338628 | controller | skipping: Conditional result was False
2025-11-24 00:14:56.347001 | 
2025-11-24 00:14:56.347138 | TASK [ansible-test : Install requirements with unit tests]
2025-11-24 00:14:56.455021 | controller | ok
2025-11-24 00:14:56.462651 | 
2025-11-24 00:14:56.462792 | TASK [ansible-test : Set --skip-test]
2025-11-24 00:14:56.520884 | controller | skipping: Conditional result was False
2025-11-24 00:14:56.530784 | 
2025-11-24 00:14:56.530917 | TASK [ansible-test : Reduce verbosity]
2025-11-24 00:14:56.563979 | controller | ok
2025-11-24 00:14:56.572941 | 
2025-11-24 00:14:56.573188 | TASK [ansible-test : Setup --python option]
2025-11-24 00:14:56.600589 | controller | skipping: Conditional result was False
2025-11-24 00:14:56.610173 | 
2025-11-24 00:14:56.610319 | TASK [ansible-test : Setup --docker option]
2025-11-24 00:14:56.648865 | controller | ok
2025-11-24 00:14:56.658364 | 
2025-11-24 00:14:56.658481 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-24 00:14:57.873666 | controller | ok
2025-11-24 00:14:57.884207 | 
2025-11-24 00:14:57.884330 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-24 00:14:57.909990 | controller | skipping: Conditional result was False
2025-11-24 00:14:57.919155 | 
2025-11-24 00:14:57.919284 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-24 00:14:57.944173 | controller | skipping: Conditional result was False
2025-11-24 00:14:57.952100 | 
2025-11-24 00:14:57.952230 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-24 00:14:57.976232 | controller | skipping: Conditional result was False
2025-11-24 00:14:57.982946 | 
2025-11-24 00:14:57.983048 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-24 00:14:59.431097 | controller | ok
2025-11-24 00:14:59.446100 | 
2025-11-24 00:14:59.446277 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-24 00:14:59.504415 | controller | ok
2025-11-24 00:14:59.514368 | 
2025-11-24 00:14:59.514509 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-24 00:14:59.558087 | controller | ok
2025-11-24 00:14:59.563947 | 
2025-11-24 00:14:59.564025 | TASK [ansible-test : Setup minimum test requirements]
2025-11-24 00:14:59.594469 | controller | ok
2025-11-24 00:14:59.600480 | 
2025-11-24 00:14:59.600569 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-24 00:15:00.502638 | controller | ok
2025-11-24 00:15:00.517196 | 
2025-11-24 00:15:00.517316 | TASK [ansible-test : Install python requirements]
2025-11-24 00:15:01.843193 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-24 00:15:01.862245 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-24 00:15:01.892868 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-24 00:15:01.898745 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-24 00:15:01.909056 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-11-24 00:15:01.916330 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-24 00:15:01.932031 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-24 00:15:01.993119 | controller | Installing collected packages: pathspec, yamllint
2025-11-24 00:15:02.087630 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-24 00:15:02.376290 | controller | ok: Runtime: 0:00:00.972971
2025-11-24 00:15:02.390711 | 
2025-11-24 00:15:02.390955 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-24 00:15:03.198223 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-24 00:15:03.772079 | controller | ERROR
2025-11-24 00:15:03.772426 | controller | {
2025-11-24 00:15:03.772496 | controller |   "delta": "0:00:00.007205",
2025-11-24 00:15:03.772542 | controller |   "end": "2025-11-24 00:15:03.198450",
2025-11-24 00:15:03.772583 | controller |   "msg": "non-zero return code",
2025-11-24 00:15:03.772672 | controller |   "rc": 1,
2025-11-24 00:15:03.772817 | controller |   "start": "2025-11-24 00:15:03.191245"
2025-11-24 00:15:03.772874 | controller | }
2025-11-24 00:15:03.772932 | controller | ERROR: Ignoring Errors
2025-11-24 00:15:03.790170 | 
2025-11-24 00:15:03.790366 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-24 00:15:04.166464 | controller | skipping: Conditional result was False
2025-11-24 00:15:04.181006 | 
2025-11-24 00:15:04.181201 | TASK [ansible-test : Enable persistent connection logging]
2025-11-24 00:15:05.688588 | controller | changed: section and option added
2025-11-24 00:15:05.704178 | 
2025-11-24 00:15:05.704382 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-24 00:15:07.066107 | controller | changed: option added
2025-11-24 00:15:07.079587 | 
2025-11-24 00:15:07.079780 | TASK [ansible-test : Set ansible log path]
2025-11-24 00:15:08.470992 | controller | changed: section and option added
2025-11-24 00:15:08.479899 | 
2025-11-24 00:15:08.480056 | TASK [ansible-test : Identify the changed targets]
2025-11-24 00:15:08.845081 | controller | skipping: Conditional result was False
2025-11-24 00:15:08.861688 | 
2025-11-24 00:15:08.861926 | TASK [ansible-test : set_fact]
2025-11-24 00:15:08.920720 | controller | skipping: Conditional result was False
2025-11-24 00:15:08.935048 | 
2025-11-24 00:15:08.935208 | TASK [ansible-test : Set the targets]
2025-11-24 00:15:08.976755 | controller | ok
2025-11-24 00:15:09.023676 | 
2025-11-24 00:15:09.023843 | TASK [ansible-test : set_fact]
2025-11-24 00:15:09.049133 | controller | skipping: Conditional result was False
2025-11-24 00:15:09.058290 | 
2025-11-24 00:15:09.058383 | TASK [ansible-test : List the targets]
2025-11-24 00:15:09.422840 | controller | skipping: Conditional result was False
2025-11-24 00:15:09.437894 | 
2025-11-24 00:15:09.438094 | TASK [ansible-test : set_fact]
2025-11-24 00:15:09.465557 | controller | skipping: Conditional result was False
2025-11-24 00:15:09.479929 | 
2025-11-24 00:15:09.480120 | TASK [ansible-test : set_fact]
2025-11-24 00:15:09.498919 | controller | skipping: Conditional result was False
2025-11-24 00:15:09.516245 | 
2025-11-24 00:15:09.516482 | LOOP [ansible-test : set_fact]
2025-11-24 00:15:09.603167 | 
2025-11-24 00:15:09.603465 | TASK [ansible-test : set_fact]
2025-11-24 00:15:09.631153 | controller | skipping: Conditional result was False
2025-11-24 00:15:09.645623 | 
2025-11-24 00:15:09.645845 | TASK [ansible-test : debug]
2025-11-24 00:15:09.673536 | controller | skipping: Conditional result was False
2025-11-24 00:15:09.687146 | 
2025-11-24 00:15:09.687316 | TASK [ansible-test : debug]
2025-11-24 00:15:09.739139 | About to run: ansible-test sanity --requirements --docker -v
2025-11-24 00:15:09.752397 | 
2025-11-24 00:15:09.752561 | TASK [ansible-test : Run the test suite]
2025-11-24 00:15:11.777058 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-11-24 00:15:11.849065 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-11-24 00:15:13.445917 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-11-24 00:15:13.446302 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2025-11-24 00:15:13.446331 | controller | 5667fdb72017: Pulling fs layer
2025-11-24 00:15:13.446340 | controller | d83811f270d5: Pulling fs layer
2025-11-24 00:15:13.446347 | controller | ee671aafb583: Pulling fs layer
2025-11-24 00:15:13.446368 | controller | 7fc152dfb3a6: Pulling fs layer
2025-11-24 00:15:13.446378 | controller | 3fb967a6fd03: Pulling fs layer
2025-11-24 00:15:13.446384 | controller | b3e63023ca5f: Pulling fs layer
2025-11-24 00:15:13.446390 | controller | 653cb3e65dbf: Pulling fs layer
2025-11-24 00:15:13.446396 | controller | f4712b586375: Pulling fs layer
2025-11-24 00:15:13.446402 | controller | d696ed7812f8: Pulling fs layer
2025-11-24 00:15:13.446408 | controller | 29bf28764f14: Pulling fs layer
2025-11-24 00:15:13.446414 | controller | 47d7edb3c2b7: Pulling fs layer
2025-11-24 00:15:13.446424 | controller | 07550ad4f6e4: Pulling fs layer
2025-11-24 00:15:13.447204 | controller | 7e79526e64c5: Pulling fs layer
2025-11-24 00:15:13.447221 | controller | 4fffb32882b7: Pulling fs layer
2025-11-24 00:15:13.447228 | controller | fc7a38b06f4e: Pulling fs layer
2025-11-24 00:15:13.447237 | controller | 6d9d912e060e: Pulling fs layer
2025-11-24 00:15:13.447256 | controller | 5dd542dd28d9: Pulling fs layer
2025-11-24 00:15:13.447279 | controller | cef8beb99552: Pulling fs layer
2025-11-24 00:15:13.447285 | controller | bd5557e101ad: Pulling fs layer
2025-11-24 00:15:13.447291 | controller | e057c20fabdd: Pulling fs layer
2025-11-24 00:15:13.447299 | controller | d16059cdae2e: Pulling fs layer
2025-11-24 00:15:13.447305 | controller | 431e42d906a3: Pulling fs layer
2025-11-24 00:15:13.447311 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-11-24 00:15:13.447317 | controller | aacdb6db0c8c: Pulling fs layer
2025-11-24 00:15:13.447323 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-11-24 00:15:13.447329 | controller | 4cd343027a44: Pulling fs layer
2025-11-24 00:15:13.447335 | controller | 0b4379883f90: Pulling fs layer
2025-11-24 00:15:13.447341 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-11-24 00:15:13.447350 | controller | 57d6314bd293: Pulling fs layer
2025-11-24 00:15:13.447356 | controller | 774036afeb7f: Pulling fs layer
2025-11-24 00:15:13.447362 | controller | 54b10f0e1645: Pulling fs layer
2025-11-24 00:15:13.447368 | controller | 78cf439d9fdb: Pulling fs layer
2025-11-24 00:15:13.447374 | controller | 36e5e20969c7: Pulling fs layer
2025-11-24 00:15:13.447380 | controller | 4b19ac3f17a8: Pulling fs layer
2025-11-24 00:15:13.447386 | controller | bd5557e101ad: Waiting
2025-11-24 00:15:13.447392 | controller | e057c20fabdd: Waiting
2025-11-24 00:15:13.447409 | controller | d16059cdae2e: Waiting
2025-11-24 00:15:13.447415 | controller | 431e42d906a3: Waiting
2025-11-24 00:15:13.447421 | controller | 7a0fd3b1d7c3: Waiting
2025-11-24 00:15:13.447427 | controller | aacdb6db0c8c: Waiting
2025-11-24 00:15:13.447433 | controller | 2e7f04ae8c8b: Waiting
2025-11-24 00:15:13.447439 | controller | 4cd343027a44: Waiting
2025-11-24 00:15:13.447445 | controller | 0b4379883f90: Waiting
2025-11-24 00:15:13.447451 | controller | 7fc152dfb3a6: Waiting
2025-11-24 00:15:13.447457 | controller | 3fb967a6fd03: Waiting
2025-11-24 00:15:13.447467 | controller | 3d9d6d9dfc91: Waiting
2025-11-24 00:15:13.447540 | controller | b3e63023ca5f: Waiting
2025-11-24 00:15:13.447571 | controller | 653cb3e65dbf: Waiting
2025-11-24 00:15:13.447580 | controller | f4712b586375: Waiting
2025-11-24 00:15:13.447586 | controller | d696ed7812f8: Waiting
2025-11-24 00:15:13.447592 | controller | 29bf28764f14: Waiting
2025-11-24 00:15:13.447601 | controller | 07550ad4f6e4: Waiting
2025-11-24 00:15:13.447668 | controller | 7e79526e64c5: Waiting
2025-11-24 00:15:13.447689 | controller | 4fffb32882b7: Waiting
2025-11-24 00:15:13.447696 | controller | fc7a38b06f4e: Waiting
2025-11-24 00:15:13.447705 | controller | 6d9d912e060e: Waiting
2025-11-24 00:15:13.447858 | controller | 5dd542dd28d9: Waiting
2025-11-24 00:15:13.448334 | controller | cef8beb99552: Waiting
2025-11-24 00:15:13.448343 | controller | 47d7edb3c2b7: Waiting
2025-11-24 00:15:13.448365 | controller | 57d6314bd293: Waiting
2025-11-24 00:15:13.448524 | controller | 774036afeb7f: Waiting
2025-11-24 00:15:13.448557 | controller | 54b10f0e1645: Waiting
2025-11-24 00:15:13.448567 | controller | 78cf439d9fdb: Waiting
2025-11-24 00:15:14.459090 | controller | 36e5e20969c7: Waiting
2025-11-24 00:15:14.459153 | controller | 4b19ac3f17a8: Waiting
2025-11-24 00:15:14.459170 | controller | ee671aafb583: Verifying Checksum
2025-11-24 00:15:14.776574 | controller | ee671aafb583: Download complete
2025-11-24 00:15:14.776626 | controller | d83811f270d5: Verifying Checksum
2025-11-24 00:15:14.862195 | controller | d83811f270d5: Download complete
2025-11-24 00:15:14.862260 | controller | 5667fdb72017: Verifying Checksum
2025-11-24 00:15:14.865085 | controller | 5667fdb72017: Download complete
2025-11-24 00:15:15.302299 | controller | b3e63023ca5f: Verifying Checksum
2025-11-24 00:15:15.537208 | controller | b3e63023ca5f: Download complete
2025-11-24 00:15:15.537272 | controller | 7fc152dfb3a6: Verifying Checksum
2025-11-24 00:15:15.743830 | controller | 7fc152dfb3a6: Download complete
2025-11-24 00:15:15.744848 | controller | 653cb3e65dbf: Verifying Checksum
2025-11-24 00:15:16.153179 | controller | 653cb3e65dbf: Download complete
2025-11-24 00:15:16.153240 | controller | d696ed7812f8: Verifying Checksum
2025-11-24 00:15:16.578706 | controller | d696ed7812f8: Download complete
2025-11-24 00:15:16.578770 | controller | 29bf28764f14: Verifying Checksum
2025-11-24 00:15:16.578903 | controller | 29bf28764f14: Download complete
2025-11-24 00:15:17.033767 | controller | 47d7edb3c2b7: Verifying Checksum
2025-11-24 00:15:17.444277 | controller | 47d7edb3c2b7: Download complete
2025-11-24 00:15:17.444344 | controller | 07550ad4f6e4: Verifying Checksum
2025-11-24 00:15:17.797501 | controller | 07550ad4f6e4: Download complete
2025-11-24 00:15:17.797572 | controller | 7e79526e64c5: Verifying Checksum
2025-11-24 00:15:18.696016 | controller | 7e79526e64c5: Download complete
2025-11-24 00:15:18.696069 | controller | 5667fdb72017: Pull complete
2025-11-24 00:15:18.717068 | controller | 4fffb32882b7: Verifying Checksum
2025-11-24 00:15:18.717189 | controller | 4fffb32882b7: Download complete
2025-11-24 00:15:18.751810 | controller | d83811f270d5: Pull complete
2025-11-24 00:15:18.775827 | controller | ee671aafb583: Pull complete
2025-11-24 00:15:18.800613 | controller | 7fc152dfb3a6: Pull complete
2025-11-24 00:15:18.995096 | controller | 3fb967a6fd03: Verifying Checksum
2025-11-24 00:15:19.729195 | controller | 3fb967a6fd03: Download complete
2025-11-24 00:15:19.729250 | controller | 6d9d912e060e: Verifying Checksum
2025-11-24 00:15:19.729320 | controller | 6d9d912e060e: Download complete
2025-11-24 00:15:19.885643 | controller | 3fb967a6fd03: Pull complete
2025-11-24 00:15:19.918801 | controller | b3e63023ca5f: Pull complete
2025-11-24 00:15:19.952006 | controller | 653cb3e65dbf: Pull complete
2025-11-24 00:15:20.096201 | controller | 5dd542dd28d9: Verifying Checksum
2025-11-24 00:15:20.096366 | controller | 5dd542dd28d9: Download complete
2025-11-24 00:15:20.114817 | controller | fc7a38b06f4e: Verifying Checksum
2025-11-24 00:15:20.114991 | controller | fc7a38b06f4e: Download complete
2025-11-24 00:15:20.502137 | controller | bd5557e101ad: Verifying Checksum
2025-11-24 00:15:20.502291 | controller | bd5557e101ad: Download complete
2025-11-24 00:15:20.866122 | controller | e057c20fabdd: Verifying Checksum
2025-11-24 00:15:20.866276 | controller | e057c20fabdd: Download complete
2025-11-24 00:15:21.589112 | controller | d16059cdae2e: Verifying Checksum
2025-11-24 00:15:21.589187 | controller | d16059cdae2e: Download complete
2025-11-24 00:15:22.045568 | controller | 431e42d906a3: Verifying Checksum
2025-11-24 00:15:22.045695 | controller | 431e42d906a3: Download complete
2025-11-24 00:15:22.958357 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-11-24 00:15:22.958439 | controller | 7a0fd3b1d7c3: Download complete
2025-11-24 00:15:23.356154 | controller | aacdb6db0c8c: Verifying Checksum
2025-11-24 00:15:23.356231 | controller | aacdb6db0c8c: Download complete
2025-11-24 00:15:23.746432 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-11-24 00:15:23.746505 | controller | 2e7f04ae8c8b: Download complete
2025-11-24 00:15:24.114318 | controller | 4cd343027a44: Verifying Checksum
2025-11-24 00:15:24.114405 | controller | 4cd343027a44: Download complete
2025-11-24 00:15:24.510179 | controller | 0b4379883f90: Verifying Checksum
2025-11-24 00:15:24.510254 | controller | 0b4379883f90: Download complete
2025-11-24 00:15:24.787237 | controller | cef8beb99552: Verifying Checksum
2025-11-24 00:15:24.787316 | controller | cef8beb99552: Download complete
2025-11-24 00:15:24.886750 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-11-24 00:15:24.886825 | controller | 3d9d6d9dfc91: Download complete
2025-11-24 00:15:26.368075 | controller | 774036afeb7f: Verifying Checksum
2025-11-24 00:15:26.368181 | controller | 774036afeb7f: Download complete
2025-11-24 00:15:28.345679 | controller | 54b10f0e1645: Verifying Checksum
2025-11-24 00:15:28.345805 | controller | 54b10f0e1645: Download complete
2025-11-24 00:15:30.671006 | controller | 78cf439d9fdb: Verifying Checksum
2025-11-24 00:15:31.106078 | controller | 78cf439d9fdb: Download complete
2025-11-24 00:15:31.106142 | controller | f4712b586375: Verifying Checksum
2025-11-24 00:15:31.109915 | controller | f4712b586375: Download complete
2025-11-24 00:15:32.772876 | controller | 4b19ac3f17a8: Verifying Checksum
2025-11-24 00:15:32.772979 | controller | 4b19ac3f17a8: Download complete
2025-11-24 00:15:35.069282 | controller | 57d6314bd293: Verifying Checksum
2025-11-24 00:15:41.979365 | controller | 36e5e20969c7: Verifying Checksum
2025-11-24 00:15:41.979448 | controller | 36e5e20969c7: Download complete
2025-11-24 00:15:49.168585 | controller | f4712b586375: Pull complete
2025-11-24 00:15:49.191008 | controller | d696ed7812f8: Pull complete
2025-11-24 00:15:49.220753 | controller | 29bf28764f14: Pull complete
2025-11-24 00:15:49.261218 | controller | 47d7edb3c2b7: Pull complete
2025-11-24 00:15:49.283149 | controller | 07550ad4f6e4: Pull complete
2025-11-24 00:15:49.307428 | controller | 7e79526e64c5: Pull complete
2025-11-24 00:15:49.326999 | controller | 4fffb32882b7: Pull complete
2025-11-24 00:15:49.396607 | controller | fc7a38b06f4e: Pull complete
2025-11-24 00:15:49.418039 | controller | 6d9d912e060e: Pull complete
2025-11-24 00:15:49.438289 | controller | 5dd542dd28d9: Pull complete
2025-11-24 00:16:07.964351 | controller | cef8beb99552: Pull complete
2025-11-24 00:16:08.007399 | controller | bd5557e101ad: Pull complete
2025-11-24 00:16:08.045103 | controller | e057c20fabdd: Pull complete
2025-11-24 00:16:08.097031 | controller | d16059cdae2e: Pull complete
2025-11-24 00:16:09.994501 | controller | 431e42d906a3: Pull complete
2025-11-24 00:16:10.043178 | controller | 7a0fd3b1d7c3: Pull complete
2025-11-24 00:16:10.087899 | controller | aacdb6db0c8c: Pull complete
2025-11-24 00:16:10.116785 | controller | 2e7f04ae8c8b: Pull complete
2025-11-24 00:16:10.151900 | controller | 4cd343027a44: Pull complete
2025-11-24 00:16:10.192438 | controller | 0b4379883f90: Pull complete
2025-11-24 00:16:10.231951 | controller | 3d9d6d9dfc91: Pull complete
2025-11-24 00:16:16.521006 | controller | 57d6314bd293: Pull complete
2025-11-24 00:16:35.561647 | controller | 774036afeb7f: Pull complete
2025-11-24 00:16:59.456648 | controller | 54b10f0e1645: Pull complete
2025-11-24 00:17:24.738611 | controller | 78cf439d9fdb: Pull complete
2025-11-24 00:17:51.202598 | controller | 36e5e20969c7: Pull complete
2025-11-24 00:18:14.486632 | controller | 4b19ac3f17a8: Pull complete
2025-11-24 00:18:14.505863 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-11-24 00:18:14.509264 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-11-24 00:18:14.516584 | controller | quay.io/ansible/default-test-container:1.10.1
2025-11-24 00:18:14.519760 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-24 00:18:14.549371 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-24 00:18:15.984111 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-24 00:18:15.984314 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-24 00:18:15.985286 | controller | ff496a88c8ed: Pulling fs layer
2025-11-24 00:18:15.985336 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-24 00:18:15.985391 | controller | 2e5170e1f099: Pulling fs layer
2025-11-24 00:18:15.985446 | controller | 7641eb41b08c: Pulling fs layer
2025-11-24 00:18:15.985500 | controller | ad15fa9da398: Pulling fs layer
2025-11-24 00:18:15.985552 | controller | 087d91352424: Pulling fs layer
2025-11-24 00:18:15.985622 | controller | 8b92efd6a100: Pulling fs layer
2025-11-24 00:18:15.986416 | controller | 7641eb41b08c: Waiting
2025-11-24 00:18:15.986482 | controller | ad15fa9da398: Waiting
2025-11-24 00:18:15.986536 | controller | 087d91352424: Waiting
2025-11-24 00:18:15.986588 | controller | 8b92efd6a100: Waiting
2025-11-24 00:18:15.986657 | controller | 2e5170e1f099: Waiting
2025-11-24 00:18:16.371499 | controller | ff496a88c8ed: Verifying Checksum
2025-11-24 00:18:16.385441 | controller | ff496a88c8ed: Download complete
2025-11-24 00:18:16.385501 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-24 00:18:16.850593 | controller | 0ce83f459fe7: Download complete
2025-11-24 00:18:16.850661 | controller | 7641eb41b08c: Verifying Checksum
2025-11-24 00:18:16.865627 | controller | 7641eb41b08c: Download complete
2025-11-24 00:18:16.865673 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-24 00:18:17.660200 | controller | 04a5f4cda3ee: Download complete
2025-11-24 00:18:17.660261 | controller | ad15fa9da398: Verifying Checksum
2025-11-24 00:18:17.662496 | controller | ad15fa9da398: Download complete
2025-11-24 00:18:18.019197 | controller | 8b92efd6a100: Verifying Checksum
2025-11-24 00:18:18.019278 | controller | 8b92efd6a100: Download complete
2025-11-24 00:18:18.216344 | controller | 2e5170e1f099: Verifying Checksum
2025-11-24 00:18:18.216432 | controller | 2e5170e1f099: Download complete
2025-11-24 00:18:18.567366 | controller | 087d91352424: Verifying Checksum
2025-11-24 00:18:18.567413 | controller | 087d91352424: Download complete
2025-11-24 00:18:20.985683 | controller | 04a5f4cda3ee: Pull complete
2025-11-24 00:18:21.402105 | controller | ff496a88c8ed: Pull complete
2025-11-24 00:18:21.429669 | controller | 0ce83f459fe7: Pull complete
2025-11-24 00:18:23.484143 | controller | 2e5170e1f099: Pull complete
2025-11-24 00:18:23.520044 | controller | 7641eb41b08c: Pull complete
2025-11-24 00:18:23.538678 | controller | ad15fa9da398: Pull complete
2025-11-24 00:18:24.440235 | controller | 087d91352424: Pull complete
2025-11-24 00:18:24.470682 | controller | 8b92efd6a100: Pull complete
2025-11-24 00:18:24.490384 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-24 00:18:24.494963 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-24 00:18:24.501651 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-24 00:18:24.504094 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-24 00:18:26.477542 | controller | Run command: docker inspect 52e0d49d4d07e12a221317a9467026e251b771391bb1e3d5430d2d6a102f28c5
2025-11-24 00:18:26.731504 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-24 00:18:26.752462 | controller | Including collection: ansible.netcommon (292 files)
2025-11-24 00:18:26.752543 | controller | Including collection: ansible.network (23 files)
2025-11-24 00:18:26.764444 | controller | Including collection: ansible.utils (442 files)
2025-11-24 00:18:26.803677 | controller | Including collection: arista.eos (1022 files)
2025-11-24 00:18:26.845793 | controller | Including collection: cisco.ios (1172 files)
2025-11-24 00:18:26.887357 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-24 00:18:26.962899 | controller | Including collection: cisco.nxos (1684 files)
2025-11-24 00:18:27.005389 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-24 00:18:27.047352 | controller | Creating a payload archive containing 8378 files...
2025-11-24 00:18:29.285333 | controller | Created a 6889945 byte payload archive containing 8378 files in 2 seconds.
2025-11-24 00:18:29.289729 | controller | Assuming Docker is available on localhost.
2025-11-24 00:18:29.290082 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:1.10.1
2025-11-24 00:18:29.818053 | controller | Run command: docker exec -i a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de dd of=/root/docker.sh bs=65536
2025-11-24 00:18:29.902048 | controller | Run command: docker exec a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de /bin/bash /root/docker.sh
2025-11-24 00:18:29.987065 | controller | Run command: docker exec -i a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de dd of=/root/ansible.tgz bs=65536
2025-11-24 00:18:30.096213 | controller | Run command: docker exec a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de mkdir /root/ansible
2025-11-24 00:18:30.163176 | controller | Run command: docker exec a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de tar oxzf /root/ansible.tgz -C /root/ansible
2025-11-24 00:18:31.225869 | controller | Run command: docker exec a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-62t8clgc.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-24 00:18:31.686841 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-24 00:18:31.687397 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-24 00:18:31.687801 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-24 00:18:31.690308 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-24 00:18:31.757246 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-24 00:18:31.761606 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-11-24 00:18:31.768779 | controller | No tests applicable.
2025-11-24 00:18:31.768829 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-24 00:18:31.769072 | controller | No tests applicable.
2025-11-24 00:18:31.769408 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-24 00:18:31.839900 | controller | Running sanity test 'compile' with Python 2.6
2025-11-24 00:18:31.840501 | controller | No tests applicable.
2025-11-24 00:18:31.840916 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-24 00:18:32.033475 | controller | Running sanity test 'compile' with Python 2.7
2025-11-24 00:18:32.033813 | controller | No tests applicable.
2025-11-24 00:18:32.034917 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-24 00:18:32.145250 | controller | Running sanity test 'compile' with Python 3.5
2025-11-24 00:18:32.145532 | controller | No tests applicable.
2025-11-24 00:18:32.145652 | controller | Running sanity test 'compile' with Python 3.6
2025-11-24 00:18:32.145833 | controller | No tests applicable.
2025-11-24 00:18:32.146232 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-24 00:18:32.244913 | controller | Running sanity test 'compile' with Python 3.7
2025-11-24 00:18:32.245229 | controller | No tests applicable.
2025-11-24 00:18:32.245653 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-24 00:18:32.344607 | controller | Running sanity test 'compile' with Python 3.8
2025-11-24 00:18:32.345091 | controller | No tests applicable.
2025-11-24 00:18:32.345348 | controller | Running sanity test 'empty-init' with Python 3.6
2025-11-24 00:18:32.345365 | controller | No tests applicable.
2025-11-24 00:18:32.345525 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-11-24 00:18:32.345685 | controller | No tests applicable.
2025-11-24 00:18:32.345888 | controller | Running sanity test 'ignores'
2025-11-24 00:18:32.347073 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-11-24 00:18:33.040674 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-11-24 00:18:33.046263 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-11-24 00:18:33.047860 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.050749 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-11-24 00:18:33.052358 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.053766 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.056811 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.061478 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.063044 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.067443 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.069996 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.071603 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-11-24 00:18:33.073022 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.076773 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.083504 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.085067 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.086462 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.088893 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.090309 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.093594 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.094989 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.097946 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-11-24 00:18:33.100835 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-11-24 00:18:33.106980 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-11-24 00:18:33.110987 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-11-24 00:18:33.112561 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-11-24 00:18:33.129142 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-11-24 00:18:33.130317 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2025-11-24 00:18:33.157051 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2025-11-24 00:18:33.160723 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2025-11-24 00:18:33.164038 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2025-11-24 00:18:33.166473 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2025-11-24 00:18:33.173878 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2025-11-24 00:18:33.177657 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2025-11-24 00:18:33.181800 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2025-11-24 00:18:33.183504 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2025-11-24 00:18:33.200556 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2025-11-24 00:18:33.204242 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2025-11-24 00:18:33.206198 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2025-11-24 00:18:33.208654 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2025-11-24 00:18:33.210750 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2025-11-24 00:18:33.213266 | controller | Collecting astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-24 00:18:48.236572 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-24 00:19:03.753327 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-24 00:19:19.770251 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-24 00:19:36.785959 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-24 00:19:55.804179 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/astroid/
2025-11-24 00:20:10.817118 | controller |   ERROR: Could not find a version that satisfies the requirement astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54)) (from versions: none)
2025-11-24 00:20:10.817804 | controller | ERROR: No matching distribution found for astroid==2.3.3 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 54))
2025-11-24 00:20:10.929336 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-11-24 00:20:10.929547 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-24 00:20:10.966255 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-24 00:20:10.966317 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-24 00:20:10.966340 | controller | Run command: docker exec a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-11-24 00:20:11.048061 | controller | Run command: docker exec -i a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de dd if=/root/output.tgz bs=65536
2025-11-24 00:20:11.116636 | controller | Run command: tar oxzf /tmp/ansible-result-jmaw23u0.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-24 00:20:11.128909 | controller | Run command: docker rm -f 52e0d49d4d07e12a221317a9467026e251b771391bb1e3d5430d2d6a102f28c5
2025-11-24 00:20:11.400763 | controller | Run command: docker rm -f a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de
2025-11-24 00:20:12.119434 | controller | ERROR: Command "docker exec a0c807d378bf7e4fb3fb792852333d823976917c3548095e00cb098c7d0664de /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-62t8clgc.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-24 00:20:12.768048 | controller | ERROR
2025-11-24 00:20:12.768403 | controller | {
2025-11-24 00:20:12.768683 | controller |   "delta": "0:05:01.067742",
2025-11-24 00:20:12.768806 | controller |   "end": "2025-11-24 00:20:12.173941",
2025-11-24 00:20:12.768880 | controller |   "msg": "non-zero return code",
2025-11-24 00:20:12.768938 | controller |   "rc": 1,
2025-11-24 00:20:12.768997 | controller |   "start": "2025-11-24 00:15:11.106199"
2025-11-24 00:20:12.769077 | controller | }
failure
2025-11-24 00:20:12.776307 | 
2025-11-24 00:20:12.776391 | PLAY RECAP
2025-11-24 00:20:12.776434 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-24 00:20:12.776455 | 
2025-11-24 00:20:12.902908 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-24 00:20:12.904699 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-24 00:20:13.606138 | 
2025-11-24 00:20:13.606845 | PLAY [all]
2025-11-24 00:20:13.630899 | 
2025-11-24 00:20:13.631067 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-24 00:20:16.592027 | controller | changed: non-zero return code
2025-11-24 00:20:16.606098 | 
2025-11-24 00:20:16.606287 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-24 00:20:16.634661 | controller | skipping: Conditional result was False
2025-11-24 00:20:16.669372 | 
2025-11-24 00:20:16.669576 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-24 00:20:16.712254 | 
2025-11-24 00:20:16.712447 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-24 00:20:16.745419 | 
2025-11-24 00:20:16.745619 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-24 00:20:16.771504 | controller | skipping: Conditional result was False
2025-11-24 00:20:16.781171 | 
2025-11-24 00:20:16.781331 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-24 00:20:16.820537 | 
2025-11-24 00:20:16.820877 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-24 00:20:16.849885 | controller | skipping: Conditional result was False
2025-11-24 00:20:16.858684 | 
2025-11-24 00:20:16.858846 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-24 00:20:16.883922 | controller | skipping: Conditional result was False
2025-11-24 00:20:16.891285 | 
2025-11-24 00:20:16.891380 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-24 00:20:16.917319 | controller | skipping: Conditional result was False
2025-11-24 00:20:16.951105 | 
2025-11-24 00:20:16.951228 | PLAY RECAP
2025-11-24 00:20:16.951274 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-24 00:20:16.951293 | 
2025-11-24 00:20:17.059089 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-24 00:20:17.061193 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-24 00:20:17.741173 | 
2025-11-24 00:20:17.741309 | PLAY [all:!appliance*]
2025-11-24 00:20:17.767135 | 
2025-11-24 00:20:17.767259 | TASK [unregister the node]
2025-11-24 00:20:18.122938 | controller | skipping: Conditional result was False
2025-11-24 00:20:18.129384 | 
2025-11-24 00:20:18.129522 | TASK [include_role : fetch-output]
2025-11-24 00:20:18.164187 | controller | ok
2025-11-24 00:20:18.192785 | 
2025-11-24 00:20:18.192920 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-24 00:20:18.249835 | controller | skipping: Conditional result was False
2025-11-24 00:20:18.260151 | 
2025-11-24 00:20:18.260323 | TASK [fetch-output : Set log path for single node]
2025-11-24 00:20:18.317332 | controller | ok
2025-11-24 00:20:18.325512 | 
2025-11-24 00:20:18.325629 | LOOP [fetch-output : Ensure local output dirs]
2025-11-24 00:20:18.848913 | controller -> localhost | ok: "/var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/logs"
2025-11-24 00:20:19.122788 | controller -> localhost | changed: "/var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/artifacts"
2025-11-24 00:20:19.400583 | controller -> localhost | changed: "/var/lib/zuul/builds/6a6c8dc374fe47d5b966364b2a120564/work/docs"
2025-11-24 00:20:19.428970 | 
2025-11-24 00:20:19.429170 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-24 00:20:21.734785 | controller | changed:
2025-11-24 00:20:21.735037 | controller | .d..t...... ./
2025-11-24 00:20:21.735076 | controller | cd+++++++++ controller/
2025-11-24 00:20:21.735123 | controller | changed: All items complete
2025-11-24 00:20:21.735153 | 
2025-11-24 00:20:23.777137 | controller | changed: .d..t...... ./
2025-11-24 00:20:25.776979 | controller | changed: .d..t...... ./
2025-11-24 00:20:25.808068 | 
2025-11-24 00:20:25.808216 | TASK [include_role : fetch-output-openshift]
2025-11-24 00:20:25.834450 | controller | skipping: Conditional result was False
2025-11-24 00:20:25.845613 | 
2025-11-24 00:20:25.845799 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-24 00:20:25.893108 | controller | skipping: Conditional result was False
2025-11-24 00:20:25.905659 | controller | skipping: Conditional result was False
2025-11-24 00:20:25.968076 | 
2025-11-24 00:20:25.968220 | PLAY [localhost]
2025-11-24 00:20:25.989296 | 
2025-11-24 00:20:25.989441 | TASK [Run Zuul manifest role]
2025-11-24 00:20:26.011090 | localhost | ok
2025-11-24 00:20:26.035013 | 
2025-11-24 00:20:26.035173 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-24 00:20:26.434231 | localhost | changed
2025-11-24 00:20:26.440948 | 
2025-11-24 00:20:26.441646 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-24 00:20:26.494149 | localhost | ok
2025-11-24 00:20:26.507088 | 
2025-11-24 00:20:26.507224 | TASK [Set zuul-log-path fact]
2025-11-24 00:20:26.530644 | localhost | ok
2025-11-24 00:20:26.578536 | 
2025-11-24 00:20:26.578657 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-24 00:20:26.617843 | localhost | ok
2025-11-24 00:20:26.626679 | 
2025-11-24 00:20:26.626795 | LOOP [Run upload-logs-swift role]
2025-11-24 00:20:26.671710 | localhost | Output suppressed because no_log was given
2025-11-24 00:20:26.711500 | 
2025-11-24 00:20:26.711649 | TASK [Set zuul-log-path fact]
2025-11-24 00:20:26.748995 | localhost | skipping: Conditional result was False
2025-11-24 00:20:26.759531 | 
2025-11-24 00:20:26.759695 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-24 00:20:27.163067 | localhost -> localhost | ok: Runtime: 0:00:00.007984
2025-11-24 00:20:27.170702 | 
2025-11-24 00:20:27.170828 | TASK [upload-logs-swift : Upload logs to swift]