2026-01-16 00:06:48.393358 | Job console starting...
2026-01-16 00:06:48.405831 | Updating repositories
2026-01-16 00:06:49.969195 | Preparing job workspace
2026-01-16 00:06:56.969162 | Running Ansible setup...
2026-01-16 00:07:05.494796 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-16 00:07:06.194238 | 
2026-01-16 00:07:06.194370 | PLAY [localhost]
2026-01-16 00:07:06.203347 | 
2026-01-16 00:07:06.203448 | TASK [Gathering Facts]
2026-01-16 00:07:07.278758 | localhost | ok
2026-01-16 00:07:07.315206 | 
2026-01-16 00:07:07.315446 | TASK [Setup log path fact]
2026-01-16 00:07:07.342084 | localhost | ok
2026-01-16 00:07:07.368035 | 
2026-01-16 00:07:07.368153 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-16 00:07:07.398501 | localhost | ok
2026-01-16 00:07:07.409397 | 
2026-01-16 00:07:07.409491 | TASK [emit-job-header : Print job information]
2026-01-16 00:07:07.444265 | # Job Information
2026-01-16 00:07:07.444502 | Ansible Version: 2.15.12
2026-01-16 00:07:07.444568 | Job: ansible-test-sanity-docker-stable-2.10
2026-01-16 00:07:07.444631 | Pipeline: periodic
2026-01-16 00:07:07.444690 | Executor: ze01.softwarefactory-project.io
2026-01-16 00:07:07.444751 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-01-16 00:07:07.444841 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/cc0/ansible/cc0422115b2a46fdb750c716ad16d21b/
2026-01-16 00:07:07.444914 | Event ID: 4598a725dc6d4d59b84a56706cafa642
2026-01-16 00:07:07.454476 | 
2026-01-16 00:07:07.454600 | LOOP [emit-job-header : Print node information]
2026-01-16 00:07:07.562766 | localhost | ok:
2026-01-16 00:07:07.562929 | localhost | # Node Information
2026-01-16 00:07:07.562956 | localhost | Inventory Hostname: controller
2026-01-16 00:07:07.562975 | localhost | Hostname: np0005585416
2026-01-16 00:07:07.562994 | localhost | Username: zuul
2026-01-16 00:07:07.563034 | localhost | Distro: Fedora 37
2026-01-16 00:07:07.563058 | localhost | Provider: ansible-vexxhost-ams1
2026-01-16 00:07:07.563076 | localhost | Region: ams1
2026-01-16 00:07:07.563094 | localhost | Label: ansible-fedora-37-1vcpu
2026-01-16 00:07:07.563110 | localhost | Product Name: OpenStack Nova
2026-01-16 00:07:07.563127 | localhost | Interface IP: 38.129.16.84
2026-01-16 00:07:07.583515 | 
2026-01-16 00:07:07.583846 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-01-16 00:07:08.014029 | localhost -> localhost | changed
2026-01-16 00:07:08.019423 | 
2026-01-16 00:07:08.019487 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-01-16 00:07:09.016501 | localhost -> localhost | changed
2026-01-16 00:07:09.038309 | 
2026-01-16 00:07:09.038400 | PLAY [all:!appliance*]
2026-01-16 00:07:09.055692 | 
2026-01-16 00:07:09.055766 | TASK [include_role : start-zuul-console]
2026-01-16 00:07:09.077006 | controller | ok
2026-01-16 00:07:09.093620 | 
2026-01-16 00:07:09.093705 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-01-16 00:07:10.184735 | controller | ok
2026-01-16 00:07:10.210578 | 
2026-01-16 00:07:10.210709 | TASK [use-our-mirror : Retrieve the IP address]
2026-01-16 00:07:12.147677 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-01-16 00:07:12.163211 | 
2026-01-16 00:07:12.163388 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-01-16 00:07:12.546080 | controller | skipping: Conditional result was False
2026-01-16 00:07:12.552693 | 
2026-01-16 00:07:12.552767 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-01-16 00:07:12.586401 | controller | skipping: Conditional result was False
2026-01-16 00:07:12.594771 | 
2026-01-16 00:07:12.594873 | TASK [use-our-mirror : Create the podman configuration directory]
2026-01-16 00:07:12.619703 | controller | skipping: Conditional result was False
2026-01-16 00:07:12.628008 | 
2026-01-16 00:07:12.628166 | TASK [use-our-mirror : Copy the podman configuration]
2026-01-16 00:07:12.652752 | controller | skipping: Conditional result was False
2026-01-16 00:07:12.661854 | 
2026-01-16 00:07:12.661970 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-01-16 00:07:12.687033 | controller | skipping: Conditional result was False
2026-01-16 00:07:12.695119 | 
2026-01-16 00:07:12.695224 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-01-16 00:07:12.720230 | controller | skipping: Conditional result was False
2026-01-16 00:07:12.732073 | 
2026-01-16 00:07:12.732174 | TASK [Disable Fedora Modular]
2026-01-16 00:07:13.892151 | controller | changed
2026-01-16 00:07:13.904652 | 
2026-01-16 00:07:13.904780 | TASK [Enable EPEL]
2026-01-16 00:07:13.931216 | controller | skipping: Conditional result was False
2026-01-16 00:07:13.942301 | 
2026-01-16 00:07:13.942426 | TASK [Register the RHEL node]
2026-01-16 00:07:14.352900 | 
2026-01-16 00:07:14.353049 | TASK [Show the subscription-manager status]
2026-01-16 00:07:14.711465 | controller | skipping: Conditional result was False
2026-01-16 00:07:14.730537 | 
2026-01-16 00:07:14.730707 | TASK [Enable EPEL on RHEL]
2026-01-16 00:07:15.122709 | controller | skipping: Conditional result was False
2026-01-16 00:07:15.141132 | 
2026-01-16 00:07:15.141311 | TASK [Install git and tox]
2026-01-16 00:09:26.563305 | controller | changed
2026-01-16 00:09:26.578840 | 
2026-01-16 00:09:26.578989 | TASK [include_role : prepare-workspace]
2026-01-16 00:09:26.611603 | controller | ok
2026-01-16 00:09:26.650203 | 
2026-01-16 00:09:26.650336 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-01-16 00:09:27.506313 | controller | ok
2026-01-16 00:09:27.520499 | 
2026-01-16 00:09:27.520634 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-01-16 00:09:44.653035 | controller | Output suppressed because no_log was given
2026-01-16 00:09:44.667491 | 
2026-01-16 00:09:44.667623 | TASK [include_role : prepare-workspace-openshift]
2026-01-16 00:09:44.686660 | controller | skipping: Conditional result was False
2026-01-16 00:09:44.716729 | 
2026-01-16 00:09:44.716815 | PLAY [all:!appliance]
2026-01-16 00:09:44.734482 | 
2026-01-16 00:09:44.734597 | TASK [Run add-build-sshkey role (RSA)]
2026-01-16 00:09:44.767369 | controller | ok
2026-01-16 00:09:44.784362 | 
2026-01-16 00:09:44.784474 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-16 00:09:45.013253 | controller -> localhost | ok
2026-01-16 00:09:45.022697 | 
2026-01-16 00:09:45.022837 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-16 00:09:45.044916 | controller | ok
2026-01-16 00:09:45.060299 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-16 00:09:45.067335 | 
2026-01-16 00:09:45.067440 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-16 00:09:45.532090 | controller -> localhost | Generating public/private rsa key pair.
2026-01-16 00:09:45.532522 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/cc0422115b2a46fdb750c716ad16d21b_id_rsa.
2026-01-16 00:09:45.532588 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/cc0422115b2a46fdb750c716ad16d21b_id_rsa.pub.
2026-01-16 00:09:45.532653 | controller -> localhost | The key fingerprint is:
2026-01-16 00:09:45.532722 | controller -> localhost | SHA256:mnEdPYpVCVj+A8erW0VplClCgdDmh/ggpR+ZU6bFC78 zuul-build-sshkey
2026-01-16 00:09:45.532795 | controller -> localhost | The key's randomart image is:
2026-01-16 00:09:45.532859 | controller -> localhost | +---[RSA 2048]----+
2026-01-16 00:09:45.532902 | controller -> localhost | |      .+ ==o...o |
2026-01-16 00:09:45.532944 | controller -> localhost | |      o X..+o.o. |
2026-01-16 00:09:45.532986 | controller -> localhost | |     o & o=.=.+  |
2026-01-16 00:09:45.533071 | controller -> localhost | |    o O =+.* =   |
2026-01-16 00:09:45.533114 | controller -> localhost | |     o.=Soo + .  |
2026-01-16 00:09:45.533171 | controller -> localhost | |      .=E  . o   |
2026-01-16 00:09:45.533227 | controller -> localhost | |      o   . .    |
2026-01-16 00:09:45.533269 | controller -> localhost | |           o     |
2026-01-16 00:09:45.533308 | controller -> localhost | |          .      |
2026-01-16 00:09:45.533371 | controller -> localhost | +----[SHA256]-----+
2026-01-16 00:09:45.533500 | controller -> localhost | ok: Runtime: 0:00:00.101484
2026-01-16 00:09:45.551733 | 
2026-01-16 00:09:45.551923 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-16 00:09:45.580503 | controller | ok
2026-01-16 00:09:45.608858 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-16 00:09:45.628618 | 
2026-01-16 00:09:45.628737 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-16 00:09:45.653647 | controller | skipping: Conditional result was False
2026-01-16 00:09:45.660217 | 
2026-01-16 00:09:45.660332 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-16 00:09:46.805175 | controller | changed
2026-01-16 00:09:46.817523 | 
2026-01-16 00:09:46.817679 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-16 00:09:47.476993 | controller | ok
2026-01-16 00:09:47.485560 | 
2026-01-16 00:09:47.485689 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-16 00:09:50.523370 | controller | changed
2026-01-16 00:09:50.536570 | 
2026-01-16 00:09:50.536728 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-16 00:09:53.574255 | controller | changed
2026-01-16 00:09:53.588539 | 
2026-01-16 00:09:53.588722 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-16 00:09:53.626093 | controller | skipping: Conditional result was False
2026-01-16 00:09:53.637822 | 
2026-01-16 00:09:53.637997 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-16 00:09:54.052260 | controller -> localhost | changed
2026-01-16 00:09:54.064259 | 
2026-01-16 00:09:54.064352 | TASK [add-build-sshkey : Add back temp key]
2026-01-16 00:09:54.364399 | controller -> localhost | Identity added: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/cc0422115b2a46fdb750c716ad16d21b_id_rsa (zuul-build-sshkey)
2026-01-16 00:09:54.364758 | controller -> localhost | ok: Runtime: 0:00:00.014220
2026-01-16 00:09:54.381496 | 
2026-01-16 00:09:54.381668 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-16 00:09:55.307388 | controller | ok
2026-01-16 00:09:55.312874 | 
2026-01-16 00:09:55.312937 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-16 00:09:55.336671 | controller | skipping: Conditional result was False
2026-01-16 00:09:55.347911 | 
2026-01-16 00:09:55.347974 | TASK [Run add-build-sshkey role (ECDSA)]
2026-01-16 00:09:55.368237 | controller | ok
2026-01-16 00:09:55.383940 | 
2026-01-16 00:09:55.384036 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-01-16 00:09:55.641125 | controller -> localhost | ok
2026-01-16 00:09:55.647713 | 
2026-01-16 00:09:55.647781 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-01-16 00:09:55.677197 | controller | ok
2026-01-16 00:09:55.688663 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-01-16 00:09:55.695529 | 
2026-01-16 00:09:55.695597 | TASK [add-build-sshkey : Create Temp SSH key]
2026-01-16 00:09:56.025261 | controller -> localhost | Generating public/private ecdsa key pair.
2026-01-16 00:09:56.025472 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/cc0422115b2a46fdb750c716ad16d21b_id_ecdsa.
2026-01-16 00:09:56.025508 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/cc0422115b2a46fdb750c716ad16d21b_id_ecdsa.pub.
2026-01-16 00:09:56.025543 | controller -> localhost | The key fingerprint is:
2026-01-16 00:09:56.025565 | controller -> localhost | SHA256:QSux0A547ke6miyHIoNC8tqaht3LqORiiqJAOXzK+g8 zuul-build-sshkey
2026-01-16 00:09:56.025584 | controller -> localhost | The key's randomart image is:
2026-01-16 00:09:56.025603 | controller -> localhost | +---[ECDSA 521]---+
2026-01-16 00:09:56.025623 | controller -> localhost | |   .... .        |
2026-01-16 00:09:56.025641 | controller -> localhost | |  . o..+ .       |
2026-01-16 00:09:56.025659 | controller -> localhost | |   o oo o        |
2026-01-16 00:09:56.025676 | controller -> localhost | |. . . o. .       |
2026-01-16 00:09:56.025693 | controller -> localhost | | = o o  S        |
2026-01-16 00:09:56.025710 | controller -> localhost | |+.+ o .          |
2026-01-16 00:09:56.025727 | controller -> localhost | |*BE. o           |
2026-01-16 00:09:56.025743 | controller -> localhost | |^*+=o            |
2026-01-16 00:09:56.025760 | controller -> localhost | |^XBo+.           |
2026-01-16 00:09:56.025777 | controller -> localhost | +----[SHA256]-----+
2026-01-16 00:09:56.025823 | controller -> localhost | ok: Runtime: 0:00:00.007523
2026-01-16 00:09:56.033358 | 
2026-01-16 00:09:56.033467 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-01-16 00:09:56.059892 | controller | ok
2026-01-16 00:09:56.069826 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-01-16 00:09:56.080135 | 
2026-01-16 00:09:56.080204 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-01-16 00:09:56.095885 | controller | skipping: Conditional result was False
2026-01-16 00:09:56.110287 | 
2026-01-16 00:09:56.110428 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-01-16 00:09:57.005337 | controller | changed
2026-01-16 00:09:57.011699 | 
2026-01-16 00:09:57.011778 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-01-16 00:09:57.654634 | controller | ok
2026-01-16 00:09:57.661150 | 
2026-01-16 00:09:57.661254 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-01-16 00:10:00.719485 | controller | changed
2026-01-16 00:10:00.729446 | 
2026-01-16 00:10:00.729590 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-01-16 00:10:03.809481 | controller | changed
2026-01-16 00:10:03.824404 | 
2026-01-16 00:10:03.824586 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-01-16 00:10:03.852289 | controller | skipping: Conditional result was False
2026-01-16 00:10:03.867791 | 
2026-01-16 00:10:03.867970 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-01-16 00:10:04.120382 | controller -> localhost | changed
2026-01-16 00:10:04.135769 | 
2026-01-16 00:10:04.135909 | TASK [add-build-sshkey : Add back temp key]
2026-01-16 00:10:04.439403 | controller -> localhost | Identity added: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/cc0422115b2a46fdb750c716ad16d21b_id_ecdsa (zuul-build-sshkey)
2026-01-16 00:10:04.439624 | controller -> localhost | ok: Runtime: 0:00:00.010798
2026-01-16 00:10:04.451079 | 
2026-01-16 00:10:04.451215 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-01-16 00:10:05.147875 | controller | ok
2026-01-16 00:10:05.153821 | 
2026-01-16 00:10:05.153902 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-01-16 00:10:05.198291 | controller | skipping: Conditional result was False
2026-01-16 00:10:05.214492 | 
2026-01-16 00:10:05.214607 | TASK [include_role : remove-zuul-sshkey]
2026-01-16 00:10:05.239066 | controller | skipping: Conditional result was False
2026-01-16 00:10:05.255275 | 
2026-01-16 00:10:05.255365 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-01-16 00:10:05.935309 | controller | ok: "logs"
2026-01-16 00:10:05.935963 | controller | ok: All items complete
2026-01-16 00:10:05.936094 | 
2026-01-16 00:10:06.546916 | controller | ok: "artifacts"
2026-01-16 00:10:07.149778 | controller | ok: "docs"
2026-01-16 00:10:07.156867 | 
2026-01-16 00:10:07.156948 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-01-16 00:10:07.807836 | controller | changed: "logs"
2026-01-16 00:10:08.430294 | controller | changed: "artifacts"
2026-01-16 00:10:09.042574 | controller | changed: "docs"
2026-01-16 00:10:09.097620 | 
2026-01-16 00:10:09.097744 | PLAY RECAP
2026-01-16 00:10:09.097800 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-01-16 00:10:09.097839 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-01-16 00:10:09.097864 | 
2026-01-16 00:10:09.235191 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-01-16 00:10:09.236068 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-16 00:10:09.896455 | 
2026-01-16 00:10:09.896594 | PLAY [all]
2026-01-16 00:10:09.921798 | 
2026-01-16 00:10:09.921944 | TASK [Install binary dependencies]
2026-01-16 00:10:09.973643 | controller | ok
2026-01-16 00:10:09.993585 | 
2026-01-16 00:10:09.993695 | TASK [bindep : Include find tasks]
2026-01-16 00:10:10.032974 | controller | ok
2026-01-16 00:10:10.045978 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-01-16 00:10:10.055787 | 
2026-01-16 00:10:10.055898 | TASK [bindep : Look for bindep.txt]
2026-01-16 00:10:11.016500 | controller | ok
2026-01-16 00:10:11.032326 | 
2026-01-16 00:10:11.032495 | TASK [bindep : Define bindep_file fact]
2026-01-16 00:10:11.050735 | controller | skipping: Conditional result was False
2026-01-16 00:10:11.068950 | 
2026-01-16 00:10:11.069215 | TASK [bindep : Look for other-requirements.txt]
2026-01-16 00:10:11.699751 | controller | ok
2026-01-16 00:10:11.713179 | 
2026-01-16 00:10:11.713363 | TASK [bindep : Define bindep_file fact]
2026-01-16 00:10:11.739545 | controller | skipping: Conditional result was False
2026-01-16 00:10:11.749183 | 
2026-01-16 00:10:11.749307 | TASK [bindep : Look for bindep fallback file]
2026-01-16 00:10:11.794378 | controller | skipping: Conditional result was False
2026-01-16 00:10:11.803131 | 
2026-01-16 00:10:11.803254 | TASK [bindep : Define bindep_file fact]
2026-01-16 00:10:11.838359 | controller | skipping: Conditional result was False
2026-01-16 00:10:11.847932 | 
2026-01-16 00:10:11.848105 | TASK [bindep : Include bindep tasks]
2026-01-16 00:10:11.873113 | controller | skipping: Conditional result was False
2026-01-16 00:10:11.881759 | 
2026-01-16 00:10:11.881864 | TASK [bindep : Include install tasks]
2026-01-16 00:10:11.906255 | controller | skipping: Conditional result was False
2026-01-16 00:10:11.915002 | 
2026-01-16 00:10:11.915131 | LOOP [bindep : Include package tasks]
2026-01-16 00:10:11.974108 | 
2026-01-16 00:10:11.974275 | TASK [Run test-setup role]
2026-01-16 00:10:11.993874 | controller | ok
2026-01-16 00:10:12.015657 | 
2026-01-16 00:10:12.015771 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-01-16 00:10:12.635646 | controller | ok
2026-01-16 00:10:12.649179 | 
2026-01-16 00:10:12.649334 | TASK [test-setup : Run tools/test-setup.sh]
2026-01-16 00:10:13.018221 | controller | skipping: Conditional result was False
2026-01-16 00:10:13.044096 | 
2026-01-16 00:10:13.044649 | PLAY RECAP
2026-01-16 00:10:13.044705 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-16 00:10:13.044729 | 
2026-01-16 00:10:13.137759 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-01-16 00:10:13.138736 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-16 00:10:13.754197 | 
2026-01-16 00:10:13.754329 | PLAY [controller]
2026-01-16 00:10:13.774661 | 
2026-01-16 00:10:13.774810 | TASK [Create the /root directory]
2026-01-16 00:10:14.802229 | controller | ok
2026-01-16 00:10:14.808264 | 
2026-01-16 00:10:14.808363 | TASK [Install glibc-langpack-en]
2026-01-16 00:10:22.913794 | controller | ok: Nothing to do
2026-01-16 00:10:22.938271 | 
2026-01-16 00:10:22.938496 | TASK [Ensure controller directory exists]
2026-01-16 00:10:23.744648 | controller | changed
2026-01-16 00:10:23.753474 | 
2026-01-16 00:10:23.753590 | TASK [Install container runtime]
2026-01-16 00:10:23.827804 | controller | ok
2026-01-16 00:10:23.880853 | 
2026-01-16 00:10:23.880961 | LOOP [ensure-docker : Gather variables for each operating system]
2026-01-16 00:10:23.922440 | controller | ok: "/var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-01-16 00:10:23.931940 | 
2026-01-16 00:10:23.932050 | TASK [ensure-docker : Sanity check for distro selection]
2026-01-16 00:10:23.947011 | controller | skipping: Conditional result was False
2026-01-16 00:10:23.957949 | 
2026-01-16 00:10:23.958065 | TASK [ensure-docker : Sanity check for distro install]
2026-01-16 00:10:23.983978 | controller | skipping: Conditional result was False
2026-01-16 00:10:23.993716 | 
2026-01-16 00:10:23.993816 | TASK [ensure-docker : Sanity check for upstream install]
2026-01-16 00:10:24.019794 | controller | skipping: Conditional result was False
2026-01-16 00:10:24.029130 | 
2026-01-16 00:10:24.029239 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-01-16 00:10:24.056230 | controller | skipping: Conditional result was False
2026-01-16 00:10:24.065903 | 
2026-01-16 00:10:24.066003 | TASK [ensure-docker : Include tasks for docker install]
2026-01-16 00:10:24.090862 | controller | ok
2026-01-16 00:10:24.103745 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-01-16 00:10:24.120063 | 
2026-01-16 00:10:24.120166 | TASK [ensure-docker : Not Implemented]
2026-01-16 00:10:24.134425 | controller | skipping: Conditional result was False
2026-01-16 00:10:24.141899 | 
2026-01-16 00:10:24.141977 | TASK [Set up docker mirrors]
2026-01-16 00:10:24.161619 | controller | ok
2026-01-16 00:10:24.182424 | 
2026-01-16 00:10:24.182601 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-01-16 00:10:24.216872 | controller | skipping: Conditional result was False
2026-01-16 00:10:24.223463 | 
2026-01-16 00:10:24.223669 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-01-16 00:10:24.257978 | controller | skipping: Conditional result was False
2026-01-16 00:10:24.294850 | 
2026-01-16 00:10:24.294988 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-01-16 00:10:24.329221 | controller | skipping: Conditional result was False
2026-01-16 00:10:24.336839 | 
2026-01-16 00:10:24.336947 | TASK [use-docker-mirror : Set up docker mirrors]
2026-01-16 00:10:24.361392 | controller | skipping: Conditional result was False
2026-01-16 00:10:24.374048 | 
2026-01-16 00:10:24.374144 | TASK [Add all repositories]
2026-01-16 00:10:24.394401 | controller | ok
2026-01-16 00:10:24.421706 | 
2026-01-16 00:10:24.421821 | LOOP [ensure-package-repositories : Install repositories]
2026-01-16 00:10:24.458876 | controller | ok: "/var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-01-16 00:10:24.459301 | controller | ok: All items complete
2026-01-16 00:10:24.459370 | 
2026-01-16 00:10:24.478932 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-01-16 00:10:24.486075 | 
2026-01-16 00:10:24.486155 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-01-16 00:10:25.419312 | controller | changed
2026-01-16 00:10:25.428224 | 
2026-01-16 00:10:25.428311 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-01-16 00:10:28.634200 | controller | changed:
2026-01-16 00:10:28.634466 | controller | {
2026-01-16 00:10:28.634507 | 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-01-16 00:10:28.634540 | controller | }
2026-01-16 00:10:28.653654 | 
2026-01-16 00:10:28.653850 | LOOP [ensure-package-repositories : Import GPG keys]
2026-01-16 00:10:29.977050 | controller | changed:
2026-01-16 00:10:29.977253 | controller | {
2026-01-16 00:10:29.977280 | 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-01-16 00:10:29.977303 | controller | }
2026-01-16 00:10:29.977332 | controller | changed: All items complete
2026-01-16 00:10:29.977351 | 
2026-01-16 00:10:29.997534 | 
2026-01-16 00:10:29.997734 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-01-16 00:10:30.691636 | controller | changed
2026-01-16 00:10:30.701283 | 
2026-01-16 00:10:30.701400 | LOOP [ensure-package-repositories : Add repositories]
2026-01-16 00:10:31.591464 | controller | changed:
2026-01-16 00:10:31.591884 | controller | {
2026-01-16 00:10:31.591952 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-01-16 00:10:31.592008 | controller |   "description": "Docker CE Stable - $basearch",
2026-01-16 00:10:31.592095 | controller |   "gpgcheck": true,
2026-01-16 00:10:31.592144 | controller |   "name": "docker-ce-stable"
2026-01-16 00:10:31.592194 | controller | }
2026-01-16 00:10:31.613729 | 
2026-01-16 00:10:31.613848 | TASK [ensure-docker : Install pre-reqs]
2026-01-16 00:10:36.509501 | controller | ok: Nothing to do
2026-01-16 00:10:36.522498 | 
2026-01-16 00:10:36.522663 | TASK [ensure-docker : Remove packages]
2026-01-16 00:10:39.525247 | controller | ok: Nothing to do
2026-01-16 00:10:39.533300 | 
2026-01-16 00:10:39.533414 | TASK [ensure-docker : Install upstream docker using package]
2026-01-16 00:12:01.549940 | controller | changed
2026-01-16 00:12:01.557215 | 
2026-01-16 00:12:01.557341 | TASK [ensure-docker : Setup docker]
2026-01-16 00:12:01.578407 | controller | ok
2026-01-16 00:12:01.634152 | controller | included: /var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-01-16 00:12:01.651329 | 
2026-01-16 00:12:01.651472 | TASK [ensure-docker : Ensure "docker" group exists]
2026-01-16 00:12:02.612204 | controller | ok
2026-01-16 00:12:02.618789 | 
2026-01-16 00:12:02.618886 | TASK [ensure-docker : Add user to docker group]
2026-01-16 00:12:03.678839 | controller | changed
2026-01-16 00:12:03.688373 | 
2026-01-16 00:12:03.688505 | TASK [Update docker daemon proxy configuration]
2026-01-16 00:12:03.714537 | controller | skipping: Conditional result was False
2026-01-16 00:12:03.721557 | 
2026-01-16 00:12:03.721650 | TASK [Lower default MTU]
2026-01-16 00:12:03.745774 | controller | skipping: Conditional result was False
2026-01-16 00:12:03.754066 | 
2026-01-16 00:12:03.754182 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-01-16 00:12:04.743097 | controller | inactive
2026-01-16 00:12:05.110854 | controller | ok: Runtime: 0:00:00.013925
2026-01-16 00:12:05.119131 | 
2026-01-16 00:12:05.119266 | TASK [ensure-docker : Get the status of the docket unit]
2026-01-16 00:12:05.935564 | controller | inactive
2026-01-16 00:12:06.503364 | controller | ok: Runtime: 0:00:00.020140
2026-01-16 00:12:06.513052 | 
2026-01-16 00:12:06.513194 | TASK [ensure-docker : Restart docker]
2026-01-16 00:12:06.573342 | Trigger a Docker restart
2026-01-16 00:12:06.590649 | 
2026-01-16 00:12:06.590796 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-01-16 00:12:06.664612 | 
2026-01-16 00:12:06.664762 | TASK [ensure-docker : Install docker-compose]
2026-01-16 00:12:06.701753 | controller | skipping: Conditional result was False
2026-01-16 00:12:06.712416 | 
2026-01-16 00:12:06.712593 | TASK [ensure-docker : Flush handlers before role exit]
2026-01-16 00:12:06.733587 | 
2026-01-16 00:12:06.733905 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-01-16 00:12:10.036760 | controller | changed
2026-01-16 00:12:10.051205 | 
2026-01-16 00:12:10.051316 | TASK [ensure-docker : Assure docker service is running]
2026-01-16 00:12:14.129333 | controller | changed
2026-01-16 00:12:14.171326 | 
2026-01-16 00:12:14.171494 | TASK [ensure-docker : Assure docker.socket service is running]
2026-01-16 00:12:15.616556 | controller | ok
2026-01-16 00:12:15.627471 | 
2026-01-16 00:12:15.627996 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-01-16 00:12:16.369469 | controller | ok
2026-01-16 00:12:16.381640 | 
2026-01-16 00:12:16.381780 | TASK [ensure-docker : Validate ability to talk with docker]
2026-01-16 00:12:17.017009 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-01-16 00:12:17.275159 | controller | ok: Runtime: 0:00:00.039504
2026-01-16 00:12:17.282506 | 
2026-01-16 00:12:17.282617 | TASK [ensure-docker : Get version details]
2026-01-16 00:12:18.021533 | controller | Client: Docker Engine - Community
2026-01-16 00:12:18.021616 | controller |  Version:           24.0.7
2026-01-16 00:12:18.021726 | controller |  API version:       1.43
2026-01-16 00:12:18.021737 | controller |  Go version:        go1.20.10
2026-01-16 00:12:18.021756 | controller |  Git commit:        afdd53b
2026-01-16 00:12:18.021764 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-01-16 00:12:18.021776 | controller |  OS/Arch:           linux/amd64
2026-01-16 00:12:18.021786 | controller |  Context:           default
2026-01-16 00:12:18.021795 | controller |
2026-01-16 00:12:18.021816 | controller | Server: Docker Engine - Community
2026-01-16 00:12:18.021823 | controller |  Engine:
2026-01-16 00:12:18.021833 | controller |   Version:          24.0.7
2026-01-16 00:12:18.021876 | controller |   API version:      1.43 (minimum version 1.12)
2026-01-16 00:12:18.021885 | controller |   Go version:       go1.20.10
2026-01-16 00:12:18.021892 | controller |   Git commit:       311b9ff
2026-01-16 00:12:18.021898 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-01-16 00:12:18.021905 | controller |   OS/Arch:          linux/amd64
2026-01-16 00:12:18.021911 | controller |   Experimental:     false
2026-01-16 00:12:18.021917 | controller |  containerd:
2026-01-16 00:12:18.021928 | controller |   Version:          1.6.25
2026-01-16 00:12:18.021935 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-01-16 00:12:18.021956 | controller |  runc:
2026-01-16 00:12:18.021966 | controller |   Version:          1.1.10
2026-01-16 00:12:18.021973 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-01-16 00:12:18.021982 | controller |  docker-init:
2026-01-16 00:12:18.021999 | controller |   Version:          0.19.0
2026-01-16 00:12:18.023622 | controller |   GitCommit:        de40ad0
2026-01-16 00:12:18.224542 | controller | ok: Runtime: 0:00:00.045421
2026-01-16 00:12:18.247544 | 
2026-01-16 00:12:18.247666 | TASK [ensure-docker : Dump installed docker details]
2026-01-16 00:12:18.289241 | {'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-01-16 00:12:17.978602', 'end': '2026-01-16 00:12:18.024023', 'delta': '0:00:00.045421', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-c623-0e1c-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-01-16 00:12:18.295812 | 
2026-01-16 00:12:18.295903 | TASK [Use buildset registry]
2026-01-16 00:12:18.309858 | controller | skipping: Conditional result was False
2026-01-16 00:12:18.322671 | 
2026-01-16 00:12:18.322788 | TASK [Ensure python3.8 is present]
2026-01-16 00:12:18.339835 | controller | skipping: Conditional result was False
2026-01-16 00:12:18.347299 | 
2026-01-16 00:12:18.347412 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-01-16 00:12:18.402364 | controller | ok
2026-01-16 00:12:18.431695 | 
2026-01-16 00:12:18.431843 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-01-16 00:12:24.798873 | controller | ok: Nothing to do
2026-01-16 00:12:24.805120 | 
2026-01-16 00:12:24.805214 | TASK [our-ensure-python : Also install python3-devel]
2026-01-16 00:12:43.194113 | controller | changed
2026-01-16 00:12:43.214464 | 
2026-01-16 00:12:43.214625 | TASK [Run ensure-virtualenv role]
2026-01-16 00:12:43.240769 | controller | ok
2026-01-16 00:12:43.276221 | 
2026-01-16 00:12:43.276375 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-01-16 00:12:44.040110 | controller | /usr/bin/virtualenv
2026-01-16 00:12:44.659655 | controller | ok: Runtime: 0:00:00.008439
2026-01-16 00:12:44.667321 | 
2026-01-16 00:12:44.667474 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-01-16 00:12:44.705549 | controller | skipping: Conditional result was False
2026-01-16 00:12:44.705898 | controller | changed: All items complete
2026-01-16 00:12:44.705935 | 
2026-01-16 00:12:44.734347 | 
2026-01-16 00:12:44.734481 | TASK [Find the full path of the Python interpreter]
2026-01-16 00:12:45.517685 | controller | /usr/bin/python3.8
2026-01-16 00:12:46.114739 | controller | ok
2026-01-16 00:12:46.120947 | 
2026-01-16 00:12:46.121053 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-01-16 00:12:48.444151 | controller | created virtual environment CPython3.8.18.final.0-64 in 1034ms
2026-01-16 00:12:48.496424 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-01-16 00:12:48.496478 | 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-01-16 00:12:48.496493 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-01-16 00:12:48.496513 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-01-16 00:12:49.011724 | controller | changed
2026-01-16 00:12:49.021469 | 
2026-01-16 00:12:49.021603 | TASK [Set selinux package]
2026-01-16 00:12:49.057715 | controller | ok
2026-01-16 00:12:49.066970 | 
2026-01-16 00:12:49.067187 | TASK [Set selinux package (Fedora)]
2026-01-16 00:12:49.111252 | controller | ok
2026-01-16 00:12:49.116963 | 
2026-01-16 00:12:49.117344 | TASK [Install selinux into virtualenv]
2026-01-16 00:12:54.890112 | controller | Collecting selinux-please-lie-to-me
2026-01-16 00:12:55.025571 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-01-16 00:12:56.133753 | controller | Collecting setuptools<50.0.0
2026-01-16 00:12:56.142335 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-01-16 00:12:56.241877 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.0 MB/s eta 0:00:00
2026-01-16 00:12:56.389141 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-01-16 00:12:56.389498 | controller |   Attempting uninstall: setuptools
2026-01-16 00:12:56.390714 | controller |     Found existing installation: setuptools 62.6.0
2026-01-16 00:12:56.521961 | controller |     Uninstalling setuptools-62.6.0:
2026-01-16 00:12:56.538302 | controller |       Successfully uninstalled setuptools-62.6.0
2026-01-16 00:12:57.319719 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-01-16 00:12:57.538529 | controller |
2026-01-16 00:12:57.808712 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-16 00:12:57.808764 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-16 00:12:58.007395 | controller | ok: Runtime: 0:00:07.539828
2026-01-16 00:12:58.016579 | 
2026-01-16 00:12:58.016746 | TASK [Install pytest-forked into virtualenv]
2026-01-16 00:12:59.859115 | controller | Collecting pytest-forked
2026-01-16 00:12:59.928346 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-01-16 00:13:00.159958 | controller | Collecting pytest>=3.10
2026-01-16 00:13:00.166002 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-01-16 00:13:00.228788 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 5.8 MB/s eta 0:00:00
2026-01-16 00:13:00.292975 | controller | Collecting py
2026-01-16 00:13:00.299086 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-01-16 00:13:00.312565 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 9.2 MB/s eta 0:00:00
2026-01-16 00:13:00.395260 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-01-16 00:13:00.405552 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-01-16 00:13:00.453644 | controller | Collecting iniconfig
2026-01-16 00:13:00.458271 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-01-16 00:13:00.562624 | controller | Collecting tomli>=1
2026-01-16 00:13:00.568706 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-01-16 00:13:00.635385 | controller | Collecting pluggy<2,>=1.5
2026-01-16 00:13:00.640677 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-01-16 00:13:00.725106 | controller | Collecting packaging
2026-01-16 00:13:00.732843 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2026-01-16 00:13:00.750061 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 5.0 MB/s eta 0:00:00
2026-01-16 00:13:00.838575 | controller | Collecting typing-extensions>=4.6.0
2026-01-16 00:13:00.846311 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-01-16 00:13:00.865622 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 2.6 MB/s eta 0:00:00
2026-01-16 00:13:00.971033 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-01-16 00:13:01.912140 | 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.4.0 typing-extensions-4.13.2
2026-01-16 00:13:01.917585 | controller |
2026-01-16 00:13:02.162575 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-01-16 00:13:02.162627 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-01-16 00:13:02.392880 | controller | ok: Runtime: 0:00:03.071502
2026-01-16 00:13:02.403835 | 
2026-01-16 00:13:02.404059 | TASK [Update pip]
2026-01-16 00:13:04.695241 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-01-16 00:13:05.131729 | controller | Collecting pip
2026-01-16 00:13:05.203312 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-01-16 00:13:05.293113 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 23.0 MB/s eta 0:00:00
2026-01-16 00:13:05.524542 | controller | Installing collected packages: pip
2026-01-16 00:13:05.529673 | controller |   Attempting uninstall: pip
2026-01-16 00:13:05.531995 | controller |     Found existing installation: pip 22.2.2
2026-01-16 00:13:06.160851 | controller |     Uninstalling pip-22.2.2:
2026-01-16 00:13:06.241477 | controller |       Successfully uninstalled pip-22.2.2
2026-01-16 00:13:08.802903 | controller | Successfully installed pip-25.0.1
2026-01-16 00:13:09.300738 | controller | ok: Runtime: 0:00:05.644402
2026-01-16 00:13:09.307249 | 
2026-01-16 00:13:09.307345 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-01-16 00:13:10.449926 | controller | changed
2026-01-16 00:13:10.458484 | 
2026-01-16 00:13:10.458830 | TASK [Install ansible into virtualenv]
2026-01-16 00:13:12.481170 | controller | Processing ./src/github.com/ansible/ansible
2026-01-16 00:13:12.482738 | controller |   Preparing metadata (setup.py): started
2026-01-16 00:13:13.265607 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-01-16 00:13:13.327597 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-01-16 00:13:13.340639 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-01-16 00:13:13.429992 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-01-16 00:13:13.436486 | 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-01-16 00:13:13.885224 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-01-16 00:13:13.892655 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-01-16 00:13:13.917312 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2026-01-16 00:13:14.129829 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-01-16 00:13:14.134421 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-01-16 00:13:14.146480 | 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-01-16 00:13:14.253167 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-01-16 00:13:14.258865 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-01-16 00:13:14.290541 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-01-16 00:13:14.295466 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-01-16 00:13:14.313326 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-01-16 00:13:14.377755 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 82.8 MB/s eta 0:00:00
2026-01-16 00:13:14.382753 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-01-16 00:13:14.399815 | 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-01-16 00:13:14.418550 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 42.0 MB/s eta 0:00:00
2026-01-16 00:13:14.423912 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-01-16 00:13:14.439150 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-01-16 00:13:14.454152 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-01-16 00:13:14.478349 | controller | Building wheels for collected packages: ansible-base
2026-01-16 00:13:14.479193 | controller |   Building wheel for ansible-base (setup.py): started
2026-01-16 00:13:16.666669 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-01-16 00:13:16.670020 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=4cdd39826ec40bdaca0ebbaaeb1e849a8f20e0feb5df1b852942ecff5286b610
2026-01-16 00:13:16.671413 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-myhajauf/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-01-16 00:13:16.685878 | controller | Successfully built ansible-base
2026-01-16 00:13:16.748590 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-01-16 00:13:19.960865 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2026-01-16 00:13:20.339675 | controller | ok: Runtime: 0:00:08.884307
2026-01-16 00:13:20.347913 | 
2026-01-16 00:13:20.348079 | TASK [Setup download-artifact-fork role]
2026-01-16 00:13:20.385169 | controller | ok
2026-01-16 00:13:20.411933 | 
2026-01-16 00:13:20.412075 | TASK [download-artifact-fork : Parse inventory response]
2026-01-16 00:13:20.453790 | controller | ok
2026-01-16 00:13:20.461068 | 
2026-01-16 00:13:20.461206 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-01-16 00:13:20.502792 | controller | skipping: Conditional result was False
2026-01-16 00:13:20.510709 | 
2026-01-16 00:13:20.510853 | TASK [download-artifact-fork : Parse build response]
2026-01-16 00:13:20.558709 | controller | skipping: Conditional result was False
2026-01-16 00:13:20.566266 | 
2026-01-16 00:13:20.566398 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-01-16 00:13:21.446513 | controller | changed
2026-01-16 00:13:21.453883 | 
2026-01-16 00:13:21.454034 | LOOP [download-artifact-fork : Download archive by type]
2026-01-16 00:13:21.538902 | controller | skipping: Conditional result was False
2026-01-16 00:13:21.539169 | 
2026-01-16 00:13:23.810001 | controller | changed: OK (20193 bytes)
2026-01-16 00:13:23.832118 | 
2026-01-16 00:13:23.832261 | TASK [deploy-artifacts : define __collections]
2026-01-16 00:13:23.872165 | controller | ok
2026-01-16 00:13:23.878172 | 
2026-01-16 00:13:23.878254 | LOOP [deploy-artifacts : Create list of collections]
2026-01-16 00:13:23.904760 | controller | skipping: Conditional result was False
2026-01-16 00:13:23.915332 | controller | ok:
2026-01-16 00:13:23.915552 | controller | {
2026-01-16 00:13:23.915868 | controller |   "branch": "main",
2026-01-16 00:13:23.915915 | controller |   "job": "build-ansible-collection",
2026-01-16 00:13:23.916076 | controller |   "metadata": {
2026-01-16 00:13:23.916119 | controller |     "type": "ansible_collection",
2026-01-16 00:13:23.916154 | controller |     "version": "5.0.0"
2026-01-16 00:13:23.916174 | controller |   },
2026-01-16 00:13:23.916215 | controller |   "name": "ansible.network",
2026-01-16 00:13:23.916232 | controller |   "project": "ansible-collections/ansible.network",
2026-01-16 00:13:23.916249 | controller |   "url": "https://a40b1ecea3f5110d287f-ec05b7ab81488f2ffa3b27614e4403f2.ssl.cf5.rackcdn.com/ansible/9ad460f43f7241cd9ad71e6af2b1d197/artifacts/ansible-network-5.0.0.tar.gz"
2026-01-16 00:13:23.916272 | controller | }
2026-01-16 00:13:23.930487 | 
2026-01-16 00:13:23.930624 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-01-16 00:13:25.366646 | controller | Starting galaxy collection install process
2026-01-16 00:13:25.372458 | controller | Process install dependency map
2026-01-16 00:13:39.144507 | controller | Starting collection install process
2026-01-16 00:13:39.159972 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-01-16 00:13:39.160041 | controller | ansible.network (5.0.0) was installed successfully
2026-01-16 00:13:39.160143 | controller | Installing 'ansible.netcommon:8.2.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-01-16 00:13:39.160159 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4038z_8ztuxu/tmp4u32jo6e
2026-01-16 00:13:40.754604 | controller | ansible.netcommon (8.2.1) was installed successfully
2026-01-16 00:13:40.754788 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-01-16 00:13:40.754802 | 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-4038z_8ztuxu/tmp4u32jo6e
2026-01-16 00:13:42.593884 | controller | ansible.utils (6.0.1) was installed successfully
2026-01-16 00:13:42.593978 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-01-16 00:13:42.593993 | 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-4038z_8ztuxu/tmp4u32jo6e
2026-01-16 00:13:45.047458 | controller | arista.eos (12.0.0) was installed successfully
2026-01-16 00:13:45.047581 | controller | Installing 'cisco.ios:11.2.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-01-16 00:13:45.047822 | 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-4038z_8ztuxu/tmp4u32jo6e
2026-01-16 00:13:48.227663 | controller | cisco.ios (11.2.0) was installed successfully
2026-01-16 00:13:48.227856 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-01-16 00:13:48.227872 | 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-4038z_8ztuxu/tmp4u32jo6e
2026-01-16 00:13:51.420929 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-01-16 00:13:51.421157 | controller | Installing 'cisco.nxos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-01-16 00:13:51.421171 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4038z_8ztuxu/tmp4u32jo6e
2026-01-16 00:13:55.401000 | controller | cisco.nxos (11.1.1) was installed successfully
2026-01-16 00:13:55.401142 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-01-16 00:13:55.401433 | 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-4038z_8ztuxu/tmp4u32jo6e
2026-01-16 00:13:57.866923 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-01-16 00:13:58.338415 | controller | ok: Runtime: 0:00:33.269854
2026-01-16 00:13:58.354089 | 
2026-01-16 00:13:58.354210 | TASK [podman may need iptables kernel modules]
2026-01-16 00:13:59.728503 | controller | ok: Runtime: 0:00:00.007272
2026-01-16 00:13:59.770205 | 
2026-01-16 00:13:59.770315 | PLAY RECAP
2026-01-16 00:13:59.770362 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-01-16 00:13:59.770389 | 
2026-01-16 00:13:59.912308 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-01-16 00:13:59.913521 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-16 00:14:00.721922 | 
2026-01-16 00:14:00.722139 | PLAY [controller]
2026-01-16 00:14:00.758923 | 
2026-01-16 00:14:00.759111 | TASK [Copy the galaxy.yml on the controller]
2026-01-16 00:14:04.382579 | controller | changed
2026-01-16 00:14:04.389362 | 
2026-01-16 00:14:04.389499 | TASK [Load information from galaxy.yml]
2026-01-16 00:14:04.439237 | controller | ok
2026-01-16 00:14:04.448839 | 
2026-01-16 00:14:04.448978 | LOOP [Enable FIPS mode]
2026-01-16 00:14:04.473962 | 
2026-01-16 00:14:04.474204 | LOOP [ansible-test : Include branch specific variables.]
2026-01-16 00:14:04.507803 | controller | ok: "/var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-01-16 00:14:04.508046 | 
2026-01-16 00:14:04.517364 | 
2026-01-16 00:14:04.517566 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-01-16 00:14:04.571141 | controller | ok
2026-01-16 00:14:04.579794 | 
2026-01-16 00:14:04.579911 | TASK [ansible-test : Enable --inventory for network-integration]
2026-01-16 00:14:04.604883 | controller | skipping: Conditional result was False
2026-01-16 00:14:04.615901 | 
2026-01-16 00:14:04.616081 | TASK [ansible-test : Enable --retry-on-error]
2026-01-16 00:14:04.631911 | controller | skipping: Conditional result was False
2026-01-16 00:14:04.642312 | 
2026-01-16 00:14:04.642440 | TASK [ansible-test : Enable --continue-on-error]
2026-01-16 00:14:04.676903 | controller | ok
2026-01-16 00:14:04.686163 | 
2026-01-16 00:14:04.686264 | TASK [ansible-test : Enable --requirements]
2026-01-16 00:14:04.702119 | controller | skipping: Conditional result was False
2026-01-16 00:14:04.712823 | 
2026-01-16 00:14:04.712926 | TASK [ansible-test : Adjust options for unit tests]
2026-01-16 00:14:04.737931 | controller | skipping: Conditional result was False
2026-01-16 00:14:04.747824 | 
2026-01-16 00:14:04.747929 | TASK [ansible-test : Install requirements with unit tests]
2026-01-16 00:14:04.788988 | controller | ok
2026-01-16 00:14:04.830877 | 
2026-01-16 00:14:04.830998 | TASK [ansible-test : Set --skip-test]
2026-01-16 00:14:04.895851 | controller | skipping: Conditional result was False
2026-01-16 00:14:04.903759 | 
2026-01-16 00:14:04.903874 | TASK [ansible-test : Reduce verbosity]
2026-01-16 00:14:04.944413 | controller | ok
2026-01-16 00:14:04.950317 | 
2026-01-16 00:14:04.950397 | TASK [ansible-test : Setup --python option]
2026-01-16 00:14:04.976446 | controller | skipping: Conditional result was False
2026-01-16 00:14:04.984951 | 
2026-01-16 00:14:04.985113 | TASK [ansible-test : Setup --docker option]
2026-01-16 00:14:05.017665 | controller | ok
2026-01-16 00:14:05.025270 | 
2026-01-16 00:14:05.025482 | TASK [ansible-test : check if MANIFEST.json exists]
2026-01-16 00:14:06.178801 | controller | ok
2026-01-16 00:14:06.188488 | 
2026-01-16 00:14:06.188617 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-01-16 00:14:06.203807 | controller | skipping: Conditional result was False
2026-01-16 00:14:06.213923 | 
2026-01-16 00:14:06.214103 | TASK [ansible-test : Load information from MANIFEST.json]
2026-01-16 00:14:06.239958 | controller | skipping: Conditional result was False
2026-01-16 00:14:06.250598 | 
2026-01-16 00:14:06.250712 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-16 00:14:06.276234 | controller | skipping: Conditional result was False
2026-01-16 00:14:06.283586 | 
2026-01-16 00:14:06.283669 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-01-16 00:14:07.613965 | controller | ok
2026-01-16 00:14:07.619379 | 
2026-01-16 00:14:07.619457 | TASK [ansible-test : Load information from galaxy.yml]
2026-01-16 00:14:07.678359 | controller | ok
2026-01-16 00:14:07.688601 | 
2026-01-16 00:14:07.688727 | TASK [ansible-test : Setup location of project for integration tests]
2026-01-16 00:14:07.719778 | controller | ok
2026-01-16 00:14:07.726478 | 
2026-01-16 00:14:07.726563 | TASK [ansible-test : Setup minimum test requirements]
2026-01-16 00:14:07.757040 | controller | ok
2026-01-16 00:14:07.762752 | 
2026-01-16 00:14:07.762822 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-01-16 00:14:08.741479 | controller | ok
2026-01-16 00:14:08.754664 | 
2026-01-16 00:14:08.754763 | TASK [ansible-test : Install python requirements]
2026-01-16 00:14:10.215126 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-16 00:14:10.232150 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-01-16 00:14:10.275337 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-01-16 00:14:10.280964 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-01-16 00:14:10.292452 | 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-01-16 00:14:10.300033 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-01-16 00:14:10.321495 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-01-16 00:14:10.388627 | controller | Installing collected packages: pathspec, yamllint
2026-01-16 00:14:10.496742 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-01-16 00:14:11.122274 | controller | ok: Runtime: 0:00:01.112580
2026-01-16 00:14:11.128887 | 
2026-01-16 00:14:11.131054 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-01-16 00:14:11.918204 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-01-16 00:14:12.488559 | controller | ERROR
2026-01-16 00:14:12.488917 | controller | {
2026-01-16 00:14:12.489004 | controller |   "delta": "0:00:00.010740",
2026-01-16 00:14:12.489107 | controller |   "end": "2026-01-16 00:14:11.918460",
2026-01-16 00:14:12.489151 | controller |   "msg": "non-zero return code",
2026-01-16 00:14:12.489190 | controller |   "rc": 1,
2026-01-16 00:14:12.489228 | controller |   "start": "2026-01-16 00:14:11.907720"
2026-01-16 00:14:12.489265 | controller | }
2026-01-16 00:14:12.489315 | controller | ERROR: Ignoring Errors
2026-01-16 00:14:12.501760 | 
2026-01-16 00:14:12.501999 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-01-16 00:14:12.859557 | controller | skipping: Conditional result was False
2026-01-16 00:14:12.870754 | 
2026-01-16 00:14:12.870960 | TASK [ansible-test : Enable persistent connection logging]
2026-01-16 00:14:14.318583 | controller | changed: section and option added
2026-01-16 00:14:14.327400 | 
2026-01-16 00:14:14.327534 | TASK [ansible-test : Set persistent ssh_type value]
2026-01-16 00:14:15.818884 | controller | changed: option added
2026-01-16 00:14:15.825123 | 
2026-01-16 00:14:15.825202 | TASK [ansible-test : Set ansible log path]
2026-01-16 00:14:17.185872 | controller | changed: section and option added
2026-01-16 00:14:17.196320 | 
2026-01-16 00:14:17.196498 | TASK [ansible-test : Identify the changed targets]
2026-01-16 00:14:17.567702 | controller | skipping: Conditional result was False
2026-01-16 00:14:17.577032 | 
2026-01-16 00:14:17.577186 | TASK [ansible-test : set_fact]
2026-01-16 00:14:17.633359 | controller | skipping: Conditional result was False
2026-01-16 00:14:17.641774 | 
2026-01-16 00:14:17.641908 | TASK [ansible-test : Set the targets]
2026-01-16 00:14:17.675702 | controller | ok
2026-01-16 00:14:17.719165 | 
2026-01-16 00:14:17.719295 | TASK [ansible-test : set_fact]
2026-01-16 00:14:17.733230 | controller | skipping: Conditional result was False
2026-01-16 00:14:17.740214 | 
2026-01-16 00:14:17.740332 | TASK [ansible-test : List the targets]
2026-01-16 00:14:18.097463 | controller | skipping: Conditional result was False
2026-01-16 00:14:18.114164 | 
2026-01-16 00:14:18.114359 | TASK [ansible-test : set_fact]
2026-01-16 00:14:18.142105 | controller | skipping: Conditional result was False
2026-01-16 00:14:18.158331 | 
2026-01-16 00:14:18.158535 | TASK [ansible-test : set_fact]
2026-01-16 00:14:18.186222 | controller | skipping: Conditional result was False
2026-01-16 00:14:18.201581 | 
2026-01-16 00:14:18.201796 | LOOP [ansible-test : set_fact]
2026-01-16 00:14:18.271147 | 
2026-01-16 00:14:18.271397 | TASK [ansible-test : set_fact]
2026-01-16 00:14:18.287277 | controller | skipping: Conditional result was False
2026-01-16 00:14:18.295878 | 
2026-01-16 00:14:18.295979 | TASK [ansible-test : debug]
2026-01-16 00:14:18.310374 | controller | skipping: Conditional result was False
2026-01-16 00:14:18.317610 | 
2026-01-16 00:14:18.317686 | TASK [ansible-test : debug]
2026-01-16 00:14:18.351645 | About to run: ansible-test sanity --requirements --docker -v
2026-01-16 00:14:18.359607 | 
2026-01-16 00:14:18.359749 | TASK [ansible-test : Run the test suite]
2026-01-16 00:14:20.101873 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-01-16 00:14:20.155515 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-01-16 00:14:21.795174 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-01-16 00:14:21.795220 | controller | 171857c49d0f: Pulling fs layer
2026-01-16 00:14:21.795243 | controller | 419640447d26: Pulling fs layer
2026-01-16 00:14:21.795251 | controller | 61e52f862619: Pulling fs layer
2026-01-16 00:14:21.795258 | controller | 851de0426e26: Pulling fs layer
2026-01-16 00:14:21.795267 | controller | 37425716ea5b: Pulling fs layer
2026-01-16 00:14:21.795276 | controller | cd9b0c439616: Pulling fs layer
2026-01-16 00:14:21.795283 | controller | d4612dbd8855: Pulling fs layer
2026-01-16 00:14:21.795292 | controller | 554699364730: Pulling fs layer
2026-01-16 00:14:21.795298 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-01-16 00:14:21.795304 | controller | 5f2e4724adea: Pulling fs layer
2026-01-16 00:14:21.795310 | controller | a02d9a95ccb8: Pulling fs layer
2026-01-16 00:14:21.795316 | controller | 989f4ce370cb: Pulling fs layer
2026-01-16 00:14:21.795322 | controller | 0d7f52cdf9df: Pulling fs layer
2026-01-16 00:14:21.795328 | controller | c423742351d5: Pulling fs layer
2026-01-16 00:14:21.795334 | controller | 109236341d21: Pulling fs layer
2026-01-16 00:14:21.795340 | controller | 07a2f61c96b8: Pulling fs layer
2026-01-16 00:14:21.795346 | controller | 041f122f27ae: Pulling fs layer
2026-01-16 00:14:21.795352 | controller | 851de0426e26: Waiting
2026-01-16 00:14:21.795358 | controller | 37425716ea5b: Waiting
2026-01-16 00:14:21.795364 | controller | cd9b0c439616: Waiting
2026-01-16 00:14:21.795372 | controller | d4612dbd8855: Waiting
2026-01-16 00:14:21.795380 | controller | 554699364730: Waiting
2026-01-16 00:14:21.795386 | controller | 1ff15d4bdb3e: Waiting
2026-01-16 00:14:21.795392 | controller | 5f2e4724adea: Waiting
2026-01-16 00:14:21.795397 | controller | a02d9a95ccb8: Waiting
2026-01-16 00:14:21.795403 | controller | 989f4ce370cb: Waiting
2026-01-16 00:14:21.795409 | controller | 0d7f52cdf9df: Waiting
2026-01-16 00:14:21.795415 | controller | c423742351d5: Waiting
2026-01-16 00:14:21.795421 | controller | 109236341d21: Waiting
2026-01-16 00:14:21.795432 | controller | 07a2f61c96b8: Waiting
2026-01-16 00:14:21.795439 | controller | ca6f5098b12e: Pulling fs layer
2026-01-16 00:14:21.795445 | controller | 041f122f27ae: Waiting
2026-01-16 00:14:21.795470 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-01-16 00:14:21.795478 | controller | 18a0b750727a: Pulling fs layer
2026-01-16 00:14:21.795484 | controller | ca6f5098b12e: Waiting
2026-01-16 00:14:21.795490 | controller | 1eb3e7b2afd9: Waiting
2026-01-16 00:14:21.795496 | controller | 18a0b750727a: Waiting
2026-01-16 00:14:21.795505 | controller | 17f2f596716c: Pulling fs layer
2026-01-16 00:14:21.795511 | controller | f35a9998a9bd: Pulling fs layer
2026-01-16 00:14:21.795517 | controller | fdfc10566405: Pulling fs layer
2026-01-16 00:14:21.795523 | controller | 1ff1c442c5fe: Pulling fs layer
2026-01-16 00:14:21.795529 | controller | a3443c4a17ed: Pulling fs layer
2026-01-16 00:14:21.795535 | controller | 17f2f596716c: Waiting
2026-01-16 00:14:21.795541 | controller | f35a9998a9bd: Waiting
2026-01-16 00:14:21.795547 | controller | fdfc10566405: Waiting
2026-01-16 00:14:21.795554 | controller | 1ff1c442c5fe: Waiting
2026-01-16 00:14:21.795561 | controller | 0edcf9fb2b15: Pulling fs layer
2026-01-16 00:14:21.795567 | controller | 5af43b69e845: Pulling fs layer
2026-01-16 00:14:21.795573 | controller | 1651773effb2: Pulling fs layer
2026-01-16 00:14:21.795584 | controller | 79e23c5c0fe4: Pulling fs layer
2026-01-16 00:14:22.231825 | controller | a3443c4a17ed: Waiting
2026-01-16 00:14:22.231876 | controller | 0edcf9fb2b15: Waiting
2026-01-16 00:14:22.231884 | controller | 5af43b69e845: Waiting
2026-01-16 00:14:22.231889 | controller | 1651773effb2: Waiting
2026-01-16 00:14:22.231895 | controller | e5058b1faaa6: Pulling fs layer
2026-01-16 00:14:22.231901 | controller | 846775490569: Pulling fs layer
2026-01-16 00:14:22.231907 | controller | 79e23c5c0fe4: Waiting
2026-01-16 00:14:22.231913 | controller | e5058b1faaa6: Waiting
2026-01-16 00:14:22.231920 | controller | 846775490569: Waiting
2026-01-16 00:14:22.231937 | controller | 61e52f862619: Verifying Checksum
2026-01-16 00:14:22.271316 | controller | 61e52f862619: Download complete
2026-01-16 00:14:22.271370 | controller | 419640447d26: Verifying Checksum
2026-01-16 00:14:22.271465 | controller | 419640447d26: Download complete
2026-01-16 00:14:22.517623 | controller | 171857c49d0f: Verifying Checksum
2026-01-16 00:14:22.520435 | controller | 171857c49d0f: Download complete
2026-01-16 00:14:23.063181 | controller | 851de0426e26: Verifying Checksum
2026-01-16 00:14:23.540994 | controller | 851de0426e26: Download complete
2026-01-16 00:14:23.541050 | controller | cd9b0c439616: Verifying Checksum
2026-01-16 00:14:23.541209 | controller | cd9b0c439616: Download complete
2026-01-16 00:14:23.973498 | controller | d4612dbd8855: Verifying Checksum
2026-01-16 00:14:23.973684 | controller | d4612dbd8855: Download complete
2026-01-16 00:14:24.865370 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-01-16 00:14:25.719306 | controller | 1ff15d4bdb3e: Download complete
2026-01-16 00:14:25.719375 | controller | 5f2e4724adea: Verifying Checksum
2026-01-16 00:14:25.925286 | controller | 5f2e4724adea: Download complete
2026-01-16 00:14:25.925354 | controller | 171857c49d0f: Pull complete
2026-01-16 00:14:25.974927 | controller | 419640447d26: Pull complete
2026-01-16 00:14:26.008963 | controller | 61e52f862619: Pull complete
2026-01-16 00:14:26.038666 | controller | 851de0426e26: Pull complete
2026-01-16 00:14:27.104879 | controller | a02d9a95ccb8: Download complete
2026-01-16 00:14:27.845002 | controller | 989f4ce370cb: Verifying Checksum
2026-01-16 00:14:28.744272 | controller | 989f4ce370cb: Download complete
2026-01-16 00:14:28.744342 | controller | 0d7f52cdf9df: Verifying Checksum
2026-01-16 00:14:29.503683 | controller | 0d7f52cdf9df: Download complete
2026-01-16 00:14:29.503759 | controller | c423742351d5: Verifying Checksum
2026-01-16 00:14:37.750581 | controller | c423742351d5: Download complete
2026-01-16 00:14:37.750647 | controller | 554699364730: Verifying Checksum
2026-01-16 00:14:38.601033 | controller | 554699364730: Download complete
2026-01-16 00:14:38.601087 | controller | 07a2f61c96b8: Verifying Checksum
2026-01-16 00:14:39.475643 | controller | 07a2f61c96b8: Download complete
2026-01-16 00:14:39.475728 | controller | 041f122f27ae: Download complete
2026-01-16 00:14:39.693734 | controller | 109236341d21: Verifying Checksum
2026-01-16 00:14:39.693903 | controller | 109236341d21: Download complete
2026-01-16 00:14:40.976090 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-01-16 00:14:40.976178 | controller | 1eb3e7b2afd9: Download complete
2026-01-16 00:14:41.109205 | controller | ca6f5098b12e: Verifying Checksum
2026-01-16 00:14:41.109304 | controller | ca6f5098b12e: Download complete
2026-01-16 00:14:41.846585 | controller | 17f2f596716c: Verifying Checksum
2026-01-16 00:14:41.846666 | controller | 17f2f596716c: Download complete
2026-01-16 00:14:42.664634 | controller | 18a0b750727a: Verifying Checksum
2026-01-16 00:14:42.664820 | controller | 18a0b750727a: Download complete
2026-01-16 00:14:42.676635 | controller | f35a9998a9bd: Verifying Checksum
2026-01-16 00:14:42.676729 | controller | f35a9998a9bd: Download complete
2026-01-16 00:14:43.433273 | controller | 1ff1c442c5fe: Verifying Checksum
2026-01-16 00:14:43.433403 | controller | 1ff1c442c5fe: Download complete
2026-01-16 00:14:43.506293 | controller | fdfc10566405: Verifying Checksum
2026-01-16 00:14:43.506529 | controller | fdfc10566405: Download complete
2026-01-16 00:14:47.263966 | controller | a3443c4a17ed: Verifying Checksum
2026-01-16 00:14:48.516814 | controller | a3443c4a17ed: Download complete
2026-01-16 00:14:48.516880 | controller | 0edcf9fb2b15: Verifying Checksum
2026-01-16 00:14:52.768689 | controller | 0edcf9fb2b15: Download complete
2026-01-16 00:14:52.768804 | controller | 5af43b69e845: Verifying Checksum
2026-01-16 00:14:55.393605 | controller | 5af43b69e845: Download complete
2026-01-16 00:14:55.393666 | controller | 37425716ea5b: Verifying Checksum
2026-01-16 00:14:55.528138 | controller | 37425716ea5b: Download complete
2026-01-16 00:14:55.528210 | controller | 1651773effb2: Verifying Checksum
2026-01-16 00:14:55.528265 | controller | 1651773effb2: Download complete
2026-01-16 00:14:57.754868 | controller | 79e23c5c0fe4: Verifying Checksum
2026-01-16 00:14:57.755254 | controller | 79e23c5c0fe4: Download complete
2026-01-16 00:14:58.752380 | controller | e5058b1faaa6: Verifying Checksum
2026-01-16 00:14:58.752535 | controller | e5058b1faaa6: Download complete
2026-01-16 00:15:02.680119 | controller | 846775490569: Verifying Checksum
2026-01-16 00:15:02.680214 | controller | 846775490569: Download complete
2026-01-16 00:15:14.085194 | controller | 37425716ea5b: Pull complete
2026-01-16 00:15:14.110571 | controller | cd9b0c439616: Pull complete
2026-01-16 00:15:14.130870 | controller | d4612dbd8855: Pull complete
2026-01-16 00:15:18.615685 | controller | 554699364730: Pull complete
2026-01-16 00:15:18.650757 | controller | 1ff15d4bdb3e: Pull complete
2026-01-16 00:15:18.673670 | controller | 5f2e4724adea: Pull complete
2026-01-16 00:15:18.712918 | controller | a02d9a95ccb8: Pull complete
2026-01-16 00:15:18.733968 | controller | 989f4ce370cb: Pull complete
2026-01-16 00:15:18.757265 | controller | 0d7f52cdf9df: Pull complete
2026-01-16 00:15:18.801847 | controller | c423742351d5: Pull complete
2026-01-16 00:15:21.194713 | controller | 109236341d21: Pull complete
2026-01-16 00:15:21.227112 | controller | 07a2f61c96b8: Pull complete
2026-01-16 00:15:21.251272 | controller | 041f122f27ae: Pull complete
2026-01-16 00:15:21.441084 | controller | ca6f5098b12e: Pull complete
2026-01-16 00:15:21.498454 | controller | 1eb3e7b2afd9: Pull complete
2026-01-16 00:15:21.555401 | controller | 18a0b750727a: Pull complete
2026-01-16 00:15:21.587346 | controller | 17f2f596716c: Pull complete
2026-01-16 00:15:21.613567 | controller | f35a9998a9bd: Pull complete
2026-01-16 00:15:21.641061 | controller | fdfc10566405: Pull complete
2026-01-16 00:15:21.665135 | controller | 1ff1c442c5fe: Pull complete
2026-01-16 00:15:23.313748 | controller | a3443c4a17ed: Pull complete
2026-01-16 00:15:25.238066 | controller | 0edcf9fb2b15: Pull complete
2026-01-16 00:15:27.988826 | controller | 5af43b69e845: Pull complete
2026-01-16 00:15:32.078181 | controller | 1651773effb2: Pull complete
2026-01-16 00:15:36.080314 | controller | 79e23c5c0fe4: Pull complete
2026-01-16 00:15:38.004011 | controller | e5058b1faaa6: Pull complete
2026-01-16 00:15:40.789225 | controller | 846775490569: Pull complete
2026-01-16 00:15:40.818442 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-01-16 00:15:40.826806 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-01-16 00:15:40.840541 | controller | quay.io/ansible/default-test-container:2.9.0
2026-01-16 00:15:40.846024 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-01-16 00:15:40.875903 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-01-16 00:15:42.471090 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-01-16 00:15:42.472196 | controller | 04a5f4cda3ee: Pulling fs layer
2026-01-16 00:15:42.472230 | controller | ff496a88c8ed: Pulling fs layer
2026-01-16 00:15:42.472238 | controller | 0ce83f459fe7: Pulling fs layer
2026-01-16 00:15:42.472247 | controller | 2e5170e1f099: Pulling fs layer
2026-01-16 00:15:42.472254 | controller | 7641eb41b08c: Pulling fs layer
2026-01-16 00:15:42.472260 | controller | ad15fa9da398: Pulling fs layer
2026-01-16 00:15:42.472266 | controller | 087d91352424: Pulling fs layer
2026-01-16 00:15:42.472272 | controller | 8b92efd6a100: Pulling fs layer
2026-01-16 00:15:42.472278 | controller | 2e5170e1f099: Waiting
2026-01-16 00:15:42.472283 | controller | 7641eb41b08c: Waiting
2026-01-16 00:15:42.472289 | controller | ad15fa9da398: Waiting
2026-01-16 00:15:42.472295 | controller | 087d91352424: Waiting
2026-01-16 00:15:42.472304 | controller | 8b92efd6a100: Waiting
2026-01-16 00:15:42.813323 | controller | 0ce83f459fe7: Verifying Checksum
2026-01-16 00:15:43.020650 | controller | 0ce83f459fe7: Download complete
2026-01-16 00:15:43.020706 | controller | ff496a88c8ed: Verifying Checksum
2026-01-16 00:15:43.343190 | controller | ff496a88c8ed: Download complete
2026-01-16 00:15:43.343283 | controller | 7641eb41b08c: Verifying Checksum
2026-01-16 00:15:43.832033 | controller | 7641eb41b08c: Download complete
2026-01-16 00:15:43.832096 | controller | ad15fa9da398: Verifying Checksum
2026-01-16 00:15:44.348075 | controller | ad15fa9da398: Download complete
2026-01-16 00:15:44.348130 | controller | 087d91352424: Verifying Checksum
2026-01-16 00:15:44.557608 | controller | 087d91352424: Download complete
2026-01-16 00:15:44.557706 | controller | 2e5170e1f099: Verifying Checksum
2026-01-16 00:15:44.606399 | controller | 2e5170e1f099: Download complete
2026-01-16 00:15:44.606458 | controller | 04a5f4cda3ee: Verifying Checksum
2026-01-16 00:15:44.719382 | controller | 04a5f4cda3ee: Download complete
2026-01-16 00:15:44.719454 | controller | 8b92efd6a100: Verifying Checksum
2026-01-16 00:15:46.929881 | controller | 8b92efd6a100: Download complete
2026-01-16 00:15:46.929944 | controller | 04a5f4cda3ee: Pull complete
2026-01-16 00:15:46.962063 | controller | ff496a88c8ed: Pull complete
2026-01-16 00:15:46.985705 | controller | 0ce83f459fe7: Pull complete
2026-01-16 00:15:48.414160 | controller | 2e5170e1f099: Pull complete
2026-01-16 00:15:48.455024 | controller | 7641eb41b08c: Pull complete
2026-01-16 00:15:48.488151 | controller | ad15fa9da398: Pull complete
2026-01-16 00:15:49.529528 | controller | 087d91352424: Pull complete
2026-01-16 00:15:49.567029 | controller | 8b92efd6a100: Pull complete
2026-01-16 00:15:49.595322 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-01-16 00:15:49.610341 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-01-16 00:15:49.627500 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-01-16 00:15:58.799586 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-01-16 00:15:58.799654 | controller | Run command: docker inspect 48b57af2ee1b834630f3be31a080885b294207da1f2fc9ed2a3ab203b902e99d
2026-01-16 00:15:58.883958 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-01-16 00:15:58.904064 | controller | Including collection: ansible.netcommon (287 files)
2026-01-16 00:15:58.904147 | controller | Including collection: ansible.network (23 files)
2026-01-16 00:15:58.919357 | controller | Including collection: ansible.utils (441 files)
2026-01-16 00:15:58.977123 | controller | Including collection: arista.eos (1022 files)
2026-01-16 00:15:59.041762 | controller | Including collection: cisco.ios (1174 files)
2026-01-16 00:15:59.103979 | controller | Including collection: cisco.iosxr (1044 files)
2026-01-16 00:15:59.198681 | controller | Including collection: cisco.nxos (1682 files)
2026-01-16 00:15:59.259076 | controller | Including collection: junipernetworks.junos (963 files)
2026-01-16 00:15:59.312034 | controller | Creating a payload archive containing 7362 files...
2026-01-16 00:16:01.563998 | controller | Created a 6002414 byte payload archive containing 7362 files in 2 seconds.
2026-01-16 00:16:01.567919 | controller | Assuming Docker is available on localhost.
2026-01-16 00:16:01.568371 | 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:2.9.0
2026-01-16 00:16:02.104306 | controller | Run command: docker exec -i 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 dd of=/root/docker.sh bs=65536
2026-01-16 00:16:02.186797 | controller | Run command: docker exec 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 /bin/bash /root/docker.sh
2026-01-16 00:16:02.266034 | controller | Run command: docker exec -i 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 dd of=/root/test.tgz bs=65536
2026-01-16 00:16:02.374694 | controller | Run command: docker exec 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 tar oxzf /root/test.tgz -C /root
2026-01-16 00:16:03.460412 | controller | Run command: docker exec 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-_mkoaus6.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-01-16 00:16:03.929029 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-01-16 00:16:03.929398 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-01-16 00:16:03.931539 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-01-16 00:16:03.931566 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-01-16 00:16:03.932313 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-01-16 00:16:03.939064 | controller | No tests applicable.
2026-01-16 00:16:03.939346 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-01-16 00:16:03.939364 | controller | No tests applicable.
2026-01-16 00:16:03.939879 | controller | Running sanity test 'changelog' with Python 3.6
2026-01-16 00:16:03.939911 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2026-01-16 00:16:04.925440 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-01-16 00:16:05.562544 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-16 00:16:20.589337 | 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/antsibull-changelog/
2026-01-16 00:16:36.101307 | 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/antsibull-changelog/
2026-01-16 00:16:52.120544 | 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/antsibull-changelog/
2026-01-16 00:17:09.133655 | 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/antsibull-changelog/
2026-01-16 00:17:28.148020 | 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/antsibull-changelog/
2026-01-16 00:17:43.163599 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2026-01-16 00:17:43.167252 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-01-16 00:17:43.296275 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-01-16 00:17:43.297129 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-01-16 00:17:43.297263 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-01-16 00:17:43.297419 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-01-16 00:17:43.382899 | controller | Run command: docker exec 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-01-16 00:17:43.493120 | controller | Run command: docker exec -i 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 dd if=/root/output.tgz bs=65536
2026-01-16 00:17:43.578934 | controller | Run command: tar oxzf /tmp/ansible-result-u8cvoauo.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-01-16 00:17:43.608874 | controller | Run command: docker rm -f 48b57af2ee1b834630f3be31a080885b294207da1f2fc9ed2a3ab203b902e99d
2026-01-16 00:17:43.926880 | controller | Run command: docker rm -f 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416
2026-01-16 00:17:44.627236 | controller | ERROR: Command "docker exec 3c58aea5d71589d4be0a0617f66d9d755f66bc62200682cbfa51394fe2269416 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata tests/output/.tmp/metadata-_mkoaus6.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-01-16 00:17:44.871591 | controller | ERROR
2026-01-16 00:17:44.871816 | controller | {
2026-01-16 00:17:44.871846 | controller |   "delta": "0:03:24.923809",
2026-01-16 00:17:44.871866 | controller |   "end": "2026-01-16 00:17:44.690422",
2026-01-16 00:17:44.871883 | controller |   "msg": "non-zero return code",
2026-01-16 00:17:44.871900 | controller |   "rc": 1,
2026-01-16 00:17:44.871924 | controller |   "start": "2026-01-16 00:14:19.766613"
2026-01-16 00:17:44.871971 | controller | }
failure
2026-01-16 00:17:44.875580 | 
2026-01-16 00:17:44.875630 | PLAY RECAP
2026-01-16 00:17:44.875672 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-01-16 00:17:44.875693 | 
2026-01-16 00:17:45.030347 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-01-16 00:17:45.031368 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-16 00:17:45.819521 | 
2026-01-16 00:17:45.819703 | PLAY [all]
2026-01-16 00:17:45.848728 | 
2026-01-16 00:17:45.848863 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-01-16 00:17:48.874698 | controller | changed: non-zero return code
2026-01-16 00:17:48.897187 | 
2026-01-16 00:17:48.897440 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-01-16 00:17:48.926881 | controller | skipping: Conditional result was False
2026-01-16 00:17:48.936895 | 
2026-01-16 00:17:48.937079 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-01-16 00:17:48.974556 | 
2026-01-16 00:17:48.974993 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-01-16 00:17:49.011720 | 
2026-01-16 00:17:49.011896 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-01-16 00:17:49.029510 | controller | skipping: Conditional result was False
2026-01-16 00:17:49.039840 | 
2026-01-16 00:17:49.039984 | LOOP [fetch-subunit-output : Generate subunit file]
2026-01-16 00:17:49.076553 | 
2026-01-16 00:17:49.076762 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-01-16 00:17:49.103941 | controller | skipping: Conditional result was False
2026-01-16 00:17:49.113685 | 
2026-01-16 00:17:49.113815 | TASK [fetch-subunit-output : Remove the temporary file]
2026-01-16 00:17:49.148688 | controller | skipping: Conditional result was False
2026-01-16 00:17:49.158220 | 
2026-01-16 00:17:49.158334 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-01-16 00:17:49.182926 | controller | skipping: Conditional result was False
2026-01-16 00:17:49.218430 | 
2026-01-16 00:17:49.218593 | PLAY RECAP
2026-01-16 00:17:49.218644 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-01-16 00:17:49.218663 | 
2026-01-16 00:17:49.447590 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-01-16 00:17:49.448700 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-01-16 00:17:50.246494 | 
2026-01-16 00:17:50.246660 | PLAY [all:!appliance*]
2026-01-16 00:17:50.276584 | 
2026-01-16 00:17:50.276778 | TASK [unregister the node]
2026-01-16 00:17:50.639682 | controller | skipping: Conditional result was False
2026-01-16 00:17:50.648160 | 
2026-01-16 00:17:50.648457 | TASK [include_role : fetch-output]
2026-01-16 00:17:50.691260 | controller | ok
2026-01-16 00:17:50.714463 | 
2026-01-16 00:17:50.714593 | TASK [fetch-output : Set log path for multiple nodes]
2026-01-16 00:17:50.769998 | controller | skipping: Conditional result was False
2026-01-16 00:17:50.780757 | 
2026-01-16 00:17:50.780915 | TASK [fetch-output : Set log path for single node]
2026-01-16 00:17:50.816949 | controller | ok
2026-01-16 00:17:50.826257 | 
2026-01-16 00:17:50.826377 | LOOP [fetch-output : Ensure local output dirs]
2026-01-16 00:17:51.398714 | controller -> localhost | ok: "/var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/logs"
2026-01-16 00:17:51.696333 | controller -> localhost | changed: "/var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/artifacts"
2026-01-16 00:17:52.037113 | controller -> localhost | changed: "/var/lib/zuul/builds/cc0422115b2a46fdb750c716ad16d21b/work/docs"
2026-01-16 00:17:52.058872 | 
2026-01-16 00:17:52.059004 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-01-16 00:17:54.369447 | controller | changed:
2026-01-16 00:17:54.369715 | controller | .d..t...... ./
2026-01-16 00:17:54.369763 | controller | cd+++++++++ controller/
2026-01-16 00:17:54.369820 | controller | changed: All items complete
2026-01-16 00:17:54.369858 | 
2026-01-16 00:17:56.409287 | controller | changed: .d..t...... ./
2026-01-16 00:17:58.487794 | controller | changed: .d..t...... ./
2026-01-16 00:17:58.512689 | 
2026-01-16 00:17:58.512812 | TASK [include_role : fetch-output-openshift]
2026-01-16 00:17:58.528751 | controller | skipping: Conditional result was False
2026-01-16 00:17:58.537045 | 
2026-01-16 00:17:58.537141 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-01-16 00:17:58.570649 | controller | skipping: Conditional result was False
2026-01-16 00:17:58.580973 | controller | skipping: Conditional result was False
2026-01-16 00:17:58.625717 | 
2026-01-16 00:17:58.625842 | PLAY [localhost]
2026-01-16 00:17:58.651867 | 
2026-01-16 00:17:58.652035 | TASK [Run Zuul manifest role]
2026-01-16 00:17:58.672594 | localhost | ok
2026-01-16 00:17:58.688596 | 
2026-01-16 00:17:58.688738 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-01-16 00:17:59.121530 | localhost | changed
2026-01-16 00:17:59.131392 | 
2026-01-16 00:17:59.131595 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-01-16 00:17:59.162995 | localhost | ok
2026-01-16 00:17:59.178870 | 
2026-01-16 00:17:59.179009 | TASK [Set zuul-log-path fact]
2026-01-16 00:17:59.247604 | localhost | ok
2026-01-16 00:17:59.268785 | 
2026-01-16 00:17:59.268945 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-01-16 00:17:59.311723 | localhost | ok
2026-01-16 00:17:59.323522 | 
2026-01-16 00:17:59.323653 | LOOP [Run upload-logs-swift role]
2026-01-16 00:17:59.360723 | localhost | Output suppressed because no_log was given
2026-01-16 00:17:59.398459 | 
2026-01-16 00:17:59.398625 | TASK [Set zuul-log-path fact]
2026-01-16 00:17:59.424685 | localhost | skipping: Conditional result was False
2026-01-16 00:17:59.431882 | 
2026-01-16 00:17:59.432006 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-01-16 00:17:59.898320 | localhost -> localhost | ok: Runtime: 0:00:00.006163
2026-01-16 00:17:59.906669 | 
2026-01-16 00:17:59.906801 | TASK [upload-logs-swift : Upload logs to swift]