2026-02-15 00:14:37.336156 | Job console starting...
2026-02-15 00:14:37.353353 | Updating repositories
2026-02-15 00:14:37.513199 | Preparing job workspace
2026-02-15 00:14:41.637403 | Running Ansible setup...
2026-02-15 00:14:46.222052 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-15 00:14:46.896338 | 
2026-02-15 00:14:46.896485 | PLAY [localhost]
2026-02-15 00:14:46.905907 | 
2026-02-15 00:14:46.906001 | TASK [Gathering Facts]
2026-02-15 00:14:48.016468 | localhost | ok
2026-02-15 00:14:48.031473 | 
2026-02-15 00:14:48.031769 | TASK [Setup log path fact]
2026-02-15 00:14:48.063316 | localhost | ok
2026-02-15 00:14:48.076827 | 
2026-02-15 00:14:48.076916 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-15 00:14:48.109141 | localhost | ok
2026-02-15 00:14:48.119136 | 
2026-02-15 00:14:48.119257 | TASK [emit-job-header : Print job information]
2026-02-15 00:14:48.160582 | # Job Information
2026-02-15 00:14:48.160833 | Ansible Version: 2.15.12
2026-02-15 00:14:48.160867 | Job: ansible-test-sanity-docker-stable-2.10
2026-02-15 00:14:48.160892 | Pipeline: periodic
2026-02-15 00:14:48.160910 | Executor: ze03.softwarefactory-project.io
2026-02-15 00:14:48.160928 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-02-15 00:14:48.160948 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/f82/ansible/f8282497732849d88885a5fd560c6455/
2026-02-15 00:14:48.160967 | Event ID: bfcfc7f67e194cfab2f082a1d8ce3d78
2026-02-15 00:14:48.165325 | 
2026-02-15 00:14:48.165391 | LOOP [emit-job-header : Print node information]
2026-02-15 00:14:48.279255 | localhost | ok:
2026-02-15 00:14:48.279477 | localhost | # Node Information
2026-02-15 00:14:48.279505 | localhost | Inventory Hostname: controller
2026-02-15 00:14:48.279524 | localhost | Hostname: np0005620028
2026-02-15 00:14:48.279543 | localhost | Username: zuul
2026-02-15 00:14:48.279563 | localhost | Distro: Fedora 37
2026-02-15 00:14:48.279581 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-02-15 00:14:48.279599 | localhost | Region: ca-ymq-1
2026-02-15 00:14:48.279617 | localhost | Label: ansible-fedora-37-1vcpu
2026-02-15 00:14:48.279633 | localhost | Product Name: OpenStack Nova
2026-02-15 00:14:48.279649 | localhost | Interface IP: 162.253.55.227
2026-02-15 00:14:48.309110 | 
2026-02-15 00:14:48.309353 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-15 00:14:48.785092 | localhost -> localhost | changed
2026-02-15 00:14:48.791200 | 
2026-02-15 00:14:48.791292 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-15 00:14:49.757518 | localhost -> localhost | changed
2026-02-15 00:14:49.818443 | 
2026-02-15 00:14:49.818610 | PLAY [all:!appliance*]
2026-02-15 00:14:49.841175 | 
2026-02-15 00:14:49.841276 | TASK [include_role : start-zuul-console]
2026-02-15 00:14:49.861450 | controller | ok
2026-02-15 00:14:49.876295 | 
2026-02-15 00:14:49.876432 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-15 00:14:50.280461 | controller | ok
2026-02-15 00:14:50.292553 | 
2026-02-15 00:14:50.292663 | TASK [use-our-mirror : Retrieve the IP address]
2026-02-15 00:14:51.246506 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-02-15 00:14:51.253450 | 
2026-02-15 00:14:51.253546 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-02-15 00:14:51.806129 | controller | skipping: Conditional result was False
2026-02-15 00:14:51.823817 | 
2026-02-15 00:14:51.824102 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-02-15 00:14:51.851196 | controller | skipping: Conditional result was False
2026-02-15 00:14:51.859354 | 
2026-02-15 00:14:51.859498 | TASK [use-our-mirror : Create the podman configuration directory]
2026-02-15 00:14:51.874632 | controller | skipping: Conditional result was False
2026-02-15 00:14:51.884953 | 
2026-02-15 00:14:51.885058 | TASK [use-our-mirror : Copy the podman configuration]
2026-02-15 00:14:51.910509 | controller | skipping: Conditional result was False
2026-02-15 00:14:51.919867 | 
2026-02-15 00:14:51.919975 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-02-15 00:14:51.935421 | controller | skipping: Conditional result was False
2026-02-15 00:14:51.947260 | 
2026-02-15 00:14:51.947417 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-02-15 00:14:51.975649 | controller | skipping: Conditional result was False
2026-02-15 00:14:51.991989 | 
2026-02-15 00:14:51.992118 | TASK [Disable Fedora Modular]
2026-02-15 00:14:52.237585 | controller | changed
2026-02-15 00:14:52.243323 | 
2026-02-15 00:14:52.243390 | TASK [Enable EPEL]
2026-02-15 00:14:52.257206 | controller | skipping: Conditional result was False
2026-02-15 00:14:52.264208 | 
2026-02-15 00:14:52.264315 | TASK [Register the RHEL node]
2026-02-15 00:14:52.814329 | 
2026-02-15 00:14:52.814563 | TASK [Show the subscription-manager status]
2026-02-15 00:14:53.433202 | controller | skipping: Conditional result was False
2026-02-15 00:14:53.443413 | 
2026-02-15 00:14:53.443534 | TASK [Enable EPEL on RHEL]
2026-02-15 00:14:53.990313 | controller | skipping: Conditional result was False
2026-02-15 00:14:54.003053 | 
2026-02-15 00:14:54.003189 | TASK [Install git and tox]
2026-02-15 00:16:25.219120 | controller | changed
2026-02-15 00:16:25.236616 | 
2026-02-15 00:16:25.236843 | TASK [include_role : prepare-workspace]
2026-02-15 00:16:25.271910 | controller | ok
2026-02-15 00:16:25.303774 | 
2026-02-15 00:16:25.303925 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-15 00:16:25.569972 | controller | ok
2026-02-15 00:16:25.577438 | 
2026-02-15 00:16:25.577558 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-15 00:22:16.078132 | controller | Output suppressed because no_log was given
2026-02-15 00:22:16.089445 | 
2026-02-15 00:22:16.089581 | TASK [include_role : prepare-workspace-openshift]
2026-02-15 00:22:16.113927 | controller | skipping: Conditional result was False
2026-02-15 00:22:16.137845 | 
2026-02-15 00:22:16.137953 | PLAY [all:!appliance]
2026-02-15 00:22:16.153720 | 
2026-02-15 00:22:16.153827 | TASK [Run add-build-sshkey role (RSA)]
2026-02-15 00:22:16.183523 | controller | ok
2026-02-15 00:22:16.198302 | 
2026-02-15 00:22:16.198378 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-15 00:22:16.519715 | controller -> localhost | ok
2026-02-15 00:22:16.535614 | 
2026-02-15 00:22:16.535897 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-15 00:22:16.579802 | controller | ok
2026-02-15 00:22:16.609255 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-15 00:22:16.619295 | 
2026-02-15 00:22:16.619402 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-15 00:22:17.290122 | controller -> localhost | Generating public/private rsa key pair.
2026-02-15 00:22:17.290333 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/f8282497732849d88885a5fd560c6455_id_rsa.
2026-02-15 00:22:17.290362 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/f8282497732849d88885a5fd560c6455_id_rsa.pub.
2026-02-15 00:22:17.290383 | controller -> localhost | The key fingerprint is:
2026-02-15 00:22:17.290402 | controller -> localhost | SHA256:Hx9cY3FeWv09sV6n7T0bw1o4NSXDTM2BYGs5H1w/ykM zuul-build-sshkey
2026-02-15 00:22:17.290421 | controller -> localhost | The key's randomart image is:
2026-02-15 00:22:17.290439 | controller -> localhost | +---[RSA 2048]----+
2026-02-15 00:22:17.290457 | controller -> localhost | |           o. +=*|
2026-02-15 00:22:17.290476 | controller -> localhost | |          . +=.B*|
2026-02-15 00:22:17.290493 | controller -> localhost | |           = EX.O|
2026-02-15 00:22:17.290511 | controller -> localhost | |          ..=oo*B|
2026-02-15 00:22:17.290528 | controller -> localhost | |        S . o=.=+|
2026-02-15 00:22:17.290557 | controller -> localhost | |         . o .*.o|
2026-02-15 00:22:17.290580 | controller -> localhost | |          . .o *.|
2026-02-15 00:22:17.290599 | controller -> localhost | |              +.=|
2026-02-15 00:22:17.290617 | controller -> localhost | |             . .o|
2026-02-15 00:22:17.290637 | controller -> localhost | +----[SHA256]-----+
2026-02-15 00:22:17.290712 | controller -> localhost | ok: Runtime: 0:00:00.217072
2026-02-15 00:22:17.297687 | 
2026-02-15 00:22:17.297768 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-15 00:22:17.329796 | controller | ok
2026-02-15 00:22:17.339557 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-15 00:22:17.348446 | 
2026-02-15 00:22:17.348548 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-15 00:22:17.372968 | controller | skipping: Conditional result was False
2026-02-15 00:22:17.380260 | 
2026-02-15 00:22:17.380335 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-15 00:22:18.018623 | controller | changed
2026-02-15 00:22:18.032997 | 
2026-02-15 00:22:18.033136 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-15 00:22:18.278684 | controller | ok
2026-02-15 00:22:18.296536 | 
2026-02-15 00:22:18.296789 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-15 00:22:18.961948 | controller | changed
2026-02-15 00:22:18.968787 | 
2026-02-15 00:22:18.968944 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-15 00:22:19.609440 | controller | changed
2026-02-15 00:22:19.624174 | 
2026-02-15 00:22:19.624315 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-15 00:22:19.652040 | controller | skipping: Conditional result was False
2026-02-15 00:22:19.667449 | 
2026-02-15 00:22:19.667638 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-15 00:22:20.113413 | controller -> localhost | changed
2026-02-15 00:22:20.143436 | 
2026-02-15 00:22:20.143654 | TASK [add-build-sshkey : Add back temp key]
2026-02-15 00:22:20.469244 | controller -> localhost | Identity added: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/f8282497732849d88885a5fd560c6455_id_rsa (zuul-build-sshkey)
2026-02-15 00:22:20.469587 | controller -> localhost | ok: Runtime: 0:00:00.012934
2026-02-15 00:22:20.480669 | 
2026-02-15 00:22:20.480845 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-15 00:22:20.830512 | controller | ok
2026-02-15 00:22:20.844180 | 
2026-02-15 00:22:20.844362 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-15 00:22:20.884253 | controller | skipping: Conditional result was False
2026-02-15 00:22:20.914307 | 
2026-02-15 00:22:20.914487 | TASK [Run add-build-sshkey role (ECDSA)]
2026-02-15 00:22:20.951855 | controller | ok
2026-02-15 00:22:20.979396 | 
2026-02-15 00:22:20.979563 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-15 00:22:21.250391 | controller -> localhost | ok
2026-02-15 00:22:21.261447 | 
2026-02-15 00:22:21.261592 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-15 00:22:21.297427 | controller | ok
2026-02-15 00:22:21.313993 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-15 00:22:21.324822 | 
2026-02-15 00:22:21.324939 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-15 00:22:21.636977 | controller -> localhost | Generating public/private ecdsa key pair.
2026-02-15 00:22:21.637316 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/f8282497732849d88885a5fd560c6455_id_ecdsa.
2026-02-15 00:22:21.637381 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/f8282497732849d88885a5fd560c6455_id_ecdsa.pub.
2026-02-15 00:22:21.637445 | controller -> localhost | The key fingerprint is:
2026-02-15 00:22:21.637492 | controller -> localhost | SHA256:yXH3uer3Pdw6atg5Wum66ls6WL9PhRhONyC224+8YG8 zuul-build-sshkey
2026-02-15 00:22:21.637537 | controller -> localhost | The key's randomart image is:
2026-02-15 00:22:21.637583 | controller -> localhost | +---[ECDSA 521]---+
2026-02-15 00:22:21.637626 | controller -> localhost | |        o .      |
2026-02-15 00:22:21.637667 | controller -> localhost | |       . o .     |
2026-02-15 00:22:21.637761 | controller -> localhost | |        o + +    |
2026-02-15 00:22:21.637818 | controller -> localhost | |       . O = + . |
2026-02-15 00:22:21.637861 | controller -> localhost | |        S + . +  |
2026-02-15 00:22:21.637901 | controller -> localhost | |        .. o o . |
2026-02-15 00:22:21.637941 | controller -> localhost | |       oo.+o=.o .|
2026-02-15 00:22:21.637981 | controller -> localhost | |      ...=E==.ooo|
2026-02-15 00:22:21.638022 | controller -> localhost | |       .==BO*+.++|
2026-02-15 00:22:21.638062 | controller -> localhost | +----[SHA256]-----+
2026-02-15 00:22:21.638165 | controller -> localhost | ok: Runtime: 0:00:00.010426
2026-02-15 00:22:21.654854 | 
2026-02-15 00:22:21.655051 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-15 00:22:21.685966 | controller | ok
2026-02-15 00:22:21.705623 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-15 00:22:21.724412 | 
2026-02-15 00:22:21.724686 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-15 00:22:21.750313 | controller | skipping: Conditional result was False
2026-02-15 00:22:21.767364 | 
2026-02-15 00:22:21.767541 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-15 00:22:22.039444 | controller | changed
2026-02-15 00:22:22.046258 | 
2026-02-15 00:22:22.046400 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-15 00:22:22.269326 | controller | ok
2026-02-15 00:22:22.283387 | 
2026-02-15 00:22:22.283575 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-15 00:22:22.911322 | controller | changed
2026-02-15 00:22:22.976329 | 
2026-02-15 00:22:22.976478 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-15 00:22:23.557027 | controller | changed
2026-02-15 00:22:23.572105 | 
2026-02-15 00:22:23.572295 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-15 00:22:23.600929 | controller | skipping: Conditional result was False
2026-02-15 00:22:23.617459 | 
2026-02-15 00:22:23.617649 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-15 00:22:23.913562 | controller -> localhost | changed
2026-02-15 00:22:23.931597 | 
2026-02-15 00:22:23.931792 | TASK [add-build-sshkey : Add back temp key]
2026-02-15 00:22:24.223132 | controller -> localhost | Identity added: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/f8282497732849d88885a5fd560c6455_id_ecdsa (zuul-build-sshkey)
2026-02-15 00:22:24.223363 | controller -> localhost | ok: Runtime: 0:00:00.007364
2026-02-15 00:22:24.230275 | 
2026-02-15 00:22:24.230377 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-15 00:22:24.423725 | controller | ok
2026-02-15 00:22:24.430034 | 
2026-02-15 00:22:24.430165 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-15 00:22:24.464296 | controller | skipping: Conditional result was False
2026-02-15 00:22:24.476900 | 
2026-02-15 00:22:24.477011 | TASK [include_role : remove-zuul-sshkey]
2026-02-15 00:22:24.501407 | controller | skipping: Conditional result was False
2026-02-15 00:22:24.507996 | 
2026-02-15 00:22:24.508077 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-15 00:22:24.742714 | controller | ok: "logs"
2026-02-15 00:22:24.743670 | controller | ok: All items complete
2026-02-15 00:22:24.744169 | 
2026-02-15 00:22:24.923591 | controller | ok: "artifacts"
2026-02-15 00:22:25.113186 | controller | ok: "docs"
2026-02-15 00:22:25.131150 | 
2026-02-15 00:22:25.131296 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-15 00:22:25.368652 | controller | changed: "logs"
2026-02-15 00:22:25.567324 | controller | changed: "artifacts"
2026-02-15 00:22:25.765037 | controller | changed: "docs"
2026-02-15 00:22:25.794793 | 
2026-02-15 00:22:25.794884 | PLAY RECAP
2026-02-15 00:22:25.794927 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-02-15 00:22:25.794952 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-15 00:22:25.794970 | 
2026-02-15 00:22:25.928087 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-15 00:22:25.929031 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-15 00:22:26.541957 | 
2026-02-15 00:22:26.542086 | PLAY [all]
2026-02-15 00:22:26.565885 | 
2026-02-15 00:22:26.566028 | TASK [Install binary dependencies]
2026-02-15 00:22:26.607474 | controller | ok
2026-02-15 00:22:26.626979 | 
2026-02-15 00:22:26.627109 | TASK [bindep : Include find tasks]
2026-02-15 00:22:26.656680 | controller | ok
2026-02-15 00:22:26.665332 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-15 00:22:26.671940 | 
2026-02-15 00:22:26.672017 | TASK [bindep : Look for bindep.txt]
2026-02-15 00:22:27.232824 | controller | ok
2026-02-15 00:22:27.250966 | 
2026-02-15 00:22:27.251167 | TASK [bindep : Define bindep_file fact]
2026-02-15 00:22:27.268935 | controller | skipping: Conditional result was False
2026-02-15 00:22:27.276606 | 
2026-02-15 00:22:27.276713 | TASK [bindep : Look for other-requirements.txt]
2026-02-15 00:22:27.480041 | controller | ok
2026-02-15 00:22:27.486473 | 
2026-02-15 00:22:27.486551 | TASK [bindep : Define bindep_file fact]
2026-02-15 00:22:27.521891 | controller | skipping: Conditional result was False
2026-02-15 00:22:27.532050 | 
2026-02-15 00:22:27.532164 | TASK [bindep : Look for bindep fallback file]
2026-02-15 00:22:27.577766 | controller | skipping: Conditional result was False
2026-02-15 00:22:27.588809 | 
2026-02-15 00:22:27.588922 | TASK [bindep : Define bindep_file fact]
2026-02-15 00:22:27.624120 | controller | skipping: Conditional result was False
2026-02-15 00:22:27.634039 | 
2026-02-15 00:22:27.634139 | TASK [bindep : Include bindep tasks]
2026-02-15 00:22:27.670668 | controller | skipping: Conditional result was False
2026-02-15 00:22:27.679620 | 
2026-02-15 00:22:27.679800 | TASK [bindep : Include install tasks]
2026-02-15 00:22:27.706355 | controller | skipping: Conditional result was False
2026-02-15 00:22:27.713267 | 
2026-02-15 00:22:27.713337 | LOOP [bindep : Include package tasks]
2026-02-15 00:22:27.779981 | 
2026-02-15 00:22:27.780155 | TASK [Run test-setup role]
2026-02-15 00:22:27.801631 | controller | ok
2026-02-15 00:22:27.819591 | 
2026-02-15 00:22:27.819699 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-15 00:22:28.034788 | controller | ok
2026-02-15 00:22:28.041090 | 
2026-02-15 00:22:28.041173 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-15 00:22:28.574508 | controller | skipping: Conditional result was False
2026-02-15 00:22:28.631961 | 
2026-02-15 00:22:28.632108 | PLAY RECAP
2026-02-15 00:22:28.632173 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-15 00:22:28.632202 | 
2026-02-15 00:22:28.749700 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-15 00:22:28.750596 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-15 00:22:29.454609 | 
2026-02-15 00:22:29.454821 | PLAY [controller]
2026-02-15 00:22:29.479545 | 
2026-02-15 00:22:29.479760 | TASK [Create the /root directory]
2026-02-15 00:22:30.049440 | controller | ok
2026-02-15 00:22:30.058076 | 
2026-02-15 00:22:30.058177 | TASK [Install glibc-langpack-en]
2026-02-15 00:22:34.063937 | controller | ok: Nothing to do
2026-02-15 00:22:34.070346 | 
2026-02-15 00:22:34.070440 | TASK [Ensure controller directory exists]
2026-02-15 00:22:34.303496 | controller | changed
2026-02-15 00:22:34.317461 | 
2026-02-15 00:22:34.317602 | TASK [Install container runtime]
2026-02-15 00:22:34.394399 | controller | ok
2026-02-15 00:22:34.450492 | 
2026-02-15 00:22:34.450605 | LOOP [ensure-docker : Gather variables for each operating system]
2026-02-15 00:22:34.518820 | controller | ok: "/var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-02-15 00:22:34.526751 | 
2026-02-15 00:22:34.526831 | TASK [ensure-docker : Sanity check for distro selection]
2026-02-15 00:22:34.540890 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.554189 | 
2026-02-15 00:22:34.554322 | TASK [ensure-docker : Sanity check for distro install]
2026-02-15 00:22:34.572399 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.583929 | 
2026-02-15 00:22:34.584050 | TASK [ensure-docker : Sanity check for upstream install]
2026-02-15 00:22:34.608832 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.617973 | 
2026-02-15 00:22:34.618070 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-02-15 00:22:34.642526 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.653589 | 
2026-02-15 00:22:34.653695 | TASK [ensure-docker : Include tasks for docker install]
2026-02-15 00:22:34.677005 | controller | ok
2026-02-15 00:22:34.688876 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-02-15 00:22:34.700537 | 
2026-02-15 00:22:34.700630 | TASK [ensure-docker : Not Implemented]
2026-02-15 00:22:34.716669 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.724120 | 
2026-02-15 00:22:34.724190 | TASK [Set up docker mirrors]
2026-02-15 00:22:34.744674 | controller | ok
2026-02-15 00:22:34.765676 | 
2026-02-15 00:22:34.765777 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-02-15 00:22:34.800389 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.808209 | 
2026-02-15 00:22:34.808307 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-02-15 00:22:34.842289 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.848907 | 
2026-02-15 00:22:34.848973 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-02-15 00:22:34.882859 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.898825 | 
2026-02-15 00:22:34.899000 | TASK [use-docker-mirror : Set up docker mirrors]
2026-02-15 00:22:34.925958 | controller | skipping: Conditional result was False
2026-02-15 00:22:34.953910 | 
2026-02-15 00:22:34.954058 | TASK [Add all repositories]
2026-02-15 00:22:34.979515 | controller | ok
2026-02-15 00:22:35.016563 | 
2026-02-15 00:22:35.016681 | LOOP [ensure-package-repositories : Install repositories]
2026-02-15 00:22:35.059882 | controller | ok: "/var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-02-15 00:22:35.060210 | controller | ok: All items complete
2026-02-15 00:22:35.060323 | 
2026-02-15 00:22:35.115003 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-02-15 00:22:35.121522 | 
2026-02-15 00:22:35.121670 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-02-15 00:22:35.453855 | controller | changed
2026-02-15 00:22:35.469381 | 
2026-02-15 00:22:35.469573 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-02-15 00:22:36.348647 | controller | changed:
2026-02-15 00:22:36.348940 | controller | {
2026-02-15 00:22:36.348985 | 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"
2026-02-15 00:22:36.349016 | controller | }
2026-02-15 00:22:36.369531 | 
2026-02-15 00:22:36.369759 | LOOP [ensure-package-repositories : Import GPG keys]
2026-02-15 00:22:36.972939 | controller | changed:
2026-02-15 00:22:36.973158 | controller | {
2026-02-15 00:22:36.973187 | 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"
2026-02-15 00:22:36.973212 | controller | }
2026-02-15 00:22:36.973250 | controller | changed: All items complete
2026-02-15 00:22:36.973282 | 
2026-02-15 00:22:36.982208 | 
2026-02-15 00:22:36.982339 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-02-15 00:22:37.203139 | controller | changed
2026-02-15 00:22:37.213937 | 
2026-02-15 00:22:37.214014 | LOOP [ensure-package-repositories : Add repositories]
2026-02-15 00:22:37.531150 | controller | changed:
2026-02-15 00:22:37.531413 | controller | {
2026-02-15 00:22:37.531466 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-02-15 00:22:37.531507 | controller |   "description": "Docker CE Stable - $basearch",
2026-02-15 00:22:37.531544 | controller |   "gpgcheck": true,
2026-02-15 00:22:37.531580 | controller |   "name": "docker-ce-stable"
2026-02-15 00:22:37.531615 | controller | }
2026-02-15 00:22:37.559299 | 
2026-02-15 00:22:37.559452 | TASK [ensure-docker : Install pre-reqs]
2026-02-15 00:22:39.928685 | controller | ok: Nothing to do
2026-02-15 00:22:39.934929 | 
2026-02-15 00:22:39.935013 | TASK [ensure-docker : Remove packages]
2026-02-15 00:22:41.241714 | controller | ok: Nothing to do
2026-02-15 00:22:41.255840 | 
2026-02-15 00:22:41.255985 | TASK [ensure-docker : Install upstream docker using package]
2026-02-15 00:23:27.450970 | controller | changed
2026-02-15 00:23:27.459617 | 
2026-02-15 00:23:27.459695 | TASK [ensure-docker : Setup docker]
2026-02-15 00:23:27.492884 | controller | ok
2026-02-15 00:23:27.506944 | controller | included: /var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-02-15 00:23:27.520011 | 
2026-02-15 00:23:27.520077 | TASK [ensure-docker : Ensure "docker" group exists]
2026-02-15 00:23:27.913885 | controller | ok
2026-02-15 00:23:27.927210 | 
2026-02-15 00:23:27.927376 | TASK [ensure-docker : Add user to docker group]
2026-02-15 00:23:28.427361 | controller | changed
2026-02-15 00:23:28.441221 | 
2026-02-15 00:23:28.441359 | TASK [Update docker daemon proxy configuration]
2026-02-15 00:23:28.477965 | controller | skipping: Conditional result was False
2026-02-15 00:23:28.491279 | 
2026-02-15 00:23:28.491410 | TASK [Lower default MTU]
2026-02-15 00:23:28.518302 | controller | skipping: Conditional result was False
2026-02-15 00:23:28.537399 | 
2026-02-15 00:23:28.537574 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-02-15 00:23:28.964142 | controller | inactive
2026-02-15 00:23:29.092849 | controller | ok: Runtime: 0:00:00.009270
2026-02-15 00:23:29.106087 | 
2026-02-15 00:23:29.106227 | TASK [ensure-docker : Get the status of the docket unit]
2026-02-15 00:23:29.367094 | controller | inactive
2026-02-15 00:23:29.659954 | controller | ok: Runtime: 0:00:00.008922
2026-02-15 00:23:29.671665 | 
2026-02-15 00:23:29.671865 | TASK [ensure-docker : Restart docker]
2026-02-15 00:23:29.737699 | Trigger a Docker restart
2026-02-15 00:23:29.743922 | 
2026-02-15 00:23:29.744004 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-02-15 00:23:29.782213 | 
2026-02-15 00:23:29.782382 | TASK [ensure-docker : Install docker-compose]
2026-02-15 00:23:29.809468 | controller | skipping: Conditional result was False
2026-02-15 00:23:29.827081 | 
2026-02-15 00:23:29.827264 | TASK [ensure-docker : Flush handlers before role exit]
2026-02-15 00:23:29.844204 | 
2026-02-15 00:23:29.844303 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-02-15 00:23:30.966545 | controller | changed
2026-02-15 00:23:30.985185 | 
2026-02-15 00:23:30.985304 | TASK [ensure-docker : Assure docker service is running]
2026-02-15 00:23:32.749555 | controller | changed
2026-02-15 00:23:32.770907 | 
2026-02-15 00:23:32.771162 | TASK [ensure-docker : Assure docker.socket service is running]
2026-02-15 00:23:33.849955 | controller | ok
2026-02-15 00:23:33.873223 | 
2026-02-15 00:23:33.873402 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-02-15 00:23:34.517411 | controller | ok
2026-02-15 00:23:34.531944 | 
2026-02-15 00:23:34.532084 | TASK [ensure-docker : Validate ability to talk with docker]
2026-02-15 00:23:35.126604 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-02-15 00:23:35.580350 | controller | ok: Runtime: 0:00:00.056012
2026-02-15 00:23:35.598344 | 
2026-02-15 00:23:35.598548 | TASK [ensure-docker : Get version details]
2026-02-15 00:23:36.027826 | controller | Client: Docker Engine - Community
2026-02-15 00:23:36.027887 | controller |  Version:           24.0.7
2026-02-15 00:23:36.027914 | controller |  API version:       1.43
2026-02-15 00:23:36.027923 | controller |  Go version:        go1.20.10
2026-02-15 00:23:36.027939 | controller |  Git commit:        afdd53b
2026-02-15 00:23:36.027946 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-02-15 00:23:36.027954 | controller |  OS/Arch:           linux/amd64
2026-02-15 00:23:36.027962 | controller |  Context:           default
2026-02-15 00:23:36.027970 | controller |
2026-02-15 00:23:36.027977 | controller | Server: Docker Engine - Community
2026-02-15 00:23:36.027983 | controller |  Engine:
2026-02-15 00:23:36.028003 | controller |   Version:          24.0.7
2026-02-15 00:23:36.029208 | controller |   API version:      1.43 (minimum version 1.12)
2026-02-15 00:23:36.029220 | controller |   Go version:       go1.20.10
2026-02-15 00:23:36.029226 | controller |   Git commit:       311b9ff
2026-02-15 00:23:36.029232 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-02-15 00:23:36.029238 | controller |   OS/Arch:          linux/amd64
2026-02-15 00:23:36.029244 | controller |   Experimental:     false
2026-02-15 00:23:36.029250 | controller |  containerd:
2026-02-15 00:23:36.029256 | controller |   Version:          1.6.25
2026-02-15 00:23:36.029262 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-02-15 00:23:36.029268 | controller |  runc:
2026-02-15 00:23:36.029274 | controller |   Version:          1.1.10
2026-02-15 00:23:36.029280 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-02-15 00:23:36.029288 | controller |  docker-init:
2026-02-15 00:23:36.029294 | controller |   Version:          0.19.0
2026-02-15 00:23:36.029299 | controller |   GitCommit:        de40ad0
2026-02-15 00:23:36.152449 | controller | ok: Runtime: 0:00:00.089236
2026-02-15 00:23:36.170605 | 
2026-02-15 00:23:36.170857 | TASK [ensure-docker : Dump installed docker details]
2026-02-15 00:23:36.236588 | {'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': '2026-02-15 00:23:35.940364', 'end': '2026-02-15 00:23:36.029600', 'delta': '0:00:00.089236', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-4b66-4daf-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}
2026-02-15 00:23:36.251906 | 
2026-02-15 00:23:36.251997 | TASK [Use buildset registry]
2026-02-15 00:23:36.277172 | controller | skipping: Conditional result was False
2026-02-15 00:23:36.295313 | 
2026-02-15 00:23:36.295424 | TASK [Ensure python3.8 is present]
2026-02-15 00:23:36.320565 | controller | skipping: Conditional result was False
2026-02-15 00:23:36.330551 | 
2026-02-15 00:23:36.330641 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-02-15 00:23:36.355221 | controller | ok
2026-02-15 00:23:36.384976 | 
2026-02-15 00:23:36.385100 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-02-15 00:23:38.608369 | controller | ok: Nothing to do
2026-02-15 00:23:38.618392 | 
2026-02-15 00:23:38.618502 | TASK [our-ensure-python : Also install python3-devel]
2026-02-15 00:28:52.029586 | controller | changed
2026-02-15 00:28:52.040939 | 
2026-02-15 00:28:52.041010 | TASK [Run ensure-virtualenv role]
2026-02-15 00:28:52.063267 | controller | ok
2026-02-15 00:28:52.088300 | 
2026-02-15 00:28:52.088464 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-02-15 00:28:52.301523 | controller | /usr/bin/virtualenv
2026-02-15 00:28:52.631769 | controller | ok: Runtime: 0:00:00.005043
2026-02-15 00:28:52.642393 | 
2026-02-15 00:28:52.642531 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-02-15 00:28:52.663564 | controller | skipping: Conditional result was False
2026-02-15 00:28:52.663874 | controller | changed: All items complete
2026-02-15 00:28:52.663904 | 
2026-02-15 00:28:52.694352 | 
2026-02-15 00:28:52.694500 | TASK [Find the full path of the Python interpreter]
2026-02-15 00:28:52.934785 | controller | /usr/bin/python3.8
2026-02-15 00:28:53.242492 | controller | ok
2026-02-15 00:28:53.250080 | 
2026-02-15 00:28:53.250186 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-02-15 00:28:54.671052 | controller | created virtual environment CPython3.8.18.final.0-64 in 779ms
2026-02-15 00:28:54.703802 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-02-15 00:28:54.703830 | 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)
2026-02-15 00:28:54.703840 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-02-15 00:28:54.703854 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-02-15 00:28:54.785571 | controller | changed
2026-02-15 00:28:54.802238 | 
2026-02-15 00:28:54.803140 | TASK [Set selinux package]
2026-02-15 00:28:54.830251 | controller | ok
2026-02-15 00:28:54.839458 | 
2026-02-15 00:28:54.839611 | TASK [Set selinux package (Fedora)]
2026-02-15 00:28:54.885554 | controller | ok
2026-02-15 00:28:54.893163 | 
2026-02-15 00:28:54.893273 | TASK [Install selinux into virtualenv]
2026-02-15 00:28:58.038721 | controller | Collecting selinux-please-lie-to-me
2026-02-15 00:28:58.122187 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-02-15 00:28:58.813630 | controller | Collecting setuptools<50.0.0
2026-02-15 00:28:58.822726 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-02-15 00:28:58.887629 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 12.9 MB/s eta 0:00:00
2026-02-15 00:28:59.078139 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-02-15 00:28:59.078325 | controller |   Attempting uninstall: setuptools
2026-02-15 00:28:59.081349 | controller |     Found existing installation: setuptools 62.6.0
2026-02-15 00:28:59.289474 | controller |     Uninstalling setuptools-62.6.0:
2026-02-15 00:28:59.309907 | controller |       Successfully uninstalled setuptools-62.6.0
2026-02-15 00:28:59.809418 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-02-15 00:28:59.943076 | controller |
2026-02-15 00:29:00.017044 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-15 00:29:00.017064 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-15 00:29:00.731044 | controller | ok: Runtime: 0:00:04.550427
2026-02-15 00:29:00.737355 | 
2026-02-15 00:29:00.737454 | TASK [Install pytest-forked into virtualenv]
2026-02-15 00:29:01.629988 | controller | Collecting pytest-forked
2026-02-15 00:29:01.706283 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-02-15 00:29:01.749455 | controller | Collecting py
2026-02-15 00:29:01.803571 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-02-15 00:29:01.820171 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 6.9 MB/s eta 0:00:00
2026-02-15 00:29:01.970397 | controller | Collecting pytest>=3.10
2026-02-15 00:29:02.009662 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-02-15 00:29:02.026628 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 26.2 MB/s eta 0:00:00
2026-02-15 00:29:02.121914 | controller | Collecting pluggy<2,>=1.5
2026-02-15 00:29:02.157613 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-02-15 00:29:02.204176 | controller | Collecting iniconfig
2026-02-15 00:29:02.215469 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-02-15 00:29:02.272509 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-02-15 00:29:02.278763 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-02-15 00:29:02.358412 | controller | Collecting tomli>=1
2026-02-15 00:29:02.369557 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-02-15 00:29:02.433801 | controller | Collecting packaging
2026-02-15 00:29:02.447575 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-02-15 00:29:02.454839 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 13.1 MB/s eta 0:00:00
2026-02-15 00:29:02.525684 | controller | Collecting typing-extensions>=4.6.0
2026-02-15 00:29:02.530463 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-02-15 00:29:02.535853 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 13.2 MB/s eta 0:00:00
2026-02-15 00:29:02.671354 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-02-15 00:29:03.727234 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-02-15 00:29:03.730404 | controller |
2026-02-15 00:29:03.915776 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-02-15 00:29:03.915831 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-02-15 00:29:04.642333 | controller | ok: Runtime: 0:00:02.817343
2026-02-15 00:29:04.648516 | 
2026-02-15 00:29:04.648643 | TASK [Update pip]
2026-02-15 00:29:05.966445 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-02-15 00:29:06.362210 | controller | Collecting pip
2026-02-15 00:29:06.449557 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-02-15 00:29:06.554472 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 18.2 MB/s eta 0:00:00
2026-02-15 00:29:06.608553 | controller | Installing collected packages: pip
2026-02-15 00:29:06.608756 | controller |   Attempting uninstall: pip
2026-02-15 00:29:06.609370 | controller |     Found existing installation: pip 22.2.2
2026-02-15 00:29:06.808040 | controller |     Uninstalling pip-22.2.2:
2026-02-15 00:29:06.872939 | controller |       Successfully uninstalled pip-22.2.2
2026-02-15 00:29:09.264973 | controller | Successfully installed pip-25.0.1
2026-02-15 00:29:09.715166 | controller | ok: Runtime: 0:00:04.456997
2026-02-15 00:29:09.729069 | 
2026-02-15 00:29:09.729211 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-02-15 00:29:10.164487 | controller | changed
2026-02-15 00:29:10.177779 | 
2026-02-15 00:29:10.177952 | TASK [Install ansible into virtualenv]
2026-02-15 00:29:11.744777 | controller | Processing ./src/github.com/ansible/ansible
2026-02-15 00:29:11.745553 | controller |   Preparing metadata (setup.py): started
2026-02-15 00:29:13.173152 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-02-15 00:29:13.226912 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-02-15 00:29:13.250741 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-02-15 00:29:13.404867 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-02-15 00:29:13.412765 | 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)
2026-02-15 00:29:14.357183 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-02-15 00:29:14.368866 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-02-15 00:29:14.396735 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-02-15 00:29:14.615316 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-02-15 00:29:14.628668 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-02-15 00:29:14.634110 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2026-02-15 00:29:14.725405 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-02-15 00:29:14.733005 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-02-15 00:29:14.759862 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-02-15 00:29:14.765589 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-02-15 00:29:14.780474 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-02-15 00:29:14.862359 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 58.8 MB/s eta 0:00:00
2026-02-15 00:29:14.867410 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-02-15 00:29:14.929889 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-02-15 00:29:14.991219 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 10.2 MB/s eta 0:00:00
2026-02-15 00:29:15.038324 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-02-15 00:29:15.065940 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-02-15 00:29:15.096907 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-02-15 00:29:15.137247 | controller | Building wheels for collected packages: ansible-base
2026-02-15 00:29:15.138078 | controller |   Building wheel for ansible-base (setup.py): started
2026-02-15 00:29:16.439575 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-02-15 00:29:16.440618 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=b2e67ec8d715c1c1bc29204e56b976d0faafb5c6265d448ec082839344ac1dfa
2026-02-15 00:29:16.440856 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-2_54za9g/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-02-15 00:29:16.449307 | controller | Successfully built ansible-base
2026-02-15 00:29:16.485769 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-02-15 00:29:17.980485 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-02-15 00:29:18.226200 | controller | ok: Runtime: 0:00:07.318913
2026-02-15 00:29:18.245789 | 
2026-02-15 00:29:18.246077 | TASK [Setup download-artifact-fork role]
2026-02-15 00:29:18.278464 | controller | ok
2026-02-15 00:29:18.317482 | 
2026-02-15 00:29:18.317679 | TASK [download-artifact-fork : Parse inventory response]
2026-02-15 00:29:18.361943 | controller | ok
2026-02-15 00:29:18.371212 | 
2026-02-15 00:29:18.371305 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-02-15 00:29:18.401661 | controller | skipping: Conditional result was False
2026-02-15 00:29:18.419323 | 
2026-02-15 00:29:18.419487 | TASK [download-artifact-fork : Parse build response]
2026-02-15 00:29:18.448649 | controller | skipping: Conditional result was False
2026-02-15 00:29:18.466750 | 
2026-02-15 00:29:18.466920 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-02-15 00:29:19.125333 | controller | changed
2026-02-15 00:29:19.139759 | 
2026-02-15 00:29:19.139908 | LOOP [download-artifact-fork : Download archive by type]
2026-02-15 00:29:19.166979 | controller | skipping: Conditional result was False
2026-02-15 00:29:19.167494 | 
2026-02-15 00:29:20.164428 | controller | changed: OK (20191 bytes)
2026-02-15 00:29:20.202718 | 
2026-02-15 00:29:20.202918 | TASK [deploy-artifacts : define __collections]
2026-02-15 00:29:20.238283 | controller | ok
2026-02-15 00:29:20.246856 | 
2026-02-15 00:29:20.246947 | LOOP [deploy-artifacts : Create list of collections]
2026-02-15 00:29:20.270809 | controller | skipping: Conditional result was False
2026-02-15 00:29:20.283638 | controller | ok:
2026-02-15 00:29:20.283774 | controller | {
2026-02-15 00:29:20.287769 | controller |   "branch": "main",
2026-02-15 00:29:20.287833 | controller |   "job": "build-ansible-collection",
2026-02-15 00:29:20.287879 | controller |   "metadata": {
2026-02-15 00:29:20.287907 | controller |     "type": "ansible_collection",
2026-02-15 00:29:20.287934 | controller |     "version": "5.0.0"
2026-02-15 00:29:20.287959 | controller |   },
2026-02-15 00:29:20.287984 | controller |   "name": "ansible.network",
2026-02-15 00:29:20.288009 | controller |   "project": "ansible-collections/ansible.network",
2026-02-15 00:29:20.288068 | controller |   "url": "https://ecaab02bb3eb54f1bca5-b4e956b772563fa61e706a1409623043.ssl.cf2.rackcdn.com/ansible/1b4315d3b1dc46d4bc03c495bce8c843/artifacts/ansible-network-5.0.0.tar.gz"
2026-02-15 00:29:20.288105 | controller | }
2026-02-15 00:29:20.302579 | 
2026-02-15 00:29:20.302694 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-02-15 00:29:20.960327 | controller | Starting galaxy collection install process
2026-02-15 00:29:20.960656 | controller | Process install dependency map
2026-02-15 00:29:30.457646 | controller | Starting collection install process
2026-02-15 00:29:30.457807 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-02-15 00:29:30.468167 | controller | ansible.network (5.0.0) was installed successfully
2026-02-15 00:29:30.468253 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-02-15 00:29:30.468386 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4249xbcyzn9k/tmpov75ig5r
2026-02-15 00:29:31.310679 | controller | ansible.netcommon (8.4.0) was installed successfully
2026-02-15 00:29:31.310734 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-02-15 00:29:31.310751 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4249xbcyzn9k/tmpov75ig5r
2026-02-15 00:29:32.135335 | controller | ansible.utils (6.0.1) was installed successfully
2026-02-15 00:29:32.135475 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-02-15 00:29:32.135525 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4249xbcyzn9k/tmpov75ig5r
2026-02-15 00:29:33.456162 | controller | arista.eos (12.0.0) was installed successfully
2026-02-15 00:29:33.456251 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-02-15 00:29:33.456271 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4249xbcyzn9k/tmpov75ig5r
2026-02-15 00:29:34.939074 | controller | cisco.ios (11.2.0) was installed successfully
2026-02-15 00:29:34.939215 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-02-15 00:29:34.939229 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4249xbcyzn9k/tmpov75ig5r
2026-02-15 00:29:36.317420 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-02-15 00:29:36.317484 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-02-15 00:29:36.317510 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4249xbcyzn9k/tmpov75ig5r
2026-02-15 00:29:39.101969 | controller | cisco.nxos (11.1.3) was installed successfully
2026-02-15 00:29:39.102040 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-02-15 00:29:39.102076 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4249xbcyzn9k/tmpov75ig5r
2026-02-15 00:29:40.789023 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-02-15 00:29:40.917770 | controller | ok: Runtime: 0:00:20.224080
2026-02-15 00:29:40.942963 | 
2026-02-15 00:29:40.943114 | TASK [podman may need iptables kernel modules]
2026-02-15 00:29:41.802002 | controller | ok: Runtime: 0:00:00.004232
2026-02-15 00:29:41.823432 | 
2026-02-15 00:29:41.823535 | PLAY RECAP
2026-02-15 00:29:41.823579 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-02-15 00:29:41.823598 | 
2026-02-15 00:29:41.964481 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-15 00:29:41.965712 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-02-15 00:29:42.792825 | 
2026-02-15 00:29:42.793016 | PLAY [controller]
2026-02-15 00:29:42.819112 | 
2026-02-15 00:29:42.819262 | TASK [Copy the galaxy.yml on the controller]
2026-02-15 00:29:45.265698 | controller | changed
2026-02-15 00:29:45.280388 | 
2026-02-15 00:29:45.280591 | TASK [Load information from galaxy.yml]
2026-02-15 00:29:45.327343 | controller | ok
2026-02-15 00:29:45.335912 | 
2026-02-15 00:29:45.336002 | LOOP [Enable FIPS mode]
2026-02-15 00:29:45.366280 | 
2026-02-15 00:29:45.366566 | LOOP [ansible-test : Include branch specific variables.]
2026-02-15 00:29:45.422100 | controller | ok: "/var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-02-15 00:29:45.422358 | 
2026-02-15 00:29:45.433893 | 
2026-02-15 00:29:45.434043 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-02-15 00:29:45.488579 | controller | ok
2026-02-15 00:29:45.501245 | 
2026-02-15 00:29:45.501434 | TASK [ansible-test : Enable --inventory for network-integration]
2026-02-15 00:29:45.533829 | controller | skipping: Conditional result was False
2026-02-15 00:29:45.551448 | 
2026-02-15 00:29:45.551659 | TASK [ansible-test : Enable --retry-on-error]
2026-02-15 00:29:45.581794 | controller | skipping: Conditional result was False
2026-02-15 00:29:45.600212 | 
2026-02-15 00:29:45.600407 | TASK [ansible-test : Enable --continue-on-error]
2026-02-15 00:29:45.658428 | controller | ok
2026-02-15 00:29:45.670576 | 
2026-02-15 00:29:45.670667 | TASK [ansible-test : Enable --requirements]
2026-02-15 00:29:45.696172 | controller | skipping: Conditional result was False
2026-02-15 00:29:45.705604 | 
2026-02-15 00:29:45.705700 | TASK [ansible-test : Adjust options for unit tests]
2026-02-15 00:29:45.731677 | controller | skipping: Conditional result was False
2026-02-15 00:29:45.740488 | 
2026-02-15 00:29:45.740578 | TASK [ansible-test : Install requirements with unit tests]
2026-02-15 00:29:45.786677 | controller | ok
2026-02-15 00:29:45.798043 | 
2026-02-15 00:29:45.798170 | TASK [ansible-test : Set --skip-test]
2026-02-15 00:29:45.887323 | controller | skipping: Conditional result was False
2026-02-15 00:29:45.903010 | 
2026-02-15 00:29:45.903196 | TASK [ansible-test : Reduce verbosity]
2026-02-15 00:29:46.009943 | controller | ok
2026-02-15 00:29:46.016373 | 
2026-02-15 00:29:46.016533 | TASK [ansible-test : Setup --python option]
2026-02-15 00:29:46.041403 | controller | skipping: Conditional result was False
2026-02-15 00:29:46.049505 | 
2026-02-15 00:29:46.049586 | TASK [ansible-test : Setup --docker option]
2026-02-15 00:29:46.089675 | controller | ok
2026-02-15 00:29:46.095241 | 
2026-02-15 00:29:46.095305 | TASK [ansible-test : check if MANIFEST.json exists]
2026-02-15 00:29:47.301544 | controller | ok
2026-02-15 00:29:47.307109 | 
2026-02-15 00:29:47.307177 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-02-15 00:29:47.321318 | controller | skipping: Conditional result was False
2026-02-15 00:29:47.328287 | 
2026-02-15 00:29:47.328389 | TASK [ansible-test : Load information from MANIFEST.json]
2026-02-15 00:29:47.342834 | controller | skipping: Conditional result was False
2026-02-15 00:29:47.349824 | 
2026-02-15 00:29:47.349915 | TASK [ansible-test : Setup location of project for integration tests]
2026-02-15 00:29:47.364011 | controller | skipping: Conditional result was False
2026-02-15 00:29:47.371150 | 
2026-02-15 00:29:47.371244 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-02-15 00:29:48.665840 | controller | ok
2026-02-15 00:29:48.679931 | 
2026-02-15 00:29:48.680083 | TASK [ansible-test : Load information from galaxy.yml]
2026-02-15 00:29:48.724432 | controller | ok
2026-02-15 00:29:48.732999 | 
2026-02-15 00:29:48.733090 | TASK [ansible-test : Setup location of project for integration tests]
2026-02-15 00:29:48.784672 | controller | ok
2026-02-15 00:29:48.790850 | 
2026-02-15 00:29:48.790930 | TASK [ansible-test : Setup minimum test requirements]
2026-02-15 00:29:48.840549 | controller | ok
2026-02-15 00:29:48.847998 | 
2026-02-15 00:29:48.848085 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-02-15 00:29:49.525431 | controller | ok
2026-02-15 00:29:49.532955 | 
2026-02-15 00:29:49.533024 | TASK [ansible-test : Install python requirements]
2026-02-15 00:29:51.057794 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-02-15 00:29:51.071980 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-02-15 00:29:51.112330 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-02-15 00:29:51.117744 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-02-15 00:29:51.142032 | 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)
2026-02-15 00:29:51.150373 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-02-15 00:29:51.171258 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-02-15 00:29:51.218417 | controller | Installing collected packages: pathspec, yamllint
2026-02-15 00:29:51.286244 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-02-15 00:29:51.594640 | controller | ok: Runtime: 0:00:01.232702
2026-02-15 00:29:51.602148 | 
2026-02-15 00:29:51.602243 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-02-15 00:29:52.013712 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-02-15 00:29:52.360022 | controller | ERROR
2026-02-15 00:29:52.360223 | controller | {
2026-02-15 00:29:52.360277 | controller |   "delta": "0:00:00.004066",
2026-02-15 00:29:52.360317 | controller |   "end": "2026-02-15 00:29:52.013849",
2026-02-15 00:29:52.360355 | controller |   "msg": "non-zero return code",
2026-02-15 00:29:52.360391 | controller |   "rc": 1,
2026-02-15 00:29:52.360425 | controller |   "start": "2026-02-15 00:29:52.009783"
2026-02-15 00:29:52.360457 | controller | }
2026-02-15 00:29:52.360501 | controller | ERROR: Ignoring Errors
2026-02-15 00:29:52.370418 | 
2026-02-15 00:29:52.370591 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-02-15 00:29:52.936173 | controller | skipping: Conditional result was False
2026-02-15 00:29:52.943313 | 
2026-02-15 00:29:52.943397 | TASK [ansible-test : Enable persistent connection logging]
2026-02-15 00:29:53.650588 | controller | changed: section and option added
2026-02-15 00:29:53.656716 | 
2026-02-15 00:29:53.656802 | TASK [ansible-test : Set persistent ssh_type value]
2026-02-15 00:29:54.075060 | controller | changed: option added
2026-02-15 00:29:54.081981 | 
2026-02-15 00:29:54.082078 | TASK [ansible-test : Set ansible log path]
2026-02-15 00:29:54.416464 | controller | changed: section and option added
2026-02-15 00:29:54.430437 | 
2026-02-15 00:29:54.430592 | TASK [ansible-test : Identify the changed targets]
2026-02-15 00:29:54.982145 | controller | skipping: Conditional result was False
2026-02-15 00:29:54.992860 | 
2026-02-15 00:29:54.992965 | TASK [ansible-test : set_fact]
2026-02-15 00:29:55.039674 | controller | skipping: Conditional result was False
2026-02-15 00:29:55.050351 | 
2026-02-15 00:29:55.050470 | TASK [ansible-test : Set the targets]
2026-02-15 00:29:55.085667 | controller | ok
2026-02-15 00:29:55.094926 | 
2026-02-15 00:29:55.095031 | TASK [ansible-test : set_fact]
2026-02-15 00:29:55.121646 | controller | skipping: Conditional result was False
2026-02-15 00:29:55.131820 | 
2026-02-15 00:29:55.131952 | TASK [ansible-test : List the targets]
2026-02-15 00:29:55.690618 | controller | skipping: Conditional result was False
2026-02-15 00:29:55.702532 | 
2026-02-15 00:29:55.702677 | TASK [ansible-test : set_fact]
2026-02-15 00:29:55.729802 | controller | skipping: Conditional result was False
2026-02-15 00:29:55.745110 | 
2026-02-15 00:29:55.745274 | TASK [ansible-test : set_fact]
2026-02-15 00:29:55.764146 | controller | skipping: Conditional result was False
2026-02-15 00:29:55.781479 | 
2026-02-15 00:29:55.781663 | LOOP [ansible-test : set_fact]
2026-02-15 00:29:55.957310 | 
2026-02-15 00:29:55.957526 | TASK [ansible-test : set_fact]
2026-02-15 00:29:55.993024 | controller | skipping: Conditional result was False
2026-02-15 00:29:56.000916 | 
2026-02-15 00:29:56.001000 | TASK [ansible-test : debug]
2026-02-15 00:29:56.026254 | controller | skipping: Conditional result was False
2026-02-15 00:29:56.037017 | 
2026-02-15 00:29:56.037252 | TASK [ansible-test : debug]
2026-02-15 00:29:56.073493 | About to run: ansible-test sanity --requirements --docker -v
2026-02-15 00:29:56.082103 | 
2026-02-15 00:29:56.082217 | TASK [ansible-test : Run the test suite]
2026-02-15 00:29:56.846016 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-02-15 00:29:56.881559 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-02-15 00:29:57.338067 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-02-15 00:29:57.568039 | controller | 171857c49d0f: Pulling fs layer
2026-02-15 00:29:57.568110 | controller | 419640447d26: Pulling fs layer
2026-02-15 00:29:57.568120 | controller | 61e52f862619: Pulling fs layer
2026-02-15 00:29:57.568127 | controller | 851de0426e26: Pulling fs layer
2026-02-15 00:29:57.568136 | controller | 37425716ea5b: Pulling fs layer
2026-02-15 00:29:57.568145 | controller | cd9b0c439616: Pulling fs layer
2026-02-15 00:29:57.568152 | controller | d4612dbd8855: Pulling fs layer
2026-02-15 00:29:57.568157 | controller | 554699364730: Pulling fs layer
2026-02-15 00:29:57.568163 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-02-15 00:29:57.568169 | controller | 5f2e4724adea: Pulling fs layer
2026-02-15 00:29:57.568175 | controller | a02d9a95ccb8: Pulling fs layer
2026-02-15 00:29:57.568180 | controller | 989f4ce370cb: Pulling fs layer
2026-02-15 00:29:57.568186 | controller | 0d7f52cdf9df: Pulling fs layer
2026-02-15 00:29:57.568192 | controller | c423742351d5: Pulling fs layer
2026-02-15 00:29:57.568198 | controller | 109236341d21: Pulling fs layer
2026-02-15 00:29:57.568204 | controller | 07a2f61c96b8: Pulling fs layer
2026-02-15 00:29:57.568211 | controller | 041f122f27ae: Pulling fs layer
2026-02-15 00:29:57.568217 | controller | ca6f5098b12e: Pulling fs layer
2026-02-15 00:29:57.568224 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-02-15 00:29:57.568230 | controller | 18a0b750727a: Pulling fs layer
2026-02-15 00:29:57.568235 | controller | 17f2f596716c: Pulling fs layer
2026-02-15 00:29:57.568243 | controller | f35a9998a9bd: Pulling fs layer
2026-02-15 00:29:57.568248 | controller | fdfc10566405: Pulling fs layer
2026-02-15 00:29:57.568254 | controller | 1ff1c442c5fe: Pulling fs layer
2026-02-15 00:29:57.568260 | controller | a3443c4a17ed: Pulling fs layer
2026-02-15 00:29:57.568265 | controller | 0edcf9fb2b15: Pulling fs layer
2026-02-15 00:29:57.568271 | controller | 5af43b69e845: Pulling fs layer
2026-02-15 00:29:57.568277 | controller | 1651773effb2: Pulling fs layer
2026-02-15 00:29:57.568283 | controller | 79e23c5c0fe4: Pulling fs layer
2026-02-15 00:29:57.568293 | controller | e5058b1faaa6: Pulling fs layer
2026-02-15 00:29:57.568299 | controller | 846775490569: Pulling fs layer
2026-02-15 00:29:57.568305 | controller | 041f122f27ae: Waiting
2026-02-15 00:29:57.568311 | controller | ca6f5098b12e: Waiting
2026-02-15 00:29:57.568316 | controller | 1eb3e7b2afd9: Waiting
2026-02-15 00:29:57.568322 | controller | 18a0b750727a: Waiting
2026-02-15 00:29:57.568328 | controller | 17f2f596716c: Waiting
2026-02-15 00:29:57.568334 | controller | f35a9998a9bd: Waiting
2026-02-15 00:29:57.568342 | controller | fdfc10566405: Waiting
2026-02-15 00:29:57.568348 | controller | 1ff1c442c5fe: Waiting
2026-02-15 00:29:57.568353 | controller | a3443c4a17ed: Waiting
2026-02-15 00:29:57.568359 | controller | 0edcf9fb2b15: Waiting
2026-02-15 00:29:57.568365 | controller | 5af43b69e845: Waiting
2026-02-15 00:29:57.568370 | controller | 851de0426e26: Waiting
2026-02-15 00:29:57.568376 | controller | 37425716ea5b: Waiting
2026-02-15 00:29:57.568382 | controller | cd9b0c439616: Waiting
2026-02-15 00:29:57.568387 | controller | d4612dbd8855: Waiting
2026-02-15 00:29:57.568393 | controller | 1651773effb2: Waiting
2026-02-15 00:29:57.568399 | controller | 554699364730: Waiting
2026-02-15 00:29:57.568404 | controller | 79e23c5c0fe4: Waiting
2026-02-15 00:29:57.568412 | controller | e5058b1faaa6: Waiting
2026-02-15 00:29:57.568418 | controller | 846775490569: Waiting
2026-02-15 00:29:57.568424 | controller | 1ff15d4bdb3e: Waiting
2026-02-15 00:29:57.568429 | controller | 5f2e4724adea: Waiting
2026-02-15 00:29:57.568435 | controller | a02d9a95ccb8: Waiting
2026-02-15 00:29:57.568441 | controller | 989f4ce370cb: Waiting
2026-02-15 00:29:57.568447 | controller | 0d7f52cdf9df: Waiting
2026-02-15 00:29:57.568452 | controller | c423742351d5: Waiting
2026-02-15 00:29:57.568458 | controller | 109236341d21: Waiting
2026-02-15 00:29:57.568464 | controller | 07a2f61c96b8: Waiting
2026-02-15 00:29:57.568479 | controller | 61e52f862619: Verifying Checksum
2026-02-15 00:29:57.932575 | controller | 61e52f862619: Download complete
2026-02-15 00:29:57.932616 | controller | 851de0426e26: Verifying Checksum
2026-02-15 00:29:58.008824 | controller | 851de0426e26: Download complete
2026-02-15 00:29:58.008872 | controller | 171857c49d0f: Verifying Checksum
2026-02-15 00:29:58.008966 | controller | 171857c49d0f: Download complete
2026-02-15 00:29:58.209088 | controller | 419640447d26: Verifying Checksum
2026-02-15 00:29:58.356171 | controller | 419640447d26: Download complete
2026-02-15 00:29:58.356224 | controller | cd9b0c439616: Verifying Checksum
2026-02-15 00:29:58.356760 | controller | cd9b0c439616: Download complete
2026-02-15 00:29:58.554170 | controller | d4612dbd8855: Verifying Checksum
2026-02-15 00:29:58.554248 | controller | d4612dbd8855: Download complete
2026-02-15 00:29:58.963942 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-02-15 00:29:59.323697 | controller | 1ff15d4bdb3e: Download complete
2026-02-15 00:29:59.323741 | controller | 5f2e4724adea: Verifying Checksum
2026-02-15 00:29:59.822692 | controller | 5f2e4724adea: Download complete
2026-02-15 00:29:59.822729 | controller | a02d9a95ccb8: Verifying Checksum
2026-02-15 00:30:00.155377 | controller | a02d9a95ccb8: Download complete
2026-02-15 00:30:00.155429 | controller | 989f4ce370cb: Verifying Checksum
2026-02-15 00:30:00.155506 | controller | 989f4ce370cb: Download complete
2026-02-15 00:30:00.486549 | controller | 0d7f52cdf9df: Verifying Checksum
2026-02-15 00:30:00.657254 | controller | 0d7f52cdf9df: Download complete
2026-02-15 00:30:00.657293 | controller | 554699364730: Verifying Checksum
2026-02-15 00:30:00.699360 | controller | 554699364730: Download complete
2026-02-15 00:30:00.699396 | controller | 171857c49d0f: Pull complete
2026-02-15 00:30:00.721552 | controller | 419640447d26: Pull complete
2026-02-15 00:30:00.737638 | controller | 61e52f862619: Pull complete
2026-02-15 00:30:00.754292 | controller | 851de0426e26: Pull complete
2026-02-15 00:30:00.757571 | controller | c423742351d5: Download complete
2026-02-15 00:30:01.051057 | controller | 07a2f61c96b8: Verifying Checksum
2026-02-15 00:30:01.051187 | controller | 07a2f61c96b8: Download complete
2026-02-15 00:30:01.565977 | controller | 041f122f27ae: Verifying Checksum
2026-02-15 00:30:02.063426 | controller | 041f122f27ae: Download complete
2026-02-15 00:30:02.063466 | controller | ca6f5098b12e: Verifying Checksum
2026-02-15 00:30:02.381122 | controller | ca6f5098b12e: Download complete
2026-02-15 00:30:02.381163 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-02-15 00:30:02.780527 | controller | 1eb3e7b2afd9: Download complete
2026-02-15 00:30:02.780568 | controller | 18a0b750727a: Verifying Checksum
2026-02-15 00:30:03.047851 | controller | 18a0b750727a: Download complete
2026-02-15 00:30:03.047898 | controller | 17f2f596716c: Verifying Checksum
2026-02-15 00:30:03.329465 | controller | 17f2f596716c: Download complete
2026-02-15 00:30:03.329539 | controller | f35a9998a9bd: Verifying Checksum
2026-02-15 00:30:03.737842 | controller | f35a9998a9bd: Download complete
2026-02-15 00:30:03.737878 | controller | fdfc10566405: Verifying Checksum
2026-02-15 00:30:03.977431 | controller | fdfc10566405: Download complete
2026-02-15 00:30:03.977488 | controller | 1ff1c442c5fe: Verifying Checksum
2026-02-15 00:30:04.234601 | controller | 1ff1c442c5fe: Download complete
2026-02-15 00:30:04.234651 | controller | 109236341d21: Verifying Checksum
2026-02-15 00:30:04.781527 | controller | 109236341d21: Download complete
2026-02-15 00:30:04.781569 | controller | 0edcf9fb2b15: Verifying Checksum
2026-02-15 00:30:05.278076 | controller | 0edcf9fb2b15: Download complete
2026-02-15 00:30:05.278440 | controller | a3443c4a17ed: Verifying Checksum
2026-02-15 00:30:05.278454 | controller | a3443c4a17ed: Download complete
2026-02-15 00:30:05.609982 | controller | 5af43b69e845: Verifying Checksum
2026-02-15 00:30:05.851040 | controller | 5af43b69e845: Download complete
2026-02-15 00:30:05.851106 | controller | 1651773effb2: Verifying Checksum
2026-02-15 00:30:05.851160 | controller | 1651773effb2: Download complete
2026-02-15 00:30:06.510694 | controller | 79e23c5c0fe4: Verifying Checksum
2026-02-15 00:30:06.510781 | controller | 79e23c5c0fe4: Download complete
2026-02-15 00:30:06.701636 | controller | e5058b1faaa6: Verifying Checksum
2026-02-15 00:30:08.155079 | controller | 846775490569: Verifying Checksum
2026-02-15 00:30:08.155260 | controller | 846775490569: Download complete
2026-02-15 00:30:15.704573 | controller | 37425716ea5b: Verifying Checksum
2026-02-15 00:30:28.988798 | controller | 37425716ea5b: Download complete
2026-02-15 00:30:28.988891 | controller | 37425716ea5b: Pull complete
2026-02-15 00:30:29.008844 | controller | cd9b0c439616: Pull complete
2026-02-15 00:30:29.027651 | controller | d4612dbd8855: Pull complete
2026-02-15 00:30:31.734010 | controller | 554699364730: Pull complete
2026-02-15 00:30:31.753612 | controller | 1ff15d4bdb3e: Pull complete
2026-02-15 00:30:31.775069 | controller | 5f2e4724adea: Pull complete
2026-02-15 00:30:31.808651 | controller | a02d9a95ccb8: Pull complete
2026-02-15 00:30:31.827948 | controller | 989f4ce370cb: Pull complete
2026-02-15 00:30:31.846161 | controller | 0d7f52cdf9df: Pull complete
2026-02-15 00:30:31.866365 | controller | c423742351d5: Pull complete
2026-02-15 00:30:33.141450 | controller | 109236341d21: Pull complete
2026-02-15 00:30:33.161687 | controller | 07a2f61c96b8: Pull complete
2026-02-15 00:30:33.183197 | controller | 041f122f27ae: Pull complete
2026-02-15 00:30:33.292335 | controller | ca6f5098b12e: Pull complete
2026-02-15 00:30:33.338518 | controller | 1eb3e7b2afd9: Pull complete
2026-02-15 00:30:33.377113 | controller | 18a0b750727a: Pull complete
2026-02-15 00:30:33.397083 | controller | 17f2f596716c: Pull complete
2026-02-15 00:30:33.420612 | controller | f35a9998a9bd: Pull complete
2026-02-15 00:30:33.443192 | controller | fdfc10566405: Pull complete
2026-02-15 00:30:33.463492 | controller | 1ff1c442c5fe: Pull complete
2026-02-15 00:30:34.382289 | controller | a3443c4a17ed: Pull complete
2026-02-15 00:30:35.683936 | controller | 0edcf9fb2b15: Pull complete
2026-02-15 00:30:37.331575 | controller | 5af43b69e845: Pull complete
2026-02-15 00:30:39.170739 | controller | 1651773effb2: Pull complete
2026-02-15 00:30:41.652534 | controller | 79e23c5c0fe4: Pull complete
2026-02-15 00:30:42.765228 | controller | e5058b1faaa6: Pull complete
2026-02-15 00:30:44.315966 | controller | 846775490569: Pull complete
2026-02-15 00:30:44.333574 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-02-15 00:30:44.336718 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-02-15 00:30:44.342521 | controller | quay.io/ansible/default-test-container:2.9.0
2026-02-15 00:30:44.345539 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-02-15 00:30:44.368566 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-02-15 00:30:44.820520 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-02-15 00:30:44.821503 | controller | 04a5f4cda3ee: Pulling fs layer
2026-02-15 00:30:44.821522 | controller | ff496a88c8ed: Pulling fs layer
2026-02-15 00:30:44.821531 | controller | 0ce83f459fe7: Pulling fs layer
2026-02-15 00:30:44.821538 | controller | 2e5170e1f099: Pulling fs layer
2026-02-15 00:30:44.821544 | controller | 7641eb41b08c: Pulling fs layer
2026-02-15 00:30:44.821549 | controller | ad15fa9da398: Pulling fs layer
2026-02-15 00:30:44.821557 | controller | 087d91352424: Pulling fs layer
2026-02-15 00:30:44.822482 | controller | 8b92efd6a100: Pulling fs layer
2026-02-15 00:30:44.822521 | controller | 2e5170e1f099: Waiting
2026-02-15 00:30:44.994301 | controller | 7641eb41b08c: Waiting
2026-02-15 00:30:44.994375 | controller | ad15fa9da398: Waiting
2026-02-15 00:30:44.994392 | controller | 087d91352424: Waiting
2026-02-15 00:30:44.994404 | controller | 8b92efd6a100: Waiting
2026-02-15 00:30:44.994428 | controller | 0ce83f459fe7: Download complete
2026-02-15 00:30:45.143604 | controller | ff496a88c8ed: Download complete
2026-02-15 00:30:45.401619 | controller | 7641eb41b08c: Verifying Checksum
2026-02-15 00:30:45.401792 | controller | 7641eb41b08c: Download complete
2026-02-15 00:30:45.521477 | controller | 04a5f4cda3ee: Verifying Checksum
2026-02-15 00:30:45.714104 | controller | 04a5f4cda3ee: Download complete
2026-02-15 00:30:45.714145 | controller | ad15fa9da398: Verifying Checksum
2026-02-15 00:30:45.826129 | controller | ad15fa9da398: Download complete
2026-02-15 00:30:45.826175 | controller | 087d91352424: Verifying Checksum
2026-02-15 00:30:45.964637 | controller | 087d91352424: Download complete
2026-02-15 00:30:45.964710 | controller | 8b92efd6a100: Verifying Checksum
2026-02-15 00:30:45.964794 | controller | 8b92efd6a100: Download complete
2026-02-15 00:30:46.046317 | controller | 2e5170e1f099: Verifying Checksum
2026-02-15 00:30:46.046354 | controller | 2e5170e1f099: Download complete
2026-02-15 00:30:46.671882 | controller | 04a5f4cda3ee: Pull complete
2026-02-15 00:30:46.725033 | controller | ff496a88c8ed: Pull complete
2026-02-15 00:30:46.772936 | controller | 0ce83f459fe7: Pull complete
2026-02-15 00:30:47.632054 | controller | 2e5170e1f099: Pull complete
2026-02-15 00:30:47.656945 | controller | 7641eb41b08c: Pull complete
2026-02-15 00:30:47.693271 | controller | ad15fa9da398: Pull complete
2026-02-15 00:30:48.257675 | controller | 087d91352424: Pull complete
2026-02-15 00:30:48.279575 | controller | 8b92efd6a100: Pull complete
2026-02-15 00:30:48.295022 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-02-15 00:30:48.299132 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-02-15 00:30:48.304625 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-02-15 00:30:48.306916 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-02-15 00:35:40.636404 | [Zuul] Log Stream did not terminate
2026-02-15 00:35:40.636953 | controller | ERROR
2026-02-15 00:35:40.637157 | controller | {
2026-02-15 00:35:40.637222 | controller |   "msg": "Data could not be sent to remote host \"162.253.55.227\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.227 port 22: Connection timed out\r\n",
2026-02-15 00:35:40.637273 | controller |   "unreachable": true
2026-02-15 00:35:40.637320 | controller | }
unreachable
2026-02-15 00:35:40.640370 | 
2026-02-15 00:35:40.640504 | PLAY RECAP
2026-02-15 00:35:40.640602 | controller | ok: 21 changed: 6 unreachable: 1 failed: 0 skipped: 20 rescued: 0 ignored: 1
2026-02-15 00:35:40.640658 | 
2026-02-15 00:35:40.893902 | RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-02-15 00:35:40.894966 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-15 00:35:41.502960 | 
2026-02-15 00:35:41.503106 | PLAY [all]
2026-02-15 00:35:41.525475 | 
2026-02-15 00:35:41.525624 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-15 00:35:50.575142 | controller | changed: non-zero return code
2026-02-15 00:35:50.583105 | 
2026-02-15 00:35:50.583256 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-15 00:35:50.609916 | controller | skipping: Conditional result was False
2026-02-15 00:35:50.618666 | 
2026-02-15 00:35:50.618776 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-15 00:35:50.652670 | 
2026-02-15 00:35:50.652882 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-15 00:35:50.693287 | 
2026-02-15 00:35:50.693529 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-15 00:35:50.719786 | controller | skipping: Conditional result was False
2026-02-15 00:35:50.729372 | 
2026-02-15 00:35:50.729474 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-15 00:35:50.765031 | 
2026-02-15 00:35:50.765246 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-15 00:35:50.790893 | controller | skipping: Conditional result was False
2026-02-15 00:35:50.805181 | 
2026-02-15 00:35:50.805281 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-15 00:35:50.830537 | controller | skipping: Conditional result was False
2026-02-15 00:35:50.838769 | 
2026-02-15 00:35:50.838863 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-15 00:35:50.855137 | controller | skipping: Conditional result was False
2026-02-15 00:35:50.896267 | 
2026-02-15 00:35:50.896413 | PLAY RECAP
2026-02-15 00:35:50.896461 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-15 00:35:50.896481 | 
2026-02-15 00:35:51.029603 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-15 00:35:51.030624 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-02-15 00:35:51.711276 | 
2026-02-15 00:35:51.711430 | PLAY [all:!appliance*]
2026-02-15 00:35:51.736452 | 
2026-02-15 00:35:51.736596 | TASK [unregister the node]
2026-02-15 00:35:52.270139 | controller | skipping: Conditional result was False
2026-02-15 00:35:52.281635 | 
2026-02-15 00:35:52.281825 | TASK [include_role : fetch-output]
2026-02-15 00:35:52.333580 | controller | ok
2026-02-15 00:35:52.366637 | 
2026-02-15 00:35:52.366786 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-15 00:35:52.423323 | controller | skipping: Conditional result was False
2026-02-15 00:35:52.430028 | 
2026-02-15 00:35:52.430104 | TASK [fetch-output : Set log path for single node]
2026-02-15 00:35:52.469871 | controller | ok
2026-02-15 00:35:52.475267 | 
2026-02-15 00:35:52.475332 | LOOP [fetch-output : Ensure local output dirs]
2026-02-15 00:35:52.994375 | controller -> localhost | ok: "/var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/logs"
2026-02-15 00:35:53.268463 | controller -> localhost | changed: "/var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/artifacts"
2026-02-15 00:35:53.513020 | controller -> localhost | changed: "/var/lib/zuul/builds/f8282497732849d88885a5fd560c6455/work/docs"
2026-02-15 00:35:53.523885 | 
2026-02-15 00:35:53.524015 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-15 00:35:54.300127 | controller | changed:
2026-02-15 00:35:54.300483 | controller | .d..t...... ./
2026-02-15 00:35:54.300555 | controller | cd+++++++++ controller/
2026-02-15 00:35:54.300622 | controller | changed: All items complete
2026-02-15 00:35:54.300667 | 
2026-02-15 00:35:54.854121 | controller | changed: .d..t...... ./
2026-02-15 00:35:55.385882 | controller | changed: .d..t...... ./
2026-02-15 00:35:55.411709 | 
2026-02-15 00:35:55.411932 | TASK [include_role : fetch-output-openshift]
2026-02-15 00:35:55.438146 | controller | skipping: Conditional result was False
2026-02-15 00:35:55.448143 | 
2026-02-15 00:35:55.448246 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-15 00:35:55.493788 | controller | skipping: Conditional result was False
2026-02-15 00:35:55.506931 | controller | skipping: Conditional result was False
2026-02-15 00:35:55.557387 | 
2026-02-15 00:35:55.557537 | PLAY [localhost]
2026-02-15 00:35:55.579259 | 
2026-02-15 00:35:55.579393 | TASK [Run Zuul manifest role]
2026-02-15 00:35:55.602937 | localhost | ok
2026-02-15 00:35:55.628840 | 
2026-02-15 00:35:55.629015 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-15 00:35:56.123525 | localhost | changed
2026-02-15 00:35:56.130494 | 
2026-02-15 00:35:56.130591 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-15 00:35:56.163624 | localhost | ok
2026-02-15 00:35:56.175902 | 
2026-02-15 00:35:56.176000 | TASK [Set zuul-log-path fact]
2026-02-15 00:35:56.196677 | localhost | ok
2026-02-15 00:35:56.218956 | 
2026-02-15 00:35:56.219111 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-15 00:35:56.248975 | localhost | ok
2026-02-15 00:35:56.257377 | 
2026-02-15 00:35:56.257447 | LOOP [Run upload-logs-swift role]
2026-02-15 00:35:56.291999 | localhost | Output suppressed because no_log was given
2026-02-15 00:35:56.328638 | 
2026-02-15 00:35:56.328816 | TASK [Set zuul-log-path fact]
2026-02-15 00:35:56.375503 | localhost | skipping: Conditional result was False
2026-02-15 00:35:56.382649 | 
2026-02-15 00:35:56.382790 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-02-15 00:35:56.871822 | localhost -> localhost | ok: Runtime: 0:00:00.005105
2026-02-15 00:35:56.908951 | 
2026-02-15 00:35:56.909100 | TASK [upload-logs-swift : Upload logs to swift]