2025-11-10 00:07:04.069426 | Job console starting...
2025-11-10 00:07:04.077836 | Updating repositories
2025-11-10 00:07:04.253732 | Preparing job workspace
2025-11-10 00:07:08.649441 | Running Ansible setup...
2025-11-10 00:07:16.505295 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-10 00:07:17.137117 | 
2025-11-10 00:07:17.137275 | PLAY [localhost]
2025-11-10 00:07:17.147041 | 
2025-11-10 00:07:17.147137 | TASK [Gathering Facts]
2025-11-10 00:07:18.198262 | localhost | ok
2025-11-10 00:07:18.232670 | 
2025-11-10 00:07:18.232916 | TASK [Setup log path fact]
2025-11-10 00:07:18.264369 | localhost | ok
2025-11-10 00:07:18.289849 | 
2025-11-10 00:07:18.290007 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-10 00:07:18.330883 | localhost | ok
2025-11-10 00:07:18.342067 | 
2025-11-10 00:07:18.342166 | TASK [emit-job-header : Print job information]
2025-11-10 00:07:18.393214 | # Job Information
2025-11-10 00:07:18.393416 | Ansible Version: 2.15.12
2025-11-10 00:07:18.393459 | Job: ansible-test-sanity-docker-stable-2.10
2025-11-10 00:07:18.393494 | Pipeline: periodic
2025-11-10 00:07:18.393521 | Executor: ze04.softwarefactory-project.io
2025-11-10 00:07:18.393547 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-10 00:07:18.393577 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/949/ansible/949d8b56e7484ede81f3659e1db5f32d/
2025-11-10 00:07:18.393604 | Event ID: e7ed9acfd5d246fc9eb02e45c4dd8a00
2025-11-10 00:07:18.398639 | 
2025-11-10 00:07:18.398772 | LOOP [emit-job-header : Print node information]
2025-11-10 00:07:18.555483 | localhost | ok:
2025-11-10 00:07:18.555878 | localhost | # Node Information
2025-11-10 00:07:18.555947 | localhost | Inventory Hostname: controller
2025-11-10 00:07:18.555994 | localhost | Hostname: np0005516254
2025-11-10 00:07:18.556038 | localhost | Username: zuul
2025-11-10 00:07:18.556089 | localhost | Distro: Fedora 37
2025-11-10 00:07:18.556133 | localhost | Provider: ansible-vexxhost-ams1
2025-11-10 00:07:18.556176 | localhost | Region: ams1
2025-11-10 00:07:18.556216 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-10 00:07:18.556256 | localhost | Product Name: OpenStack Nova
2025-11-10 00:07:18.556296 | localhost | Interface IP: 38.129.16.70
2025-11-10 00:07:18.569669 | 
2025-11-10 00:07:18.569870 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-10 00:07:19.087151 | localhost -> localhost | changed
2025-11-10 00:07:19.100238 | 
2025-11-10 00:07:19.100392 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-10 00:07:20.106852 | localhost -> localhost | changed
2025-11-10 00:07:20.141655 | 
2025-11-10 00:07:20.141881 | PLAY [all:!appliance*]
2025-11-10 00:07:20.167731 | 
2025-11-10 00:07:20.167838 | TASK [include_role : start-zuul-console]
2025-11-10 00:07:20.188735 | controller | ok
2025-11-10 00:07:20.202184 | 
2025-11-10 00:07:20.202250 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-10 00:07:21.244131 | controller | ok
2025-11-10 00:07:21.261934 | 
2025-11-10 00:07:21.262099 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-10 00:07:23.314616 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-10 00:07:23.325887 | 
2025-11-10 00:07:23.326030 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-10 00:07:23.707758 | controller | skipping: Conditional result was False
2025-11-10 00:07:23.718812 | 
2025-11-10 00:07:23.718932 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-10 00:07:23.749398 | controller | skipping: Conditional result was False
2025-11-10 00:07:23.759241 | 
2025-11-10 00:07:23.759360 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-10 00:07:23.784183 | controller | skipping: Conditional result was False
2025-11-10 00:07:23.799125 | 
2025-11-10 00:07:23.799426 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-10 00:07:23.816276 | controller | skipping: Conditional result was False
2025-11-10 00:07:23.826719 | 
2025-11-10 00:07:23.826837 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-10 00:07:23.852492 | controller | skipping: Conditional result was False
2025-11-10 00:07:23.861807 | 
2025-11-10 00:07:23.861906 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-10 00:07:23.887697 | controller | skipping: Conditional result was False
2025-11-10 00:07:23.904237 | 
2025-11-10 00:07:23.904327 | TASK [Disable Fedora Modular]
2025-11-10 00:07:25.214281 | controller | changed
2025-11-10 00:07:25.228292 | 
2025-11-10 00:07:25.228480 | TASK [Enable EPEL]
2025-11-10 00:07:25.267452 | controller | skipping: Conditional result was False
2025-11-10 00:07:25.282791 | 
2025-11-10 00:07:25.282989 | TASK [Register the RHEL node]
2025-11-10 00:07:25.713783 | 
2025-11-10 00:07:25.714031 | TASK [Show the subscription-manager status]
2025-11-10 00:07:26.091772 | controller | skipping: Conditional result was False
2025-11-10 00:07:26.106485 | 
2025-11-10 00:07:26.106666 | TASK [Enable EPEL on RHEL]
2025-11-10 00:07:26.487070 | controller | skipping: Conditional result was False
2025-11-10 00:07:26.502536 | 
2025-11-10 00:07:26.502724 | TASK [Install git and tox]
2025-11-10 00:09:45.883931 | controller | changed
2025-11-10 00:09:45.891641 | 
2025-11-10 00:09:45.891777 | TASK [include_role : prepare-workspace]
2025-11-10 00:09:45.926740 | controller | ok
2025-11-10 00:09:45.957595 | 
2025-11-10 00:09:45.957785 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-10 00:09:46.866493 | controller | ok
2025-11-10 00:09:46.875607 | 
2025-11-10 00:09:46.875733 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-10 00:10:07.310986 | controller | Output suppressed because no_log was given
2025-11-10 00:10:07.326504 | 
2025-11-10 00:10:07.326647 | TASK [include_role : prepare-workspace-openshift]
2025-11-10 00:10:07.352103 | controller | skipping: Conditional result was False
2025-11-10 00:10:07.378222 | 
2025-11-10 00:10:07.378362 | PLAY [all:!appliance]
2025-11-10 00:10:07.436483 | 
2025-11-10 00:10:07.436604 | TASK [Run add-build-sshkey role (RSA)]
2025-11-10 00:10:07.466728 | controller | ok
2025-11-10 00:10:07.482885 | 
2025-11-10 00:10:07.483023 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-10 00:10:07.753848 | controller -> localhost | ok
2025-11-10 00:10:07.771167 | 
2025-11-10 00:10:07.771359 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-10 00:10:07.810357 | controller | ok
2025-11-10 00:10:07.825473 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-10 00:10:07.832214 | 
2025-11-10 00:10:07.832284 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-10 00:10:08.435636 | controller -> localhost | Generating public/private rsa key pair.
2025-11-10 00:10:08.436101 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/949d8b56e7484ede81f3659e1db5f32d_id_rsa.
2025-11-10 00:10:08.436169 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/949d8b56e7484ede81f3659e1db5f32d_id_rsa.pub.
2025-11-10 00:10:08.436215 | controller -> localhost | The key fingerprint is:
2025-11-10 00:10:08.436258 | controller -> localhost | SHA256:NiHZJmbC7G80qAsKG+7v/O/36CTR1JhrNeBENoBZvzM zuul-build-sshkey
2025-11-10 00:10:08.436300 | controller -> localhost | The key's randomart image is:
2025-11-10 00:10:08.436341 | controller -> localhost | +---[RSA 2048]----+
2025-11-10 00:10:08.436382 | controller -> localhost | |      +oo*       |
2025-11-10 00:10:08.436423 | controller -> localhost | |   o o o= *      |
2025-11-10 00:10:08.436463 | controller -> localhost | |    + * +* +     |
2025-11-10 00:10:08.436502 | controller -> localhost | |   . = +o.+ .    |
2025-11-10 00:10:08.436540 | controller -> localhost | |    o o.SE       |
2025-11-10 00:10:08.436625 | controller -> localhost | |   . o oo.o      |
2025-11-10 00:10:08.436703 | controller -> localhost | |+ .   o. .       |
2025-11-10 00:10:08.437358 | controller -> localhost | |++.. .  o..      |
2025-11-10 00:10:08.437414 | controller -> localhost | |+o++..oooo..     |
2025-11-10 00:10:08.437464 | controller -> localhost | +----[SHA256]-----+
2025-11-10 00:10:08.437585 | controller -> localhost | ok: Runtime: 0:00:00.155641
2025-11-10 00:10:08.453589 | 
2025-11-10 00:10:08.453757 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-10 00:10:08.493474 | controller | ok
2025-11-10 00:10:08.514768 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-10 00:10:08.527304 | 
2025-11-10 00:10:08.527395 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-10 00:10:08.553241 | controller | skipping: Conditional result was False
2025-11-10 00:10:08.562492 | 
2025-11-10 00:10:08.562579 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-10 00:10:09.626494 | controller | changed
2025-11-10 00:10:09.641347 | 
2025-11-10 00:10:09.641507 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-10 00:10:10.312522 | controller | ok
2025-11-10 00:10:10.327301 | 
2025-11-10 00:10:10.327850 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-10 00:10:13.438959 | controller | changed
2025-11-10 00:10:13.453088 | 
2025-11-10 00:10:13.453228 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-10 00:10:16.581557 | controller | changed
2025-11-10 00:10:16.594590 | 
2025-11-10 00:10:16.594758 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-10 00:10:16.621961 | controller | skipping: Conditional result was False
2025-11-10 00:10:16.635937 | 
2025-11-10 00:10:16.636071 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-10 00:10:17.090714 | controller -> localhost | changed
2025-11-10 00:10:17.115189 | 
2025-11-10 00:10:17.115333 | TASK [add-build-sshkey : Add back temp key]
2025-11-10 00:10:17.409346 | controller -> localhost | Identity added: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/949d8b56e7484ede81f3659e1db5f32d_id_rsa (zuul-build-sshkey)
2025-11-10 00:10:17.409808 | controller -> localhost | ok: Runtime: 0:00:00.013851
2025-11-10 00:10:17.426182 | 
2025-11-10 00:10:17.426451 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-10 00:10:18.499615 | controller | ok
2025-11-10 00:10:18.512556 | 
2025-11-10 00:10:18.512731 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-10 00:10:18.561153 | controller | skipping: Conditional result was False
2025-11-10 00:10:18.587807 | 
2025-11-10 00:10:18.588048 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-10 00:10:18.634162 | controller | ok
2025-11-10 00:10:18.677983 | 
2025-11-10 00:10:18.678185 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-10 00:10:18.961468 | controller -> localhost | ok
2025-11-10 00:10:18.975885 | 
2025-11-10 00:10:18.976039 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-10 00:10:19.016088 | controller | ok
2025-11-10 00:10:19.039943 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-10 00:10:19.050062 | 
2025-11-10 00:10:19.050148 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-10 00:10:19.494161 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-10 00:10:19.494490 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/949d8b56e7484ede81f3659e1db5f32d_id_ecdsa.
2025-11-10 00:10:19.494552 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/949d8b56e7484ede81f3659e1db5f32d_id_ecdsa.pub.
2025-11-10 00:10:19.494616 | controller -> localhost | The key fingerprint is:
2025-11-10 00:10:19.494663 | controller -> localhost | SHA256:SL4fBMZJXo9jvqTEHOl/YswiSjt8hQWS5aZ4jKTwiV8 zuul-build-sshkey
2025-11-10 00:10:19.494756 | controller -> localhost | The key's randomart image is:
2025-11-10 00:10:19.494803 | controller -> localhost | +---[ECDSA 521]---+
2025-11-10 00:10:19.494846 | controller -> localhost | |   o. . .        |
2025-11-10 00:10:19.494889 | controller -> localhost | |  o..+ + o       |
2025-11-10 00:10:19.494931 | controller -> localhost | |.. .o.X + .      |
2025-11-10 00:10:19.494972 | controller -> localhost | |+* + B.* .       |
2025-11-10 00:10:19.495012 | controller -> localhost | |= * EoB S        |
2025-11-10 00:10:19.495054 | controller -> localhost | | o ....O .       |
2025-11-10 00:10:19.495095 | controller -> localhost | | .o ..+ O .      |
2025-11-10 00:10:19.495135 | controller -> localhost | | .oo.. + +       |
2025-11-10 00:10:19.495174 | controller -> localhost | |  oo    .        |
2025-11-10 00:10:19.495214 | controller -> localhost | +----[SHA256]-----+
2025-11-10 00:10:19.495314 | controller -> localhost | ok: Runtime: 0:00:00.018549
2025-11-10 00:10:19.510233 | 
2025-11-10 00:10:19.510374 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-10 00:10:19.541264 | controller | ok
2025-11-10 00:10:19.556821 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-10 00:10:19.569589 | 
2025-11-10 00:10:19.569704 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-10 00:10:19.595838 | controller | skipping: Conditional result was False
2025-11-10 00:10:19.606126 | 
2025-11-10 00:10:19.606216 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-10 00:10:20.533406 | controller | changed
2025-11-10 00:10:20.547643 | 
2025-11-10 00:10:20.547841 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-10 00:10:21.257994 | controller | ok
2025-11-10 00:10:21.328844 | 
2025-11-10 00:10:21.329085 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-10 00:10:24.515086 | controller | changed
2025-11-10 00:10:24.525807 | 
2025-11-10 00:10:24.525878 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-10 00:10:27.678211 | controller | changed
2025-11-10 00:10:27.693835 | 
2025-11-10 00:10:27.693996 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-10 00:10:27.732754 | controller | skipping: Conditional result was False
2025-11-10 00:10:27.748617 | 
2025-11-10 00:10:27.748841 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-10 00:10:28.076064 | controller -> localhost | changed
2025-11-10 00:10:28.102149 | 
2025-11-10 00:10:28.102253 | TASK [add-build-sshkey : Add back temp key]
2025-11-10 00:10:28.444596 | controller -> localhost | Identity added: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/949d8b56e7484ede81f3659e1db5f32d_id_ecdsa (zuul-build-sshkey)
2025-11-10 00:10:28.445039 | controller -> localhost | ok: Runtime: 0:00:00.016804
2025-11-10 00:10:28.460004 | 
2025-11-10 00:10:28.460141 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-10 00:10:29.246808 | controller | ok
2025-11-10 00:10:29.254733 | 
2025-11-10 00:10:29.254814 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-10 00:10:29.301287 | controller | skipping: Conditional result was False
2025-11-10 00:10:29.329230 | 
2025-11-10 00:10:29.329392 | TASK [include_role : remove-zuul-sshkey]
2025-11-10 00:10:29.365090 | controller | skipping: Conditional result was False
2025-11-10 00:10:29.374140 | 
2025-11-10 00:10:29.374232 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-10 00:10:30.109313 | controller | ok: "logs"
2025-11-10 00:10:30.109641 | controller | ok: All items complete
2025-11-10 00:10:30.109706 | 
2025-11-10 00:10:30.739745 | controller | ok: "artifacts"
2025-11-10 00:10:31.370594 | controller | ok: "docs"
2025-11-10 00:10:31.389190 | 
2025-11-10 00:10:31.389436 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-10 00:10:32.065462 | controller | changed: "logs"
2025-11-10 00:10:32.721054 | controller | changed: "artifacts"
2025-11-10 00:10:33.366199 | controller | changed: "docs"
2025-11-10 00:10:33.427880 | 
2025-11-10 00:10:33.428017 | PLAY RECAP
2025-11-10 00:10:33.428079 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-10 00:10:33.428117 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-10 00:10:33.428141 | 
2025-11-10 00:10:33.559819 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-10 00:10:33.562418 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-10 00:10:34.177095 | 
2025-11-10 00:10:34.177282 | PLAY [all]
2025-11-10 00:10:34.199383 | 
2025-11-10 00:10:34.199475 | TASK [Install binary dependencies]
2025-11-10 00:10:34.259254 | controller | ok
2025-11-10 00:10:34.279773 | 
2025-11-10 00:10:34.279895 | TASK [bindep : Include find tasks]
2025-11-10 00:10:34.321643 | controller | ok
2025-11-10 00:10:34.332339 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-10 00:10:34.341918 | 
2025-11-10 00:10:34.342011 | TASK [bindep : Look for bindep.txt]
2025-11-10 00:10:35.284330 | controller | ok
2025-11-10 00:10:35.297901 | 
2025-11-10 00:10:35.298072 | TASK [bindep : Define bindep_file fact]
2025-11-10 00:10:35.325671 | controller | skipping: Conditional result was False
2025-11-10 00:10:35.339122 | 
2025-11-10 00:10:35.339273 | TASK [bindep : Look for other-requirements.txt]
2025-11-10 00:10:35.987655 | controller | ok
2025-11-10 00:10:35.994386 | 
2025-11-10 00:10:35.994460 | TASK [bindep : Define bindep_file fact]
2025-11-10 00:10:36.028092 | controller | skipping: Conditional result was False
2025-11-10 00:10:36.034594 | 
2025-11-10 00:10:36.034664 | TASK [bindep : Look for bindep fallback file]
2025-11-10 00:10:36.069065 | controller | skipping: Conditional result was False
2025-11-10 00:10:36.075478 | 
2025-11-10 00:10:36.075549 | TASK [bindep : Define bindep_file fact]
2025-11-10 00:10:36.099956 | controller | skipping: Conditional result was False
2025-11-10 00:10:36.106794 | 
2025-11-10 00:10:36.106876 | TASK [bindep : Include bindep tasks]
2025-11-10 00:10:36.132073 | controller | skipping: Conditional result was False
2025-11-10 00:10:36.140159 | 
2025-11-10 00:10:36.140313 | TASK [bindep : Include install tasks]
2025-11-10 00:10:36.164895 | controller | skipping: Conditional result was False
2025-11-10 00:10:36.173666 | 
2025-11-10 00:10:36.173801 | LOOP [bindep : Include package tasks]
2025-11-10 00:10:36.254787 | 
2025-11-10 00:10:36.255012 | TASK [Run test-setup role]
2025-11-10 00:10:36.314100 | controller | ok
2025-11-10 00:10:36.341294 | 
2025-11-10 00:10:36.341437 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-10 00:10:37.020963 | controller | ok
2025-11-10 00:10:37.038888 | 
2025-11-10 00:10:37.039068 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-10 00:10:37.427490 | controller | skipping: Conditional result was False
2025-11-10 00:10:37.482082 | 
2025-11-10 00:10:37.482230 | PLAY RECAP
2025-11-10 00:10:37.482304 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-10 00:10:37.482347 | 
2025-11-10 00:10:37.592397 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-10 00:10:37.593804 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-10 00:10:38.189753 | 
2025-11-10 00:10:38.189903 | PLAY [controller]
2025-11-10 00:10:38.210066 | 
2025-11-10 00:10:38.210161 | TASK [Create the /root directory]
2025-11-10 00:10:39.429368 | controller | ok
2025-11-10 00:10:39.442612 | 
2025-11-10 00:10:39.442810 | TASK [Install glibc-langpack-en]
2025-11-10 00:10:47.703775 | controller | ok: Nothing to do
2025-11-10 00:10:47.717200 | 
2025-11-10 00:10:47.717349 | TASK [Ensure controller directory exists]
2025-11-10 00:10:48.628934 | controller | changed
2025-11-10 00:10:48.645818 | 
2025-11-10 00:10:48.646077 | TASK [Install container runtime]
2025-11-10 00:10:48.723580 | controller | ok
2025-11-10 00:10:48.768150 | 
2025-11-10 00:10:48.768333 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-10 00:10:48.829285 | controller | ok: "/var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-10 00:10:48.856185 | 
2025-11-10 00:10:48.856453 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-10 00:10:48.885951 | controller | skipping: Conditional result was False
2025-11-10 00:10:48.899167 | 
2025-11-10 00:10:48.899279 | TASK [ensure-docker : Sanity check for distro install]
2025-11-10 00:10:48.936445 | controller | skipping: Conditional result was False
2025-11-10 00:10:48.946189 | 
2025-11-10 00:10:48.946290 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-10 00:10:48.972948 | controller | skipping: Conditional result was False
2025-11-10 00:10:48.986252 | 
2025-11-10 00:10:48.986396 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-10 00:10:49.014874 | controller | skipping: Conditional result was False
2025-11-10 00:10:49.024169 | 
2025-11-10 00:10:49.024261 | TASK [ensure-docker : Include tasks for docker install]
2025-11-10 00:10:49.057724 | controller | ok
2025-11-10 00:10:49.069665 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-10 00:10:49.083036 | 
2025-11-10 00:10:49.083124 | TASK [ensure-docker : Not Implemented]
2025-11-10 00:10:49.109062 | controller | skipping: Conditional result was False
2025-11-10 00:10:49.119210 | 
2025-11-10 00:10:49.119305 | TASK [Set up docker mirrors]
2025-11-10 00:10:49.144958 | controller | ok
2025-11-10 00:10:49.172411 | 
2025-11-10 00:10:49.172514 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-10 00:10:49.208146 | controller | skipping: Conditional result was False
2025-11-10 00:10:49.219251 | 
2025-11-10 00:10:49.219351 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-10 00:10:49.298276 | controller | skipping: Conditional result was False
2025-11-10 00:10:49.361979 | 
2025-11-10 00:10:49.362151 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-10 00:10:49.399036 | controller | skipping: Conditional result was False
2025-11-10 00:10:49.414396 | 
2025-11-10 00:10:49.414572 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-10 00:10:49.453333 | controller | skipping: Conditional result was False
2025-11-10 00:10:49.468969 | 
2025-11-10 00:10:49.469088 | TASK [Add all repositories]
2025-11-10 00:10:49.499349 | controller | ok
2025-11-10 00:10:49.528041 | 
2025-11-10 00:10:49.528165 | LOOP [ensure-package-repositories : Install repositories]
2025-11-10 00:10:49.578732 | controller | ok: "/var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-10 00:10:49.579291 | controller | ok: All items complete
2025-11-10 00:10:49.579536 | 
2025-11-10 00:10:49.599289 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-10 00:10:49.606041 | 
2025-11-10 00:10:49.606107 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-10 00:10:50.668875 | controller | changed
2025-11-10 00:10:50.684030 | 
2025-11-10 00:10:50.684199 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-10 00:10:54.051826 | controller | changed:
2025-11-10 00:10:54.052172 | controller | {
2025-11-10 00:10:54.052230 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-10 00:10:54.052278 | controller | }
2025-11-10 00:10:54.085150 | 
2025-11-10 00:10:54.085368 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-10 00:10:55.509425 | controller | changed:
2025-11-10 00:10:55.509813 | controller | {
2025-11-10 00:10:55.509882 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-10 00:10:55.509934 | controller | }
2025-11-10 00:10:55.509997 | controller | changed: All items complete
2025-11-10 00:10:55.510039 | 
2025-11-10 00:10:55.531111 | 
2025-11-10 00:10:55.531305 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-10 00:10:56.228334 | controller | changed
2025-11-10 00:10:56.242674 | 
2025-11-10 00:10:56.242848 | LOOP [ensure-package-repositories : Add repositories]
2025-11-10 00:10:57.132762 | controller | changed:
2025-11-10 00:10:57.133243 | controller | {
2025-11-10 00:10:57.133288 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-10 00:10:57.133316 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-10 00:10:57.133339 | controller |   "gpgcheck": true,
2025-11-10 00:10:57.133362 | controller |   "name": "docker-ce-stable"
2025-11-10 00:10:57.133385 | controller | }
2025-11-10 00:10:57.170499 | 
2025-11-10 00:10:57.170758 | TASK [ensure-docker : Install pre-reqs]
2025-11-10 00:11:02.190059 | controller | ok: Nothing to do
2025-11-10 00:11:02.203579 | 
2025-11-10 00:11:02.203806 | TASK [ensure-docker : Remove packages]
2025-11-10 00:11:05.430123 | controller | ok: Nothing to do
2025-11-10 00:11:05.436242 | 
2025-11-10 00:11:05.436311 | TASK [ensure-docker : Install upstream docker using package]
2025-11-10 00:12:32.232663 | controller | changed
2025-11-10 00:12:32.246878 | 
2025-11-10 00:12:32.247023 | TASK [ensure-docker : Setup docker]
2025-11-10 00:12:32.277775 | controller | ok
2025-11-10 00:12:32.303496 | controller | included: /var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-10 00:12:32.330227 | 
2025-11-10 00:12:32.330361 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-10 00:12:33.391583 | controller | ok
2025-11-10 00:12:33.411302 | 
2025-11-10 00:12:33.411500 | TASK [ensure-docker : Add user to docker group]
2025-11-10 00:12:34.544329 | controller | changed
2025-11-10 00:12:34.557634 | 
2025-11-10 00:12:34.557753 | TASK [Update docker daemon proxy configuration]
2025-11-10 00:12:34.583735 | controller | skipping: Conditional result was False
2025-11-10 00:12:34.601056 | 
2025-11-10 00:12:34.601252 | TASK [Lower default MTU]
2025-11-10 00:12:34.629418 | controller | skipping: Conditional result was False
2025-11-10 00:12:34.646560 | 
2025-11-10 00:12:34.646786 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-10 00:12:35.505966 | controller | inactive
2025-11-10 00:12:36.029073 | controller | ok: Runtime: 0:00:00.014538
2025-11-10 00:12:36.042044 | 
2025-11-10 00:12:36.042182 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-10 00:12:36.802732 | controller | inactive
2025-11-10 00:12:37.418903 | controller | ok: Runtime: 0:00:00.026567
2025-11-10 00:12:37.434464 | 
2025-11-10 00:12:37.434667 | TASK [ensure-docker : Restart docker]
2025-11-10 00:12:37.491101 | Trigger a Docker restart
2025-11-10 00:12:37.501440 | 
2025-11-10 00:12:37.501564 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-10 00:12:37.528662 | 
2025-11-10 00:12:37.528807 | TASK [ensure-docker : Install docker-compose]
2025-11-10 00:12:37.544654 | controller | skipping: Conditional result was False
2025-11-10 00:12:37.551917 | 
2025-11-10 00:12:37.552005 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-10 00:12:37.562376 | 
2025-11-10 00:12:37.562446 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-10 00:12:40.917311 | controller | changed
2025-11-10 00:12:40.942672 | 
2025-11-10 00:12:40.942970 | TASK [ensure-docker : Assure docker service is running]
2025-11-10 00:12:44.794491 | controller | changed
2025-11-10 00:12:44.812313 | 
2025-11-10 00:12:44.812505 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-10 00:12:46.452256 | controller | ok
2025-11-10 00:12:46.462341 | 
2025-11-10 00:12:46.462488 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-10 00:12:47.318059 | controller | ok
2025-11-10 00:12:47.331178 | 
2025-11-10 00:12:47.331321 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-10 00:12:48.043993 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-10 00:12:48.209600 | controller | ok: Runtime: 0:00:00.050083
2025-11-10 00:12:48.223441 | 
2025-11-10 00:12:48.223584 | TASK [ensure-docker : Get version details]
2025-11-10 00:12:48.962491 | controller | Client: Docker Engine - Community
2025-11-10 00:12:48.966502 | controller |  Version:           24.0.7
2025-11-10 00:12:48.966583 | controller |  API version:       1.43
2025-11-10 00:12:48.966593 | controller |  Go version:        go1.20.10
2025-11-10 00:12:48.966625 | controller |  Git commit:        afdd53b
2025-11-10 00:12:48.966633 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-10 00:12:48.966641 | controller |  OS/Arch:           linux/amd64
2025-11-10 00:12:48.966651 | controller |  Context:           default
2025-11-10 00:12:48.966661 | controller |
2025-11-10 00:12:48.966668 | controller | Server: Docker Engine - Community
2025-11-10 00:12:48.966675 | controller |  Engine:
2025-11-10 00:12:48.966681 | controller |   Version:          24.0.7
2025-11-10 00:12:48.966688 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-10 00:12:48.966694 | controller |   Go version:       go1.20.10
2025-11-10 00:12:48.966700 | controller |   Git commit:       311b9ff
2025-11-10 00:12:48.966707 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-10 00:12:48.966714 | controller |   OS/Arch:          linux/amd64
2025-11-10 00:12:48.966720 | controller |   Experimental:     false
2025-11-10 00:12:48.966727 | controller |  containerd:
2025-11-10 00:12:48.966733 | controller |   Version:          1.6.25
2025-11-10 00:12:48.966739 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-10 00:12:48.966745 | controller |  runc:
2025-11-10 00:12:48.966751 | controller |   Version:          1.1.10
2025-11-10 00:12:48.966758 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-10 00:12:48.966769 | controller |  docker-init:
2025-11-10 00:12:48.966775 | controller |   Version:          0.19.0
2025-11-10 00:12:48.966782 | controller |   GitCommit:        de40ad0
2025-11-10 00:12:49.111109 | controller | ok: Runtime: 0:00:00.062530
2025-11-10 00:12:49.124561 | 
2025-11-10 00:12:49.124755 | TASK [ensure-docker : Dump installed docker details]
2025-11-10 00:12:49.177280 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-11-10 00:12:48.905163', 'end': '2025-11-10 00:12:48.967693', 'delta': '0:00:00.062530', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-9eb4-fa34-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-11-10 00:12:49.190445 | 
2025-11-10 00:12:49.190551 | TASK [Use buildset registry]
2025-11-10 00:12:49.217139 | controller | skipping: Conditional result was False
2025-11-10 00:12:49.236597 | 
2025-11-10 00:12:49.236721 | TASK [Ensure python3.8 is present]
2025-11-10 00:12:49.253078 | controller | skipping: Conditional result was False
2025-11-10 00:12:49.262265 | 
2025-11-10 00:12:49.262442 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-10 00:12:49.285592 | controller | ok
2025-11-10 00:12:49.313145 | 
2025-11-10 00:12:49.313243 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-10 00:12:55.271320 | controller | ok: Nothing to do
2025-11-10 00:12:55.277557 | 
2025-11-10 00:12:55.277640 | TASK [our-ensure-python : Also install python3-devel]
2025-11-10 00:13:11.029872 | controller | changed
2025-11-10 00:13:11.055735 | 
2025-11-10 00:13:11.055932 | TASK [Run ensure-virtualenv role]
2025-11-10 00:13:11.099523 | controller | ok
2025-11-10 00:13:11.142207 | 
2025-11-10 00:13:11.142401 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-10 00:13:11.935511 | controller | /usr/bin/virtualenv
2025-11-10 00:13:12.502647 | controller | ok: Runtime: 0:00:00.008018
2025-11-10 00:13:12.509175 | 
2025-11-10 00:13:12.509255 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-10 00:13:12.540175 | controller | skipping: Conditional result was False
2025-11-10 00:13:12.540489 | controller | changed: All items complete
2025-11-10 00:13:12.540524 | 
2025-11-10 00:13:12.562993 | 
2025-11-10 00:13:12.563150 | TASK [Find the full path of the Python interpreter]
2025-11-10 00:13:13.331983 | controller | /usr/bin/python3.8
2025-11-10 00:13:13.927340 | controller | ok
2025-11-10 00:13:13.933384 | 
2025-11-10 00:13:13.933498 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-10 00:13:16.390649 | controller | created virtual environment CPython3.8.18.final.0-64 in 1283ms
2025-11-10 00:13:16.458885 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-10 00:13:16.458946 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-10 00:13:16.458962 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-10 00:13:16.458980 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-10 00:13:16.815973 | controller | changed
2025-11-10 00:13:16.831287 | 
2025-11-10 00:13:16.831475 | TASK [Set selinux package]
2025-11-10 00:13:16.869825 | controller | ok
2025-11-10 00:13:16.882093 | 
2025-11-10 00:13:16.882249 | TASK [Set selinux package (Fedora)]
2025-11-10 00:13:16.935652 | controller | ok
2025-11-10 00:13:17.002273 | 
2025-11-10 00:13:17.002443 | TASK [Install selinux into virtualenv]
2025-11-10 00:13:22.928262 | controller | Collecting selinux-please-lie-to-me
2025-11-10 00:13:23.045592 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-10 00:13:24.087121 | controller | Collecting setuptools<50.0.0
2025-11-10 00:13:24.095545 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-10 00:13:24.185872 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.3 MB/s eta 0:00:00
2025-11-10 00:13:24.325553 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-10 00:13:24.325887 | controller |   Attempting uninstall: setuptools
2025-11-10 00:13:24.327066 | controller |     Found existing installation: setuptools 62.6.0
2025-11-10 00:13:24.467350 | controller |     Uninstalling setuptools-62.6.0:
2025-11-10 00:13:24.485990 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-10 00:13:25.250448 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-10 00:13:25.459232 | controller |
2025-11-10 00:13:25.695903 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-10 00:13:25.695952 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-10 00:13:25.881883 | controller | ok: Runtime: 0:00:07.464817
2025-11-10 00:13:25.888098 | 
2025-11-10 00:13:25.888172 | TASK [Install pytest-forked into virtualenv]
2025-11-10 00:13:27.656900 | controller | Collecting pytest-forked
2025-11-10 00:13:27.714648 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-10 00:13:27.912822 | controller | Collecting pytest>=3.10
2025-11-10 00:13:27.918573 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-10 00:13:27.957412 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 9.9 MB/s eta 0:00:00
2025-11-10 00:13:28.019156 | controller | Collecting py
2025-11-10 00:13:28.024052 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-10 00:13:28.037182 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 9.1 MB/s eta 0:00:00
2025-11-10 00:13:28.134742 | controller | Collecting packaging
2025-11-10 00:13:28.140251 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-10 00:13:28.149085 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.3 MB/s eta 0:00:00
2025-11-10 00:13:28.222990 | controller | Collecting tomli>=1
2025-11-10 00:13:28.227722 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-10 00:13:28.279949 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-10 00:13:28.286922 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-11-10 00:13:28.326120 | controller | Collecting iniconfig
2025-11-10 00:13:28.331423 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-10 00:13:28.401279 | controller | Collecting pluggy<2,>=1.5
2025-11-10 00:13:28.406751 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-10 00:13:28.524489 | controller | Collecting typing-extensions>=4.6.0
2025-11-10 00:13:28.531451 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-10 00:13:28.544681 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.7 MB/s eta 0:00:00
2025-11-10 00:13:28.664338 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-10 00:13:29.565388 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-10 00:13:29.571633 | controller |
2025-11-10 00:13:29.850452 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-10 00:13:29.850503 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-10 00:13:30.282186 | controller | ok: Runtime: 0:00:02.924316
2025-11-10 00:13:30.318011 | 
2025-11-10 00:13:30.318135 | TASK [Update pip]
2025-11-10 00:13:32.573755 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-10 00:13:32.910172 | controller | Collecting pip
2025-11-10 00:13:33.051162 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-10 00:13:33.206474 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 13.0 MB/s eta 0:00:00
2025-11-10 00:13:33.440220 | controller | Installing collected packages: pip
2025-11-10 00:13:33.444446 | controller |   Attempting uninstall: pip
2025-11-10 00:13:33.445827 | controller |     Found existing installation: pip 22.2.2
2025-11-10 00:13:33.987066 | controller |     Uninstalling pip-22.2.2:
2025-11-10 00:13:34.065781 | controller |       Successfully uninstalled pip-22.2.2
2025-11-10 00:13:36.818875 | controller | Successfully installed pip-25.0.1
2025-11-10 00:13:37.190999 | controller | ok: Runtime: 0:00:05.578068
2025-11-10 00:13:37.200092 | 
2025-11-10 00:13:37.200186 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-10 00:13:38.332994 | controller | changed
2025-11-10 00:13:38.342953 | 
2025-11-10 00:13:38.343053 | TASK [Install ansible into virtualenv]
2025-11-10 00:13:40.175000 | controller | Processing ./src/github.com/ansible/ansible
2025-11-10 00:13:40.177679 | controller |   Preparing metadata (setup.py): started
2025-11-10 00:13:41.046416 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-10 00:13:41.117511 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2025-11-10 00:13:41.134249 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-10 00:13:41.223794 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2025-11-10 00:13:41.232443 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-11-10 00:13:41.690688 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2025-11-10 00:13:41.698680 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-10 00:13:41.736060 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (25.0)
2025-11-10 00:13:41.959846 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2025-11-10 00:13:41.966334 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-10 00:13:41.980215 | 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)
2025-11-10 00:13:42.147538 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2025-11-10 00:13:42.153820 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-10 00:13:42.188877 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2025-11-10 00:13:42.193891 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-10 00:13:42.212907 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-10 00:13:42.266797 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 99.0 MB/s eta 0:00:00
2025-11-10 00:13:42.272558 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-10 00:13:42.289156 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-11-10 00:13:42.310576 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 32.8 MB/s eta 0:00:00
2025-11-10 00:13:42.320210 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-10 00:13:42.347936 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-10 00:13:42.364877 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-10 00:13:42.402751 | controller | Building wheels for collected packages: ansible-base
2025-11-10 00:13:42.404235 | controller |   Building wheel for ansible-base (setup.py): started
2025-11-10 00:13:44.800509 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2025-11-10 00:13:44.803437 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=fc70a89dee5277c8d55a00b083085e1012c7a6954e59b57d8314c360b748ad63
2025-11-10 00:13:44.803720 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-5_lm0rcq/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-10 00:13:44.818316 | controller | Successfully built ansible-base
2025-11-10 00:13:44.876324 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2025-11-10 00:13:48.018960 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-11-10 00:13:48.731503 | controller | ok: Runtime: 0:00:09.044357
2025-11-10 00:13:48.746526 | 
2025-11-10 00:13:48.746719 | TASK [Setup download-artifact-fork role]
2025-11-10 00:13:48.780805 | controller | ok
2025-11-10 00:13:48.823005 | 
2025-11-10 00:13:48.823198 | TASK [download-artifact-fork : Parse inventory response]
2025-11-10 00:13:48.879511 | controller | ok
2025-11-10 00:13:48.888719 | 
2025-11-10 00:13:48.888834 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-10 00:13:48.914613 | controller | skipping: Conditional result was False
2025-11-10 00:13:48.925355 | 
2025-11-10 00:13:48.925465 | TASK [download-artifact-fork : Parse build response]
2025-11-10 00:13:48.951005 | controller | skipping: Conditional result was False
2025-11-10 00:13:48.960559 | 
2025-11-10 00:13:48.960661 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-10 00:13:49.836498 | controller | changed
2025-11-10 00:13:49.842166 | 
2025-11-10 00:13:49.842236 | LOOP [download-artifact-fork : Download archive by type]
2025-11-10 00:13:49.883706 | controller | skipping: Conditional result was False
2025-11-10 00:13:49.883965 | 
2025-11-10 00:13:51.961604 | controller | changed: OK (20212 bytes)
2025-11-10 00:13:51.988951 | 
2025-11-10 00:13:51.989138 | TASK [deploy-artifacts : define __collections]
2025-11-10 00:13:52.023051 | controller | ok
2025-11-10 00:13:52.031185 | 
2025-11-10 00:13:52.031277 | LOOP [deploy-artifacts : Create list of collections]
2025-11-10 00:13:52.067243 | controller | skipping: Conditional result was False
2025-11-10 00:13:52.083118 | controller | ok:
2025-11-10 00:13:52.083266 | controller | {
2025-11-10 00:13:52.083296 | controller |   "branch": "main",
2025-11-10 00:13:52.083321 | controller |   "job": "build-ansible-collection",
2025-11-10 00:13:52.083343 | controller |   "metadata": {
2025-11-10 00:13:52.083365 | controller |     "type": "ansible_collection",
2025-11-10 00:13:52.083386 | controller |     "version": "5.0.0"
2025-11-10 00:13:52.083407 | controller |   },
2025-11-10 00:13:52.083428 | controller |   "name": "ansible.network",
2025-11-10 00:13:52.083636 | controller |   "project": "ansible-collections/ansible.network",
2025-11-10 00:13:52.083706 | controller |   "url": "https://4e584d751c724d9fcfef-cdbe3e6088114cf153ba8d2a81fbe483.ssl.cf5.rackcdn.com/ansible/d166085815d3438ea5baa90da800959a/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-10 00:13:52.083762 | controller | }
2025-11-10 00:13:52.101059 | 
2025-11-10 00:13:52.101165 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-10 00:13:53.537225 | controller | Starting galaxy collection install process
2025-11-10 00:13:53.537657 | controller | Process install dependency map
2025-11-10 00:14:06.756075 | controller | Starting collection install process
2025-11-10 00:14:06.756184 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-10 00:14:06.773444 | controller | ansible.network (5.0.0) was installed successfully
2025-11-10 00:14:06.773636 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-10 00:14:06.773653 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.2.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4014g40jx4vt/tmp651ki5bz
2025-11-10 00:14:08.487321 | controller | ansible.netcommon (8.2.0) was installed successfully
2025-11-10 00:14:08.487450 | controller | Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-10 00:14:08.487642 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4014g40jx4vt/tmp651ki5bz
2025-11-10 00:14:10.358232 | controller | ansible.utils (6.0.0) was installed successfully
2025-11-10 00:14:10.358359 | controller | Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-10 00:14:10.358544 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4014g40jx4vt/tmp651ki5bz
2025-11-10 00:14:12.981569 | controller | arista.eos (12.0.0) was installed successfully
2025-11-10 00:14:12.981617 | controller | Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-10 00:14:12.981630 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4014g40jx4vt/tmp651ki5bz
2025-11-10 00:14:15.963446 | controller | cisco.ios (11.1.1) was installed successfully
2025-11-10 00:14:15.963496 | controller | Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-10 00:14:15.963700 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4014g40jx4vt/tmp651ki5bz
2025-11-10 00:14:18.849822 | controller | cisco.iosxr (12.1.0) was installed successfully
2025-11-10 00:14:18.850060 | controller | Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-10 00:14:18.850080 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4014g40jx4vt/tmp651ki5bz
2025-11-10 00:14:23.090846 | controller | cisco.nxos (11.1.0) was installed successfully
2025-11-10 00:14:23.091223 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-10 00:14:23.091273 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-4014g40jx4vt/tmp651ki5bz
2025-11-10 00:14:25.707743 | controller | junipernetworks.junos (11.0.0) was installed successfully
2025-11-10 00:14:26.052576 | controller | ok: Runtime: 0:00:32.951340
2025-11-10 00:14:26.064239 | 
2025-11-10 00:14:26.064357 | TASK [podman may need iptables kernel modules]
2025-11-10 00:14:27.420612 | controller | ok: Runtime: 0:00:00.007561
2025-11-10 00:14:27.476423 | 
2025-11-10 00:14:27.476553 | PLAY RECAP
2025-11-10 00:14:27.476612 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-10 00:14:27.476643 | 
2025-11-10 00:14:27.626265 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-10 00:14:27.627321 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-10 00:14:28.300342 | 
2025-11-10 00:14:28.300645 | PLAY [controller]
2025-11-10 00:14:28.328571 | 
2025-11-10 00:14:28.328747 | TASK [Copy the galaxy.yml on the controller]
2025-11-10 00:14:32.114430 | controller | changed
2025-11-10 00:14:32.122940 | 
2025-11-10 00:14:32.123066 | TASK [Load information from galaxy.yml]
2025-11-10 00:14:32.167169 | controller | ok
2025-11-10 00:14:32.175776 | 
2025-11-10 00:14:32.175875 | LOOP [Enable FIPS mode]
2025-11-10 00:14:32.202962 | 
2025-11-10 00:14:32.203142 | LOOP [ansible-test : Include branch specific variables.]
2025-11-10 00:14:32.252326 | controller | ok: "/var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-10 00:14:32.252591 | 
2025-11-10 00:14:32.276643 | 
2025-11-10 00:14:32.276953 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-10 00:14:32.341779 | controller | ok
2025-11-10 00:14:32.347670 | 
2025-11-10 00:14:32.347830 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-10 00:14:32.373534 | controller | skipping: Conditional result was False
2025-11-10 00:14:32.385159 | 
2025-11-10 00:14:32.385271 | TASK [ansible-test : Enable --retry-on-error]
2025-11-10 00:14:32.412029 | controller | skipping: Conditional result was False
2025-11-10 00:14:32.422423 | 
2025-11-10 00:14:32.422530 | TASK [ansible-test : Enable --continue-on-error]
2025-11-10 00:14:32.467123 | controller | ok
2025-11-10 00:14:32.475506 | 
2025-11-10 00:14:32.475606 | TASK [ansible-test : Enable --requirements]
2025-11-10 00:14:32.501751 | controller | skipping: Conditional result was False
2025-11-10 00:14:32.511180 | 
2025-11-10 00:14:32.511272 | TASK [ansible-test : Adjust options for unit tests]
2025-11-10 00:14:32.537292 | controller | skipping: Conditional result was False
2025-11-10 00:14:32.546632 | 
2025-11-10 00:14:32.546745 | TASK [ansible-test : Install requirements with unit tests]
2025-11-10 00:14:32.626058 | controller | ok
2025-11-10 00:14:32.631653 | 
2025-11-10 00:14:32.631745 | TASK [ansible-test : Set --skip-test]
2025-11-10 00:14:32.687087 | controller | skipping: Conditional result was False
2025-11-10 00:14:32.693451 | 
2025-11-10 00:14:32.693521 | TASK [ansible-test : Reduce verbosity]
2025-11-10 00:14:32.722038 | controller | ok
2025-11-10 00:14:32.727539 | 
2025-11-10 00:14:32.727599 | TASK [ansible-test : Setup --python option]
2025-11-10 00:14:32.752572 | controller | skipping: Conditional result was False
2025-11-10 00:14:32.760846 | 
2025-11-10 00:14:32.760984 | TASK [ansible-test : Setup --docker option]
2025-11-10 00:14:32.791182 | controller | ok
2025-11-10 00:14:32.798081 | 
2025-11-10 00:14:32.798145 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-10 00:14:33.928865 | controller | ok
2025-11-10 00:14:33.936988 | 
2025-11-10 00:14:33.937074 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-10 00:14:33.962439 | controller | skipping: Conditional result was False
2025-11-10 00:14:33.972277 | 
2025-11-10 00:14:33.972379 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-10 00:14:33.997742 | controller | skipping: Conditional result was False
2025-11-10 00:14:34.007451 | 
2025-11-10 00:14:34.007543 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-10 00:14:34.032542 | controller | skipping: Conditional result was False
2025-11-10 00:14:34.041580 | 
2025-11-10 00:14:34.041712 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-10 00:14:35.372872 | controller | ok
2025-11-10 00:14:35.382258 | 
2025-11-10 00:14:35.382479 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-10 00:14:35.434574 | controller | ok
2025-11-10 00:14:35.440745 | 
2025-11-10 00:14:35.440810 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-10 00:14:35.481135 | controller | ok
2025-11-10 00:14:35.486948 | 
2025-11-10 00:14:35.487015 | TASK [ansible-test : Setup minimum test requirements]
2025-11-10 00:14:35.516406 | controller | ok
2025-11-10 00:14:35.522079 | 
2025-11-10 00:14:35.522143 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-10 00:14:36.479780 | controller | ok
2025-11-10 00:14:36.493154 | 
2025-11-10 00:14:36.493355 | TASK [ansible-test : Install python requirements]
2025-11-10 00:14:38.054151 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-10 00:14:38.082998 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-10 00:14:38.137525 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-10 00:14:38.143700 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-10 00:14:38.157497 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-11-10 00:14:38.168445 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-10 00:14:38.187099 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-10 00:14:38.251154 | controller | Installing collected packages: pathspec, yamllint
2025-11-10 00:14:38.355198 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-10 00:14:38.872263 | controller | ok: Runtime: 0:00:01.096160
2025-11-10 00:14:38.878564 | 
2025-11-10 00:14:38.878636 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-10 00:14:39.772078 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-10 00:14:40.256478 | controller | ERROR
2025-11-10 00:14:40.256845 | controller | {
2025-11-10 00:14:40.256917 | controller |   "delta": "0:00:00.012337",
2025-11-10 00:14:40.256977 | controller |   "end": "2025-11-10 00:14:39.772378",
2025-11-10 00:14:40.257037 | controller |   "msg": "non-zero return code",
2025-11-10 00:14:40.257097 | controller |   "rc": 1,
2025-11-10 00:14:40.257153 | controller |   "start": "2025-11-10 00:14:39.760041"
2025-11-10 00:14:40.257210 | controller | }
2025-11-10 00:14:40.257282 | controller | ERROR: Ignoring Errors
2025-11-10 00:14:40.279456 | 
2025-11-10 00:14:40.279650 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-10 00:14:40.658278 | controller | skipping: Conditional result was False
2025-11-10 00:14:40.667851 | 
2025-11-10 00:14:40.667953 | TASK [ansible-test : Enable persistent connection logging]
2025-11-10 00:14:42.243227 | controller | changed: section and option added
2025-11-10 00:14:42.256022 | 
2025-11-10 00:14:42.256200 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-10 00:14:43.503836 | controller | changed: option added
2025-11-10 00:14:43.521821 | 
2025-11-10 00:14:43.522280 | TASK [ansible-test : Set ansible log path]
2025-11-10 00:14:44.623232 | controller | changed: section and option added
2025-11-10 00:14:44.630453 | 
2025-11-10 00:14:44.630535 | TASK [ansible-test : Identify the changed targets]
2025-11-10 00:14:44.999276 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.008293 | 
2025-11-10 00:14:45.008403 | TASK [ansible-test : set_fact]
2025-11-10 00:14:45.126213 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.149486 | 
2025-11-10 00:14:45.149755 | TASK [ansible-test : Set the targets]
2025-11-10 00:14:45.283913 | controller | ok
2025-11-10 00:14:45.291755 | 
2025-11-10 00:14:45.291862 | TASK [ansible-test : set_fact]
2025-11-10 00:14:45.347803 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.354229 | 
2025-11-10 00:14:45.354319 | TASK [ansible-test : List the targets]
2025-11-10 00:14:45.729663 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.737410 | 
2025-11-10 00:14:45.737491 | TASK [ansible-test : set_fact]
2025-11-10 00:14:45.768258 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.775514 | 
2025-11-10 00:14:45.775596 | TASK [ansible-test : set_fact]
2025-11-10 00:14:45.800289 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.807117 | 
2025-11-10 00:14:45.807204 | LOOP [ansible-test : set_fact]
2025-11-10 00:14:45.869120 | 
2025-11-10 00:14:45.869290 | TASK [ansible-test : set_fact]
2025-11-10 00:14:45.893814 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.902202 | 
2025-11-10 00:14:45.902278 | TASK [ansible-test : debug]
2025-11-10 00:14:45.929177 | controller | skipping: Conditional result was False
2025-11-10 00:14:45.935799 | 
2025-11-10 00:14:45.935869 | TASK [ansible-test : debug]
2025-11-10 00:14:45.973830 | About to run: ansible-test sanity --requirements --docker -v
2025-11-10 00:14:45.987105 | 
2025-11-10 00:14:45.987234 | TASK [ansible-test : Run the test suite]
2025-11-10 00:14:47.445623 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2025-11-10 00:14:47.486212 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2025-11-10 00:14:48.994994 | controller | 2.9.0: Pulling from ansible/default-test-container
2025-11-10 00:14:48.996051 | controller | 171857c49d0f: Pulling fs layer
2025-11-10 00:14:48.996094 | controller | 419640447d26: Pulling fs layer
2025-11-10 00:14:48.996104 | controller | 61e52f862619: Pulling fs layer
2025-11-10 00:14:48.996112 | controller | 851de0426e26: Pulling fs layer
2025-11-10 00:14:48.996123 | controller | 37425716ea5b: Pulling fs layer
2025-11-10 00:14:48.996133 | controller | cd9b0c439616: Pulling fs layer
2025-11-10 00:14:48.996140 | controller | d4612dbd8855: Pulling fs layer
2025-11-10 00:14:48.996145 | controller | 554699364730: Pulling fs layer
2025-11-10 00:14:48.996151 | controller | 1ff15d4bdb3e: Pulling fs layer
2025-11-10 00:14:48.996157 | controller | 5f2e4724adea: Pulling fs layer
2025-11-10 00:14:48.996162 | controller | a02d9a95ccb8: Pulling fs layer
2025-11-10 00:14:48.996170 | controller | 989f4ce370cb: Pulling fs layer
2025-11-10 00:14:48.996175 | controller | 0d7f52cdf9df: Pulling fs layer
2025-11-10 00:14:48.996181 | controller | c423742351d5: Pulling fs layer
2025-11-10 00:14:48.996193 | controller | 109236341d21: Pulling fs layer
2025-11-10 00:14:48.996213 | controller | 07a2f61c96b8: Pulling fs layer
2025-11-10 00:14:48.996221 | controller | 041f122f27ae: Pulling fs layer
2025-11-10 00:14:48.996227 | controller | ca6f5098b12e: Pulling fs layer
2025-11-10 00:14:48.996232 | controller | 1eb3e7b2afd9: Pulling fs layer
2025-11-10 00:14:48.996238 | controller | 18a0b750727a: Pulling fs layer
2025-11-10 00:14:48.996243 | controller | 17f2f596716c: Pulling fs layer
2025-11-10 00:14:48.996251 | controller | f35a9998a9bd: Pulling fs layer
2025-11-10 00:14:48.996257 | controller | fdfc10566405: Pulling fs layer
2025-11-10 00:14:48.996262 | controller | 1ff1c442c5fe: Pulling fs layer
2025-11-10 00:14:48.996268 | controller | a3443c4a17ed: Pulling fs layer
2025-11-10 00:14:48.996274 | controller | 0edcf9fb2b15: Pulling fs layer
2025-11-10 00:14:48.996280 | controller | 5af43b69e845: Pulling fs layer
2025-11-10 00:14:48.996286 | controller | 851de0426e26: Waiting
2025-11-10 00:14:48.996292 | controller | 37425716ea5b: Waiting
2025-11-10 00:14:48.996301 | controller | cd9b0c439616: Waiting
2025-11-10 00:14:48.996329 | controller | d4612dbd8855: Waiting
2025-11-10 00:14:48.996335 | controller | 554699364730: Waiting
2025-11-10 00:14:48.996341 | controller | 1ff15d4bdb3e: Waiting
2025-11-10 00:14:48.996350 | controller | 5f2e4724adea: Waiting
2025-11-10 00:14:48.996356 | controller | a02d9a95ccb8: Waiting
2025-11-10 00:14:48.996362 | controller | 989f4ce370cb: Waiting
2025-11-10 00:14:48.996368 | controller | 0d7f52cdf9df: Waiting
2025-11-10 00:14:48.996377 | controller | c423742351d5: Waiting
2025-11-10 00:14:48.996383 | controller | 109236341d21: Waiting
2025-11-10 00:14:48.996389 | controller | 07a2f61c96b8: Waiting
2025-11-10 00:14:48.996394 | controller | 041f122f27ae: Waiting
2025-11-10 00:14:48.996401 | controller | ca6f5098b12e: Waiting
2025-11-10 00:14:48.996406 | controller | 1eb3e7b2afd9: Waiting
2025-11-10 00:14:48.996412 | controller | 18a0b750727a: Waiting
2025-11-10 00:14:48.996418 | controller | 17f2f596716c: Waiting
2025-11-10 00:14:48.996424 | controller | f35a9998a9bd: Waiting
2025-11-10 00:14:48.996429 | controller | fdfc10566405: Waiting
2025-11-10 00:14:48.996435 | controller | 1ff1c442c5fe: Waiting
2025-11-10 00:14:48.996441 | controller | a3443c4a17ed: Waiting
2025-11-10 00:14:48.996449 | controller | 0edcf9fb2b15: Waiting
2025-11-10 00:14:48.996455 | controller | 1651773effb2: Pulling fs layer
2025-11-10 00:14:48.996461 | controller | 79e23c5c0fe4: Pulling fs layer
2025-11-10 00:14:48.996467 | controller | e5058b1faaa6: Pulling fs layer
2025-11-10 00:14:48.996473 | controller | 5af43b69e845: Waiting
2025-11-10 00:14:48.996478 | controller | 1651773effb2: Waiting
2025-11-10 00:14:48.996484 | controller | 79e23c5c0fe4: Waiting
2025-11-10 00:14:48.996492 | controller | 846775490569: Pulling fs layer
2025-11-10 00:14:49.376373 | controller | e5058b1faaa6: Waiting
2025-11-10 00:14:49.376422 | controller | 846775490569: Waiting
2025-11-10 00:14:49.376438 | controller | 419640447d26: Verifying Checksum
2025-11-10 00:14:49.462175 | controller | 419640447d26: Download complete
2025-11-10 00:14:49.462234 | controller | 61e52f862619: Verifying Checksum
2025-11-10 00:14:49.700200 | controller | 61e52f862619: Download complete
2025-11-10 00:14:49.700269 | controller | 171857c49d0f: Verifying Checksum
2025-11-10 00:14:50.476430 | controller | 171857c49d0f: Download complete
2025-11-10 00:14:50.476509 | controller | 851de0426e26: Verifying Checksum
2025-11-10 00:14:50.622228 | controller | 851de0426e26: Download complete
2025-11-10 00:14:50.622284 | controller | cd9b0c439616: Verifying Checksum
2025-11-10 00:14:51.723403 | controller | cd9b0c439616: Download complete
2025-11-10 00:14:51.723458 | controller | d4612dbd8855: Verifying Checksum
2025-11-10 00:14:52.865655 | controller | d4612dbd8855: Download complete
2025-11-10 00:14:52.865717 | controller | 1ff15d4bdb3e: Verifying Checksum
2025-11-10 00:14:53.905574 | controller | 1ff15d4bdb3e: Download complete
2025-11-10 00:14:53.905639 | controller | 171857c49d0f: Pull complete
2025-11-10 00:14:53.941192 | controller | 419640447d26: Pull complete
2025-11-10 00:14:53.972768 | controller | 61e52f862619: Pull complete
2025-11-10 00:14:53.980645 | controller | 5f2e4724adea: Verifying Checksum
2025-11-10 00:14:54.003633 | controller | 5f2e4724adea: Download complete
2025-11-10 00:14:54.003679 | controller | 851de0426e26: Pull complete
2025-11-10 00:14:55.761894 | controller | a02d9a95ccb8: Verifying Checksum
2025-11-10 00:14:56.886359 | controller | a02d9a95ccb8: Download complete
2025-11-10 00:14:56.886433 | controller | 989f4ce370cb: Download complete
2025-11-10 00:14:58.096259 | controller | 0d7f52cdf9df: Verifying Checksum
2025-11-10 00:14:58.934333 | controller | 0d7f52cdf9df: Download complete
2025-11-10 00:14:58.934399 | controller | c423742351d5: Verifying Checksum
2025-11-10 00:14:59.562155 | controller | c423742351d5: Download complete
2025-11-10 00:14:59.562246 | controller | 554699364730: Verifying Checksum
2025-11-10 00:15:00.607237 | controller | 554699364730: Download complete
2025-11-10 00:15:00.607312 | controller | 07a2f61c96b8: Verifying Checksum
2025-11-10 00:15:06.595610 | controller | 07a2f61c96b8: Download complete
2025-11-10 00:15:06.595696 | controller | 109236341d21: Verifying Checksum
2025-11-10 00:15:07.952920 | controller | 109236341d21: Download complete
2025-11-10 00:15:07.953020 | controller | ca6f5098b12e: Verifying Checksum
2025-11-10 00:15:09.018420 | controller | ca6f5098b12e: Download complete
2025-11-10 00:15:09.018515 | controller | 1eb3e7b2afd9: Verifying Checksum
2025-11-10 00:15:10.903504 | controller | 1eb3e7b2afd9: Download complete
2025-11-10 00:15:10.903592 | controller | 18a0b750727a: Verifying Checksum
2025-11-10 00:15:11.844302 | controller | 18a0b750727a: Download complete
2025-11-10 00:15:11.844393 | controller | 17f2f596716c: Verifying Checksum
2025-11-10 00:15:11.978112 | controller | 17f2f596716c: Download complete
2025-11-10 00:15:11.978205 | controller | 041f122f27ae: Verifying Checksum
2025-11-10 00:15:12.771360 | controller | 041f122f27ae: Download complete
2025-11-10 00:15:12.771453 | controller | f35a9998a9bd: Verifying Checksum
2025-11-10 00:15:14.201344 | controller | f35a9998a9bd: Download complete
2025-11-10 00:15:14.201408 | controller | 1ff1c442c5fe: Verifying Checksum
2025-11-10 00:15:20.143866 | controller | 1ff1c442c5fe: Download complete
2025-11-10 00:15:20.143982 | controller | a3443c4a17ed: Verifying Checksum
2025-11-10 00:15:20.145921 | controller | a3443c4a17ed: Download complete
2025-11-10 00:15:23.127064 | controller | 0edcf9fb2b15: Verifying Checksum
2025-11-10 00:15:23.127201 | controller | 0edcf9fb2b15: Download complete
2025-11-10 00:15:23.293610 | controller | fdfc10566405: Verifying Checksum
2025-11-10 00:15:23.293759 | controller | fdfc10566405: Download complete
2025-11-10 00:15:25.868234 | controller | 5af43b69e845: Verifying Checksum
2025-11-10 00:15:25.868350 | controller | 5af43b69e845: Download complete
2025-11-10 00:15:26.909845 | controller | 37425716ea5b: Verifying Checksum
2025-11-10 00:15:26.910287 | controller | 37425716ea5b: Download complete
2025-11-10 00:15:27.964208 | controller | 1651773effb2: Verifying Checksum
2025-11-10 00:15:27.964332 | controller | 1651773effb2: Download complete
2025-11-10 00:15:29.373325 | controller | 79e23c5c0fe4: Verifying Checksum
2025-11-10 00:15:29.373451 | controller | 79e23c5c0fe4: Download complete
2025-11-10 00:15:30.948721 | controller | e5058b1faaa6: Verifying Checksum
2025-11-10 00:15:30.949104 | controller | e5058b1faaa6: Download complete
2025-11-10 00:15:32.091446 | controller | 846775490569: Verifying Checksum
2025-11-10 00:15:32.091576 | controller | 846775490569: Download complete
2025-11-10 00:15:46.322777 | controller | 37425716ea5b: Pull complete
2025-11-10 00:15:46.348887 | controller | cd9b0c439616: Pull complete
2025-11-10 00:15:46.379850 | controller | d4612dbd8855: Pull complete
2025-11-10 00:15:51.509158 | controller | 554699364730: Pull complete
2025-11-10 00:15:51.551851 | controller | 1ff15d4bdb3e: Pull complete
2025-11-10 00:15:51.589637 | controller | 5f2e4724adea: Pull complete
2025-11-10 00:15:51.636185 | controller | a02d9a95ccb8: Pull complete
2025-11-10 00:15:51.661108 | controller | 989f4ce370cb: Pull complete
2025-11-10 00:15:51.689022 | controller | 0d7f52cdf9df: Pull complete
2025-11-10 00:15:51.722323 | controller | c423742351d5: Pull complete
2025-11-10 00:15:54.198558 | controller | 109236341d21: Pull complete
2025-11-10 00:15:54.229860 | controller | 07a2f61c96b8: Pull complete
2025-11-10 00:15:54.251541 | controller | 041f122f27ae: Pull complete
2025-11-10 00:15:54.437922 | controller | ca6f5098b12e: Pull complete
2025-11-10 00:15:54.494827 | controller | 1eb3e7b2afd9: Pull complete
2025-11-10 00:15:54.541118 | controller | 18a0b750727a: Pull complete
2025-11-10 00:15:54.562610 | controller | 17f2f596716c: Pull complete
2025-11-10 00:15:54.604805 | controller | f35a9998a9bd: Pull complete
2025-11-10 00:15:54.640172 | controller | fdfc10566405: Pull complete
2025-11-10 00:15:54.668893 | controller | 1ff1c442c5fe: Pull complete
2025-11-10 00:15:56.501956 | controller | a3443c4a17ed: Pull complete
2025-11-10 00:15:58.689636 | controller | 0edcf9fb2b15: Pull complete
2025-11-10 00:16:01.552469 | controller | 5af43b69e845: Pull complete
2025-11-10 00:16:06.678108 | controller | 1651773effb2: Pull complete
2025-11-10 00:16:09.486110 | controller | 79e23c5c0fe4: Pull complete
2025-11-10 00:16:11.394241 | controller | e5058b1faaa6: Pull complete
2025-11-10 00:16:14.176663 | controller | 846775490569: Pull complete
2025-11-10 00:16:14.223834 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2025-11-10 00:16:14.231942 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2025-11-10 00:16:14.241798 | controller | quay.io/ansible/default-test-container:2.9.0
2025-11-10 00:16:14.249414 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-10 00:16:14.283272 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:16:15.691284 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-10 00:16:15.691545 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-10 00:16:15.691558 | controller | ff496a88c8ed: Pulling fs layer
2025-11-10 00:16:15.691566 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-10 00:16:15.691619 | controller | 2e5170e1f099: Pulling fs layer
2025-11-10 00:16:15.691631 | controller | 7641eb41b08c: Pulling fs layer
2025-11-10 00:16:15.691699 | controller | ad15fa9da398: Pulling fs layer
2025-11-10 00:16:15.691764 | controller | 087d91352424: Pulling fs layer
2025-11-10 00:16:15.691776 | controller | 8b92efd6a100: Pulling fs layer
2025-11-10 00:16:15.692522 | controller | 2e5170e1f099: Waiting
2025-11-10 00:16:15.692578 | controller | 7641eb41b08c: Waiting
2025-11-10 00:16:15.692610 | controller | ad15fa9da398: Waiting
2025-11-10 00:16:15.692673 | controller | 087d91352424: Waiting
2025-11-10 00:16:15.692716 | controller | 8b92efd6a100: Waiting
2025-11-10 00:16:16.621588 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-10 00:16:16.937952 | controller | 0ce83f459fe7: Download complete
2025-11-10 00:16:16.938066 | controller | ff496a88c8ed: Verifying Checksum
2025-11-10 00:16:17.829051 | controller | ff496a88c8ed: Download complete
2025-11-10 00:16:17.829129 | controller | 2e5170e1f099: Verifying Checksum
2025-11-10 00:16:18.028660 | controller | 2e5170e1f099: Download complete
2025-11-10 00:16:18.028785 | controller | 7641eb41b08c: Verifying Checksum
2025-11-10 00:16:18.951133 | controller | 7641eb41b08c: Download complete
2025-11-10 00:16:18.951211 | controller | ad15fa9da398: Verifying Checksum
2025-11-10 00:16:19.164128 | controller | ad15fa9da398: Download complete
2025-11-10 00:16:19.164218 | controller | 087d91352424: Verifying Checksum
2025-11-10 00:16:19.883014 | controller | 087d91352424: Download complete
2025-11-10 00:16:19.883145 | controller | 8b92efd6a100: Verifying Checksum
2025-11-10 00:16:23.956531 | controller | 8b92efd6a100: Download complete
2025-11-10 00:16:23.956610 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-10 00:16:28.232864 | controller | 04a5f4cda3ee: Download complete
2025-11-10 00:16:28.232944 | controller | 04a5f4cda3ee: Pull complete
2025-11-10 00:16:28.278839 | controller | ff496a88c8ed: Pull complete
2025-11-10 00:16:28.331915 | controller | 0ce83f459fe7: Pull complete
2025-11-10 00:16:29.931160 | controller | 2e5170e1f099: Pull complete
2025-11-10 00:16:29.975213 | controller | 7641eb41b08c: Pull complete
2025-11-10 00:16:30.018362 | controller | ad15fa9da398: Pull complete
2025-11-10 00:16:30.998857 | controller | 087d91352424: Pull complete
2025-11-10 00:16:31.048231 | controller | 8b92efd6a100: Pull complete
2025-11-10 00:16:31.091684 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-10 00:16:31.099302 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:16:31.110464 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:16:31.114675 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:16:34.027152 | controller | Run command: docker inspect 1b09669183b7ec6cefc141bf51d670e1adc0b800ffdca87e5934c5a958595b0d
2025-11-10 00:16:34.107130 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-10 00:16:34.120746 | controller | Including collection: ansible.netcommon (292 files)
2025-11-10 00:16:34.120853 | controller | Including collection: ansible.network (23 files)
2025-11-10 00:16:34.135698 | controller | Including collection: ansible.utils (442 files)
2025-11-10 00:16:34.191012 | controller | Including collection: arista.eos (1022 files)
2025-11-10 00:16:34.245310 | controller | Including collection: cisco.ios (1172 files)
2025-11-10 00:16:34.303295 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-10 00:16:34.385888 | controller | Including collection: cisco.nxos (1684 files)
2025-11-10 00:16:34.441012 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-10 00:16:34.486238 | controller | Creating a payload archive containing 7367 files...
2025-11-10 00:16:36.738049 | controller | Created a 5981815 byte payload archive containing 7367 files in 2 seconds.
2025-11-10 00:16:36.742348 | controller | Assuming Docker is available on localhost.
2025-11-10 00:16:36.743239 | 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
2025-11-10 00:16:37.358554 | controller | Run command: docker exec -i e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be dd of=/root/docker.sh bs=65536
2025-11-10 00:16:37.464142 | controller | Run command: docker exec e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be /bin/bash /root/docker.sh
2025-11-10 00:16:37.564161 | controller | Run command: docker exec -i e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be dd of=/root/test.tgz bs=65536
2025-11-10 00:16:37.680452 | controller | Run command: docker exec e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be tar oxzf /root/test.tgz -C /root
2025-11-10 00:16:38.822947 | controller | Run command: docker exec e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be /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-ql_0wtsw.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-10 00:16:39.309796 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-10 00:16:39.310293 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-10 00:16:39.310755 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-10 00:16:39.313021 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-10 00:16:39.314147 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2025-11-10 00:16:39.321157 | controller | No tests applicable.
2025-11-10 00:16:39.322776 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-10 00:16:40.387471 | controller | No tests applicable.
2025-11-10 00:16:40.387547 | controller | Running sanity test 'changelog' with Python 3.6
2025-11-10 00:16:40.387556 | 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
2025-11-10 00:16:40.387578 | 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
2025-11-10 00:16:41.032966 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2025-11-10 00:16:56.057513 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-10 00:17:11.573843 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-10 00:17:27.592191 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-10 00:17:44.611839 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-10 00:18:03.631377 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2025-11-10 00:18:18.648453 | 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)
2025-11-10 00:18:18.649318 | 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))
2025-11-10 00:18:18.754614 | 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.
2025-11-10 00:18:18.817711 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-10 00:18:18.817778 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-10 00:18:18.817786 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-10 00:18:18.817805 | controller | Run command: docker exec e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2025-11-10 00:18:18.917381 | controller | Run command: docker exec -i e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be dd if=/root/output.tgz bs=65536
2025-11-10 00:18:19.009629 | controller | Run command: tar oxzf /tmp/ansible-result-ccw3hhal.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-10 00:18:19.024296 | controller | Run command: docker rm -f 1b09669183b7ec6cefc141bf51d670e1adc0b800ffdca87e5934c5a958595b0d
2025-11-10 00:18:19.322425 | controller | Run command: docker rm -f e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be
2025-11-10 00:18:20.139983 | controller | ERROR: Command "docker exec e30d74641bf1e89ab062c295ec1de261e5646193c24e2b9e5ada9787c2a1f0be /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-ql_0wtsw.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-10 00:18:20.539307 | controller | ERROR
2025-11-10 00:18:20.539644 | controller | {
2025-11-10 00:18:20.539761 | controller |   "delta": "0:03:33.111548",
2025-11-10 00:18:20.539814 | controller |   "end": "2025-11-10 00:18:20.210226",
2025-11-10 00:18:20.539861 | controller |   "msg": "non-zero return code",
2025-11-10 00:18:20.539906 | controller |   "rc": 1,
2025-11-10 00:18:20.539949 | controller |   "start": "2025-11-10 00:14:47.098678"
2025-11-10 00:18:20.540011 | controller | }
failure
2025-11-10 00:18:20.549391 | 
2025-11-10 00:18:20.549644 | PLAY RECAP
2025-11-10 00:18:20.549784 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-10 00:18:20.549843 | 
2025-11-10 00:18:20.713084 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-10 00:18:20.714085 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-10 00:18:21.311259 | 
2025-11-10 00:18:21.311421 | PLAY [all]
2025-11-10 00:18:21.334384 | 
2025-11-10 00:18:21.334542 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-10 00:18:24.418442 | controller | changed: non-zero return code
2025-11-10 00:18:24.428248 | 
2025-11-10 00:18:24.428447 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-10 00:18:24.455341 | controller | skipping: Conditional result was False
2025-11-10 00:18:24.462538 | 
2025-11-10 00:18:24.462729 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-10 00:18:24.500107 | 
2025-11-10 00:18:24.500356 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-10 00:18:24.533877 | 
2025-11-10 00:18:24.534095 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-10 00:18:24.558892 | controller | skipping: Conditional result was False
2025-11-10 00:18:24.566352 | 
2025-11-10 00:18:24.566432 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-10 00:18:24.598117 | 
2025-11-10 00:18:24.598308 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-10 00:18:24.622932 | controller | skipping: Conditional result was False
2025-11-10 00:18:24.631348 | 
2025-11-10 00:18:24.631428 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-10 00:18:24.656293 | controller | skipping: Conditional result was False
2025-11-10 00:18:24.663956 | 
2025-11-10 00:18:24.664060 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-10 00:18:24.689100 | controller | skipping: Conditional result was False
2025-11-10 00:18:24.715606 | 
2025-11-10 00:18:24.715819 | PLAY RECAP
2025-11-10 00:18:24.715868 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-10 00:18:24.715889 | 
2025-11-10 00:18:24.853771 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-10 00:18:24.854717 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-10 00:18:25.491821 | 
2025-11-10 00:18:25.491958 | PLAY [all:!appliance*]
2025-11-10 00:18:25.523300 | 
2025-11-10 00:18:25.523430 | TASK [unregister the node]
2025-11-10 00:18:25.877506 | controller | skipping: Conditional result was False
2025-11-10 00:18:25.883306 | 
2025-11-10 00:18:25.883392 | TASK [include_role : fetch-output]
2025-11-10 00:18:25.923271 | controller | ok
2025-11-10 00:18:25.943633 | 
2025-11-10 00:18:25.943773 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-10 00:18:26.019002 | controller | skipping: Conditional result was False
2025-11-10 00:18:26.027729 | 
2025-11-10 00:18:26.027843 | TASK [fetch-output : Set log path for single node]
2025-11-10 00:18:26.071861 | controller | ok
2025-11-10 00:18:26.086740 | 
2025-11-10 00:18:26.086869 | LOOP [fetch-output : Ensure local output dirs]
2025-11-10 00:18:26.506192 | controller -> localhost | ok: "/var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/logs"
2025-11-10 00:18:26.758134 | controller -> localhost | changed: "/var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/artifacts"
2025-11-10 00:18:27.008248 | controller -> localhost | changed: "/var/lib/zuul/builds/949d8b56e7484ede81f3659e1db5f32d/work/docs"
2025-11-10 00:18:27.024005 | 
2025-11-10 00:18:27.024192 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-10 00:18:29.255794 | controller | changed:
2025-11-10 00:18:29.256100 | controller | .d..t...... ./
2025-11-10 00:18:29.256134 | controller | cd+++++++++ controller/
2025-11-10 00:18:29.256202 | controller | changed: All items complete
2025-11-10 00:18:29.256236 | 
2025-11-10 00:18:31.400118 | controller | changed: .d..t...... ./
2025-11-10 00:18:33.502139 | controller | changed: .d..t...... ./
2025-11-10 00:18:33.531456 | 
2025-11-10 00:18:33.531664 | TASK [include_role : fetch-output-openshift]
2025-11-10 00:18:33.558314 | controller | skipping: Conditional result was False
2025-11-10 00:18:33.572487 | 
2025-11-10 00:18:33.572949 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-10 00:18:33.624208 | controller | skipping: Conditional result was False
2025-11-10 00:18:33.648062 | controller | skipping: Conditional result was False
2025-11-10 00:18:33.696696 | 
2025-11-10 00:18:33.696810 | PLAY [localhost]
2025-11-10 00:18:33.711228 | 
2025-11-10 00:18:33.711311 | TASK [Run Zuul manifest role]
2025-11-10 00:18:33.731567 | localhost | ok
2025-11-10 00:18:33.744415 | 
2025-11-10 00:18:33.744496 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-10 00:18:34.212594 | localhost | changed
2025-11-10 00:18:34.217957 | 
2025-11-10 00:18:34.218038 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-10 00:18:34.262035 | localhost | ok
2025-11-10 00:18:34.285437 | 
2025-11-10 00:18:34.285624 | TASK [Set zuul-log-path fact]
2025-11-10 00:18:34.343391 | localhost | ok
2025-11-10 00:18:34.357870 | 
2025-11-10 00:18:34.358058 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-10 00:18:34.397234 | localhost | ok
2025-11-10 00:18:34.405500 | 
2025-11-10 00:18:34.405563 | LOOP [Run upload-logs-swift role]
2025-11-10 00:18:34.452155 | localhost | Output suppressed because no_log was given
2025-11-10 00:18:34.480094 | 
2025-11-10 00:18:34.480171 | TASK [Set zuul-log-path fact]
2025-11-10 00:18:34.516443 | localhost | skipping: Conditional result was False
2025-11-10 00:18:34.522342 | 
2025-11-10 00:18:34.522412 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-10 00:18:34.933817 | localhost -> localhost | ok: Runtime: 0:00:00.007060
2025-11-10 00:18:34.939805 | 
2025-11-10 00:18:34.939875 | TASK [upload-logs-swift : Upload logs to swift]