2026-03-05 00:22:41.528695 | Job console starting...
2026-03-05 00:22:41.544706 | Updating repositories
2026-03-05 00:22:41.671497 | Preparing job workspace
2026-03-05 00:22:45.666645 | Running Ansible setup...
2026-03-05 00:22:53.627485 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-05 00:22:54.241765 | 
2026-03-05 00:22:54.241905 | PLAY [localhost]
2026-03-05 00:22:54.250545 | 
2026-03-05 00:22:54.250618 | TASK [Gathering Facts]
2026-03-05 00:22:55.316940 | localhost | ok
2026-03-05 00:22:55.330405 | 
2026-03-05 00:22:55.330495 | TASK [Setup log path fact]
2026-03-05 00:22:55.358441 | localhost | ok
2026-03-05 00:22:55.386965 | 
2026-03-05 00:22:55.387137 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-05 00:22:55.420277 | localhost | ok
2026-03-05 00:22:55.432691 | 
2026-03-05 00:22:55.432813 | TASK [emit-job-header : Print job information]
2026-03-05 00:22:55.475800 | # Job Information
2026-03-05 00:22:55.476035 | Ansible Version: 2.15.12
2026-03-05 00:22:55.476088 | Job: ansible-test-sanity-docker-stable-2.10
2026-03-05 00:22:55.476128 | Pipeline: periodic
2026-03-05 00:22:55.476161 | Executor: ze03.softwarefactory-project.io
2026-03-05 00:22:55.476194 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-05 00:22:55.476232 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/91c/ansible/91ce42fbfc6e47cba5462cb98d6fa5ce/
2026-03-05 00:22:55.476269 | Event ID: 4fa9857b728648278118233f7d4d0858
2026-03-05 00:22:55.483510 | 
2026-03-05 00:22:55.483620 | LOOP [emit-job-header : Print node information]
2026-03-05 00:22:55.595467 | localhost | ok:
2026-03-05 00:22:55.595708 | localhost | # Node Information
2026-03-05 00:22:55.595778 | localhost | Inventory Hostname: controller
2026-03-05 00:22:55.595810 | localhost | Hostname: np0005638186
2026-03-05 00:22:55.595838 | localhost | Username: zuul
2026-03-05 00:22:55.595870 | localhost | Distro: Fedora 37
2026-03-05 00:22:55.595896 | localhost | Provider: ansible-vexxhost-ams1
2026-03-05 00:22:55.595922 | localhost | Region: ams1
2026-03-05 00:22:55.595947 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-05 00:22:55.595974 | localhost | Product Name: OpenStack Nova
2026-03-05 00:22:55.596007 | localhost | Interface IP: 38.129.16.101
2026-03-05 00:22:55.607940 | 
2026-03-05 00:22:55.608045 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-05 00:22:56.022229 | localhost -> localhost | changed
2026-03-05 00:22:56.028093 | 
2026-03-05 00:22:56.028201 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-05 00:22:56.951128 | localhost -> localhost | changed
2026-03-05 00:22:56.971459 | 
2026-03-05 00:22:56.971522 | PLAY [all:!appliance*]
2026-03-05 00:22:56.987954 | 
2026-03-05 00:22:56.988083 | TASK [include_role : start-zuul-console]
2026-03-05 00:22:57.008088 | controller | ok
2026-03-05 00:22:57.022379 | 
2026-03-05 00:22:57.022459 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-05 00:22:58.178271 | controller | ok
2026-03-05 00:22:58.194703 | 
2026-03-05 00:22:58.194851 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-05 00:23:00.579604 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-05 00:23:00.589920 | 
2026-03-05 00:23:00.590050 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-05 00:23:00.984651 | controller | skipping: Conditional result was False
2026-03-05 00:23:00.991442 | 
2026-03-05 00:23:00.991516 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-05 00:23:01.015674 | controller | skipping: Conditional result was False
2026-03-05 00:23:01.022168 | 
2026-03-05 00:23:01.022248 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-05 00:23:01.056694 | controller | skipping: Conditional result was False
2026-03-05 00:23:01.064539 | 
2026-03-05 00:23:01.064754 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-05 00:23:01.098905 | controller | skipping: Conditional result was False
2026-03-05 00:23:01.108659 | 
2026-03-05 00:23:01.108797 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-05 00:23:01.135103 | controller | skipping: Conditional result was False
2026-03-05 00:23:01.144830 | 
2026-03-05 00:23:01.144938 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-05 00:23:01.170698 | controller | skipping: Conditional result was False
2026-03-05 00:23:01.186197 | 
2026-03-05 00:23:01.186281 | TASK [Disable Fedora Modular]
2026-03-05 00:23:02.490633 | controller | changed
2026-03-05 00:23:02.499264 | 
2026-03-05 00:23:02.499371 | TASK [Enable EPEL]
2026-03-05 00:23:02.524937 | controller | skipping: Conditional result was False
2026-03-05 00:23:02.534717 | 
2026-03-05 00:23:02.534850 | TASK [Register the RHEL node]
2026-03-05 00:23:02.978529 | 
2026-03-05 00:23:02.978928 | TASK [Show the subscription-manager status]
2026-03-05 00:23:03.375363 | controller | skipping: Conditional result was False
2026-03-05 00:23:03.391105 | 
2026-03-05 00:23:03.391259 | TASK [Enable EPEL on RHEL]
2026-03-05 00:23:03.803494 | controller | skipping: Conditional result was False
2026-03-05 00:23:03.824168 | 
2026-03-05 00:23:03.824339 | TASK [Install git and tox]
2026-03-05 00:25:28.236133 | controller | changed
2026-03-05 00:25:28.242715 | 
2026-03-05 00:25:28.242806 | TASK [include_role : prepare-workspace]
2026-03-05 00:25:28.265632 | controller | ok
2026-03-05 00:25:28.288927 | 
2026-03-05 00:25:28.289059 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-05 00:25:29.185010 | controller | ok
2026-03-05 00:25:29.198505 | 
2026-03-05 00:25:29.198643 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-05 00:25:45.967139 | controller | Output suppressed because no_log was given
2026-03-05 00:25:45.993027 | 
2026-03-05 00:25:45.993226 | TASK [include_role : prepare-workspace-openshift]
2026-03-05 00:25:46.019768 | controller | skipping: Conditional result was False
2026-03-05 00:25:46.111914 | 
2026-03-05 00:25:46.112067 | PLAY [all:!appliance]
2026-03-05 00:25:46.130869 | 
2026-03-05 00:25:46.130957 | TASK [Run add-build-sshkey role (RSA)]
2026-03-05 00:25:46.162365 | controller | ok
2026-03-05 00:25:46.179685 | 
2026-03-05 00:25:46.179875 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-05 00:25:46.442525 | controller -> localhost | ok
2026-03-05 00:25:46.458336 | 
2026-03-05 00:25:46.458505 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-05 00:25:46.501919 | controller | ok
2026-03-05 00:25:46.522665 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-05 00:25:46.531190 | 
2026-03-05 00:25:46.531312 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-05 00:25:47.097239 | controller -> localhost | Generating public/private rsa key pair.
2026-03-05 00:25:47.097469 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/91ce42fbfc6e47cba5462cb98d6fa5ce_id_rsa.
2026-03-05 00:25:47.097501 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/91ce42fbfc6e47cba5462cb98d6fa5ce_id_rsa.pub.
2026-03-05 00:25:47.097523 | controller -> localhost | The key fingerprint is:
2026-03-05 00:25:47.097543 | controller -> localhost | SHA256:JmsytsBzYf40bDBy/JCtI9x3QtmV13+xN6Yk/52QXJM zuul-build-sshkey
2026-03-05 00:25:47.097562 | controller -> localhost | The key's randomart image is:
2026-03-05 00:25:47.097581 | controller -> localhost | +---[RSA 2048]----+
2026-03-05 00:25:47.097600 | controller -> localhost | |                 |
2026-03-05 00:25:47.097620 | controller -> localhost | |           . .   |
2026-03-05 00:25:47.097641 | controller -> localhost | |          o . .. |
2026-03-05 00:25:47.097662 | controller -> localhost | |   . o o . .   .+|
2026-03-05 00:25:47.097682 | controller -> localhost | |  . X = S  . . E+|
2026-03-05 00:25:47.097717 | controller -> localhost | | o * X +    = = =|
2026-03-05 00:25:47.097765 | controller -> localhost | |  * X @ .    *   |
2026-03-05 00:25:47.097789 | controller -> localhost | |   * @ +      o o|
2026-03-05 00:25:47.097809 | controller -> localhost | |    . .        o.|
2026-03-05 00:25:47.097832 | controller -> localhost | +----[SHA256]-----+
2026-03-05 00:25:47.097881 | controller -> localhost | ok: Runtime: 0:00:00.126113
2026-03-05 00:25:47.107225 | 
2026-03-05 00:25:47.107345 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-05 00:25:47.139364 | controller | ok
2026-03-05 00:25:47.150714 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-05 00:25:47.160153 | 
2026-03-05 00:25:47.160240 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-05 00:25:47.187103 | controller | skipping: Conditional result was False
2026-03-05 00:25:47.195926 | 
2026-03-05 00:25:47.196058 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-05 00:25:48.457470 | controller | changed
2026-03-05 00:25:48.472600 | 
2026-03-05 00:25:48.472772 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-05 00:25:49.262176 | controller | ok
2026-03-05 00:25:49.284051 | 
2026-03-05 00:25:49.284232 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-05 00:25:52.596352 | controller | changed
2026-03-05 00:25:52.604526 | 
2026-03-05 00:25:52.604638 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-05 00:25:55.989081 | controller | changed
2026-03-05 00:25:55.998072 | 
2026-03-05 00:25:55.998183 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-05 00:25:56.024413 | controller | skipping: Conditional result was False
2026-03-05 00:25:56.036144 | 
2026-03-05 00:25:56.036371 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-05 00:25:56.463939 | controller -> localhost | changed
2026-03-05 00:25:56.494849 | 
2026-03-05 00:25:56.495106 | TASK [add-build-sshkey : Add back temp key]
2026-03-05 00:25:56.892696 | controller -> localhost | Identity added: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/91ce42fbfc6e47cba5462cb98d6fa5ce_id_rsa (zuul-build-sshkey)
2026-03-05 00:25:56.893009 | controller -> localhost | ok: Runtime: 0:00:00.017212
2026-03-05 00:25:56.900994 | 
2026-03-05 00:25:56.901079 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-05 00:25:58.070626 | controller | ok
2026-03-05 00:25:58.080560 | 
2026-03-05 00:25:58.080811 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-05 00:25:58.108678 | controller | skipping: Conditional result was False
2026-03-05 00:25:58.127922 | 
2026-03-05 00:25:58.128102 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-05 00:25:58.162422 | controller | ok
2026-03-05 00:25:58.189411 | 
2026-03-05 00:25:58.189611 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-05 00:25:58.440170 | controller -> localhost | ok
2026-03-05 00:25:58.448108 | 
2026-03-05 00:25:58.448175 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-05 00:25:58.489750 | controller | ok
2026-03-05 00:25:58.501806 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-05 00:25:58.509162 | 
2026-03-05 00:25:58.509240 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-05 00:25:58.842182 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-05 00:25:58.842579 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/91ce42fbfc6e47cba5462cb98d6fa5ce_id_ecdsa.
2026-03-05 00:25:58.842645 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/91ce42fbfc6e47cba5462cb98d6fa5ce_id_ecdsa.pub.
2026-03-05 00:25:58.842710 | controller -> localhost | The key fingerprint is:
2026-03-05 00:25:58.842801 | controller -> localhost | SHA256:4kJflk8JjQKSNbgMWji++mUWYdNrPoZduVb7WIa7Jnk zuul-build-sshkey
2026-03-05 00:25:58.842848 | controller -> localhost | The key's randomart image is:
2026-03-05 00:25:58.842890 | controller -> localhost | +---[ECDSA 521]---+
2026-03-05 00:25:58.842931 | controller -> localhost | | ..++            |
2026-03-05 00:25:58.842970 | controller -> localhost | |+ +. +   o       |
2026-03-05 00:25:58.843009 | controller -> localhost | |o= .+ o o .      |
2026-03-05 00:25:58.843051 | controller -> localhost | |..o. o o + .     |
2026-03-05 00:25:58.843091 | controller -> localhost | |  . o + S +      |
2026-03-05 00:25:58.843129 | controller -> localhost | | . . O = = o     |
2026-03-05 00:25:58.843169 | controller -> localhost | |.   * B o.+ o    |
2026-03-05 00:25:58.843216 | controller -> localhost | |.  + o oo E*     |
2026-03-05 00:25:58.843274 | controller -> localhost | | ..      ++..    |
2026-03-05 00:25:58.843332 | controller -> localhost | +----[SHA256]-----+
2026-03-05 00:25:58.843424 | controller -> localhost | ok: Runtime: 0:00:00.018607
2026-03-05 00:25:58.859241 | 
2026-03-05 00:25:58.859475 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-05 00:25:58.921560 | controller | ok
2026-03-05 00:25:58.939434 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-05 00:25:58.956469 | 
2026-03-05 00:25:58.956627 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-05 00:25:58.983855 | controller | skipping: Conditional result was False
2026-03-05 00:25:58.999116 | 
2026-03-05 00:25:58.999493 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-05 00:26:00.009285 | controller | changed
2026-03-05 00:26:00.022206 | 
2026-03-05 00:26:00.022410 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-05 00:26:00.773337 | controller | ok
2026-03-05 00:26:00.804082 | 
2026-03-05 00:26:00.804281 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-05 00:26:04.004159 | controller | changed
2026-03-05 00:26:04.018063 | 
2026-03-05 00:26:04.018212 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-05 00:26:07.224100 | controller | changed
2026-03-05 00:26:07.231128 | 
2026-03-05 00:26:07.231215 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-05 00:26:07.256650 | controller | skipping: Conditional result was False
2026-03-05 00:26:07.264204 | 
2026-03-05 00:26:07.264297 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-05 00:26:07.543647 | controller -> localhost | changed
2026-03-05 00:26:07.555417 | 
2026-03-05 00:26:07.555522 | TASK [add-build-sshkey : Add back temp key]
2026-03-05 00:26:07.918435 | controller -> localhost | Identity added: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/91ce42fbfc6e47cba5462cb98d6fa5ce_id_ecdsa (zuul-build-sshkey)
2026-03-05 00:26:07.918764 | controller -> localhost | ok: Runtime: 0:00:00.009675
2026-03-05 00:26:07.928583 | 
2026-03-05 00:26:07.928686 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-05 00:26:08.717900 | controller | ok
2026-03-05 00:26:08.730711 | 
2026-03-05 00:26:08.730905 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-05 00:26:08.778750 | controller | skipping: Conditional result was False
2026-03-05 00:26:08.805686 | 
2026-03-05 00:26:08.805930 | TASK [include_role : remove-zuul-sshkey]
2026-03-05 00:26:08.833850 | controller | skipping: Conditional result was False
2026-03-05 00:26:08.844752 | 
2026-03-05 00:26:08.844874 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-05 00:26:09.583360 | controller | ok: "logs"
2026-03-05 00:26:09.588819 | controller | ok: All items complete
2026-03-05 00:26:09.588903 | 
2026-03-05 00:26:10.302424 | controller | ok: "artifacts"
2026-03-05 00:26:10.979353 | controller | ok: "docs"
2026-03-05 00:26:11.001525 | 
2026-03-05 00:26:11.001718 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-05 00:26:11.731399 | controller | changed: "logs"
2026-03-05 00:26:12.424974 | controller | changed: "artifacts"
2026-03-05 00:26:13.185764 | controller | changed: "docs"
2026-03-05 00:26:13.224791 | 
2026-03-05 00:26:13.224919 | PLAY RECAP
2026-03-05 00:26:13.224967 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-05 00:26:13.224993 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-05 00:26:13.225011 | 
2026-03-05 00:26:13.377060 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-05 00:26:13.378045 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-05 00:26:14.050787 | 
2026-03-05 00:26:14.050932 | PLAY [all]
2026-03-05 00:26:14.073635 | 
2026-03-05 00:26:14.073776 | TASK [Install binary dependencies]
2026-03-05 00:26:14.124443 | controller | ok
2026-03-05 00:26:14.144060 | 
2026-03-05 00:26:14.144173 | TASK [bindep : Include find tasks]
2026-03-05 00:26:14.174013 | controller | ok
2026-03-05 00:26:14.182200 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-05 00:26:14.188552 | 
2026-03-05 00:26:14.188622 | TASK [bindep : Look for bindep.txt]
2026-03-05 00:26:15.276491 | controller | ok
2026-03-05 00:26:15.285837 | 
2026-03-05 00:26:15.286011 | TASK [bindep : Define bindep_file fact]
2026-03-05 00:26:15.311782 | controller | skipping: Conditional result was False
2026-03-05 00:26:15.320554 | 
2026-03-05 00:26:15.320657 | TASK [bindep : Look for other-requirements.txt]
2026-03-05 00:26:16.027147 | controller | ok
2026-03-05 00:26:16.040640 | 
2026-03-05 00:26:16.040900 | TASK [bindep : Define bindep_file fact]
2026-03-05 00:26:16.078760 | controller | skipping: Conditional result was False
2026-03-05 00:26:16.092810 | 
2026-03-05 00:26:16.093084 | TASK [bindep : Look for bindep fallback file]
2026-03-05 00:26:16.131276 | controller | skipping: Conditional result was False
2026-03-05 00:26:16.148127 | 
2026-03-05 00:26:16.148348 | TASK [bindep : Define bindep_file fact]
2026-03-05 00:26:16.175568 | controller | skipping: Conditional result was False
2026-03-05 00:26:16.190430 | 
2026-03-05 00:26:16.190597 | TASK [bindep : Include bindep tasks]
2026-03-05 00:26:16.227593 | controller | skipping: Conditional result was False
2026-03-05 00:26:16.243909 | 
2026-03-05 00:26:16.244085 | TASK [bindep : Include install tasks]
2026-03-05 00:26:16.281864 | controller | skipping: Conditional result was False
2026-03-05 00:26:16.299071 | 
2026-03-05 00:26:16.299299 | LOOP [bindep : Include package tasks]
2026-03-05 00:26:16.395860 | 
2026-03-05 00:26:16.396159 | TASK [Run test-setup role]
2026-03-05 00:26:16.424839 | controller | ok
2026-03-05 00:26:16.455558 | 
2026-03-05 00:26:16.455677 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-05 00:26:17.180496 | controller | ok
2026-03-05 00:26:17.194568 | 
2026-03-05 00:26:17.194796 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-05 00:26:17.586650 | controller | skipping: Conditional result was False
2026-03-05 00:26:17.641383 | 
2026-03-05 00:26:17.641525 | PLAY RECAP
2026-03-05 00:26:17.641589 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-05 00:26:17.641622 | 
2026-03-05 00:26:17.774363 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-05 00:26:17.775286 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-05 00:26:18.382380 | 
2026-03-05 00:26:18.382527 | PLAY [controller]
2026-03-05 00:26:18.403120 | 
2026-03-05 00:26:18.403252 | TASK [Create the /root directory]
2026-03-05 00:26:19.554908 | controller | ok
2026-03-05 00:26:19.568346 | 
2026-03-05 00:26:19.568500 | TASK [Install glibc-langpack-en]
2026-03-05 00:26:28.425474 | controller | ok: Nothing to do
2026-03-05 00:26:28.434254 | 
2026-03-05 00:26:28.434335 | TASK [Ensure controller directory exists]
2026-03-05 00:26:29.310536 | controller | changed
2026-03-05 00:26:29.319469 | 
2026-03-05 00:26:29.319581 | TASK [Install container runtime]
2026-03-05 00:26:29.394468 | controller | ok
2026-03-05 00:26:29.450999 | 
2026-03-05 00:26:29.451144 | LOOP [ensure-docker : Gather variables for each operating system]
2026-03-05 00:26:29.497277 | controller | ok: "/var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-03-05 00:26:29.506551 | 
2026-03-05 00:26:29.506621 | TASK [ensure-docker : Sanity check for distro selection]
2026-03-05 00:26:29.532148 | controller | skipping: Conditional result was False
2026-03-05 00:26:29.542687 | 
2026-03-05 00:26:29.542841 | TASK [ensure-docker : Sanity check for distro install]
2026-03-05 00:26:29.579678 | controller | skipping: Conditional result was False
2026-03-05 00:26:29.590147 | 
2026-03-05 00:26:29.590264 | TASK [ensure-docker : Sanity check for upstream install]
2026-03-05 00:26:29.616704 | controller | skipping: Conditional result was False
2026-03-05 00:26:29.626257 | 
2026-03-05 00:26:29.626362 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-03-05 00:26:29.652619 | controller | skipping: Conditional result was False
2026-03-05 00:26:29.661665 | 
2026-03-05 00:26:29.661779 | TASK [ensure-docker : Include tasks for docker install]
2026-03-05 00:26:29.686061 | controller | ok
2026-03-05 00:26:29.700892 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-03-05 00:26:29.714282 | 
2026-03-05 00:26:29.714382 | TASK [ensure-docker : Not Implemented]
2026-03-05 00:26:29.743937 | controller | skipping: Conditional result was False
2026-03-05 00:26:29.751365 | 
2026-03-05 00:26:29.751474 | TASK [Set up docker mirrors]
2026-03-05 00:26:29.781832 | controller | ok
2026-03-05 00:26:29.802233 | 
2026-03-05 00:26:29.802304 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-03-05 00:26:29.897792 | controller | skipping: Conditional result was False
2026-03-05 00:26:29.942598 | 
2026-03-05 00:26:29.942786 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-03-05 00:26:29.994648 | controller | skipping: Conditional result was False
2026-03-05 00:26:30.002228 | 
2026-03-05 00:26:30.002311 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-03-05 00:26:30.048837 | controller | skipping: Conditional result was False
2026-03-05 00:26:30.056246 | 
2026-03-05 00:26:30.056319 | TASK [use-docker-mirror : Set up docker mirrors]
2026-03-05 00:26:30.092299 | controller | skipping: Conditional result was False
2026-03-05 00:26:30.118847 | 
2026-03-05 00:26:30.119043 | TASK [Add all repositories]
2026-03-05 00:26:30.163090 | controller | ok
2026-03-05 00:26:30.203850 | 
2026-03-05 00:26:30.203994 | LOOP [ensure-package-repositories : Install repositories]
2026-03-05 00:26:30.236883 | controller | ok: "/var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-03-05 00:26:30.237147 | controller | ok: All items complete
2026-03-05 00:26:30.237175 | 
2026-03-05 00:26:30.249353 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-03-05 00:26:30.256276 | 
2026-03-05 00:26:30.256343 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-03-05 00:26:31.258345 | controller | changed
2026-03-05 00:26:31.273082 | 
2026-03-05 00:26:31.273256 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-03-05 00:26:34.760390 | controller | changed:
2026-03-05 00:26:34.760752 | controller | {
2026-03-05 00:26:34.760798 | 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-03-05 00:26:34.760831 | controller | }
2026-03-05 00:26:34.773352 | 
2026-03-05 00:26:34.773541 | LOOP [ensure-package-repositories : Import GPG keys]
2026-03-05 00:26:36.645282 | controller | changed:
2026-03-05 00:26:36.645569 | controller | {
2026-03-05 00:26:36.645608 | 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-03-05 00:26:36.645641 | controller | }
2026-03-05 00:26:36.645685 | controller | changed: All items complete
2026-03-05 00:26:36.645714 | 
2026-03-05 00:26:36.673626 | 
2026-03-05 00:26:36.673874 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-03-05 00:26:37.455962 | controller | changed
2026-03-05 00:26:37.477583 | 
2026-03-05 00:26:37.477863 | LOOP [ensure-package-repositories : Add repositories]
2026-03-05 00:26:38.440510 | controller | changed:
2026-03-05 00:26:38.441438 | controller | {
2026-03-05 00:26:38.441487 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-03-05 00:26:38.441515 | controller |   "description": "Docker CE Stable - $basearch",
2026-03-05 00:26:38.441538 | controller |   "gpgcheck": true,
2026-03-05 00:26:38.441559 | controller |   "name": "docker-ce-stable"
2026-03-05 00:26:38.441580 | controller | }
2026-03-05 00:26:38.463149 | 
2026-03-05 00:26:38.463333 | TASK [ensure-docker : Install pre-reqs]
2026-03-05 00:26:44.225251 | controller | ok: Nothing to do
2026-03-05 00:26:44.231559 | 
2026-03-05 00:26:44.231642 | TASK [ensure-docker : Remove packages]
2026-03-05 00:26:47.718202 | controller | ok: Nothing to do
2026-03-05 00:26:47.724140 | 
2026-03-05 00:26:47.724210 | TASK [ensure-docker : Install upstream docker using package]
2026-03-05 00:28:16.538537 | controller | changed
2026-03-05 00:28:16.555037 | 
2026-03-05 00:28:16.555245 | TASK [ensure-docker : Setup docker]
2026-03-05 00:28:16.588941 | controller | ok
2026-03-05 00:28:16.611597 | controller | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-03-05 00:28:16.629327 | 
2026-03-05 00:28:16.629458 | TASK [ensure-docker : Ensure "docker" group exists]
2026-03-05 00:28:17.687081 | controller | ok
2026-03-05 00:28:17.700122 | 
2026-03-05 00:28:17.700275 | TASK [ensure-docker : Add user to docker group]
2026-03-05 00:28:18.797859 | controller | changed
2026-03-05 00:28:18.813795 | 
2026-03-05 00:28:18.813976 | TASK [Update docker daemon proxy configuration]
2026-03-05 00:28:18.862956 | controller | skipping: Conditional result was False
2026-03-05 00:28:18.879992 | 
2026-03-05 00:28:18.880186 | TASK [Lower default MTU]
2026-03-05 00:28:18.908084 | controller | skipping: Conditional result was False
2026-03-05 00:28:18.923447 | 
2026-03-05 00:28:18.923650 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-03-05 00:28:19.809604 | controller | inactive
2026-03-05 00:28:20.313433 | controller | ok: Runtime: 0:00:00.019238
2026-03-05 00:28:20.326800 | 
2026-03-05 00:28:20.326941 | TASK [ensure-docker : Get the status of the docket unit]
2026-03-05 00:28:21.141527 | controller | inactive
2026-03-05 00:28:21.721294 | controller | ok: Runtime: 0:00:00.033251
2026-03-05 00:28:21.736091 | 
2026-03-05 00:28:21.736251 | TASK [ensure-docker : Restart docker]
2026-03-05 00:28:21.809277 | Trigger a Docker restart
2026-03-05 00:28:21.822611 | 
2026-03-05 00:28:21.822951 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-03-05 00:28:21.870901 | 
2026-03-05 00:28:21.871092 | TASK [ensure-docker : Install docker-compose]
2026-03-05 00:28:21.899377 | controller | skipping: Conditional result was False
2026-03-05 00:28:21.914302 | 
2026-03-05 00:28:21.914461 | TASK [ensure-docker : Flush handlers before role exit]
2026-03-05 00:28:21.934770 | 
2026-03-05 00:28:21.934866 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-03-05 00:28:25.420612 | controller | changed
2026-03-05 00:28:25.437107 | 
2026-03-05 00:28:25.437221 | TASK [ensure-docker : Assure docker service is running]
2026-03-05 00:28:29.396172 | controller | changed
2026-03-05 00:28:29.415791 | 
2026-03-05 00:28:29.416015 | TASK [ensure-docker : Assure docker.socket service is running]
2026-03-05 00:28:30.901178 | controller | ok
2026-03-05 00:28:30.920079 | 
2026-03-05 00:28:30.920364 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-03-05 00:28:31.691420 | controller | ok
2026-03-05 00:28:31.704927 | 
2026-03-05 00:28:31.705072 | TASK [ensure-docker : Validate ability to talk with docker]
2026-03-05 00:28:32.460249 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-03-05 00:28:33.091088 | controller | ok: Runtime: 0:00:00.049013
2026-03-05 00:28:33.103450 | 
2026-03-05 00:28:33.103626 | TASK [ensure-docker : Get version details]
2026-03-05 00:28:33.998061 | controller | Client: Docker Engine - Community
2026-03-05 00:28:33.998295 | controller |  Version:           24.0.7
2026-03-05 00:28:33.998360 | controller |  API version:       1.43
2026-03-05 00:28:33.998415 | controller |  Go version:        go1.20.10
2026-03-05 00:28:33.998508 | controller |  Git commit:        afdd53b
2026-03-05 00:28:33.998574 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-03-05 00:28:33.998622 | controller |  OS/Arch:           linux/amd64
2026-03-05 00:28:33.998691 | controller |  Context:           default
2026-03-05 00:28:33.998715 | controller |
2026-03-05 00:28:33.998781 | controller | Server: Docker Engine - Community
2026-03-05 00:28:33.998846 | controller |  Engine:
2026-03-05 00:28:33.999017 | controller |   Version:          24.0.7
2026-03-05 00:28:33.999075 | controller |   API version:      1.43 (minimum version 1.12)
2026-03-05 00:28:33.999114 | controller |   Go version:       go1.20.10
2026-03-05 00:28:33.999168 | controller |   Git commit:       311b9ff
2026-03-05 00:28:33.999248 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-03-05 00:28:33.999296 | controller |   OS/Arch:          linux/amd64
2026-03-05 00:28:33.999352 | controller |   Experimental:     false
2026-03-05 00:28:33.999377 | controller |  containerd:
2026-03-05 00:28:33.999564 | controller |   Version:          1.6.25
2026-03-05 00:28:33.999622 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-03-05 00:28:33.999648 | controller |  runc:
2026-03-05 00:28:33.999742 | controller |   Version:          1.1.10
2026-03-05 00:28:33.999800 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-03-05 00:28:33.999828 | controller |  docker-init:
2026-03-05 00:28:33.999914 | controller |   Version:          0.19.0
2026-03-05 00:28:33.999974 | controller |   GitCommit:        de40ad0
2026-03-05 00:28:34.548968 | controller | ok: Runtime: 0:00:00.050118
2026-03-05 00:28:34.561679 | 
2026-03-05 00:28:34.561910 | TASK [ensure-docker : Dump installed docker details]
2026-03-05 00:28:34.612360 | {'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-03-05 00:28:33.953740', 'end': '2026-03-05 00:28:34.003858', 'delta': '0:00:00.050118', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-c86f-dcb9-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-03-05 00:28:34.619506 | 
2026-03-05 00:28:34.619621 | TASK [Use buildset registry]
2026-03-05 00:28:34.646340 | controller | skipping: Conditional result was False
2026-03-05 00:28:34.659821 | 
2026-03-05 00:28:34.660172 | TASK [Ensure python3.8 is present]
2026-03-05 00:28:34.686598 | controller | skipping: Conditional result was False
2026-03-05 00:28:34.693463 | 
2026-03-05 00:28:34.693540 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-05 00:28:34.715150 | controller | ok
2026-03-05 00:28:34.739033 | 
2026-03-05 00:28:34.739228 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-05 00:28:41.464110 | controller | ok: Nothing to do
2026-03-05 00:28:41.477857 | 
2026-03-05 00:28:41.478019 | TASK [our-ensure-python : Also install python3-devel]
2026-03-05 00:28:57.629640 | controller | changed
2026-03-05 00:28:57.655980 | 
2026-03-05 00:28:57.656244 | TASK [Run ensure-virtualenv role]
2026-03-05 00:28:57.691277 | controller | ok
2026-03-05 00:28:57.730137 | 
2026-03-05 00:28:57.730354 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-05 00:28:58.536096 | controller | /usr/bin/virtualenv
2026-03-05 00:28:59.103909 | controller | ok: Runtime: 0:00:00.004566
2026-03-05 00:28:59.118253 | 
2026-03-05 00:28:59.118471 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-05 00:28:59.150011 | controller | skipping: Conditional result was False
2026-03-05 00:28:59.150395 | controller | changed: All items complete
2026-03-05 00:28:59.150458 | 
2026-03-05 00:28:59.187181 | 
2026-03-05 00:28:59.187416 | TASK [Find the full path of the Python interpreter]
2026-03-05 00:29:00.032889 | controller | /usr/bin/python3.8
2026-03-05 00:29:00.571627 | controller | ok
2026-03-05 00:29:00.641591 | 
2026-03-05 00:29:00.641866 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-05 00:29:03.231083 | controller | created virtual environment CPython3.8.18.final.0-64 in 1331ms
2026-03-05 00:29:03.350687 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-05 00:29:03.350742 | 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-03-05 00:29:03.350755 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-05 00:29:03.350775 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-05 00:29:03.525836 | controller | changed
2026-03-05 00:29:03.540187 | 
2026-03-05 00:29:03.540385 | TASK [Set selinux package]
2026-03-05 00:29:03.584642 | controller | ok
2026-03-05 00:29:03.597518 | 
2026-03-05 00:29:03.597751 | TASK [Set selinux package (Fedora)]
2026-03-05 00:29:03.653042 | controller | ok
2026-03-05 00:29:03.666003 | 
2026-03-05 00:29:03.666211 | TASK [Install selinux into virtualenv]
2026-03-05 00:29:09.407896 | controller | Collecting selinux-please-lie-to-me
2026-03-05 00:29:09.516744 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-05 00:29:10.728659 | controller | Collecting setuptools<50.0.0
2026-03-05 00:29:10.737918 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-05 00:29:10.852719 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 7.3 MB/s eta 0:00:00
2026-03-05 00:29:11.037752 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-05 00:29:11.038150 | controller |   Attempting uninstall: setuptools
2026-03-05 00:29:11.039701 | controller |     Found existing installation: setuptools 62.6.0
2026-03-05 00:29:11.195969 | controller |     Uninstalling setuptools-62.6.0:
2026-03-05 00:29:11.214361 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-05 00:29:12.103880 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-05 00:29:12.328969 | controller |
2026-03-05 00:29:12.808015 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-05 00:29:12.808052 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-05 00:29:13.070431 | controller | ok: Runtime: 0:00:07.901418
2026-03-05 00:29:13.081367 | 
2026-03-05 00:29:13.081457 | TASK [Install pytest-forked into virtualenv]
2026-03-05 00:29:14.926819 | controller | Collecting pytest-forked
2026-03-05 00:29:14.991557 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-05 00:29:15.072544 | controller | Collecting py
2026-03-05 00:29:15.078570 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-05 00:29:15.114143 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.1 MB/s eta 0:00:00
2026-03-05 00:29:15.331719 | controller | Collecting pytest>=3.10
2026-03-05 00:29:15.339313 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-03-05 00:29:15.365816 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 17.6 MB/s eta 0:00:00
2026-03-05 00:29:15.441763 | controller | Collecting iniconfig
2026-03-05 00:29:15.447153 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-03-05 00:29:15.543283 | controller | Collecting tomli>=1
2026-03-05 00:29:15.551396 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-03-05 00:29:15.624978 | controller | Collecting pluggy<2,>=1.5
2026-03-05 00:29:15.632994 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-03-05 00:29:15.690099 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-03-05 00:29:15.696299 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-03-05 00:29:15.788565 | controller | Collecting packaging
2026-03-05 00:29:15.793268 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-05 00:29:15.804591 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 8.1 MB/s eta 0:00:00
2026-03-05 00:29:15.899066 | controller | Collecting typing-extensions>=4.6.0
2026-03-05 00:29:15.903476 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-03-05 00:29:15.915581 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.1 MB/s eta 0:00:00
2026-03-05 00:29:16.024644 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-03-05 00:29:17.008228 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-03-05 00:29:17.013908 | controller |
2026-03-05 00:29:17.260851 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-05 00:29:17.260900 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-05 00:29:17.487977 | controller | ok: Runtime: 0:00:03.286892
2026-03-05 00:29:17.503050 | 
2026-03-05 00:29:17.503232 | TASK [Update pip]
2026-03-05 00:29:19.458901 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-03-05 00:29:19.873119 | controller | Collecting pip
2026-03-05 00:29:20.003969 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-03-05 00:29:20.154664 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 13.3 MB/s eta 0:00:00
2026-03-05 00:29:20.367473 | controller | Installing collected packages: pip
2026-03-05 00:29:20.368585 | controller |   Attempting uninstall: pip
2026-03-05 00:29:20.368761 | controller |     Found existing installation: pip 22.2.2
2026-03-05 00:29:21.027884 | controller |     Uninstalling pip-22.2.2:
2026-03-05 00:29:21.104284 | controller |       Successfully uninstalled pip-22.2.2
2026-03-05 00:29:23.859432 | controller | Successfully installed pip-25.0.1
2026-03-05 00:29:24.402261 | controller | ok: Runtime: 0:00:05.613881
2026-03-05 00:29:24.415938 | 
2026-03-05 00:29:24.416091 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-05 00:29:25.540955 | controller | changed
2026-03-05 00:29:25.547614 | 
2026-03-05 00:29:25.547686 | TASK [Install ansible into virtualenv]
2026-03-05 00:29:27.491268 | controller | Processing ./src/github.com/ansible/ansible
2026-03-05 00:29:27.495363 | controller |   Preparing metadata (setup.py): started
2026-03-05 00:29:28.404421 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-03-05 00:29:28.480888 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-03-05 00:29:28.498275 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-03-05 00:29:28.605566 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-03-05 00:29:28.611339 | 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-03-05 00:29:29.127630 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-03-05 00:29:29.133686 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-03-05 00:29:29.185630 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-03-05 00:29:29.438048 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-03-05 00:29:29.442972 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-03-05 00:29:29.465952 | 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-03-05 00:29:29.599388 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-03-05 00:29:29.603897 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-03-05 00:29:29.657908 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-03-05 00:29:29.672146 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-03-05 00:29:29.693761 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-03-05 00:29:29.761208 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 83.2 MB/s eta 0:00:00
2026-03-05 00:29:29.769581 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-03-05 00:29:29.782862 | 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-03-05 00:29:29.807478 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 32.1 MB/s eta 0:00:00
2026-03-05 00:29:29.815712 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-03-05 00:29:29.835174 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-03-05 00:29:29.850606 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-03-05 00:29:29.877776 | controller | Building wheels for collected packages: ansible-base
2026-03-05 00:29:29.878601 | controller |   Building wheel for ansible-base (setup.py): started
2026-03-05 00:29:32.370923 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-03-05 00:29:32.375777 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=1484e07209e3911f66742538e780852bc106c41ca2a1feef69d9cc240abd82e6
2026-03-05 00:29:32.376123 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-b76xxrkd/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-03-05 00:29:32.393284 | controller | Successfully built ansible-base
2026-03-05 00:29:32.459916 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-03-05 00:29:36.065598 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-03-05 00:29:36.455672 | controller | ok: Runtime: 0:00:09.943475
2026-03-05 00:29:36.479370 | 
2026-03-05 00:29:36.479585 | TASK [Setup download-artifact-fork role]
2026-03-05 00:29:36.520640 | controller | ok
2026-03-05 00:29:36.555390 | 
2026-03-05 00:29:36.555565 | TASK [download-artifact-fork : Parse inventory response]
2026-03-05 00:29:36.602469 | controller | ok
2026-03-05 00:29:36.653811 | 
2026-03-05 00:29:36.654153 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-03-05 00:29:36.679149 | controller | skipping: Conditional result was False
2026-03-05 00:29:36.686078 | 
2026-03-05 00:29:36.686161 | TASK [download-artifact-fork : Parse build response]
2026-03-05 00:29:36.710548 | controller | skipping: Conditional result was False
2026-03-05 00:29:36.719283 | 
2026-03-05 00:29:36.719370 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-03-05 00:29:37.678237 | controller | changed
2026-03-05 00:29:37.693089 | 
2026-03-05 00:29:37.693289 | LOOP [download-artifact-fork : Download archive by type]
2026-03-05 00:29:37.721859 | controller | skipping: Conditional result was False
2026-03-05 00:29:37.725444 | 
2026-03-05 00:29:40.374097 | controller | changed: OK (20212 bytes)
2026-03-05 00:29:40.399162 | 
2026-03-05 00:29:40.399325 | TASK [deploy-artifacts : define __collections]
2026-03-05 00:29:40.431067 | controller | ok
2026-03-05 00:29:40.436646 | 
2026-03-05 00:29:40.436710 | LOOP [deploy-artifacts : Create list of collections]
2026-03-05 00:29:40.467490 | controller | skipping: Conditional result was False
2026-03-05 00:29:40.487010 | controller | ok:
2026-03-05 00:29:40.487168 | controller | {
2026-03-05 00:29:40.487222 | controller |   "branch": "main",
2026-03-05 00:29:40.487263 | controller |   "job": "build-ansible-collection",
2026-03-05 00:29:40.487303 | controller |   "metadata": {
2026-03-05 00:29:40.487339 | controller |     "type": "ansible_collection",
2026-03-05 00:29:40.487374 | controller |     "version": "5.0.0"
2026-03-05 00:29:40.487408 | controller |   },
2026-03-05 00:29:40.487442 | controller |   "name": "ansible.network",
2026-03-05 00:29:40.487476 | controller |   "project": "ansible-collections/ansible.network",
2026-03-05 00:29:40.487511 | controller |   "url": "https://1730ba1b82880926cf10-3e2be7043838127cbadaf9e0daa8495d.ssl.cf1.rackcdn.com/ansible/baf7fa39eb9d431b8cbe849e75b005c2/artifacts/ansible-network-5.0.0.tar.gz"
2026-03-05 00:29:40.487558 | controller | }
2026-03-05 00:29:40.507851 | 
2026-03-05 00:29:40.508081 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-03-05 00:29:42.045257 | controller | Starting galaxy collection install process
2026-03-05 00:29:42.045744 | controller | Process install dependency map
2026-03-05 00:29:58.848963 | controller | Starting collection install process
2026-03-05 00:29:58.849145 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-03-05 00:29:58.864274 | controller | ansible.network (5.0.0) was installed successfully
2026-03-05 00:29:58.864411 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-03-05 00:29:58.864628 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:00.481560 | controller | ansible.netcommon (8.4.0) was installed successfully
2026-03-05 00:30:00.481617 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-03-05 00:30:00.481727 | 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-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:02.472167 | controller | ansible.utils (6.0.1) was installed successfully
2026-03-05 00:30:02.472482 | controller | Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-03-05 00:30:02.472692 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:05.214728 | controller | arista.eos (12.0.1) was installed successfully
2026-03-05 00:30:05.214883 | controller | Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-03-05 00:30:05.214901 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:08.607540 | controller | cisco.ios (11.3.0) was installed successfully
2026-03-05 00:30:12.017687 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-03-05 00:30:12.017737 | 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-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:12.017754 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-03-05 00:30:12.017844 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-03-05 00:30:12.018065 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:17.021339 | controller | cisco.nxos (11.1.3) was installed successfully
2026-03-05 00:30:17.021424 | controller | Installing 'junipernetworks.junos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-03-05 00:30:17.021813 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:20.471545 | controller | junipernetworks.junos (11.1.0) was installed successfully
2026-03-05 00:30:20.471670 | controller | Installing 'juniper.device:2.0.1' to '/home/zuul/.ansible/collections/ansible_collections/juniper/device'
2026-03-05 00:30:20.471893 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/juniper-device-2.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4022j2wsi06f/tmpu8gmqxhk
2026-03-05 00:30:22.932420 | controller | juniper.device (2.0.1) was installed successfully
2026-03-05 00:30:23.435801 | controller | ok: Runtime: 0:00:41.705074
2026-03-05 00:30:23.449541 | 
2026-03-05 00:30:23.449688 | TASK [podman may need iptables kernel modules]
2026-03-05 00:30:24.823363 | controller | ok: Runtime: 0:00:00.006527
2026-03-05 00:30:24.877486 | 
2026-03-05 00:30:24.877774 | PLAY RECAP
2026-03-05 00:30:24.877856 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-03-05 00:30:24.877888 | 
2026-03-05 00:30:25.057144 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-05 00:30:25.058483 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-05 00:30:25.766347 | 
2026-03-05 00:30:25.766625 | PLAY [controller]
2026-03-05 00:30:25.793850 | 
2026-03-05 00:30:25.794040 | TASK [Copy the galaxy.yml on the controller]
2026-03-05 00:30:29.609834 | controller | changed
2026-03-05 00:30:29.623810 | 
2026-03-05 00:30:29.624055 | TASK [Load information from galaxy.yml]
2026-03-05 00:30:29.681326 | controller | ok
2026-03-05 00:30:29.695838 | 
2026-03-05 00:30:29.695995 | LOOP [Enable FIPS mode]
2026-03-05 00:30:29.724026 | 
2026-03-05 00:30:29.724248 | LOOP [ansible-test : Include branch specific variables.]
2026-03-05 00:30:29.763260 | controller | ok: "/var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-03-05 00:30:29.763562 | 
2026-03-05 00:30:29.784916 | 
2026-03-05 00:30:29.785141 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-03-05 00:30:29.820642 | controller | ok
2026-03-05 00:30:29.826636 | 
2026-03-05 00:30:29.826711 | TASK [ansible-test : Enable --inventory for network-integration]
2026-03-05 00:30:29.853707 | controller | skipping: Conditional result was False
2026-03-05 00:30:29.862878 | 
2026-03-05 00:30:29.862987 | TASK [ansible-test : Enable --retry-on-error]
2026-03-05 00:30:29.889625 | controller | skipping: Conditional result was False
2026-03-05 00:30:29.899660 | 
2026-03-05 00:30:29.899801 | TASK [ansible-test : Enable --continue-on-error]
2026-03-05 00:30:29.934463 | controller | ok
2026-03-05 00:30:29.942519 | 
2026-03-05 00:30:29.942614 | TASK [ansible-test : Enable --requirements]
2026-03-05 00:30:29.969073 | controller | skipping: Conditional result was False
2026-03-05 00:30:29.978953 | 
2026-03-05 00:30:29.979252 | TASK [ansible-test : Adjust options for unit tests]
2026-03-05 00:30:30.005479 | controller | skipping: Conditional result was False
2026-03-05 00:30:30.015821 | 
2026-03-05 00:30:30.015921 | TASK [ansible-test : Install requirements with unit tests]
2026-03-05 00:30:30.097278 | controller | ok
2026-03-05 00:30:30.103111 | 
2026-03-05 00:30:30.103181 | TASK [ansible-test : Set --skip-test]
2026-03-05 00:30:30.168712 | controller | skipping: Conditional result was False
2026-03-05 00:30:30.175695 | 
2026-03-05 00:30:30.175830 | TASK [ansible-test : Reduce verbosity]
2026-03-05 00:30:30.224773 | controller | ok
2026-03-05 00:30:30.236967 | 
2026-03-05 00:30:30.237108 | TASK [ansible-test : Setup --python option]
2026-03-05 00:30:30.264096 | controller | skipping: Conditional result was False
2026-03-05 00:30:30.277839 | 
2026-03-05 00:30:30.277978 | TASK [ansible-test : Setup --docker option]
2026-03-05 00:30:30.318634 | controller | ok
2026-03-05 00:30:30.334553 | 
2026-03-05 00:30:30.334690 | TASK [ansible-test : check if MANIFEST.json exists]
2026-03-05 00:30:31.339452 | controller | ok
2026-03-05 00:30:31.348204 | 
2026-03-05 00:30:31.348316 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-03-05 00:30:31.376010 | controller | skipping: Conditional result was False
2026-03-05 00:30:31.386977 | 
2026-03-05 00:30:31.387159 | TASK [ansible-test : Load information from MANIFEST.json]
2026-03-05 00:30:31.414014 | controller | skipping: Conditional result was False
2026-03-05 00:30:31.426129 | 
2026-03-05 00:30:31.426340 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-05 00:30:31.453599 | controller | skipping: Conditional result was False
2026-03-05 00:30:31.464116 | 
2026-03-05 00:30:31.464328 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-03-05 00:30:32.668583 | controller | ok
2026-03-05 00:30:32.674476 | 
2026-03-05 00:30:32.674554 | TASK [ansible-test : Load information from galaxy.yml]
2026-03-05 00:30:32.710128 | controller | ok
2026-03-05 00:30:32.723217 | 
2026-03-05 00:30:32.723397 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-05 00:30:32.759615 | controller | ok
2026-03-05 00:30:32.768693 | 
2026-03-05 00:30:32.768835 | TASK [ansible-test : Setup minimum test requirements]
2026-03-05 00:30:32.803770 | controller | ok
2026-03-05 00:30:32.813540 | 
2026-03-05 00:30:32.813720 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-03-05 00:30:33.819596 | controller | ok
2026-03-05 00:30:33.835617 | 
2026-03-05 00:30:33.835858 | TASK [ansible-test : Install python requirements]
2026-03-05 00:30:35.508632 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-05 00:30:35.527695 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-03-05 00:30:35.566985 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-05 00:30:35.573713 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-03-05 00:30:35.587250 | 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-03-05 00:30:35.595765 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-03-05 00:30:35.610978 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-03-05 00:30:35.672406 | controller | Installing collected packages: pathspec, yamllint
2026-03-05 00:30:35.778664 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-03-05 00:30:36.223965 | controller | ok: Runtime: 0:00:01.110276
2026-03-05 00:30:36.235141 | 
2026-03-05 00:30:36.235257 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-03-05 00:30:37.171836 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-03-05 00:30:37.631312 | controller | ERROR
2026-03-05 00:30:37.631721 | controller | {
2026-03-05 00:30:37.631877 | controller |   "delta": "0:00:00.009218",
2026-03-05 00:30:37.631937 | controller |   "end": "2026-03-05 00:30:37.172118",
2026-03-05 00:30:37.631988 | controller |   "msg": "non-zero return code",
2026-03-05 00:30:37.632033 | controller |   "rc": 1,
2026-03-05 00:30:37.632072 | controller |   "start": "2026-03-05 00:30:37.162900"
2026-03-05 00:30:37.632108 | controller | }
2026-03-05 00:30:37.632157 | controller | ERROR: Ignoring Errors
2026-03-05 00:30:37.646144 | 
2026-03-05 00:30:37.646278 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-03-05 00:30:38.058802 | controller | skipping: Conditional result was False
2026-03-05 00:30:38.067777 | 
2026-03-05 00:30:38.067868 | TASK [ansible-test : Enable persistent connection logging]
2026-03-05 00:30:39.364442 | controller | changed: section and option added
2026-03-05 00:30:39.379647 | 
2026-03-05 00:30:39.379865 | TASK [ansible-test : Set persistent ssh_type value]
2026-03-05 00:30:40.506969 | controller | changed: option added
2026-03-05 00:30:40.523760 | 
2026-03-05 00:30:40.523999 | TASK [ansible-test : Set ansible log path]
2026-03-05 00:30:41.756501 | controller | changed: section and option added
2026-03-05 00:30:41.771892 | 
2026-03-05 00:30:41.772150 | TASK [ansible-test : Identify the changed targets]
2026-03-05 00:30:42.173639 | controller | skipping: Conditional result was False
2026-03-05 00:30:42.188689 | 
2026-03-05 00:30:42.188977 | TASK [ansible-test : set_fact]
2026-03-05 00:30:42.258305 | controller | skipping: Conditional result was False
2026-03-05 00:30:42.272662 | 
2026-03-05 00:30:42.272909 | TASK [ansible-test : Set the targets]
2026-03-05 00:30:42.316070 | controller | ok
2026-03-05 00:30:42.388610 | 
2026-03-05 00:30:42.388982 | TASK [ansible-test : set_fact]
2026-03-05 00:30:42.424515 | controller | skipping: Conditional result was False
2026-03-05 00:30:42.432368 | 
2026-03-05 00:30:42.432470 | TASK [ansible-test : List the targets]
2026-03-05 00:30:42.820436 | controller | skipping: Conditional result was False
2026-03-05 00:30:42.837914 | 
2026-03-05 00:30:42.838106 | TASK [ansible-test : set_fact]
2026-03-05 00:30:42.855449 | controller | skipping: Conditional result was False
2026-03-05 00:30:42.872246 | 
2026-03-05 00:30:42.872418 | TASK [ansible-test : set_fact]
2026-03-05 00:30:42.890096 | controller | skipping: Conditional result was False
2026-03-05 00:30:42.907121 | 
2026-03-05 00:30:42.907266 | LOOP [ansible-test : set_fact]
2026-03-05 00:30:42.969865 | 
2026-03-05 00:30:42.970376 | TASK [ansible-test : set_fact]
2026-03-05 00:30:42.997905 | controller | skipping: Conditional result was False
2026-03-05 00:30:43.012968 | 
2026-03-05 00:30:43.013112 | TASK [ansible-test : debug]
2026-03-05 00:30:43.031852 | controller | skipping: Conditional result was False
2026-03-05 00:30:43.048106 | 
2026-03-05 00:30:43.048291 | TASK [ansible-test : debug]
2026-03-05 00:30:43.089211 | About to run: ansible-test sanity --requirements --docker -v
2026-03-05 00:30:43.097949 | 
2026-03-05 00:30:43.098044 | TASK [ansible-test : Run the test suite]
2026-03-05 00:30:44.896694 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-03-05 00:30:44.978907 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-03-05 00:30:46.589281 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-03-05 00:30:46.589329 | controller | 171857c49d0f: Pulling fs layer
2026-03-05 00:30:46.589352 | controller | 419640447d26: Pulling fs layer
2026-03-05 00:30:46.589360 | controller | 61e52f862619: Pulling fs layer
2026-03-05 00:30:46.589366 | controller | 851de0426e26: Pulling fs layer
2026-03-05 00:30:46.589376 | controller | 37425716ea5b: Pulling fs layer
2026-03-05 00:30:46.589385 | controller | cd9b0c439616: Pulling fs layer
2026-03-05 00:30:46.589391 | controller | d4612dbd8855: Pulling fs layer
2026-03-05 00:30:46.589397 | controller | 554699364730: Pulling fs layer
2026-03-05 00:30:46.589403 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-03-05 00:30:46.589409 | controller | 5f2e4724adea: Pulling fs layer
2026-03-05 00:30:46.589415 | controller | a02d9a95ccb8: Pulling fs layer
2026-03-05 00:30:46.589420 | controller | 989f4ce370cb: Pulling fs layer
2026-03-05 00:30:46.589426 | controller | 0d7f52cdf9df: Pulling fs layer
2026-03-05 00:30:46.589432 | controller | c423742351d5: Pulling fs layer
2026-03-05 00:30:46.589438 | controller | 109236341d21: Pulling fs layer
2026-03-05 00:30:46.589460 | controller | 07a2f61c96b8: Pulling fs layer
2026-03-05 00:30:46.589467 | controller | 041f122f27ae: Pulling fs layer
2026-03-05 00:30:46.589479 | controller | ca6f5098b12e: Pulling fs layer
2026-03-05 00:30:46.589492 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-03-05 00:30:46.589498 | controller | 18a0b750727a: Pulling fs layer
2026-03-05 00:30:46.589506 | controller | 17f2f596716c: Pulling fs layer
2026-03-05 00:30:46.589514 | controller | f35a9998a9bd: Pulling fs layer
2026-03-05 00:30:46.589520 | controller | fdfc10566405: Pulling fs layer
2026-03-05 00:30:46.589526 | controller | 1ff1c442c5fe: Pulling fs layer
2026-03-05 00:30:46.589532 | controller | a3443c4a17ed: Pulling fs layer
2026-03-05 00:30:46.589538 | controller | 0edcf9fb2b15: Pulling fs layer
2026-03-05 00:30:46.589544 | controller | 5af43b69e845: Pulling fs layer
2026-03-05 00:30:46.589550 | controller | 1651773effb2: Pulling fs layer
2026-03-05 00:30:46.589555 | controller | 79e23c5c0fe4: Pulling fs layer
2026-03-05 00:30:46.589564 | controller | e5058b1faaa6: Pulling fs layer
2026-03-05 00:30:46.589570 | controller | 846775490569: Pulling fs layer
2026-03-05 00:30:46.589576 | controller | 851de0426e26: Waiting
2026-03-05 00:30:46.589582 | controller | 37425716ea5b: Waiting
2026-03-05 00:30:46.589588 | controller | cd9b0c439616: Waiting
2026-03-05 00:30:46.589594 | controller | d4612dbd8855: Waiting
2026-03-05 00:30:46.589600 | controller | 554699364730: Waiting
2026-03-05 00:30:46.589605 | controller | 1ff15d4bdb3e: Waiting
2026-03-05 00:30:46.589614 | controller | 5f2e4724adea: Waiting
2026-03-05 00:30:46.589620 | controller | a02d9a95ccb8: Waiting
2026-03-05 00:30:46.589626 | controller | 989f4ce370cb: Waiting
2026-03-05 00:30:46.589631 | controller | 0d7f52cdf9df: Waiting
2026-03-05 00:30:46.589641 | controller | c423742351d5: Waiting
2026-03-05 00:30:47.331652 | controller | 109236341d21: Waiting
2026-03-05 00:30:47.331728 | controller | 07a2f61c96b8: Waiting
2026-03-05 00:30:47.331736 | controller | 041f122f27ae: Waiting
2026-03-05 00:30:47.331742 | controller | ca6f5098b12e: Waiting
2026-03-05 00:30:47.331748 | controller | 1eb3e7b2afd9: Waiting
2026-03-05 00:30:47.331754 | controller | 18a0b750727a: Waiting
2026-03-05 00:30:47.331760 | controller | 17f2f596716c: Waiting
2026-03-05 00:30:47.331791 | controller | f35a9998a9bd: Waiting
2026-03-05 00:30:47.331799 | controller | fdfc10566405: Waiting
2026-03-05 00:30:47.331805 | controller | 1ff1c442c5fe: Waiting
2026-03-05 00:30:47.331811 | controller | a3443c4a17ed: Waiting
2026-03-05 00:30:47.331817 | controller | 0edcf9fb2b15: Waiting
2026-03-05 00:30:47.331823 | controller | 5af43b69e845: Waiting
2026-03-05 00:30:47.331828 | controller | 1651773effb2: Waiting
2026-03-05 00:30:47.331834 | controller | 79e23c5c0fe4: Waiting
2026-03-05 00:30:47.331841 | controller | e5058b1faaa6: Waiting
2026-03-05 00:30:47.331881 | controller | 846775490569: Waiting
2026-03-05 00:30:47.331898 | controller | 419640447d26: Verifying Checksum
2026-03-05 00:30:47.337097 | controller | 419640447d26: Download complete
2026-03-05 00:30:47.337150 | controller | 61e52f862619: Verifying Checksum
2026-03-05 00:30:48.108251 | controller | 61e52f862619: Download complete
2026-03-05 00:30:48.108312 | controller | 851de0426e26: Verifying Checksum
2026-03-05 00:30:48.908645 | controller | 851de0426e26: Download complete
2026-03-05 00:30:48.908698 | controller | cd9b0c439616: Download complete
2026-03-05 00:30:49.710739 | controller | d4612dbd8855: Verifying Checksum
2026-03-05 00:30:51.288938 | controller | d4612dbd8855: Download complete
2026-03-05 00:30:51.289042 | controller | 171857c49d0f: Verifying Checksum
2026-03-05 00:30:52.140667 | controller | 171857c49d0f: Download complete
2026-03-05 00:30:52.140732 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-03-05 00:30:52.929663 | controller | 1ff15d4bdb3e: Download complete
2026-03-05 00:30:52.929722 | controller | 5f2e4724adea: Verifying Checksum
2026-03-05 00:30:54.307122 | controller | 5f2e4724adea: Download complete
2026-03-05 00:30:54.307182 | controller | 171857c49d0f: Pull complete
2026-03-05 00:30:54.339821 | controller | 419640447d26: Pull complete
2026-03-05 00:30:54.367067 | controller | 61e52f862619: Pull complete
2026-03-05 00:30:54.386933 | controller | a02d9a95ccb8: Verifying Checksum
2026-03-05 00:30:54.387033 | controller | a02d9a95ccb8: Download complete
2026-03-05 00:30:54.424082 | controller | 851de0426e26: Pull complete
2026-03-05 00:30:55.204710 | controller | 989f4ce370cb: Verifying Checksum
2026-03-05 00:30:55.204859 | controller | 989f4ce370cb: Download complete
2026-03-05 00:30:55.903425 | controller | 0d7f52cdf9df: Download complete
2026-03-05 00:30:56.669437 | controller | c423742351d5: Verifying Checksum
2026-03-05 00:30:56.669669 | controller | c423742351d5: Download complete
2026-03-05 00:30:59.632776 | controller | 554699364730: Verifying Checksum
2026-03-05 00:31:00.356732 | controller | 554699364730: Download complete
2026-03-05 00:31:00.356820 | controller | 07a2f61c96b8: Verifying Checksum
2026-03-05 00:31:01.109605 | controller | 07a2f61c96b8: Download complete
2026-03-05 00:31:01.109704 | controller | 041f122f27ae: Verifying Checksum
2026-03-05 00:31:02.732719 | controller | 041f122f27ae: Download complete
2026-03-05 00:31:02.732820 | controller | ca6f5098b12e: Verifying Checksum
2026-03-05 00:31:02.732900 | controller | ca6f5098b12e: Download complete
2026-03-05 00:31:04.075621 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-03-05 00:31:05.713967 | controller | 1eb3e7b2afd9: Download complete
2026-03-05 00:31:05.714080 | controller | 18a0b750727a: Verifying Checksum
2026-03-05 00:31:05.714139 | controller | 18a0b750727a: Download complete
2026-03-05 00:31:06.358547 | controller | 109236341d21: Verifying Checksum
2026-03-05 00:31:06.358658 | controller | 109236341d21: Download complete
2026-03-05 00:31:06.431615 | controller | 17f2f596716c: Verifying Checksum
2026-03-05 00:31:06.431808 | controller | 17f2f596716c: Download complete
2026-03-05 00:31:07.128492 | controller | f35a9998a9bd: Verifying Checksum
2026-03-05 00:31:07.128609 | controller | f35a9998a9bd: Download complete
2026-03-05 00:31:07.300889 | controller | fdfc10566405: Verifying Checksum
2026-03-05 00:31:07.301016 | controller | fdfc10566405: Download complete
2026-03-05 00:31:07.927781 | controller | 1ff1c442c5fe: Verifying Checksum
2026-03-05 00:31:07.927907 | controller | 1ff1c442c5fe: Download complete
2026-03-05 00:31:15.469790 | controller | a3443c4a17ed: Verifying Checksum
2026-03-05 00:31:15.469998 | controller | a3443c4a17ed: Download complete
2026-03-05 00:31:15.562878 | controller | 0edcf9fb2b15: Verifying Checksum
2026-03-05 00:31:15.563066 | controller | 0edcf9fb2b15: Download complete
2026-03-05 00:31:20.461393 | controller | 1651773effb2: Verifying Checksum
2026-03-05 00:31:22.423892 | controller | 1651773effb2: Download complete
2026-03-05 00:31:22.423991 | controller | 5af43b69e845: Verifying Checksum
2026-03-05 00:31:22.424160 | controller | 5af43b69e845: Download complete
2026-03-05 00:31:23.623631 | controller | 79e23c5c0fe4: Verifying Checksum
2026-03-05 00:31:23.623748 | controller | 79e23c5c0fe4: Download complete
2026-03-05 00:31:25.024689 | controller | 37425716ea5b: Verifying Checksum
2026-03-05 00:31:25.024878 | controller | 37425716ea5b: Download complete
2026-03-05 00:31:27.202671 | controller | e5058b1faaa6: Verifying Checksum
2026-03-05 00:31:27.202825 | controller | e5058b1faaa6: Download complete
2026-03-05 00:31:31.633366 | controller | 846775490569: Verifying Checksum
2026-03-05 00:31:31.633478 | controller | 846775490569: Download complete
2026-03-05 00:31:44.848494 | controller | 37425716ea5b: Pull complete
2026-03-05 00:31:44.880302 | controller | cd9b0c439616: Pull complete
2026-03-05 00:31:44.903028 | controller | d4612dbd8855: Pull complete
2026-03-05 00:31:50.027538 | controller | 554699364730: Pull complete
2026-03-05 00:31:50.054398 | controller | 1ff15d4bdb3e: Pull complete
2026-03-05 00:31:50.078611 | controller | 5f2e4724adea: Pull complete
2026-03-05 00:31:50.140081 | controller | a02d9a95ccb8: Pull complete
2026-03-05 00:31:50.166636 | controller | 989f4ce370cb: Pull complete
2026-03-05 00:31:50.198237 | controller | 0d7f52cdf9df: Pull complete
2026-03-05 00:31:50.228918 | controller | c423742351d5: Pull complete
2026-03-05 00:31:52.775691 | controller | 109236341d21: Pull complete
2026-03-05 00:31:52.807668 | controller | 07a2f61c96b8: Pull complete
2026-03-05 00:31:52.856811 | controller | 041f122f27ae: Pull complete
2026-03-05 00:31:53.069341 | controller | ca6f5098b12e: Pull complete
2026-03-05 00:31:53.241740 | controller | 1eb3e7b2afd9: Pull complete
2026-03-05 00:31:53.407372 | controller | 18a0b750727a: Pull complete
2026-03-05 00:31:53.430753 | controller | 17f2f596716c: Pull complete
2026-03-05 00:31:53.458519 | controller | f35a9998a9bd: Pull complete
2026-03-05 00:31:53.509332 | controller | fdfc10566405: Pull complete
2026-03-05 00:31:53.541756 | controller | 1ff1c442c5fe: Pull complete
2026-03-05 00:31:55.231802 | controller | a3443c4a17ed: Pull complete
2026-03-05 00:31:57.115227 | controller | 0edcf9fb2b15: Pull complete
2026-03-05 00:32:02.096633 | controller | 5af43b69e845: Pull complete
2026-03-05 00:32:05.034761 | controller | 1651773effb2: Pull complete
2026-03-05 00:32:08.015966 | controller | 79e23c5c0fe4: Pull complete
2026-03-05 00:32:10.127025 | controller | e5058b1faaa6: Pull complete
2026-03-05 00:32:13.172199 | controller | 846775490569: Pull complete
2026-03-05 00:32:13.210341 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-03-05 00:32:13.217572 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-03-05 00:32:13.228406 | controller | quay.io/ansible/default-test-container:2.9.0
2026-03-05 00:32:13.234666 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-03-05 00:32:13.264545 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-03-05 00:32:14.687238 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-03-05 00:32:14.687575 | controller | 04a5f4cda3ee: Pulling fs layer
2026-03-05 00:32:14.687658 | controller | ff496a88c8ed: Pulling fs layer
2026-03-05 00:32:14.687674 | controller | 0ce83f459fe7: Pulling fs layer
2026-03-05 00:32:14.687712 | controller | 2e5170e1f099: Pulling fs layer
2026-03-05 00:32:14.687774 | controller | 7641eb41b08c: Pulling fs layer
2026-03-05 00:32:14.687789 | controller | ad15fa9da398: Pulling fs layer
2026-03-05 00:32:14.687838 | controller | 087d91352424: Pulling fs layer
2026-03-05 00:32:14.687874 | controller | 8b92efd6a100: Pulling fs layer
2026-03-05 00:32:14.688387 | controller | 2e5170e1f099: Waiting
2026-03-05 00:32:14.688486 | controller | 7641eb41b08c: Waiting
2026-03-05 00:32:14.688547 | controller | ad15fa9da398: Waiting
2026-03-05 00:32:14.688645 | controller | 087d91352424: Waiting
2026-03-05 00:32:14.688730 | controller | 8b92efd6a100: Waiting
2026-03-05 00:32:15.488767 | controller | 0ce83f459fe7: Download complete
2026-03-05 00:32:15.532843 | controller | ff496a88c8ed: Verifying Checksum
2026-03-05 00:32:16.269571 | controller | ff496a88c8ed: Download complete
2026-03-05 00:32:16.269639 | controller | 7641eb41b08c: Verifying Checksum
2026-03-05 00:32:17.025066 | controller | 7641eb41b08c: Download complete
2026-03-05 00:32:17.025161 | controller | ad15fa9da398: Verifying Checksum
2026-03-05 00:32:17.025274 | controller | ad15fa9da398: Download complete
2026-03-05 00:32:17.634683 | controller | 04a5f4cda3ee: Verifying Checksum
2026-03-05 00:32:17.634811 | controller | 04a5f4cda3ee: Download complete
2026-03-05 00:32:17.976228 | controller | 087d91352424: Verifying Checksum
2026-03-05 00:32:17.976547 | controller | 087d91352424: Download complete
2026-03-05 00:32:18.426035 | controller | 8b92efd6a100: Verifying Checksum
2026-03-05 00:32:18.426132 | controller | 8b92efd6a100: Download complete
2026-03-05 00:32:19.981871 | controller | 04a5f4cda3ee: Pull complete
2026-03-05 00:32:20.035343 | controller | ff496a88c8ed: Pull complete
2026-03-05 00:32:20.087489 | controller | 0ce83f459fe7: Pull complete
2026-03-05 00:32:22.573829 | controller | 2e5170e1f099: Verifying Checksum
2026-03-05 00:32:22.573936 | controller | 2e5170e1f099: Download complete
2026-03-05 00:32:26.316499 | controller | 2e5170e1f099: Pull complete
2026-03-05 00:32:26.388171 | controller | 7641eb41b08c: Pull complete
2026-03-05 00:32:26.428582 | controller | ad15fa9da398: Pull complete
2026-03-05 00:32:27.631560 | controller | 087d91352424: Pull complete
2026-03-05 00:32:27.676117 | controller | 8b92efd6a100: Pull complete
2026-03-05 00:32:27.704408 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-03-05 00:32:27.710654 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-03-05 00:32:27.721095 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-03-05 00:32:27.728117 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-03-05 00:32:30.802683 | controller | Run command: docker inspect ff21a0c42739b146e22de792e52dc298879c5d8465a960dc494b9c0695f0242c
2026-03-05 00:32:30.885146 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-03-05 00:32:30.903432 | controller | Including collection: ansible.netcommon (278 files)
2026-03-05 00:32:30.918569 | controller | Including collection: ansible.network (23 files)
2026-03-05 00:32:30.918616 | controller | Including collection: ansible.utils (441 files)
2026-03-05 00:32:30.969207 | controller | Including collection: arista.eos (1031 files)
2026-03-05 00:32:31.025829 | controller | Including collection: cisco.ios (1215 files)
2026-03-05 00:32:31.083025 | controller | Including collection: cisco.iosxr (1044 files)
2026-03-05 00:32:31.182848 | controller | Including collection: cisco.nxos (1687 files)
2026-03-05 00:32:31.204554 | controller | Including collection: juniper.device (391 files)
2026-03-05 00:32:31.251788 | controller | Including collection: junipernetworks.junos (700 files)
2026-03-05 00:32:31.306340 | controller | Creating a payload archive containing 7536 files...
2026-03-05 00:32:33.720985 | controller | Created a 6222906 byte payload archive containing 7536 files in 2 seconds.
2026-03-05 00:32:33.725403 | controller | Assuming Docker is available on localhost.
2026-03-05 00:32:33.725812 | 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-03-05 00:32:34.354603 | controller | Run command: docker exec -i c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 dd of=/root/docker.sh bs=65536
2026-03-05 00:32:34.445540 | controller | Run command: docker exec c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 /bin/bash /root/docker.sh
2026-03-05 00:32:34.539860 | controller | Run command: docker exec -i c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 dd of=/root/test.tgz bs=65536
2026-03-05 00:32:34.686679 | controller | Run command: docker exec c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 tar oxzf /root/test.tgz -C /root
2026-03-05 00:32:35.922045 | controller | Run command: docker exec c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 /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-k2xqc16u.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-03-05 00:32:36.383817 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-03-05 00:32:36.384712 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-03-05 00:32:36.385297 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-03-05 00:32:36.389564 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-03-05 00:32:36.390649 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-03-05 00:32:36.398844 | controller | No tests applicable.
2026-03-05 00:32:36.398925 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-03-05 00:32:36.399235 | controller | No tests applicable.
2026-03-05 00:32:36.399310 | controller | Running sanity test 'changelog' with Python 3.6
2026-03-05 00:32:36.399984 | 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-03-05 00:32:37.424697 | 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-03-05 00:32:38.105017 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-05 00:32:53.115790 | 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-03-05 00:33:08.632604 | 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-03-05 00:33:24.648471 | 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-03-05 00:33:41.668981 | 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-03-05 00:34:00.689768 | 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-03-05 00:34:15.708475 | 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-03-05 00:34:15.710799 | 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-03-05 00:34:15.944147 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-03-05 00:34:16.024691 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-03-05 00:34:16.024745 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-03-05 00:34:16.024754 | 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-03-05 00:34:16.024775 | controller | Run command: docker exec c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-03-05 00:34:16.129087 | controller | Run command: docker exec -i c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 dd if=/root/output.tgz bs=65536
2026-03-05 00:34:16.258406 | controller | Run command: tar oxzf /tmp/ansible-result-btieqbkx.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-03-05 00:34:16.274744 | controller | Run command: docker rm -f ff21a0c42739b146e22de792e52dc298879c5d8465a960dc494b9c0695f0242c
2026-03-05 00:34:16.627620 | controller | Run command: docker rm -f c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4
2026-03-05 00:34:17.561536 | controller | ERROR: Command "docker exec c017d6af786b97fdd2afb3d6abd60673349829410cb3473e95a1e493b8bf19e4 /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-k2xqc16u.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-03-05 00:34:18.208972 | controller | ERROR
2026-03-05 00:34:18.209313 | controller | {
2026-03-05 00:34:18.209382 | controller |   "delta": "0:03:33.109378",
2026-03-05 00:34:18.209425 | controller |   "end": "2026-03-05 00:34:17.626241",
2026-03-05 00:34:18.209464 | controller |   "msg": "non-zero return code",
2026-03-05 00:34:18.209501 | controller |   "rc": 1,
2026-03-05 00:34:18.209537 | controller |   "start": "2026-03-05 00:30:44.516863"
2026-03-05 00:34:18.209573 | controller | }
failure
2026-03-05 00:34:18.216533 | 
2026-03-05 00:34:18.217545 | PLAY RECAP
2026-03-05 00:34:18.217657 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-03-05 00:34:18.217707 | 
2026-03-05 00:34:18.395553 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-05 00:34:18.396501 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-05 00:34:19.043031 | 
2026-03-05 00:34:19.043186 | PLAY [all]
2026-03-05 00:34:19.065773 | 
2026-03-05 00:34:19.065914 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-05 00:34:22.231970 | controller | changed: non-zero return code
2026-03-05 00:34:22.241502 | 
2026-03-05 00:34:22.241884 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-05 00:34:22.258455 | controller | skipping: Conditional result was False
2026-03-05 00:34:22.267442 | 
2026-03-05 00:34:22.267611 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-05 00:34:22.292687 | 
2026-03-05 00:34:22.292945 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-05 00:34:22.318275 | 
2026-03-05 00:34:22.318488 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-05 00:34:22.333540 | controller | skipping: Conditional result was False
2026-03-05 00:34:22.341766 | 
2026-03-05 00:34:22.341930 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-05 00:34:22.363582 | 
2026-03-05 00:34:22.363791 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-05 00:34:22.378988 | controller | skipping: Conditional result was False
2026-03-05 00:34:22.388014 | 
2026-03-05 00:34:22.388138 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-05 00:34:22.403980 | controller | skipping: Conditional result was False
2026-03-05 00:34:22.411880 | 
2026-03-05 00:34:22.412022 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-05 00:34:22.427484 | controller | skipping: Conditional result was False
2026-03-05 00:34:22.463006 | 
2026-03-05 00:34:22.463192 | PLAY RECAP
2026-03-05 00:34:22.463272 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-05 00:34:22.463308 | 
2026-03-05 00:34:22.639115 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-05 00:34:22.640951 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-05 00:34:23.260320 | 
2026-03-05 00:34:23.260441 | PLAY [all:!appliance*]
2026-03-05 00:34:23.283190 | 
2026-03-05 00:34:23.283323 | TASK [unregister the node]
2026-03-05 00:34:23.649620 | controller | skipping: Conditional result was False
2026-03-05 00:34:23.662092 | 
2026-03-05 00:34:23.662254 | TASK [include_role : fetch-output]
2026-03-05 00:34:23.714405 | controller | ok
2026-03-05 00:34:23.760162 | 
2026-03-05 00:34:23.760328 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-05 00:34:23.806653 | controller | skipping: Conditional result was False
2026-03-05 00:34:23.822632 | 
2026-03-05 00:34:23.822850 | TASK [fetch-output : Set log path for single node]
2026-03-05 00:34:23.880143 | controller | ok
2026-03-05 00:34:23.897281 | 
2026-03-05 00:34:23.897461 | LOOP [fetch-output : Ensure local output dirs]
2026-03-05 00:34:24.353127 | controller -> localhost | ok: "/var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/logs"
2026-03-05 00:34:24.575924 | controller -> localhost | changed: "/var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/artifacts"
2026-03-05 00:34:24.820452 | controller -> localhost | changed: "/var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/work/docs"
2026-03-05 00:34:24.839569 | 
2026-03-05 00:34:24.839787 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-05 00:34:27.226526 | controller | changed:
2026-03-05 00:34:27.226757 | controller | .d..t...... ./
2026-03-05 00:34:27.226795 | controller | cd+++++++++ controller/
2026-03-05 00:34:27.226841 | controller | changed: All items complete
2026-03-05 00:34:27.226870 | 
2026-03-05 00:34:29.397564 | controller | changed: .d..t...... ./
2026-03-05 00:34:31.566179 | controller | changed: .d..t...... ./
2026-03-05 00:34:31.608491 | 
2026-03-05 00:34:31.608908 | TASK [include_role : fetch-output-openshift]
2026-03-05 00:34:31.627780 | controller | skipping: Conditional result was False
2026-03-05 00:34:31.644716 | 
2026-03-05 00:34:31.645008 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-05 00:34:31.688412 | controller | skipping: Conditional result was False
2026-03-05 00:34:31.699873 | controller | skipping: Conditional result was False
2026-03-05 00:34:31.759431 | 
2026-03-05 00:34:31.759606 | PLAY [localhost]
2026-03-05 00:34:31.779760 | 
2026-03-05 00:34:31.779888 | TASK [Run Zuul manifest role]
2026-03-05 00:34:31.801325 | localhost | ok
2026-03-05 00:34:31.821359 | 
2026-03-05 00:34:31.821516 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-05 00:34:32.240969 | localhost | changed
2026-03-05 00:34:32.247302 | 
2026-03-05 00:34:32.247385 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-05 00:34:32.279694 | localhost | ok
2026-03-05 00:34:32.328309 | 
2026-03-05 00:34:32.328446 | TASK [Set zuul-log-path fact]
2026-03-05 00:34:32.347286 | localhost | ok
2026-03-05 00:34:32.360385 | 
2026-03-05 00:34:32.360487 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-05 00:34:32.389419 | localhost | ok
2026-03-05 00:34:32.397830 | 
2026-03-05 00:34:32.397908 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-05 00:34:32.428533 | localhost | ok:
2026-03-05 00:34:32.428972 | localhost | {
2026-03-05 00:34:32.429032 | localhost |   "auth": {
2026-03-05 00:34:32.429079 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-05 00:34:32.429118 | localhost |     "username": "9LF91O0RK7JU"
2026-03-05 00:34:32.429158 | localhost |   },
2026-03-05 00:34:32.429196 | localhost |   "profile": "rackspace",
2026-03-05 00:34:32.429232 | localhost |   "region_name": "ORD"
2026-03-05 00:34:32.429271 | localhost | }
2026-03-05 00:34:32.429329 | 
2026-03-05 00:34:32.439103 | 
2026-03-05 00:34:32.439252 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-05 00:34:32.459386 | localhost | Output suppressed because no_log was given
2026-03-05 00:34:32.474989 | 
2026-03-05 00:34:32.475139 | TASK [upload-logs-failover : Initialize control vars]
2026-03-05 00:34:32.495030 | localhost | ok
2026-03-05 00:34:32.500028 | 
2026-03-05 00:34:32.500143 | LOOP [upload-logs-failover : Perform log upload]
2026-03-05 00:34:32.539022 | localhost | Output suppressed because no_log was given
2026-03-05 00:34:32.547253 | localhost | included: /var/lib/zuul/builds/91ce42fbfc6e47cba5462cb98d6fa5ce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-05 00:34:32.554946 | 
2026-03-05 00:34:32.555056 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-05 00:34:32.642981 | localhost | Output suppressed because no_log was given
2026-03-05 00:34:32.650229 | 
2026-03-05 00:34:32.650319 | TASK [Upload logs to swift]
2026-03-05 00:34:32.678570 | localhost | ok
2026-03-05 00:34:32.718638 | 
2026-03-05 00:34:32.718792 | TASK [Set zuul-log-path fact]
2026-03-05 00:34:32.753953 | localhost | skipping: Conditional result was False
2026-03-05 00:34:32.762996 | 
2026-03-05 00:34:32.763135 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-05 00:34:33.179322 | localhost -> localhost | ok: Runtime: 0:00:00.005523
2026-03-05 00:34:33.191638 | 
2026-03-05 00:34:33.191876 | TASK [upload-logs-swift : Upload logs to swift]