2026-03-20 00:06:43.777106 | Job console starting...
2026-03-20 00:06:43.788485 | Updating repositories
2026-03-20 00:06:46.612393 | Preparing job workspace
2026-03-20 00:06:50.451710 | Running Ansible setup...
2026-03-20 00:06:58.579239 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-20 00:06:59.219385 | 
2026-03-20 00:06:59.219550 | PLAY [localhost]
2026-03-20 00:06:59.228650 | 
2026-03-20 00:06:59.228773 | TASK [Gathering Facts]
2026-03-20 00:07:00.377483 | localhost | ok
2026-03-20 00:07:00.391224 | 
2026-03-20 00:07:00.391330 | TASK [Setup log path fact]
2026-03-20 00:07:00.413574 | localhost | ok
2026-03-20 00:07:00.432450 | 
2026-03-20 00:07:00.432546 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-20 00:07:00.463343 | localhost | ok
2026-03-20 00:07:00.474318 | 
2026-03-20 00:07:00.474406 | TASK [emit-job-header : Print job information]
2026-03-20 00:07:00.514057 | # Job Information
2026-03-20 00:07:00.514242 | Ansible Version: 2.15.12
2026-03-20 00:07:00.514288 | Job: ansible-test-sanity-docker-stable-2.10
2026-03-20 00:07:00.514326 | Pipeline: periodic
2026-03-20 00:07:00.514356 | Executor: ze04.softwarefactory-project.io
2026-03-20 00:07:00.514383 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-20 00:07:00.514413 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/0f9/ansible/0f9415ced5f14b6abcf6967f0743adfb/
2026-03-20 00:07:00.514443 | Event ID: e372d3abf7ed4b5bb8cf5a4a746df902
2026-03-20 00:07:00.520265 | 
2026-03-20 00:07:00.520347 | LOOP [emit-job-header : Print node information]
2026-03-20 00:07:00.635310 | localhost | ok:
2026-03-20 00:07:00.635612 | localhost | # Node Information
2026-03-20 00:07:00.635652 | localhost | Inventory Hostname: controller
2026-03-20 00:07:00.635758 | localhost | Hostname: np0005652498
2026-03-20 00:07:00.635787 | localhost | Username: zuul
2026-03-20 00:07:00.635813 | localhost | Distro: Fedora 37
2026-03-20 00:07:00.635833 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-03-20 00:07:00.635851 | localhost | Region: ca-ymq-1
2026-03-20 00:07:00.635868 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-20 00:07:00.635886 | localhost | Product Name: OpenStack Nova
2026-03-20 00:07:00.635903 | localhost | Interface IP: 199.204.45.244
2026-03-20 00:07:00.654880 | 
2026-03-20 00:07:00.655052 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-20 00:07:01.097789 | localhost -> localhost | changed
2026-03-20 00:07:01.104187 | 
2026-03-20 00:07:01.104294 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-20 00:07:02.040975 | localhost -> localhost | changed
2026-03-20 00:07:02.063586 | 
2026-03-20 00:07:02.063719 | PLAY [all:!appliance*]
2026-03-20 00:07:02.087740 | 
2026-03-20 00:07:02.087963 | TASK [include_role : start-zuul-console]
2026-03-20 00:07:02.111972 | controller | ok
2026-03-20 00:07:02.126999 | 
2026-03-20 00:07:02.127087 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-20 00:07:02.548586 | controller | ok
2026-03-20 00:07:02.568817 | 
2026-03-20 00:07:02.569099 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-20 00:07:25.209766 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-20 00:07:25.220754 | 
2026-03-20 00:07:25.220873 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-20 00:07:25.783877 | controller | skipping: Conditional result was False
2026-03-20 00:07:25.798587 | 
2026-03-20 00:07:25.798842 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-20 00:07:25.826524 | controller | skipping: Conditional result was False
2026-03-20 00:07:25.838527 | 
2026-03-20 00:07:25.838735 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-20 00:07:25.875701 | controller | skipping: Conditional result was False
2026-03-20 00:07:25.886256 | 
2026-03-20 00:07:25.886385 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-20 00:07:25.912210 | controller | skipping: Conditional result was False
2026-03-20 00:07:25.927582 | 
2026-03-20 00:07:25.927765 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-20 00:07:25.943254 | controller | skipping: Conditional result was False
2026-03-20 00:07:25.953615 | 
2026-03-20 00:07:25.953801 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-20 00:07:25.979289 | controller | skipping: Conditional result was False
2026-03-20 00:07:25.998783 | 
2026-03-20 00:07:25.998943 | TASK [Disable Fedora Modular]
2026-03-20 00:07:26.336881 | controller | changed
2026-03-20 00:07:26.350793 | 
2026-03-20 00:07:26.350943 | TASK [Enable EPEL]
2026-03-20 00:07:26.378784 | controller | skipping: Conditional result was False
2026-03-20 00:07:26.392595 | 
2026-03-20 00:07:26.392769 | TASK [Register the RHEL node]
2026-03-20 00:07:26.947254 | 
2026-03-20 00:07:26.947458 | TASK [Show the subscription-manager status]
2026-03-20 00:07:27.493952 | controller | skipping: Conditional result was False
2026-03-20 00:07:27.501284 | 
2026-03-20 00:07:27.501374 | TASK [Enable EPEL on RHEL]
2026-03-20 00:07:28.044655 | controller | skipping: Conditional result was False
2026-03-20 00:07:28.057940 | 
2026-03-20 00:07:28.058081 | TASK [Install git and tox]
2026-03-20 00:08:17.995070 | controller | changed
2026-03-20 00:08:18.007856 | 
2026-03-20 00:08:18.008005 | TASK [include_role : prepare-workspace]
2026-03-20 00:08:18.042210 | controller | ok
2026-03-20 00:08:18.069455 | 
2026-03-20 00:08:18.069589 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-20 00:08:18.311451 | controller | ok
2026-03-20 00:08:18.330298 | 
2026-03-20 00:08:18.330501 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-20 00:08:33.118442 | controller | Output suppressed because no_log was given
2026-03-20 00:08:33.141150 | 
2026-03-20 00:08:33.141316 | TASK [include_role : prepare-workspace-openshift]
2026-03-20 00:08:33.168159 | controller | skipping: Conditional result was False
2026-03-20 00:08:33.205143 | 
2026-03-20 00:08:33.205239 | PLAY [all:!appliance]
2026-03-20 00:08:33.261368 | 
2026-03-20 00:08:33.261510 | TASK [Run add-build-sshkey role (RSA)]
2026-03-20 00:08:33.292656 | controller | ok
2026-03-20 00:08:33.315613 | 
2026-03-20 00:08:33.315832 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-20 00:08:33.598241 | controller -> localhost | ok
2026-03-20 00:08:33.607050 | 
2026-03-20 00:08:33.607166 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-20 00:08:33.641617 | controller | ok
2026-03-20 00:08:33.660715 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-20 00:08:33.667044 | 
2026-03-20 00:08:33.667219 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-20 00:08:34.294558 | controller -> localhost | Generating public/private rsa key pair.
2026-03-20 00:08:34.294828 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/0f9415ced5f14b6abcf6967f0743adfb_id_rsa.
2026-03-20 00:08:34.294904 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/0f9415ced5f14b6abcf6967f0743adfb_id_rsa.pub.
2026-03-20 00:08:34.294934 | controller -> localhost | The key fingerprint is:
2026-03-20 00:08:34.294962 | controller -> localhost | SHA256:ctP3hP1MVkLhJhtSniV4KPJKADPHJyhiNdRK91TgFI0 zuul-build-sshkey
2026-03-20 00:08:34.294988 | controller -> localhost | The key's randomart image is:
2026-03-20 00:08:34.295013 | controller -> localhost | +---[RSA 2048]----+
2026-03-20 00:08:34.295038 | controller -> localhost | |  *Bo  +*. oo +. |
2026-03-20 00:08:34.295064 | controller -> localhost | |o..==o=E..oo.*   |
2026-03-20 00:08:34.295092 | controller -> localhost | |o.. o+o+ ...= + .|
2026-03-20 00:08:34.295118 | controller -> localhost | |   .  .... . B ..|
2026-03-20 00:08:34.295142 | controller -> localhost | |     ...S . + o o|
2026-03-20 00:08:34.295179 | controller -> localhost | |      .o . . o = |
2026-03-20 00:08:34.295217 | controller -> localhost | |              . o|
2026-03-20 00:08:34.295255 | controller -> localhost | |                 |
2026-03-20 00:08:34.295283 | controller -> localhost | |                 |
2026-03-20 00:08:34.295312 | controller -> localhost | +----[SHA256]-----+
2026-03-20 00:08:34.295375 | controller -> localhost | ok: Runtime: 0:00:00.212541
2026-03-20 00:08:34.304334 | 
2026-03-20 00:08:34.304422 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-20 00:08:34.337170 | controller | ok
2026-03-20 00:08:34.353863 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-20 00:08:34.367350 | 
2026-03-20 00:08:34.367446 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-20 00:08:34.392742 | controller | skipping: Conditional result was False
2026-03-20 00:08:34.400319 | 
2026-03-20 00:08:34.400396 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-20 00:08:34.850364 | controller | changed
2026-03-20 00:08:34.856504 | 
2026-03-20 00:08:34.856584 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-20 00:08:35.076243 | controller | ok
2026-03-20 00:08:35.082096 | 
2026-03-20 00:08:35.082164 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-20 00:08:35.714913 | controller | changed
2026-03-20 00:08:35.725940 | 
2026-03-20 00:08:35.726083 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-20 00:08:36.387801 | controller | changed
2026-03-20 00:08:36.397371 | 
2026-03-20 00:08:36.397517 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-20 00:08:36.423760 | controller | skipping: Conditional result was False
2026-03-20 00:08:36.435893 | 
2026-03-20 00:08:36.436132 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-20 00:08:36.954562 | controller -> localhost | changed
2026-03-20 00:08:36.970245 | 
2026-03-20 00:08:36.970355 | TASK [add-build-sshkey : Add back temp key]
2026-03-20 00:08:37.264114 | controller -> localhost | Identity added: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/0f9415ced5f14b6abcf6967f0743adfb_id_rsa (zuul-build-sshkey)
2026-03-20 00:08:37.264394 | controller -> localhost | ok: Runtime: 0:00:00.008263
2026-03-20 00:08:37.277417 | 
2026-03-20 00:08:37.277550 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-20 00:08:37.641651 | controller | ok
2026-03-20 00:08:37.661479 | 
2026-03-20 00:08:37.661629 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-20 00:08:37.689803 | controller | skipping: Conditional result was False
2026-03-20 00:08:37.708990 | 
2026-03-20 00:08:37.709151 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-20 00:08:37.746573 | controller | ok
2026-03-20 00:08:37.768580 | 
2026-03-20 00:08:37.768740 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-20 00:08:38.003252 | controller -> localhost | ok
2026-03-20 00:08:38.011103 | 
2026-03-20 00:08:38.011218 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-20 00:08:38.032055 | controller | ok
2026-03-20 00:08:38.044117 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-20 00:08:38.051607 | 
2026-03-20 00:08:38.051700 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-20 00:08:38.341084 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-20 00:08:38.341275 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/0f9415ced5f14b6abcf6967f0743adfb_id_ecdsa.
2026-03-20 00:08:38.341304 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/0f9415ced5f14b6abcf6967f0743adfb_id_ecdsa.pub.
2026-03-20 00:08:38.341332 | controller -> localhost | The key fingerprint is:
2026-03-20 00:08:38.341353 | controller -> localhost | SHA256:MyieSk5bXRbAr2bba4OhRj08/JUf9L3rehskybhKiwA zuul-build-sshkey
2026-03-20 00:08:38.341372 | controller -> localhost | The key's randomart image is:
2026-03-20 00:08:38.341390 | controller -> localhost | +---[ECDSA 521]---+
2026-03-20 00:08:38.341408 | controller -> localhost | |     ..          |
2026-03-20 00:08:38.341426 | controller -> localhost | |      ..         |
2026-03-20 00:08:38.341443 | controller -> localhost | |       ..        |
2026-03-20 00:08:38.341460 | controller -> localhost | |       ...  + .  |
2026-03-20 00:08:38.341477 | controller -> localhost | |   E.+..S  + = o |
2026-03-20 00:08:38.341493 | controller -> localhost | |   .o+@o oo o + .|
2026-03-20 00:08:38.341510 | controller -> localhost | |  o.+=.O o o . ..|
2026-03-20 00:08:38.341527 | controller -> localhost | | + +o + B o .  o.|
2026-03-20 00:08:38.341543 | controller -> localhost | |  +.   o.=   .++o|
2026-03-20 00:08:38.341560 | controller -> localhost | +----[SHA256]-----+
2026-03-20 00:08:38.341608 | controller -> localhost | ok: Runtime: 0:00:00.008189
2026-03-20 00:08:38.349101 | 
2026-03-20 00:08:38.349169 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-20 00:08:38.392088 | controller | ok
2026-03-20 00:08:38.401789 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-20 00:08:38.412640 | 
2026-03-20 00:08:38.412793 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-20 00:08:38.448712 | controller | skipping: Conditional result was False
2026-03-20 00:08:38.457902 | 
2026-03-20 00:08:38.458021 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-20 00:08:38.729764 | controller | changed
2026-03-20 00:08:38.740905 | 
2026-03-20 00:08:38.741008 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-20 00:08:38.946264 | controller | ok
2026-03-20 00:08:38.952977 | 
2026-03-20 00:08:38.953069 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-20 00:08:39.570911 | controller | changed
2026-03-20 00:08:39.580257 | 
2026-03-20 00:08:39.580363 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-20 00:08:40.212523 | controller | changed
2026-03-20 00:08:40.229671 | 
2026-03-20 00:08:40.229877 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-20 00:08:40.260320 | controller | skipping: Conditional result was False
2026-03-20 00:08:40.285461 | 
2026-03-20 00:08:40.285600 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-20 00:08:40.535758 | controller -> localhost | changed
2026-03-20 00:08:40.547964 | 
2026-03-20 00:08:40.548045 | TASK [add-build-sshkey : Add back temp key]
2026-03-20 00:08:40.829267 | controller -> localhost | Identity added: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/0f9415ced5f14b6abcf6967f0743adfb_id_ecdsa (zuul-build-sshkey)
2026-03-20 00:08:40.829819 | controller -> localhost | ok: Runtime: 0:00:00.015623
2026-03-20 00:08:40.845757 | 
2026-03-20 00:08:40.845902 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-20 00:08:41.056765 | controller | ok
2026-03-20 00:08:41.067009 | 
2026-03-20 00:08:41.067149 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-20 00:08:41.092723 | controller | skipping: Conditional result was False
2026-03-20 00:08:41.146136 | 
2026-03-20 00:08:41.146252 | TASK [include_role : remove-zuul-sshkey]
2026-03-20 00:08:41.161228 | controller | skipping: Conditional result was False
2026-03-20 00:08:41.168214 | 
2026-03-20 00:08:41.168335 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-20 00:08:41.399019 | controller | ok: "logs"
2026-03-20 00:08:41.399275 | controller | ok: All items complete
2026-03-20 00:08:41.399304 | 
2026-03-20 00:08:41.596028 | controller | ok: "artifacts"
2026-03-20 00:08:41.757245 | controller | ok: "docs"
2026-03-20 00:08:41.780560 | 
2026-03-20 00:08:41.780771 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-20 00:08:42.044255 | controller | changed: "logs"
2026-03-20 00:08:42.260972 | controller | changed: "artifacts"
2026-03-20 00:08:42.469496 | controller | changed: "docs"
2026-03-20 00:08:42.513931 | 
2026-03-20 00:08:42.514061 | PLAY RECAP
2026-03-20 00:08:42.514120 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-20 00:08:42.514157 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-20 00:08:42.514185 | 
2026-03-20 00:08:42.675193 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-20 00:08:42.676408 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-20 00:08:43.321740 | 
2026-03-20 00:08:43.321883 | PLAY [all]
2026-03-20 00:08:43.350932 | 
2026-03-20 00:08:43.351079 | TASK [Install binary dependencies]
2026-03-20 00:08:43.412373 | controller | ok
2026-03-20 00:08:43.434716 | 
2026-03-20 00:08:43.434866 | TASK [bindep : Include find tasks]
2026-03-20 00:08:43.464754 | controller | ok
2026-03-20 00:08:43.474409 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-20 00:08:43.482023 | 
2026-03-20 00:08:43.482145 | TASK [bindep : Look for bindep.txt]
2026-03-20 00:08:43.837997 | controller | ok
2026-03-20 00:08:43.852052 | 
2026-03-20 00:08:43.852454 | TASK [bindep : Define bindep_file fact]
2026-03-20 00:08:43.881247 | controller | skipping: Conditional result was False
2026-03-20 00:08:43.897995 | 
2026-03-20 00:08:43.898199 | TASK [bindep : Look for other-requirements.txt]
2026-03-20 00:08:44.126634 | controller | ok
2026-03-20 00:08:44.136539 | 
2026-03-20 00:08:44.136772 | TASK [bindep : Define bindep_file fact]
2026-03-20 00:08:44.164740 | controller | skipping: Conditional result was False
2026-03-20 00:08:44.173927 | 
2026-03-20 00:08:44.174026 | TASK [bindep : Look for bindep fallback file]
2026-03-20 00:08:44.199901 | controller | skipping: Conditional result was False
2026-03-20 00:08:44.210532 | 
2026-03-20 00:08:44.210659 | TASK [bindep : Define bindep_file fact]
2026-03-20 00:08:44.236349 | controller | skipping: Conditional result was False
2026-03-20 00:08:44.246636 | 
2026-03-20 00:08:44.246778 | TASK [bindep : Include bindep tasks]
2026-03-20 00:08:44.282120 | controller | skipping: Conditional result was False
2026-03-20 00:08:44.291218 | 
2026-03-20 00:08:44.291337 | TASK [bindep : Include install tasks]
2026-03-20 00:08:44.316635 | controller | skipping: Conditional result was False
2026-03-20 00:08:44.325019 | 
2026-03-20 00:08:44.325109 | LOOP [bindep : Include package tasks]
2026-03-20 00:08:44.387818 | 
2026-03-20 00:08:44.388033 | TASK [Run test-setup role]
2026-03-20 00:08:44.413232 | controller | ok
2026-03-20 00:08:44.432154 | 
2026-03-20 00:08:44.432237 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-20 00:08:44.652874 | controller | ok
2026-03-20 00:08:44.667427 | 
2026-03-20 00:08:44.667618 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-20 00:08:45.427547 | controller | skipping: Conditional result was False
2026-03-20 00:08:45.456033 | 
2026-03-20 00:08:45.456137 | PLAY RECAP
2026-03-20 00:08:45.456185 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-20 00:08:45.456205 | 
2026-03-20 00:08:45.581285 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-20 00:08:45.582769 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-20 00:08:46.238020 | 
2026-03-20 00:08:46.238205 | PLAY [controller]
2026-03-20 00:08:46.263515 | 
2026-03-20 00:08:46.263694 | TASK [Create the /root directory]
2026-03-20 00:08:46.657517 | controller | ok
2026-03-20 00:08:46.666917 | 
2026-03-20 00:08:46.667033 | TASK [Install glibc-langpack-en]
2026-03-20 00:08:50.628194 | controller | ok: Nothing to do
2026-03-20 00:08:50.637554 | 
2026-03-20 00:08:50.637653 | TASK [Ensure controller directory exists]
2026-03-20 00:08:50.856432 | controller | changed
2026-03-20 00:08:50.866528 | 
2026-03-20 00:08:50.866655 | TASK [Install container runtime]
2026-03-20 00:08:50.914199 | controller | ok
2026-03-20 00:08:50.976122 | 
2026-03-20 00:08:50.976253 | LOOP [ensure-docker : Gather variables for each operating system]
2026-03-20 00:08:51.030884 | controller | ok: "/var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-03-20 00:08:51.043175 | 
2026-03-20 00:08:51.043324 | TASK [ensure-docker : Sanity check for distro selection]
2026-03-20 00:08:51.069303 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.078761 | 
2026-03-20 00:08:51.078985 | TASK [ensure-docker : Sanity check for distro install]
2026-03-20 00:08:51.105555 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.116093 | 
2026-03-20 00:08:51.116252 | TASK [ensure-docker : Sanity check for upstream install]
2026-03-20 00:08:51.144007 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.155195 | 
2026-03-20 00:08:51.155341 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-03-20 00:08:51.182055 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.197433 | 
2026-03-20 00:08:51.197620 | TASK [ensure-docker : Include tasks for docker install]
2026-03-20 00:08:51.220703 | controller | ok
2026-03-20 00:08:51.232753 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-03-20 00:08:51.243216 | 
2026-03-20 00:08:51.243327 | TASK [ensure-docker : Not Implemented]
2026-03-20 00:08:51.270960 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.279584 | 
2026-03-20 00:08:51.279721 | TASK [Set up docker mirrors]
2026-03-20 00:08:51.302832 | controller | ok
2026-03-20 00:08:51.332220 | 
2026-03-20 00:08:51.332368 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-03-20 00:08:51.358354 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.367153 | 
2026-03-20 00:08:51.367276 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-03-20 00:08:51.391847 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.400429 | 
2026-03-20 00:08:51.400540 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-03-20 00:08:51.426174 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.435752 | 
2026-03-20 00:08:51.435885 | TASK [use-docker-mirror : Set up docker mirrors]
2026-03-20 00:08:51.461852 | controller | skipping: Conditional result was False
2026-03-20 00:08:51.481807 | 
2026-03-20 00:08:51.481917 | TASK [Add all repositories]
2026-03-20 00:08:51.513347 | controller | ok
2026-03-20 00:08:51.543865 | 
2026-03-20 00:08:51.543970 | LOOP [ensure-package-repositories : Install repositories]
2026-03-20 00:08:51.571264 | controller | ok: "/var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-03-20 00:08:51.571444 | controller | ok: All items complete
2026-03-20 00:08:51.571473 | 
2026-03-20 00:08:51.624092 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-03-20 00:08:51.630480 | 
2026-03-20 00:08:51.630544 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-03-20 00:08:51.939708 | controller | changed
2026-03-20 00:08:51.955615 | 
2026-03-20 00:08:51.955800 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-03-20 00:08:52.820066 | controller | changed:
2026-03-20 00:08:52.822813 | controller | {
2026-03-20 00:08:52.822974 | 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-20 00:08:52.823027 | controller | }
2026-03-20 00:08:52.841733 | 
2026-03-20 00:08:52.841893 | LOOP [ensure-package-repositories : Import GPG keys]
2026-03-20 00:08:53.406801 | controller | changed:
2026-03-20 00:08:53.407040 | controller | {
2026-03-20 00:08:53.407075 | 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-20 00:08:53.407113 | controller | }
2026-03-20 00:08:53.407154 | controller | changed: All items complete
2026-03-20 00:08:53.407181 | 
2026-03-20 00:08:53.421803 | 
2026-03-20 00:08:53.421965 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-03-20 00:08:53.642495 | controller | changed
2026-03-20 00:08:53.654542 | 
2026-03-20 00:08:53.654715 | LOOP [ensure-package-repositories : Add repositories]
2026-03-20 00:08:53.985905 | controller | changed:
2026-03-20 00:08:53.986789 | controller | {
2026-03-20 00:08:53.986885 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-03-20 00:08:53.986934 | controller |   "description": "Docker CE Stable - $basearch",
2026-03-20 00:08:53.986973 | controller |   "gpgcheck": true,
2026-03-20 00:08:53.987013 | controller |   "name": "docker-ce-stable"
2026-03-20 00:08:53.987050 | controller | }
2026-03-20 00:08:54.020365 | 
2026-03-20 00:08:54.020467 | TASK [ensure-docker : Install pre-reqs]
2026-03-20 00:08:56.888015 | controller | ok: Nothing to do
2026-03-20 00:08:56.906722 | 
2026-03-20 00:08:56.907003 | TASK [ensure-docker : Remove packages]
2026-03-20 00:08:58.308430 | controller | ok: Nothing to do
2026-03-20 00:08:58.315456 | 
2026-03-20 00:08:58.315557 | TASK [ensure-docker : Install upstream docker using package]
2026-03-20 00:09:45.643146 | controller | changed
2026-03-20 00:09:45.657901 | 
2026-03-20 00:09:45.658090 | TASK [ensure-docker : Setup docker]
2026-03-20 00:09:45.699210 | controller | ok
2026-03-20 00:09:45.724601 | controller | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-03-20 00:09:45.749068 | 
2026-03-20 00:09:45.749260 | TASK [ensure-docker : Ensure "docker" group exists]
2026-03-20 00:09:46.171262 | controller | ok
2026-03-20 00:09:46.189003 | 
2026-03-20 00:09:46.189226 | TASK [ensure-docker : Add user to docker group]
2026-03-20 00:09:46.753175 | controller | changed
2026-03-20 00:09:46.766401 | 
2026-03-20 00:09:46.766588 | TASK [Update docker daemon proxy configuration]
2026-03-20 00:09:46.815291 | controller | skipping: Conditional result was False
2026-03-20 00:09:46.829517 | 
2026-03-20 00:09:46.829660 | TASK [Lower default MTU]
2026-03-20 00:09:46.858342 | controller | skipping: Conditional result was False
2026-03-20 00:09:46.870639 | 
2026-03-20 00:09:46.870870 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-03-20 00:09:47.301430 | controller | inactive
2026-03-20 00:09:47.453914 | controller | ok: Runtime: 0:00:00.014231
2026-03-20 00:09:47.460510 | 
2026-03-20 00:09:47.460617 | TASK [ensure-docker : Get the status of the docket unit]
2026-03-20 00:09:47.690064 | controller | inactive
2026-03-20 00:09:47.993940 | controller | ok: Runtime: 0:00:00.011850
2026-03-20 00:09:48.010579 | 
2026-03-20 00:09:48.010850 | TASK [ensure-docker : Restart docker]
2026-03-20 00:09:48.065958 | Trigger a Docker restart
2026-03-20 00:09:48.082236 | 
2026-03-20 00:09:48.082439 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-03-20 00:09:48.125588 | 
2026-03-20 00:09:48.125903 | TASK [ensure-docker : Install docker-compose]
2026-03-20 00:09:48.144981 | controller | skipping: Conditional result was False
2026-03-20 00:09:48.159625 | 
2026-03-20 00:09:48.159772 | TASK [ensure-docker : Flush handlers before role exit]
2026-03-20 00:09:48.175655 | 
2026-03-20 00:09:48.175764 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-03-20 00:09:49.418039 | controller | changed
2026-03-20 00:09:49.426343 | 
2026-03-20 00:09:49.426414 | TASK [ensure-docker : Assure docker service is running]
2026-03-20 00:09:51.400792 | controller | changed
2026-03-20 00:09:51.415492 | 
2026-03-20 00:09:51.415662 | TASK [ensure-docker : Assure docker.socket service is running]
2026-03-20 00:09:52.432692 | controller | ok
2026-03-20 00:09:52.442248 | 
2026-03-20 00:09:52.442348 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-03-20 00:09:52.717754 | controller | ok
2026-03-20 00:09:52.731345 | 
2026-03-20 00:09:52.731519 | TASK [ensure-docker : Validate ability to talk with docker]
2026-03-20 00:09:53.140372 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-03-20 00:09:53.282792 | controller | ok: Runtime: 0:00:00.036630
2026-03-20 00:09:53.297628 | 
2026-03-20 00:09:53.297954 | TASK [ensure-docker : Get version details]
2026-03-20 00:09:53.785292 | controller | Client: Docker Engine - Community
2026-03-20 00:09:53.785337 | controller |  Version:           24.0.7
2026-03-20 00:09:53.785380 | controller |  API version:       1.43
2026-03-20 00:09:53.785386 | controller |  Go version:        go1.20.10
2026-03-20 00:09:53.785397 | controller |  Git commit:        afdd53b
2026-03-20 00:09:53.785403 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-03-20 00:09:53.785408 | controller |  OS/Arch:           linux/amd64
2026-03-20 00:09:53.785413 | controller |  Context:           default
2026-03-20 00:09:53.785419 | controller |
2026-03-20 00:09:53.785424 | controller | Server: Docker Engine - Community
2026-03-20 00:09:53.785429 | controller |  Engine:
2026-03-20 00:09:53.785436 | controller |   Version:          24.0.7
2026-03-20 00:09:53.785440 | controller |   API version:      1.43 (minimum version 1.12)
2026-03-20 00:09:53.785445 | controller |   Go version:       go1.20.10
2026-03-20 00:09:53.785449 | controller |   Git commit:       311b9ff
2026-03-20 00:09:53.785455 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-03-20 00:09:53.785472 | controller |   OS/Arch:          linux/amd64
2026-03-20 00:09:53.785477 | controller |   Experimental:     false
2026-03-20 00:09:53.785481 | controller |  containerd:
2026-03-20 00:09:53.785486 | controller |   Version:          1.6.25
2026-03-20 00:09:53.785490 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-03-20 00:09:53.785507 | controller |  runc:
2026-03-20 00:09:53.785514 | controller |   Version:          1.1.10
2026-03-20 00:09:53.786809 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-03-20 00:09:53.786829 | controller |  docker-init:
2026-03-20 00:09:53.786833 | controller |   Version:          0.19.0
2026-03-20 00:09:53.786838 | controller |   GitCommit:        de40ad0
2026-03-20 00:09:53.857502 | controller | ok: Runtime: 0:00:00.077708
2026-03-20 00:09:53.863977 | 
2026-03-20 00:09:53.864042 | TASK [ensure-docker : Dump installed docker details]
2026-03-20 00:09:53.903434 | {'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-20 00:09:53.709380', 'end': '2026-03-20 00:09:53.787088', 'delta': '0:00:00.077708', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-3029-ad54-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-20 00:09:53.916495 | 
2026-03-20 00:09:53.916582 | TASK [Use buildset registry]
2026-03-20 00:09:53.945981 | controller | skipping: Conditional result was False
2026-03-20 00:09:53.969099 | 
2026-03-20 00:09:53.969191 | TASK [Ensure python3.8 is present]
2026-03-20 00:09:53.996122 | controller | skipping: Conditional result was False
2026-03-20 00:09:54.011287 | 
2026-03-20 00:09:54.011394 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-20 00:09:54.036536 | controller | ok
2026-03-20 00:09:54.065797 | 
2026-03-20 00:09:54.066066 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-20 00:09:56.317479 | controller | ok: Nothing to do
2026-03-20 00:09:56.325616 | 
2026-03-20 00:09:56.325778 | TASK [our-ensure-python : Also install python3-devel]
2026-03-20 00:10:07.527197 | controller | changed
2026-03-20 00:10:07.560314 | 
2026-03-20 00:10:07.560517 | TASK [Run ensure-virtualenv role]
2026-03-20 00:10:07.591031 | controller | ok
2026-03-20 00:10:07.628311 | 
2026-03-20 00:10:07.628484 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-20 00:10:07.867803 | controller | /usr/bin/virtualenv
2026-03-20 00:10:08.170334 | controller | ok: Runtime: 0:00:00.004829
2026-03-20 00:10:08.193135 | 
2026-03-20 00:10:08.193376 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-20 00:10:08.224999 | controller | skipping: Conditional result was False
2026-03-20 00:10:08.225437 | controller | changed: All items complete
2026-03-20 00:10:08.225483 | 
2026-03-20 00:10:08.253011 | 
2026-03-20 00:10:08.253206 | TASK [Find the full path of the Python interpreter]
2026-03-20 00:10:08.468097 | controller | /usr/bin/python3.8
2026-03-20 00:10:08.796387 | controller | ok
2026-03-20 00:10:08.806600 | 
2026-03-20 00:10:08.807056 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-20 00:10:10.438761 | controller | created virtual environment CPython3.8.18.final.0-64 in 797ms
2026-03-20 00:10:10.474338 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-20 00:10:10.474372 | 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-20 00:10:10.474382 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-20 00:10:10.474395 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-20 00:10:10.860931 | controller | changed
2026-03-20 00:10:10.875853 | 
2026-03-20 00:10:10.876044 | TASK [Set selinux package]
2026-03-20 00:10:10.924136 | controller | ok
2026-03-20 00:10:10.941567 | 
2026-03-20 00:10:10.942001 | TASK [Set selinux package (Fedora)]
2026-03-20 00:10:10.977222 | controller | ok
2026-03-20 00:10:10.986299 | 
2026-03-20 00:10:10.986403 | TASK [Install selinux into virtualenv]
2026-03-20 00:10:38.066968 | controller | Collecting selinux-please-lie-to-me
2026-03-20 00:10:50.335523 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-20 00:10:50.738662 | controller | Collecting setuptools<50.0.0
2026-03-20 00:10:50.746422 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-20 00:10:50.783865 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 24.3 MB/s eta 0:00:00
2026-03-20 00:10:50.862320 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-20 00:10:50.862543 | controller |   Attempting uninstall: setuptools
2026-03-20 00:10:50.863275 | controller |     Found existing installation: setuptools 62.6.0
2026-03-20 00:10:50.937933 | controller |     Uninstalling setuptools-62.6.0:
2026-03-20 00:10:50.947041 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-20 00:10:51.288479 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-20 00:11:02.710685 | controller |
2026-03-20 00:11:02.803987 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-20 00:11:02.804043 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-20 00:11:03.056962 | controller | ok: Runtime: 0:00:51.415303
2026-03-20 00:11:03.073585 | 
2026-03-20 00:11:03.073897 | TASK [Install pytest-forked into virtualenv]
2026-03-20 00:11:27.902741 | controller | Collecting pytest-forked
2026-03-20 00:11:52.215987 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-20 00:11:52.275434 | controller | Collecting py
2026-03-20 00:11:52.280111 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-20 00:11:52.309053 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.7 MB/s eta 0:00:00
2026-03-20 00:11:52.438362 | controller | Collecting pytest>=3.10
2026-03-20 00:11:52.443894 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-03-20 00:11:52.469323 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 14.5 MB/s eta 0:00:00
2026-03-20 00:11:52.535695 | controller | Collecting tomli>=1
2026-03-20 00:11:52.539512 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-03-20 00:11:52.572052 | controller | Collecting pluggy<2,>=1.5
2026-03-20 00:11:52.581092 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-03-20 00:11:52.636651 | controller | Collecting packaging
2026-03-20 00:11:52.640711 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-20 00:11:52.652986 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 8.2 MB/s eta 0:00:00
2026-03-20 00:11:52.675433 | controller | Collecting iniconfig
2026-03-20 00:11:52.680279 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-03-20 00:11:52.715385 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-03-20 00:11:52.721001 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-03-20 00:11:52.778653 | controller | Collecting typing-extensions>=4.6.0
2026-03-20 00:11:52.786146 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-03-20 00:11:52.794460 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 6.7 MB/s eta 0:00:00
2026-03-20 00:11:52.856923 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-03-20 00:11:53.268026 | 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-20 00:11:53.272466 | controller |
2026-03-20 00:11:53.340884 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-20 00:11:53.340928 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-20 00:11:53.651119 | controller | ok: Runtime: 0:00:50.045411
2026-03-20 00:11:53.662367 | 
2026-03-20 00:11:53.662489 | TASK [Update pip]
2026-03-20 00:11:54.208707 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-03-20 00:12:06.653736 | controller | Collecting pip
2026-03-20 00:12:18.852871 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-03-20 00:12:18.902796 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 41.9 MB/s eta 0:00:00
2026-03-20 00:12:18.959591 | controller | Installing collected packages: pip
2026-03-20 00:12:18.959654 | controller |   Attempting uninstall: pip
2026-03-20 00:12:18.960264 | controller |     Found existing installation: pip 22.2.2
2026-03-20 00:12:19.128995 | controller |     Uninstalling pip-22.2.2:
2026-03-20 00:12:19.144063 | controller |       Successfully uninstalled pip-22.2.2
2026-03-20 00:12:19.932502 | controller | Successfully installed pip-25.0.1
2026-03-20 00:12:20.231334 | controller | ok: Runtime: 0:00:26.104060
2026-03-20 00:12:20.247869 | 
2026-03-20 00:12:20.248094 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-20 00:12:20.480628 | controller | changed
2026-03-20 00:12:20.488350 | 
2026-03-20 00:12:20.488439 | TASK [Install ansible into virtualenv]
2026-03-20 00:12:21.025821 | controller | Processing ./src/github.com/ansible/ansible
2026-03-20 00:12:21.026728 | controller |   Preparing metadata (setup.py): started
2026-03-20 00:12:21.464586 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-03-20 00:12:31.128920 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-03-20 00:12:41.341585 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-03-20 00:12:41.401614 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-03-20 00:12:41.408955 | 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-20 00:12:41.659164 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-03-20 00:12:41.667565 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-03-20 00:12:41.685434 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-03-20 00:12:41.818317 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-03-20 00:12:41.845421 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-03-20 00:12:41.855047 | 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-20 00:12:41.922443 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-03-20 00:12:41.948910 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-03-20 00:12:41.974140 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-03-20 00:12:41.984306 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-03-20 00:12:42.004803 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-03-20 00:12:42.121432 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 39.5 MB/s eta 0:00:00
2026-03-20 00:12:42.138374 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-03-20 00:12:42.167865 | 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-20 00:12:42.199949 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 22.6 MB/s eta 0:00:00
2026-03-20 00:12:42.208067 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-03-20 00:12:42.228779 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-03-20 00:12:42.248908 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-03-20 00:12:42.271482 | controller | Building wheels for collected packages: ansible-base
2026-03-20 00:12:42.272024 | controller |   Building wheel for ansible-base (setup.py): started
2026-03-20 00:12:43.451333 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-03-20 00:12:43.456403 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=f6e2dea3df970304c65725954a88196802bbdf25d3d8f52ac51a7b024f3229f2
2026-03-20 00:12:43.457061 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-pr6zn9zy/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-03-20 00:12:43.467415 | controller | Successfully built ansible-base
2026-03-20 00:12:43.500873 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-03-20 00:12:44.857942 | 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-20 00:12:45.044377 | controller | ok: Runtime: 0:00:24.205096
2026-03-20 00:12:45.056480 | 
2026-03-20 00:12:45.056664 | TASK [Setup download-artifact-fork role]
2026-03-20 00:12:45.086147 | controller | ok
2026-03-20 00:12:45.116816 | 
2026-03-20 00:12:45.117002 | TASK [download-artifact-fork : Parse inventory response]
2026-03-20 00:12:45.154653 | controller | ok
2026-03-20 00:12:45.175489 | 
2026-03-20 00:12:45.175777 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-03-20 00:12:45.206215 | controller | skipping: Conditional result was False
2026-03-20 00:12:45.217077 | 
2026-03-20 00:12:45.217231 | TASK [download-artifact-fork : Parse build response]
2026-03-20 00:12:45.244522 | controller | skipping: Conditional result was False
2026-03-20 00:12:45.252350 | 
2026-03-20 00:12:45.252477 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-03-20 00:12:45.464392 | controller | changed
2026-03-20 00:12:45.472274 | 
2026-03-20 00:12:45.472361 | LOOP [download-artifact-fork : Download archive by type]
2026-03-20 00:12:45.512249 | controller | skipping: Conditional result was False
2026-03-20 00:12:45.512572 | 
2026-03-20 00:12:46.585735 | controller | changed: OK (20199 bytes)
2026-03-20 00:12:46.609215 | 
2026-03-20 00:12:46.609374 | TASK [deploy-artifacts : define __collections]
2026-03-20 00:12:46.643231 | controller | ok
2026-03-20 00:12:46.649596 | 
2026-03-20 00:12:46.649736 | LOOP [deploy-artifacts : Create list of collections]
2026-03-20 00:12:46.686997 | controller | skipping: Conditional result was False
2026-03-20 00:12:46.699583 | controller | ok:
2026-03-20 00:12:46.699732 | controller | {
2026-03-20 00:12:46.699759 | controller |   "branch": "main",
2026-03-20 00:12:46.699779 | controller |   "job": "build-ansible-collection",
2026-03-20 00:12:46.699797 | controller |   "metadata": {
2026-03-20 00:12:46.699814 | controller |     "type": "ansible_collection",
2026-03-20 00:12:46.699831 | controller |     "version": "5.0.0"
2026-03-20 00:12:46.699853 | controller |   },
2026-03-20 00:12:46.699875 | controller |   "name": "ansible.network",
2026-03-20 00:12:46.699897 | controller |   "project": "ansible-collections/ansible.network",
2026-03-20 00:12:46.699918 | controller |   "url": "https://03d9ceb6e41dc4a4e8ca-97b3348287202f0ae3178e8df0623b63.ssl.cf1.rackcdn.com/ansible/82fbb6edb8894da29e301d084e1726b7/artifacts/ansible-network-5.0.0.tar.gz"
2026-03-20 00:12:46.699947 | controller | }
2026-03-20 00:12:46.710533 | 
2026-03-20 00:12:46.710749 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-03-20 00:12:47.283998 | controller | Starting galaxy collection install process
2026-03-20 00:12:47.284297 | controller | Process install dependency map
2026-03-20 00:21:05.908298 | controller | Starting collection install process
2026-03-20 00:21:05.908375 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-03-20 00:21:05.918534 | controller | ansible.network (5.0.0) was installed successfully
2026-03-20 00:21:05.918635 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-03-20 00:21:05.918654 | 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-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:21:56.939365 | controller | ansible.netcommon (8.4.0) was installed successfully
2026-03-20 00:22:43.930663 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-03-20 00:22:43.930699 | 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-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:22:43.930716 | controller | ansible.utils (6.0.1) was installed successfully
2026-03-20 00:22:43.930780 | controller | Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-03-20 00:22:43.930920 | 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-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:23:37.380538 | controller | arista.eos (12.0.1) was installed successfully
2026-03-20 00:23:37.380678 | controller | Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-03-20 00:23:37.380956 | 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-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:24:35.848412 | controller | cisco.ios (11.3.0) was installed successfully
2026-03-20 00:24:35.848504 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-03-20 00:24:35.848664 | 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-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:25:29.807363 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-03-20 00:25:29.807407 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-03-20 00:25:29.807418 | 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-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:26:10.854847 | controller | cisco.nxos (11.1.3) was installed successfully
2026-03-20 00:26:10.854940 | controller | Installing 'junipernetworks.junos:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-03-20 00:26:10.855101 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:27:06.239452 | controller | junipernetworks.junos (11.1.1) was installed successfully
2026-03-20 00:27:06.239547 | controller | Installing 'juniper.device:2.0.1' to '/home/zuul/.ansible/collections/ansible_collections/juniper/device'
2026-03-20 00:27:06.239698 | 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-4057qqp3nop8/tmptozv8s2r
2026-03-20 00:27:49.693052 | controller | juniper.device (2.0.1) was installed successfully
2026-03-20 00:27:50.178558 | controller | ok: Runtime: 0:15:02.797455
2026-03-20 00:27:50.195761 | 
2026-03-20 00:27:50.195927 | TASK [podman may need iptables kernel modules]
2026-03-20 00:27:50.740920 | controller | ok: Runtime: 0:00:00.003466
2026-03-20 00:27:50.779447 | 
2026-03-20 00:27:50.779563 | PLAY RECAP
2026-03-20 00:27:50.779619 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-03-20 00:27:50.779649 | 
2026-03-20 00:27:50.930523 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-20 00:27:50.931454 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-20 00:27:51.612383 | 
2026-03-20 00:27:51.612528 | PLAY [controller]
2026-03-20 00:27:51.641911 | 
2026-03-20 00:27:51.642069 | TASK [Copy the galaxy.yml on the controller]
2026-03-20 00:27:52.721231 | controller | changed
2026-03-20 00:27:52.742469 | 
2026-03-20 00:27:52.742913 | TASK [Load information from galaxy.yml]
2026-03-20 00:27:52.778063 | controller | ok
2026-03-20 00:27:52.787081 | 
2026-03-20 00:27:52.787199 | LOOP [Enable FIPS mode]
2026-03-20 00:27:52.811449 | 
2026-03-20 00:27:52.811649 | LOOP [ansible-test : Include branch specific variables.]
2026-03-20 00:27:52.847110 | controller | ok: "/var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-03-20 00:27:52.847327 | 
2026-03-20 00:27:52.857047 | 
2026-03-20 00:27:52.857173 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-03-20 00:27:52.915029 | controller | ok
2026-03-20 00:27:52.924310 | 
2026-03-20 00:27:52.924421 | TASK [ansible-test : Enable --inventory for network-integration]
2026-03-20 00:27:52.940016 | controller | skipping: Conditional result was False
2026-03-20 00:27:52.952119 | 
2026-03-20 00:27:52.952248 | TASK [ansible-test : Enable --retry-on-error]
2026-03-20 00:27:52.968449 | controller | skipping: Conditional result was False
2026-03-20 00:27:52.978862 | 
2026-03-20 00:27:52.978977 | TASK [ansible-test : Enable --continue-on-error]
2026-03-20 00:27:53.013625 | controller | ok
2026-03-20 00:27:53.022987 | 
2026-03-20 00:27:53.023105 | TASK [ansible-test : Enable --requirements]
2026-03-20 00:27:53.038216 | controller | skipping: Conditional result was False
2026-03-20 00:27:53.050120 | 
2026-03-20 00:27:53.050246 | TASK [ansible-test : Adjust options for unit tests]
2026-03-20 00:27:53.066622 | controller | skipping: Conditional result was False
2026-03-20 00:27:53.077980 | 
2026-03-20 00:27:53.078128 | TASK [ansible-test : Install requirements with unit tests]
2026-03-20 00:27:53.110364 | controller | ok
2026-03-20 00:27:53.116454 | 
2026-03-20 00:27:53.116547 | TASK [ansible-test : Set --skip-test]
2026-03-20 00:27:53.161525 | controller | skipping: Conditional result was False
2026-03-20 00:27:53.168666 | 
2026-03-20 00:27:53.168811 | TASK [ansible-test : Reduce verbosity]
2026-03-20 00:27:53.198547 | controller | ok
2026-03-20 00:27:53.234008 | 
2026-03-20 00:27:53.234163 | TASK [ansible-test : Setup --python option]
2026-03-20 00:27:53.258095 | controller | skipping: Conditional result was False
2026-03-20 00:27:53.265738 | 
2026-03-20 00:27:53.265839 | TASK [ansible-test : Setup --docker option]
2026-03-20 00:27:53.296485 | controller | ok
2026-03-20 00:27:53.302217 | 
2026-03-20 00:27:53.302287 | TASK [ansible-test : check if MANIFEST.json exists]
2026-03-20 00:27:53.839441 | controller | ok
2026-03-20 00:27:53.845375 | 
2026-03-20 00:27:53.845482 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-03-20 00:27:53.859196 | controller | skipping: Conditional result was False
2026-03-20 00:27:53.868044 | 
2026-03-20 00:27:53.868173 | TASK [ansible-test : Load information from MANIFEST.json]
2026-03-20 00:27:53.882124 | controller | skipping: Conditional result was False
2026-03-20 00:27:53.889751 | 
2026-03-20 00:27:53.889855 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-20 00:27:53.909480 | controller | skipping: Conditional result was False
2026-03-20 00:27:53.917371 | 
2026-03-20 00:27:53.917476 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-03-20 00:27:54.446582 | controller | ok
2026-03-20 00:27:54.462331 | 
2026-03-20 00:27:54.462550 | TASK [ansible-test : Load information from galaxy.yml]
2026-03-20 00:27:54.510188 | controller | ok
2026-03-20 00:27:54.516493 | 
2026-03-20 00:27:54.516596 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-20 00:27:54.556563 | controller | ok
2026-03-20 00:27:54.562593 | 
2026-03-20 00:27:54.562707 | TASK [ansible-test : Setup minimum test requirements]
2026-03-20 00:27:54.615193 | controller | ok
2026-03-20 00:27:54.623363 | 
2026-03-20 00:27:54.623451 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-03-20 00:27:55.317374 | controller | ok
2026-03-20 00:27:55.323307 | 
2026-03-20 00:27:55.323378 | TASK [ansible-test : Install python requirements]
2026-03-20 00:28:07.327150 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-20 00:28:31.588530 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-03-20 00:28:31.619561 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-20 00:28:31.625534 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-03-20 00:28:31.640442 | 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-20 00:28:31.646374 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-03-20 00:28:31.662420 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-03-20 00:28:31.700143 | controller | Installing collected packages: pathspec, yamllint
2026-03-20 00:28:31.755526 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-03-20 00:28:31.875729 | controller | ok: Runtime: 0:00:35.785262
2026-03-20 00:28:31.882526 | 
2026-03-20 00:28:31.882608 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-03-20 00:28:32.123347 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-03-20 00:28:32.417470 | controller | ERROR
2026-03-20 00:28:32.417876 | controller | {
2026-03-20 00:28:32.417923 | controller |   "delta": "0:00:00.004285",
2026-03-20 00:28:32.417953 | controller |   "end": "2026-03-20 00:28:32.123485",
2026-03-20 00:28:32.417979 | controller |   "msg": "non-zero return code",
2026-03-20 00:28:32.418004 | controller |   "rc": 1,
2026-03-20 00:28:32.418027 | controller |   "start": "2026-03-20 00:28:32.119200"
2026-03-20 00:28:32.418050 | controller | }
2026-03-20 00:28:32.418083 | controller | ERROR: Ignoring Errors
2026-03-20 00:28:32.427103 | 
2026-03-20 00:28:32.427212 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-03-20 00:28:32.974306 | controller | skipping: Conditional result was False
2026-03-20 00:28:32.981527 | 
2026-03-20 00:28:32.981600 | TASK [ansible-test : Enable persistent connection logging]
2026-03-20 00:28:33.359904 | controller | changed: section and option added
2026-03-20 00:28:33.366468 | 
2026-03-20 00:28:33.366564 | TASK [ansible-test : Set persistent ssh_type value]
2026-03-20 00:28:33.886109 | controller | changed: option added
2026-03-20 00:28:33.897030 | 
2026-03-20 00:28:33.897149 | TASK [ansible-test : Set ansible log path]
2026-03-20 00:28:34.399648 | controller | changed: section and option added
2026-03-20 00:28:34.405572 | 
2026-03-20 00:28:34.405646 | TASK [ansible-test : Identify the changed targets]
2026-03-20 00:28:34.937505 | controller | skipping: Conditional result was False
2026-03-20 00:28:34.945697 | 
2026-03-20 00:28:34.945870 | TASK [ansible-test : set_fact]
2026-03-20 00:28:34.992239 | controller | skipping: Conditional result was False
2026-03-20 00:28:35.003506 | 
2026-03-20 00:28:35.003649 | TASK [ansible-test : Set the targets]
2026-03-20 00:28:35.040672 | controller | ok
2026-03-20 00:28:35.052157 | 
2026-03-20 00:28:35.052261 | TASK [ansible-test : set_fact]
2026-03-20 00:28:35.077574 | controller | skipping: Conditional result was False
2026-03-20 00:28:35.089793 | 
2026-03-20 00:28:35.089931 | TASK [ansible-test : List the targets]
2026-03-20 00:28:35.666531 | controller | skipping: Conditional result was False
2026-03-20 00:28:35.681771 | 
2026-03-20 00:28:35.681949 | TASK [ansible-test : set_fact]
2026-03-20 00:28:35.708990 | controller | skipping: Conditional result was False
2026-03-20 00:28:35.718022 | 
2026-03-20 00:28:35.718125 | TASK [ansible-test : set_fact]
2026-03-20 00:28:35.743551 | controller | skipping: Conditional result was False
2026-03-20 00:28:35.753548 | 
2026-03-20 00:28:35.753664 | LOOP [ansible-test : set_fact]
2026-03-20 00:28:35.860151 | 
2026-03-20 00:28:35.860339 | TASK [ansible-test : set_fact]
2026-03-20 00:28:35.885600 | controller | skipping: Conditional result was False
2026-03-20 00:28:35.892921 | 
2026-03-20 00:28:35.893013 | TASK [ansible-test : debug]
2026-03-20 00:28:35.917129 | controller | skipping: Conditional result was False
2026-03-20 00:28:35.924397 | 
2026-03-20 00:28:35.924483 | TASK [ansible-test : debug]
2026-03-20 00:28:35.954952 | About to run: ansible-test sanity --requirements --docker -v
2026-03-20 00:28:35.960981 | 
2026-03-20 00:28:35.961054 | TASK [ansible-test : Run the test suite]
2026-03-20 00:28:36.582173 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-03-20 00:28:36.646807 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-03-20 00:28:38.374379 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-03-20 00:28:38.374995 | controller | 171857c49d0f: Pulling fs layer
2026-03-20 00:28:38.376629 | controller | 419640447d26: Pulling fs layer
2026-03-20 00:28:38.376643 | controller | 61e52f862619: Pulling fs layer
2026-03-20 00:28:38.376649 | controller | 851de0426e26: Pulling fs layer
2026-03-20 00:28:38.376659 | controller | 37425716ea5b: Pulling fs layer
2026-03-20 00:28:38.376889 | controller | cd9b0c439616: Pulling fs layer
2026-03-20 00:28:38.376898 | controller | d4612dbd8855: Pulling fs layer
2026-03-20 00:28:38.376902 | controller | 554699364730: Pulling fs layer
2026-03-20 00:28:38.376906 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-03-20 00:28:38.376914 | controller | 5f2e4724adea: Pulling fs layer
2026-03-20 00:28:39.338320 | controller | a02d9a95ccb8: Pulling fs layer
2026-03-20 00:28:39.338359 | controller | 989f4ce370cb: Pulling fs layer
2026-03-20 00:28:39.338364 | controller | 0d7f52cdf9df: Pulling fs layer
2026-03-20 00:28:39.338369 | controller | c423742351d5: Pulling fs layer
2026-03-20 00:28:39.338374 | controller | 109236341d21: Pulling fs layer
2026-03-20 00:28:39.338378 | controller | 07a2f61c96b8: Pulling fs layer
2026-03-20 00:28:39.338382 | controller | 041f122f27ae: Pulling fs layer
2026-03-20 00:28:39.338387 | controller | ca6f5098b12e: Pulling fs layer
2026-03-20 00:28:39.338391 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-03-20 00:28:39.338395 | controller | 18a0b750727a: Pulling fs layer
2026-03-20 00:28:39.338403 | controller | 17f2f596716c: Pulling fs layer
2026-03-20 00:28:39.338413 | controller | f35a9998a9bd: Pulling fs layer
2026-03-20 00:28:39.338418 | controller | fdfc10566405: Pulling fs layer
2026-03-20 00:28:39.338422 | controller | 1ff1c442c5fe: Pulling fs layer
2026-03-20 00:28:39.338426 | controller | a3443c4a17ed: Pulling fs layer
2026-03-20 00:28:39.338430 | controller | 0edcf9fb2b15: Pulling fs layer
2026-03-20 00:28:39.338434 | controller | 5af43b69e845: Pulling fs layer
2026-03-20 00:28:39.338439 | controller | 1651773effb2: Pulling fs layer
2026-03-20 00:28:39.338443 | controller | 851de0426e26: Waiting
2026-03-20 00:28:39.338450 | controller | 79e23c5c0fe4: Pulling fs layer
2026-03-20 00:28:39.338454 | controller | e5058b1faaa6: Pulling fs layer
2026-03-20 00:28:39.338459 | controller | 846775490569: Pulling fs layer
2026-03-20 00:28:39.338463 | controller | 109236341d21: Waiting
2026-03-20 00:28:39.338467 | controller | 07a2f61c96b8: Waiting
2026-03-20 00:28:39.338471 | controller | 041f122f27ae: Waiting
2026-03-20 00:28:39.338475 | controller | ca6f5098b12e: Waiting
2026-03-20 00:28:39.338479 | controller | 1eb3e7b2afd9: Waiting
2026-03-20 00:28:39.338486 | controller | 18a0b750727a: Waiting
2026-03-20 00:28:39.338490 | controller | 17f2f596716c: Waiting
2026-03-20 00:28:39.338494 | controller | f35a9998a9bd: Waiting
2026-03-20 00:28:39.338498 | controller | fdfc10566405: Waiting
2026-03-20 00:28:39.338502 | controller | 1ff1c442c5fe: Waiting
2026-03-20 00:28:39.338507 | controller | 37425716ea5b: Waiting
2026-03-20 00:28:39.338511 | controller | cd9b0c439616: Waiting
2026-03-20 00:28:39.338515 | controller | d4612dbd8855: Waiting
2026-03-20 00:28:39.338519 | controller | a3443c4a17ed: Waiting
2026-03-20 00:28:39.338523 | controller | 554699364730: Waiting
2026-03-20 00:28:39.338527 | controller | 0edcf9fb2b15: Waiting
2026-03-20 00:28:39.338532 | controller | 1ff15d4bdb3e: Waiting
2026-03-20 00:28:39.338538 | controller | 5af43b69e845: Waiting
2026-03-20 00:28:39.338542 | controller | 5f2e4724adea: Waiting
2026-03-20 00:28:39.338546 | controller | 1651773effb2: Waiting
2026-03-20 00:28:39.338550 | controller | 79e23c5c0fe4: Waiting
2026-03-20 00:28:39.338554 | controller | e5058b1faaa6: Waiting
2026-03-20 00:28:39.338558 | controller | 846775490569: Waiting
2026-03-20 00:28:39.338563 | controller | a02d9a95ccb8: Waiting
2026-03-20 00:28:39.338567 | controller | c423742351d5: Waiting
2026-03-20 00:28:39.338578 | controller | 61e52f862619: Verifying Checksum
2026-03-20 00:28:39.347781 | controller | 61e52f862619: Download complete
2026-03-20 00:28:39.347853 | controller | 419640447d26: Verifying Checksum
2026-03-20 00:28:40.294257 | controller | 419640447d26: Download complete
2026-03-20 00:28:40.294306 | controller | 851de0426e26: Verifying Checksum
2026-03-20 00:28:40.465305 | controller | 851de0426e26: Download complete
2026-03-20 00:28:40.465336 | controller | 171857c49d0f: Verifying Checksum
2026-03-20 00:28:41.181106 | controller | 171857c49d0f: Download complete
2026-03-20 00:28:41.181153 | controller | cd9b0c439616: Verifying Checksum
2026-03-20 00:28:41.435649 | controller | cd9b0c439616: Download complete
2026-03-20 00:28:41.435706 | controller | d4612dbd8855: Verifying Checksum
2026-03-20 00:28:42.194914 | controller | d4612dbd8855: Download complete
2026-03-20 00:28:42.194962 | controller | 171857c49d0f: Pull complete
2026-03-20 00:28:42.213320 | controller | 419640447d26: Pull complete
2026-03-20 00:28:42.234933 | controller | 61e52f862619: Pull complete
2026-03-20 00:28:42.258661 | controller | 851de0426e26: Pull complete
2026-03-20 00:28:42.348341 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-03-20 00:28:43.370476 | controller | 1ff15d4bdb3e: Download complete
2026-03-20 00:28:43.370527 | controller | 5f2e4724adea: Verifying Checksum
2026-03-20 00:28:44.422445 | controller | 5f2e4724adea: Download complete
2026-03-20 00:28:44.422502 | controller | a02d9a95ccb8: Verifying Checksum
2026-03-20 00:28:45.391899 | controller | a02d9a95ccb8: Download complete
2026-03-20 00:28:45.391951 | controller | 989f4ce370cb: Verifying Checksum
2026-03-20 00:28:45.392019 | controller | 989f4ce370cb: Download complete
2026-03-20 00:28:45.462135 | controller | 554699364730: Verifying Checksum
2026-03-20 00:28:45.462200 | controller | 554699364730: Download complete
2026-03-20 00:28:46.312818 | controller | c423742351d5: Verifying Checksum
2026-03-20 00:28:46.312996 | controller | c423742351d5: Download complete
2026-03-20 00:28:46.511986 | controller | 0d7f52cdf9df: Verifying Checksum
2026-03-20 00:28:46.512144 | controller | 0d7f52cdf9df: Download complete
2026-03-20 00:28:47.393438 | controller | 07a2f61c96b8: Verifying Checksum
2026-03-20 00:28:48.497497 | controller | 07a2f61c96b8: Download complete
2026-03-20 00:28:48.497577 | controller | 041f122f27ae: Verifying Checksum
2026-03-20 00:28:49.280579 | controller | 041f122f27ae: Download complete
2026-03-20 00:28:49.280652 | controller | 109236341d21: Verifying Checksum
2026-03-20 00:28:49.558664 | controller | 109236341d21: Download complete
2026-03-20 00:28:49.558709 | controller | ca6f5098b12e: Verifying Checksum
2026-03-20 00:28:50.234817 | controller | ca6f5098b12e: Download complete
2026-03-20 00:28:50.234865 | controller | 1eb3e7b2afd9: Verifying Checksum
2026-03-20 00:28:50.621504 | controller | 1eb3e7b2afd9: Download complete
2026-03-20 00:28:50.621558 | controller | 18a0b750727a: Verifying Checksum
2026-03-20 00:28:51.129917 | controller | 18a0b750727a: Download complete
2026-03-20 00:28:51.129984 | controller | 17f2f596716c: Verifying Checksum
2026-03-20 00:28:51.550892 | controller | 17f2f596716c: Download complete
2026-03-20 00:28:51.550939 | controller | f35a9998a9bd: Verifying Checksum
2026-03-20 00:28:52.120871 | controller | f35a9998a9bd: Download complete
2026-03-20 00:28:52.120944 | controller | fdfc10566405: Verifying Checksum
2026-03-20 00:28:52.625180 | controller | fdfc10566405: Download complete
2026-03-20 00:28:52.625243 | controller | 1ff1c442c5fe: Verifying Checksum
2026-03-20 00:28:52.625382 | controller | 1ff1c442c5fe: Download complete
2026-03-20 00:28:54.291981 | controller | a3443c4a17ed: Verifying Checksum
2026-03-20 00:28:54.328031 | controller | a3443c4a17ed: Download complete
2026-03-20 00:28:54.328085 | controller | 0edcf9fb2b15: Verifying Checksum
2026-03-20 00:28:56.110524 | controller | 0edcf9fb2b15: Download complete
2026-03-20 00:28:56.110628 | controller | 1651773effb2: Download complete
2026-03-20 00:28:56.306951 | controller | 5af43b69e845: Verifying Checksum
2026-03-20 00:28:57.645255 | controller | 5af43b69e845: Download complete
2026-03-20 00:28:57.645307 | controller | e5058b1faaa6: Verifying Checksum
2026-03-20 00:28:57.673216 | controller | e5058b1faaa6: Download complete
2026-03-20 00:28:57.673270 | controller | 79e23c5c0fe4: Verifying Checksum
2026-03-20 00:28:59.458357 | controller | 79e23c5c0fe4: Download complete
2026-03-20 00:28:59.458434 | controller | 846775490569: Verifying Checksum
2026-03-20 00:28:59.458527 | controller | 846775490569: Download complete
2026-03-20 00:29:01.592216 | controller | 37425716ea5b: Download complete
2026-03-20 00:29:12.836396 | controller | 37425716ea5b: Pull complete
2026-03-20 00:29:12.873369 | controller | cd9b0c439616: Pull complete
2026-03-20 00:29:12.906928 | controller | d4612dbd8855: Pull complete
2026-03-20 00:29:15.481220 | controller | 554699364730: Pull complete
2026-03-20 00:29:15.507169 | controller | 1ff15d4bdb3e: Pull complete
2026-03-20 00:29:15.528090 | controller | 5f2e4724adea: Pull complete
2026-03-20 00:29:15.563761 | controller | a02d9a95ccb8: Pull complete
2026-03-20 00:29:15.586004 | controller | 989f4ce370cb: Pull complete
2026-03-20 00:29:15.606238 | controller | 0d7f52cdf9df: Pull complete
2026-03-20 00:29:15.626481 | controller | c423742351d5: Pull complete
2026-03-20 00:29:16.823443 | controller | 109236341d21: Pull complete
2026-03-20 00:29:16.843478 | controller | 07a2f61c96b8: Pull complete
2026-03-20 00:29:16.864291 | controller | 041f122f27ae: Pull complete
2026-03-20 00:29:16.970744 | controller | ca6f5098b12e: Pull complete
2026-03-20 00:29:17.018054 | controller | 1eb3e7b2afd9: Pull complete
2026-03-20 00:29:17.060823 | controller | 18a0b750727a: Pull complete
2026-03-20 00:29:17.082686 | controller | 17f2f596716c: Pull complete
2026-03-20 00:29:17.109782 | controller | f35a9998a9bd: Pull complete
2026-03-20 00:29:17.136221 | controller | fdfc10566405: Pull complete
2026-03-20 00:29:17.157231 | controller | 1ff1c442c5fe: Pull complete
2026-03-20 00:29:18.096958 | controller | a3443c4a17ed: Pull complete
2026-03-20 00:29:19.224736 | controller | 0edcf9fb2b15: Pull complete
2026-03-20 00:29:20.868135 | controller | 5af43b69e845: Pull complete
2026-03-20 00:29:23.290926 | controller | 1651773effb2: Pull complete
2026-03-20 00:29:25.187482 | controller | 79e23c5c0fe4: Pull complete
2026-03-20 00:29:26.289815 | controller | e5058b1faaa6: Pull complete
2026-03-20 00:29:27.823263 | controller | 846775490569: Pull complete
2026-03-20 00:29:27.863209 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-03-20 00:29:27.870035 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-03-20 00:29:27.883963 | controller | quay.io/ansible/default-test-container:2.9.0
2026-03-20 00:29:27.887161 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-03-20 00:29:27.905325 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-03-20 00:29:29.771357 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-03-20 00:29:29.771558 | controller | 04a5f4cda3ee: Pulling fs layer
2026-03-20 00:29:29.771711 | controller | ff496a88c8ed: Pulling fs layer
2026-03-20 00:29:29.772170 | controller | 0ce83f459fe7: Pulling fs layer
2026-03-20 00:29:29.772249 | controller | 2e5170e1f099: Pulling fs layer
2026-03-20 00:29:29.772362 | controller | 7641eb41b08c: Pulling fs layer
2026-03-20 00:29:29.772429 | controller | ad15fa9da398: Pulling fs layer
2026-03-20 00:29:29.772494 | controller | 087d91352424: Pulling fs layer
2026-03-20 00:29:29.772594 | controller | 8b92efd6a100: Pulling fs layer
2026-03-20 00:29:29.772694 | controller | ad15fa9da398: Waiting
2026-03-20 00:29:29.772758 | controller | 087d91352424: Waiting
2026-03-20 00:29:29.772820 | controller | 8b92efd6a100: Waiting
2026-03-20 00:29:29.772889 | controller | 2e5170e1f099: Waiting
2026-03-20 00:29:29.772952 | controller | 7641eb41b08c: Waiting
2026-03-20 00:29:30.659542 | controller | 0ce83f459fe7: Verifying Checksum
2026-03-20 00:29:30.659777 | controller | ff496a88c8ed: Verifying Checksum
2026-03-20 00:29:30.659928 | controller | ff496a88c8ed: Download complete
2026-03-20 00:29:30.660104 | controller | 0ce83f459fe7: Download complete
2026-03-20 00:29:31.623757 | controller | 7641eb41b08c: Verifying Checksum
2026-03-20 00:29:31.624000 | controller | 7641eb41b08c: Download complete
2026-03-20 00:29:31.994006 | controller | 04a5f4cda3ee: Verifying Checksum
2026-03-20 00:29:31.994165 | controller | 04a5f4cda3ee: Download complete
2026-03-20 00:29:32.199567 | controller | 2e5170e1f099: Verifying Checksum
2026-03-20 00:29:32.199765 | controller | 2e5170e1f099: Download complete
2026-03-20 00:29:32.565359 | controller | ad15fa9da398: Verifying Checksum
2026-03-20 00:29:32.565444 | controller | ad15fa9da398: Download complete
2026-03-20 00:29:33.178653 | controller | 8b92efd6a100: Verifying Checksum
2026-03-20 00:29:33.178758 | controller | 8b92efd6a100: Download complete
2026-03-20 00:29:33.200944 | controller | 087d91352424: Verifying Checksum
2026-03-20 00:29:33.201071 | controller | 087d91352424: Download complete
2026-03-20 00:29:33.201903 | controller | 04a5f4cda3ee: Pull complete
2026-03-20 00:29:33.237023 | controller | ff496a88c8ed: Pull complete
2026-03-20 00:29:33.418137 | controller | 0ce83f459fe7: Pull complete
2026-03-20 00:29:34.346928 | controller | 2e5170e1f099: Pull complete
2026-03-20 00:29:34.370110 | controller | 7641eb41b08c: Pull complete
2026-03-20 00:29:34.395944 | controller | ad15fa9da398: Pull complete
2026-03-20 00:29:35.023653 | controller | 087d91352424: Pull complete
2026-03-20 00:29:35.053330 | controller | 8b92efd6a100: Pull complete
2026-03-20 00:29:35.068651 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-03-20 00:29:35.080914 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-03-20 00:29:35.088896 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-03-20 00:29:35.091927 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-03-20 00:29:42.937286 | controller | Run command: docker inspect 78751475be712cbf4bdefd7f6c29822b4bb0cfc29a1cfbb463d1762e8eb5cc94
2026-03-20 00:29:42.971263 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-03-20 00:29:42.979502 | controller | Including collection: ansible.netcommon (278 files)
2026-03-20 00:29:42.986977 | controller | Including collection: ansible.network (23 files)
2026-03-20 00:29:42.986997 | controller | Including collection: ansible.utils (441 files)
2026-03-20 00:29:43.011797 | controller | Including collection: arista.eos (1031 files)
2026-03-20 00:29:43.040926 | controller | Including collection: cisco.ios (1215 files)
2026-03-20 00:29:43.068650 | controller | Including collection: cisco.iosxr (1044 files)
2026-03-20 00:29:43.114760 | controller | Including collection: cisco.nxos (1687 files)
2026-03-20 00:29:43.128236 | controller | Including collection: juniper.device (391 files)
2026-03-20 00:29:43.148317 | controller | Including collection: junipernetworks.junos (700 files)
2026-03-20 00:29:43.172121 | controller | Creating a payload archive containing 7536 files...
2026-03-20 00:29:44.705235 | controller | Created a 6222366 byte payload archive containing 7536 files in 1 seconds.
2026-03-20 00:29:44.706398 | controller | Assuming Docker is available on localhost.
2026-03-20 00:29:44.706671 | 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-20 00:29:45.113131 | controller | Run command: docker exec -i de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac dd of=/root/docker.sh bs=65536
2026-03-20 00:29:45.166248 | controller | Run command: docker exec de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac /bin/bash /root/docker.sh
2026-03-20 00:29:45.223621 | controller | Run command: docker exec -i de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac dd of=/root/test.tgz bs=65536
2026-03-20 00:29:45.292837 | controller | Run command: docker exec de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac tar oxzf /root/test.tgz -C /root
2026-03-20 00:29:45.922546 | controller | Run command: docker exec de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac /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-2l0b7i6x.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-03-20 00:29:46.178679 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-03-20 00:29:46.178826 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-03-20 00:29:46.178862 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-03-20 00:29:46.180226 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-03-20 00:29:46.180744 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-03-20 00:29:46.185716 | controller | No tests applicable.
2026-03-20 00:29:46.185769 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-03-20 00:29:46.185800 | controller | No tests applicable.
2026-03-20 00:29:46.185829 | controller | Running sanity test 'changelog' with Python 3.6
2026-03-20 00:29:46.186014 | 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-20 00:29:46.755401 | 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-20 00:29:47.128577 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-20 00:29:47.168132 | 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-20 00:29:47.169320 | 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-20 00:29:47.208713 | 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-20 00:29:47.208891 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-03-20 00:29:47.208982 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-03-20 00:29:47.209016 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-03-20 00:29:47.226357 | controller | Run command: docker exec de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-03-20 00:29:47.281917 | controller | Run command: docker exec -i de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac dd if=/root/output.tgz bs=65536
2026-03-20 00:29:47.336321 | controller | Run command: tar oxzf /tmp/ansible-result-9k64tuzv.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-03-20 00:29:47.345223 | controller | Run command: docker rm -f 78751475be712cbf4bdefd7f6c29822b4bb0cfc29a1cfbb463d1762e8eb5cc94
2026-03-20 00:29:48.027634 | controller | Run command: docker rm -f de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac
2026-03-20 00:29:48.450085 | controller | ERROR: Command "docker exec de6ec31cf3db62f513218fae69a78b26dc97ead27ba83ff4963aa912abbac6ac /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-2l0b7i6x.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-03-20 00:29:48.544756 | controller | ERROR
2026-03-20 00:29:48.544987 | controller | {
2026-03-20 00:29:48.545030 | controller |   "delta": "0:01:12.100018",
2026-03-20 00:29:48.545058 | controller |   "end": "2026-03-20 00:29:48.481497",
2026-03-20 00:29:48.545084 | controller |   "msg": "non-zero return code",
2026-03-20 00:29:48.545143 | controller |   "rc": 1,
2026-03-20 00:29:48.545172 | controller |   "start": "2026-03-20 00:28:36.381479"
2026-03-20 00:29:48.545198 | controller | }
failure
2026-03-20 00:29:48.552049 | 
2026-03-20 00:29:48.552121 | PLAY RECAP
2026-03-20 00:29:48.552183 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-03-20 00:29:48.552215 | 
2026-03-20 00:29:48.714103 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-20 00:29:48.716052 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-20 00:29:49.376931 | 
2026-03-20 00:29:49.377134 | PLAY [all]
2026-03-20 00:29:49.402787 | 
2026-03-20 00:29:49.402937 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-20 00:29:49.816989 | controller | changed: non-zero return code
2026-03-20 00:29:49.828204 | 
2026-03-20 00:29:49.828319 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-20 00:29:49.853183 | controller | skipping: Conditional result was False
2026-03-20 00:29:49.861978 | 
2026-03-20 00:29:49.862071 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-20 00:29:49.894492 | 
2026-03-20 00:29:49.894650 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-20 00:29:49.926378 | 
2026-03-20 00:29:49.926562 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-20 00:29:49.940937 | controller | skipping: Conditional result was False
2026-03-20 00:29:49.949533 | 
2026-03-20 00:29:49.949639 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-20 00:29:49.973993 | 
2026-03-20 00:29:49.974214 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-20 00:29:49.989750 | controller | skipping: Conditional result was False
2026-03-20 00:29:49.998596 | 
2026-03-20 00:29:49.998722 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-20 00:29:50.014052 | controller | skipping: Conditional result was False
2026-03-20 00:29:50.024561 | 
2026-03-20 00:29:50.024731 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-20 00:29:50.039820 | controller | skipping: Conditional result was False
2026-03-20 00:29:50.069276 | 
2026-03-20 00:29:50.069379 | PLAY RECAP
2026-03-20 00:29:50.069423 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-20 00:29:50.069444 | 
2026-03-20 00:29:50.180327 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-20 00:29:50.181344 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-20 00:29:50.772874 | 
2026-03-20 00:29:50.773032 | PLAY [all:!appliance*]
2026-03-20 00:29:50.799257 | 
2026-03-20 00:29:50.799372 | TASK [unregister the node]
2026-03-20 00:29:51.330963 | controller | skipping: Conditional result was False
2026-03-20 00:29:51.339664 | 
2026-03-20 00:29:51.339808 | TASK [include_role : fetch-output]
2026-03-20 00:29:51.394582 | controller | ok
2026-03-20 00:29:51.426225 | 
2026-03-20 00:29:51.426378 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-20 00:29:51.483316 | controller | skipping: Conditional result was False
2026-03-20 00:29:51.491298 | 
2026-03-20 00:29:51.491432 | TASK [fetch-output : Set log path for single node]
2026-03-20 00:29:51.522011 | controller | ok
2026-03-20 00:29:51.528392 | 
2026-03-20 00:29:51.528518 | LOOP [fetch-output : Ensure local output dirs]
2026-03-20 00:29:51.937090 | controller -> localhost | ok: "/var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/logs"
2026-03-20 00:29:52.208440 | controller -> localhost | changed: "/var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/artifacts"
2026-03-20 00:29:52.454360 | controller -> localhost | changed: "/var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/work/docs"
2026-03-20 00:29:52.469116 | 
2026-03-20 00:29:52.469242 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-20 00:29:53.231850 | controller | changed:
2026-03-20 00:29:53.241792 | controller | .d..t...... ./
2026-03-20 00:29:53.241920 | controller | cd+++++++++ controller/
2026-03-20 00:29:53.241998 | controller | changed: All items complete
2026-03-20 00:29:53.242038 | 
2026-03-20 00:29:53.779837 | controller | changed: .d..t...... ./
2026-03-20 00:29:54.288266 | controller | changed: .d..t...... ./
2026-03-20 00:29:54.307403 | 
2026-03-20 00:29:54.307529 | TASK [include_role : fetch-output-openshift]
2026-03-20 00:29:54.341422 | controller | skipping: Conditional result was False
2026-03-20 00:29:54.348229 | 
2026-03-20 00:29:54.348324 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-20 00:29:54.384863 | controller | skipping: Conditional result was False
2026-03-20 00:29:54.385657 | controller | skipping: Conditional result was False
2026-03-20 00:29:54.421955 | 
2026-03-20 00:29:54.422061 | PLAY [localhost]
2026-03-20 00:29:54.434400 | 
2026-03-20 00:29:54.434475 | TASK [Run Zuul manifest role]
2026-03-20 00:29:54.453650 | localhost | ok
2026-03-20 00:29:54.471133 | 
2026-03-20 00:29:54.471267 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-20 00:29:54.836493 | localhost | changed
2026-03-20 00:29:54.841230 | 
2026-03-20 00:29:54.841339 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-20 00:29:54.869966 | localhost | ok
2026-03-20 00:29:54.879400 | 
2026-03-20 00:29:54.879514 | TASK [Set zuul-log-path fact]
2026-03-20 00:29:54.897847 | localhost | ok
2026-03-20 00:29:54.914832 | 
2026-03-20 00:29:54.914954 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-20 00:29:54.943244 | localhost | ok
2026-03-20 00:29:54.956657 | 
2026-03-20 00:29:54.956962 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-20 00:29:54.995942 | localhost | ok:
2026-03-20 00:29:54.996141 | localhost | {
2026-03-20 00:29:54.996168 | localhost |   "auth": {
2026-03-20 00:29:54.996193 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-20 00:29:54.996212 | localhost |     "username": "9LF91O0RK7JU"
2026-03-20 00:29:54.996230 | localhost |   },
2026-03-20 00:29:54.996248 | localhost |   "profile": "rackspace",
2026-03-20 00:29:54.996265 | localhost |   "project_name": "1251317",
2026-03-20 00:29:54.996282 | localhost |   "region_name": "DFW"
2026-03-20 00:29:54.996299 | localhost | }
2026-03-20 00:29:54.996324 | 
2026-03-20 00:29:55.009935 | 
2026-03-20 00:29:55.010041 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-20 00:29:55.028404 | localhost | Output suppressed because no_log was given
2026-03-20 00:29:55.043494 | 
2026-03-20 00:29:55.043624 | TASK [upload-logs-failover : Initialize control vars]
2026-03-20 00:29:55.073544 | localhost | ok
2026-03-20 00:29:55.114621 | 
2026-03-20 00:29:55.114760 | LOOP [upload-logs-failover : Perform log upload]
2026-03-20 00:29:55.140023 | localhost | Output suppressed because no_log was given
2026-03-20 00:29:55.148401 | localhost | included: /var/lib/zuul/builds/0f9415ced5f14b6abcf6967f0743adfb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-20 00:29:55.156530 | 
2026-03-20 00:29:55.156665 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-20 00:29:55.242858 | localhost | Output suppressed because no_log was given
2026-03-20 00:29:55.249938 | 
2026-03-20 00:29:55.250064 | TASK [Upload logs to swift]
2026-03-20 00:29:55.270006 | localhost | ok
2026-03-20 00:29:55.305529 | 
2026-03-20 00:29:55.305669 | TASK [Set zuul-log-path fact]
2026-03-20 00:29:55.330765 | localhost | skipping: Conditional result was False
2026-03-20 00:29:55.336792 | 
2026-03-20 00:29:55.336915 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-20 00:29:55.719731 | localhost -> localhost | ok: Runtime: 0:00:00.006300
2026-03-20 00:29:55.727693 | 
2026-03-20 00:29:55.727818 | TASK [upload-logs-swift : Upload logs to swift]