2026-03-02 00:29:37.823425 | Job console starting...
2026-03-02 00:29:37.834068 | Updating repositories
2026-03-02 00:29:37.978816 | Preparing job workspace
2026-03-02 00:29:41.693383 | Running Ansible setup...
2026-03-02 00:29:49.657555 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-02 00:29:50.282101 | 
2026-03-02 00:29:50.282220 | PLAY [localhost]
2026-03-02 00:29:50.290272 | 
2026-03-02 00:29:50.290342 | TASK [Gathering Facts]
2026-03-02 00:29:51.186350 | localhost | ok
2026-03-02 00:29:51.206148 | 
2026-03-02 00:29:51.206258 | TASK [Setup log path fact]
2026-03-02 00:29:51.225495 | localhost | ok
2026-03-02 00:29:51.238198 | 
2026-03-02 00:29:51.238267 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-02 00:29:51.265891 | localhost | ok
2026-03-02 00:29:51.273864 | 
2026-03-02 00:29:51.273930 | TASK [emit-job-header : Print job information]
2026-03-02 00:29:51.313073 | # Job Information
2026-03-02 00:29:51.313240 | Ansible Version: 2.15.12
2026-03-02 00:29:51.313280 | Job: ansible-test-sanity-docker-stable-2.10
2026-03-02 00:29:51.313307 | Pipeline: periodic
2026-03-02 00:29:51.313330 | Executor: ze01.softwarefactory-project.io
2026-03-02 00:29:51.313352 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-03-02 00:29:51.313377 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/318/ansible/31863ff63c664dc3876399e40bdb58b8/
2026-03-02 00:29:51.313403 | Event ID: 2eb49f0a79aa4455b1e117a286c05c7e
2026-03-02 00:29:51.318296 | 
2026-03-02 00:29:51.318369 | LOOP [emit-job-header : Print node information]
2026-03-02 00:29:51.453815 | localhost | ok:
2026-03-02 00:29:51.454094 | localhost | # Node Information
2026-03-02 00:29:51.454154 | localhost | Inventory Hostname: controller
2026-03-02 00:29:51.454197 | localhost | Hostname: np0005634908
2026-03-02 00:29:51.454239 | localhost | Username: zuul
2026-03-02 00:29:51.454286 | localhost | Distro: Fedora 37
2026-03-02 00:29:51.454325 | localhost | Provider: ansible-vexxhost-ams1
2026-03-02 00:29:51.454363 | localhost | Region: ams1
2026-03-02 00:29:51.454400 | localhost | Label: ansible-fedora-37-1vcpu
2026-03-02 00:29:51.454436 | localhost | Product Name: OpenStack Nova
2026-03-02 00:29:51.454472 | localhost | Interface IP: 38.129.16.222
2026-03-02 00:29:51.472427 | 
2026-03-02 00:29:51.472565 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-02 00:29:51.864307 | localhost -> localhost | changed
2026-03-02 00:29:51.879595 | 
2026-03-02 00:29:51.879771 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-02 00:29:52.780461 | localhost -> localhost | changed
2026-03-02 00:29:52.805167 | 
2026-03-02 00:29:52.805289 | PLAY [all:!appliance*]
2026-03-02 00:29:52.829258 | 
2026-03-02 00:29:52.829377 | TASK [include_role : start-zuul-console]
2026-03-02 00:29:52.853180 | controller | ok
2026-03-02 00:29:52.872245 | 
2026-03-02 00:29:52.872348 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-03-02 00:29:53.906771 | controller | ok
2026-03-02 00:29:53.917883 | 
2026-03-02 00:29:53.917957 | TASK [use-our-mirror : Retrieve the IP address]
2026-03-02 00:29:56.368282 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-03-02 00:29:56.377150 | 
2026-03-02 00:29:56.377248 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-03-02 00:29:56.740959 | controller | skipping: Conditional result was False
2026-03-02 00:29:56.747683 | 
2026-03-02 00:29:56.747756 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-03-02 00:29:56.773059 | controller | skipping: Conditional result was False
2026-03-02 00:29:56.779198 | 
2026-03-02 00:29:56.779263 | TASK [use-our-mirror : Create the podman configuration directory]
2026-03-02 00:29:56.803277 | controller | skipping: Conditional result was False
2026-03-02 00:29:56.810073 | 
2026-03-02 00:29:56.810141 | TASK [use-our-mirror : Copy the podman configuration]
2026-03-02 00:29:56.825355 | controller | skipping: Conditional result was False
2026-03-02 00:29:56.831896 | 
2026-03-02 00:29:56.831963 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-03-02 00:29:56.856108 | controller | skipping: Conditional result was False
2026-03-02 00:29:56.862394 | 
2026-03-02 00:29:56.862463 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-03-02 00:29:56.886444 | controller | skipping: Conditional result was False
2026-03-02 00:29:56.897395 | 
2026-03-02 00:29:56.897471 | TASK [Disable Fedora Modular]
2026-03-02 00:29:58.140738 | controller | changed
2026-03-02 00:29:58.154551 | 
2026-03-02 00:29:58.154687 | TASK [Enable EPEL]
2026-03-02 00:29:58.183370 | controller | skipping: Conditional result was False
2026-03-02 00:29:58.198484 | 
2026-03-02 00:29:58.198648 | TASK [Register the RHEL node]
2026-03-02 00:29:58.614274 | 
2026-03-02 00:29:58.614553 | TASK [Show the subscription-manager status]
2026-03-02 00:29:58.989856 | controller | skipping: Conditional result was False
2026-03-02 00:29:59.004323 | 
2026-03-02 00:29:59.004459 | TASK [Enable EPEL on RHEL]
2026-03-02 00:29:59.403458 | controller | skipping: Conditional result was False
2026-03-02 00:29:59.419422 | 
2026-03-02 00:29:59.419591 | TASK [Install git and tox]
2026-03-02 00:32:18.896146 | controller | changed
2026-03-02 00:32:18.905455 | 
2026-03-02 00:32:18.905542 | TASK [include_role : prepare-workspace]
2026-03-02 00:32:18.940493 | controller | ok
2026-03-02 00:32:18.969000 | 
2026-03-02 00:32:18.969118 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-02 00:32:19.876578 | controller | ok
2026-03-02 00:32:19.890201 | 
2026-03-02 00:32:19.890331 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-02 00:32:35.771298 | controller | Output suppressed because no_log was given
2026-03-02 00:32:35.782706 | 
2026-03-02 00:32:35.782878 | TASK [include_role : prepare-workspace-openshift]
2026-03-02 00:32:35.807487 | controller | skipping: Conditional result was False
2026-03-02 00:32:35.833714 | 
2026-03-02 00:32:35.833807 | PLAY [all:!appliance]
2026-03-02 00:32:35.849709 | 
2026-03-02 00:32:35.849786 | TASK [Run add-build-sshkey role (RSA)]
2026-03-02 00:32:35.882246 | controller | ok
2026-03-02 00:32:35.901459 | 
2026-03-02 00:32:35.901594 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-02 00:32:36.161594 | controller -> localhost | ok
2026-03-02 00:32:36.168543 | 
2026-03-02 00:32:36.168692 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-02 00:32:36.189903 | controller | ok
2026-03-02 00:32:36.204433 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-02 00:32:36.210640 | 
2026-03-02 00:32:36.210710 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-02 00:32:36.714460 | controller -> localhost | Generating public/private rsa key pair.
2026-03-02 00:32:36.714725 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/31863ff63c664dc3876399e40bdb58b8_id_rsa.
2026-03-02 00:32:36.714755 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/31863ff63c664dc3876399e40bdb58b8_id_rsa.pub.
2026-03-02 00:32:36.714775 | controller -> localhost | The key fingerprint is:
2026-03-02 00:32:36.714794 | controller -> localhost | SHA256:D32f9E5lD8i7MzxMFeekgYJpCaPZ6/sKohcUZB/S7cI zuul-build-sshkey
2026-03-02 00:32:36.714812 | controller -> localhost | The key's randomart image is:
2026-03-02 00:32:36.714829 | controller -> localhost | +---[RSA 2048]----+
2026-03-02 00:32:36.714846 | controller -> localhost | | .+...o. +   .   |
2026-03-02 00:32:36.714864 | controller -> localhost | | ..o.=..= . . o o|
2026-03-02 00:32:36.714881 | controller -> localhost | |   o+...   .   B |
2026-03-02 00:32:36.714897 | controller -> localhost | |  . E .. . . .o .|
2026-03-02 00:32:36.714913 | controller -> localhost | | .   .. S . +.o o|
2026-03-02 00:32:36.714939 | controller -> localhost | |  .  .   o ..+ =o|
2026-03-02 00:32:36.714958 | controller -> localhost | |  ... .   .+. o +|
2026-03-02 00:32:36.714978 | controller -> localhost | | ... . .    *. o |
2026-03-02 00:32:36.714996 | controller -> localhost | |..    oo.   .+  .|
2026-03-02 00:32:36.715037 | controller -> localhost | +----[SHA256]-----+
2026-03-02 00:32:36.715097 | controller -> localhost | ok: Runtime: 0:00:00.100315
2026-03-02 00:32:36.723644 | 
2026-03-02 00:32:36.723719 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-02 00:32:36.758208 | controller | ok
2026-03-02 00:32:36.774958 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-02 00:32:36.784328 | 
2026-03-02 00:32:36.784398 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-02 00:32:36.818816 | controller | skipping: Conditional result was False
2026-03-02 00:32:36.825528 | 
2026-03-02 00:32:36.825593 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-02 00:32:37.917173 | controller | changed
2026-03-02 00:32:37.925382 | 
2026-03-02 00:32:37.925487 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-02 00:32:38.610770 | controller | ok
2026-03-02 00:32:38.619590 | 
2026-03-02 00:32:38.619708 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-02 00:32:41.730820 | controller | changed
2026-03-02 00:32:41.741588 | 
2026-03-02 00:32:41.741680 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-02 00:32:44.856847 | controller | changed
2026-03-02 00:32:44.872750 | 
2026-03-02 00:32:44.872941 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-02 00:32:44.906420 | controller | skipping: Conditional result was False
2026-03-02 00:32:44.913826 | 
2026-03-02 00:32:44.913906 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-02 00:32:45.377530 | controller -> localhost | changed
2026-03-02 00:32:45.390050 | 
2026-03-02 00:32:45.390158 | TASK [add-build-sshkey : Add back temp key]
2026-03-02 00:32:45.714097 | controller -> localhost | Identity added: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/31863ff63c664dc3876399e40bdb58b8_id_rsa (zuul-build-sshkey)
2026-03-02 00:32:45.714342 | controller -> localhost | ok: Runtime: 0:00:00.012022
2026-03-02 00:32:45.720714 | 
2026-03-02 00:32:45.720790 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-02 00:32:46.753956 | controller | ok
2026-03-02 00:32:46.760243 | 
2026-03-02 00:32:46.760376 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-02 00:32:46.815619 | controller | skipping: Conditional result was False
2026-03-02 00:32:46.830523 | 
2026-03-02 00:32:46.830649 | TASK [Run add-build-sshkey role (ECDSA)]
2026-03-02 00:32:46.861460 | controller | ok
2026-03-02 00:32:46.878706 | 
2026-03-02 00:32:46.878775 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-02 00:32:47.096570 | controller -> localhost | ok
2026-03-02 00:32:47.103388 | 
2026-03-02 00:32:47.103451 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-02 00:32:47.142404 | controller | ok
2026-03-02 00:32:47.153851 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-02 00:32:47.160457 | 
2026-03-02 00:32:47.160519 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-02 00:32:47.481772 | controller -> localhost | Generating public/private ecdsa key pair.
2026-03-02 00:32:47.482151 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/31863ff63c664dc3876399e40bdb58b8_id_ecdsa.
2026-03-02 00:32:47.482214 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/31863ff63c664dc3876399e40bdb58b8_id_ecdsa.pub.
2026-03-02 00:32:47.482276 | controller -> localhost | The key fingerprint is:
2026-03-02 00:32:47.482319 | controller -> localhost | SHA256:+Uva5Jq4gb26rfwe0oPVRwsbvfZaKpKESP5bCHv+IV8 zuul-build-sshkey
2026-03-02 00:32:47.482360 | controller -> localhost | The key's randomart image is:
2026-03-02 00:32:47.482400 | controller -> localhost | +---[ECDSA 521]---+
2026-03-02 00:32:47.482438 | controller -> localhost | |                 |
2026-03-02 00:32:47.482475 | controller -> localhost | |         .       |
2026-03-02 00:32:47.482512 | controller -> localhost | |        o o      |
2026-03-02 00:32:47.482571 | controller -> localhost | |  .    . * o     |
2026-03-02 00:32:47.482610 | controller -> localhost | | o.. .. S =      |
2026-03-02 00:32:47.482647 | controller -> localhost | |  oo.B.  + .     |
2026-03-02 00:32:47.482684 | controller -> localhost | |  ..B.O.E + o    |
2026-03-02 00:32:47.482720 | controller -> localhost | |   +.*oO.B =     |
2026-03-02 00:32:47.482755 | controller -> localhost | |    BOXo+o*      |
2026-03-02 00:32:47.482792 | controller -> localhost | +----[SHA256]-----+
2026-03-02 00:32:47.482890 | controller -> localhost | ok: Runtime: 0:00:00.016140
2026-03-02 00:32:47.498440 | 
2026-03-02 00:32:47.498524 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-02 00:32:47.530370 | controller | ok
2026-03-02 00:32:47.540386 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-02 00:32:47.552209 | 
2026-03-02 00:32:47.552295 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-02 00:32:47.576168 | controller | skipping: Conditional result was False
2026-03-02 00:32:47.582592 | 
2026-03-02 00:32:47.582657 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-02 00:32:48.484046 | controller | changed
2026-03-02 00:32:48.491181 | 
2026-03-02 00:32:48.491349 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-02 00:32:49.282857 | controller | ok
2026-03-02 00:32:49.291687 | 
2026-03-02 00:32:49.291781 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-02 00:32:52.451006 | controller | changed
2026-03-02 00:32:52.464959 | 
2026-03-02 00:32:52.465154 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-02 00:32:55.517859 | controller | changed
2026-03-02 00:32:55.531100 | 
2026-03-02 00:32:55.531258 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-02 00:32:55.561257 | controller | skipping: Conditional result was False
2026-03-02 00:32:55.571410 | 
2026-03-02 00:32:55.571510 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-02 00:32:55.809980 | controller -> localhost | changed
2026-03-02 00:32:55.822537 | 
2026-03-02 00:32:55.822660 | TASK [add-build-sshkey : Add back temp key]
2026-03-02 00:32:56.122171 | controller -> localhost | Identity added: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/31863ff63c664dc3876399e40bdb58b8_id_ecdsa (zuul-build-sshkey)
2026-03-02 00:32:56.122471 | controller -> localhost | ok: Runtime: 0:00:00.014045
2026-03-02 00:32:56.130914 | 
2026-03-02 00:32:56.131200 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-02 00:32:56.870074 | controller | ok
2026-03-02 00:32:56.877678 | 
2026-03-02 00:32:56.877766 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-02 00:32:56.922871 | controller | skipping: Conditional result was False
2026-03-02 00:32:56.938541 | 
2026-03-02 00:32:56.938640 | TASK [include_role : remove-zuul-sshkey]
2026-03-02 00:32:56.963273 | controller | skipping: Conditional result was False
2026-03-02 00:32:56.972430 | 
2026-03-02 00:32:56.972542 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-02 00:32:57.691927 | controller | ok: "logs"
2026-03-02 00:32:57.692412 | controller | ok: All items complete
2026-03-02 00:32:57.692470 | 
2026-03-02 00:32:58.305257 | controller | ok: "artifacts"
2026-03-02 00:32:58.999248 | controller | ok: "docs"
2026-03-02 00:32:59.016380 | 
2026-03-02 00:32:59.016466 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-02 00:32:59.733111 | controller | changed: "logs"
2026-03-02 00:33:00.445513 | controller | changed: "artifacts"
2026-03-02 00:33:01.126957 | controller | changed: "docs"
2026-03-02 00:33:01.172134 | 
2026-03-02 00:33:01.172257 | PLAY RECAP
2026-03-02 00:33:01.172314 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-03-02 00:33:01.172349 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-02 00:33:01.172374 | 
2026-03-02 00:33:01.294634 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-03-02 00:33:01.295577 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-02 00:33:01.842339 | 
2026-03-02 00:33:01.842460 | PLAY [all]
2026-03-02 00:33:01.864059 | 
2026-03-02 00:33:01.864135 | TASK [Install binary dependencies]
2026-03-02 00:33:01.924185 | controller | ok
2026-03-02 00:33:01.943508 | 
2026-03-02 00:33:01.943584 | TASK [bindep : Include find tasks]
2026-03-02 00:33:01.983505 | controller | ok
2026-03-02 00:33:01.991382 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-03-02 00:33:01.997821 | 
2026-03-02 00:33:01.997885 | TASK [bindep : Look for bindep.txt]
2026-03-02 00:33:02.998712 | controller | ok
2026-03-02 00:33:03.007534 | 
2026-03-02 00:33:03.007635 | TASK [bindep : Define bindep_file fact]
2026-03-02 00:33:03.032890 | controller | skipping: Conditional result was False
2026-03-02 00:33:03.039078 | 
2026-03-02 00:33:03.039146 | TASK [bindep : Look for other-requirements.txt]
2026-03-02 00:33:03.727152 | controller | ok
2026-03-02 00:33:03.740401 | 
2026-03-02 00:33:03.740555 | TASK [bindep : Define bindep_file fact]
2026-03-02 00:33:03.765173 | controller | skipping: Conditional result was False
2026-03-02 00:33:03.771577 | 
2026-03-02 00:33:03.771647 | TASK [bindep : Look for bindep fallback file]
2026-03-02 00:33:03.805273 | controller | skipping: Conditional result was False
2026-03-02 00:33:03.814670 | 
2026-03-02 00:33:03.814788 | TASK [bindep : Define bindep_file fact]
2026-03-02 00:33:03.850811 | controller | skipping: Conditional result was False
2026-03-02 00:33:03.860338 | 
2026-03-02 00:33:03.860429 | TASK [bindep : Include bindep tasks]
2026-03-02 00:33:03.896380 | controller | skipping: Conditional result was False
2026-03-02 00:33:03.905214 | 
2026-03-02 00:33:03.905304 | TASK [bindep : Include install tasks]
2026-03-02 00:33:03.940626 | controller | skipping: Conditional result was False
2026-03-02 00:33:03.949544 | 
2026-03-02 00:33:03.949640 | LOOP [bindep : Include package tasks]
2026-03-02 00:33:04.018682 | 
2026-03-02 00:33:04.018849 | TASK [Run test-setup role]
2026-03-02 00:33:04.038967 | controller | ok
2026-03-02 00:33:04.055842 | 
2026-03-02 00:33:04.055938 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-03-02 00:33:04.812505 | controller | ok
2026-03-02 00:33:04.821097 | 
2026-03-02 00:33:04.821182 | TASK [test-setup : Run tools/test-setup.sh]
2026-03-02 00:33:05.654302 | controller | skipping: Conditional result was False
2026-03-02 00:33:05.725007 | 
2026-03-02 00:33:05.725285 | PLAY RECAP
2026-03-02 00:33:05.725349 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-02 00:33:05.725379 | 
2026-03-02 00:33:05.839126 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-03-02 00:33:05.840859 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-02 00:33:06.444624 | 
2026-03-02 00:33:06.444745 | PLAY [controller]
2026-03-02 00:33:06.464319 | 
2026-03-02 00:33:06.464393 | TASK [Create the /root directory]
2026-03-02 00:33:07.478452 | controller | ok
2026-03-02 00:33:07.492611 | 
2026-03-02 00:33:07.492885 | TASK [Install glibc-langpack-en]
2026-03-02 00:33:15.736554 | controller | ok: Nothing to do
2026-03-02 00:33:15.749662 | 
2026-03-02 00:33:15.749804 | TASK [Ensure controller directory exists]
2026-03-02 00:33:16.635163 | controller | changed
2026-03-02 00:33:16.647300 | 
2026-03-02 00:33:16.647438 | TASK [Install container runtime]
2026-03-02 00:33:16.726854 | controller | ok
2026-03-02 00:33:16.792091 | 
2026-03-02 00:33:16.792235 | LOOP [ensure-docker : Gather variables for each operating system]
2026-03-02 00:33:16.852438 | controller | ok: "/var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2026-03-02 00:33:16.874933 | 
2026-03-02 00:33:16.875158 | TASK [ensure-docker : Sanity check for distro selection]
2026-03-02 00:33:16.902332 | controller | skipping: Conditional result was False
2026-03-02 00:33:16.917769 | 
2026-03-02 00:33:16.917918 | TASK [ensure-docker : Sanity check for distro install]
2026-03-02 00:33:16.954996 | controller | skipping: Conditional result was False
2026-03-02 00:33:16.970434 | 
2026-03-02 00:33:16.970636 | TASK [ensure-docker : Sanity check for upstream install]
2026-03-02 00:33:16.998684 | controller | skipping: Conditional result was False
2026-03-02 00:33:17.012859 | 
2026-03-02 00:33:17.013074 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2026-03-02 00:33:17.050315 | controller | skipping: Conditional result was False
2026-03-02 00:33:17.064072 | 
2026-03-02 00:33:17.064236 | TASK [ensure-docker : Include tasks for docker install]
2026-03-02 00:33:17.102872 | controller | ok
2026-03-02 00:33:17.122567 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2026-03-02 00:33:17.136137 | 
2026-03-02 00:33:17.136234 | TASK [ensure-docker : Not Implemented]
2026-03-02 00:33:17.151762 | controller | skipping: Conditional result was False
2026-03-02 00:33:17.160859 | 
2026-03-02 00:33:17.160945 | TASK [Set up docker mirrors]
2026-03-02 00:33:17.183064 | controller | ok
2026-03-02 00:33:17.209137 | 
2026-03-02 00:33:17.209234 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2026-03-02 00:33:17.244448 | controller | skipping: Conditional result was False
2026-03-02 00:33:17.253706 | 
2026-03-02 00:33:17.253803 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2026-03-02 00:33:17.289131 | controller | skipping: Conditional result was False
2026-03-02 00:33:17.330906 | 
2026-03-02 00:33:17.331101 | TASK [use-docker-mirror : Set docker_mirror fact]
2026-03-02 00:33:17.376568 | controller | skipping: Conditional result was False
2026-03-02 00:33:17.385446 | 
2026-03-02 00:33:17.385538 | TASK [use-docker-mirror : Set up docker mirrors]
2026-03-02 00:33:17.411098 | controller | skipping: Conditional result was False
2026-03-02 00:33:17.434108 | 
2026-03-02 00:33:17.434206 | TASK [Add all repositories]
2026-03-02 00:33:17.457398 | controller | ok
2026-03-02 00:33:17.490359 | 
2026-03-02 00:33:17.490489 | LOOP [ensure-package-repositories : Install repositories]
2026-03-02 00:33:17.532574 | controller | ok: "/var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2026-03-02 00:33:17.532930 | controller | ok: All items complete
2026-03-02 00:33:17.532986 | 
2026-03-02 00:33:17.548487 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2026-03-02 00:33:17.554818 | 
2026-03-02 00:33:17.554894 | TASK [ensure-package-repositories : Create GPG key temp directory]
2026-03-02 00:33:18.601124 | controller | changed
2026-03-02 00:33:18.614979 | 
2026-03-02 00:33:18.615234 | LOOP [ensure-package-repositories : Upload GPG keys]
2026-03-02 00:33:21.850272 | controller | changed:
2026-03-02 00:33:21.850768 | controller | {
2026-03-02 00:33:21.850849 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-03-02 00:33:21.850896 | controller | }
2026-03-02 00:33:21.875310 | 
2026-03-02 00:33:21.875514 | LOOP [ensure-package-repositories : Import GPG keys]
2026-03-02 00:33:23.300102 | controller | changed:
2026-03-02 00:33:23.300429 | controller | {
2026-03-02 00:33:23.300485 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2026-03-02 00:33:23.300529 | controller | }
2026-03-02 00:33:23.300588 | controller | changed: All items complete
2026-03-02 00:33:23.300660 | 
2026-03-02 00:33:23.317748 | 
2026-03-02 00:33:23.317909 | TASK [ensure-package-repositories : Remove GPG temp directory]
2026-03-02 00:33:24.052370 | controller | changed
2026-03-02 00:33:24.066170 | 
2026-03-02 00:33:24.066315 | LOOP [ensure-package-repositories : Add repositories]
2026-03-02 00:33:24.974371 | controller | changed:
2026-03-02 00:33:24.974630 | controller | {
2026-03-02 00:33:24.974671 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2026-03-02 00:33:24.974698 | controller |   "description": "Docker CE Stable - $basearch",
2026-03-02 00:33:24.974721 | controller |   "gpgcheck": true,
2026-03-02 00:33:24.974744 | controller |   "name": "docker-ce-stable"
2026-03-02 00:33:24.974767 | controller | }
2026-03-02 00:33:24.992260 | 
2026-03-02 00:33:24.992422 | TASK [ensure-docker : Install pre-reqs]
2026-03-02 00:33:30.597746 | controller | ok: Nothing to do
2026-03-02 00:33:30.610677 | 
2026-03-02 00:33:30.610808 | TASK [ensure-docker : Remove packages]
2026-03-02 00:33:33.988672 | controller | ok: Nothing to do
2026-03-02 00:33:34.001863 | 
2026-03-02 00:33:34.002000 | TASK [ensure-docker : Install upstream docker using package]
2026-03-02 00:34:59.110799 | controller | changed
2026-03-02 00:34:59.123818 | 
2026-03-02 00:34:59.123955 | TASK [ensure-docker : Setup docker]
2026-03-02 00:34:59.196507 | controller | ok
2026-03-02 00:34:59.210558 | controller | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2026-03-02 00:34:59.221690 | 
2026-03-02 00:34:59.221754 | TASK [ensure-docker : Ensure "docker" group exists]
2026-03-02 00:35:00.227460 | controller | ok
2026-03-02 00:35:00.240862 | 
2026-03-02 00:35:00.241072 | TASK [ensure-docker : Add user to docker group]
2026-03-02 00:35:01.243552 | controller | changed
2026-03-02 00:35:01.251969 | 
2026-03-02 00:35:01.252086 | TASK [Update docker daemon proxy configuration]
2026-03-02 00:35:01.286972 | controller | skipping: Conditional result was False
2026-03-02 00:35:01.296526 | 
2026-03-02 00:35:01.296620 | TASK [Lower default MTU]
2026-03-02 00:35:01.321500 | controller | skipping: Conditional result was False
2026-03-02 00:35:01.331270 | 
2026-03-02 00:35:01.331363 | TASK [ensure-docker : Get the status of the docket.socket unit]
2026-03-02 00:35:02.071299 | controller | inactive
2026-03-02 00:35:02.197445 | controller | ok: Runtime: 0:00:00.016293
2026-03-02 00:35:02.211296 | 
2026-03-02 00:35:02.211436 | TASK [ensure-docker : Get the status of the docket unit]
2026-03-02 00:35:02.908996 | controller | inactive
2026-03-02 00:35:03.091377 | controller | ok: Runtime: 0:00:00.021565
2026-03-02 00:35:03.105524 | 
2026-03-02 00:35:03.105669 | TASK [ensure-docker : Restart docker]
2026-03-02 00:35:03.165252 | Trigger a Docker restart
2026-03-02 00:35:03.176757 | 
2026-03-02 00:35:03.176892 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2026-03-02 00:35:03.228501 | 
2026-03-02 00:35:03.228686 | TASK [ensure-docker : Install docker-compose]
2026-03-02 00:35:03.255621 | controller | skipping: Conditional result was False
2026-03-02 00:35:03.268135 | 
2026-03-02 00:35:03.268272 | TASK [ensure-docker : Flush handlers before role exit]
2026-03-02 00:35:03.289486 | 
2026-03-02 00:35:03.289610 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2026-03-02 00:35:06.732979 | controller | changed
2026-03-02 00:35:06.740714 | 
2026-03-02 00:35:06.740807 | TASK [ensure-docker : Assure docker service is running]
2026-03-02 00:35:10.600477 | controller | changed
2026-03-02 00:35:10.611794 | 
2026-03-02 00:35:10.611895 | TASK [ensure-docker : Assure docker.socket service is running]
2026-03-02 00:35:12.485476 | controller | ok
2026-03-02 00:35:12.501609 | 
2026-03-02 00:35:12.501743 | TASK [ensure-docker : Correct group ownership on docker sock]
2026-03-02 00:35:13.433008 | controller | ok
2026-03-02 00:35:13.446939 | 
2026-03-02 00:35:13.447123 | TASK [ensure-docker : Validate ability to talk with docker]
2026-03-02 00:35:14.143581 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2026-03-02 00:35:14.319635 | controller | ok: Runtime: 0:00:00.047599
2026-03-02 00:35:14.327086 | 
2026-03-02 00:35:14.327185 | TASK [ensure-docker : Get version details]
2026-03-02 00:35:15.045300 | controller | Client: Docker Engine - Community
2026-03-02 00:35:15.046284 | controller |  Version:           24.0.7
2026-03-02 00:35:15.046305 | controller |  API version:       1.43
2026-03-02 00:35:15.046314 | controller |  Go version:        go1.20.10
2026-03-02 00:35:15.046333 | controller |  Git commit:        afdd53b
2026-03-02 00:35:15.046341 | controller |  Built:             Thu Oct 26 09:09:39 2023
2026-03-02 00:35:15.046349 | controller |  OS/Arch:           linux/amd64
2026-03-02 00:35:15.046357 | controller |  Context:           default
2026-03-02 00:35:15.046366 | controller |
2026-03-02 00:35:15.046373 | controller | Server: Docker Engine - Community
2026-03-02 00:35:15.046379 | controller |  Engine:
2026-03-02 00:35:15.046390 | controller |   Version:          24.0.7
2026-03-02 00:35:15.047094 | controller |   API version:      1.43 (minimum version 1.12)
2026-03-02 00:35:15.047103 | controller |   Go version:       go1.20.10
2026-03-02 00:35:15.047215 | controller |   Git commit:       311b9ff
2026-03-02 00:35:15.047229 | controller |   Built:            Thu Oct 26 09:08:11 2023
2026-03-02 00:35:15.047236 | controller |   OS/Arch:          linux/amd64
2026-03-02 00:35:15.047242 | controller |   Experimental:     false
2026-03-02 00:35:15.047249 | controller |  containerd:
2026-03-02 00:35:15.047255 | controller |   Version:          1.6.25
2026-03-02 00:35:15.047273 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2026-03-02 00:35:15.047280 | controller |  runc:
2026-03-02 00:35:15.047286 | controller |   Version:          1.1.10
2026-03-02 00:35:15.047292 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2026-03-02 00:35:15.047301 | controller |  docker-init:
2026-03-02 00:35:15.047308 | controller |   Version:          0.19.0
2026-03-02 00:35:15.047314 | controller |   GitCommit:        de40ad0
2026-03-02 00:35:15.259750 | controller | ok: Runtime: 0:00:00.046882
2026-03-02 00:35:15.270749 | 
2026-03-02 00:35:15.270923 | TASK [ensure-docker : Dump installed docker details]
2026-03-02 00:35:15.323094 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-03-02 00:35:15.004108', 'end': '2026-03-02 00:35:15.050990', 'delta': '0:00:00.046882', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-de0d-fc86-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2026-03-02 00:35:15.337566 | 
2026-03-02 00:35:15.337881 | TASK [Use buildset registry]
2026-03-02 00:35:15.354505 | controller | skipping: Conditional result was False
2026-03-02 00:35:15.372086 | 
2026-03-02 00:35:15.372235 | TASK [Ensure python3.8 is present]
2026-03-02 00:35:15.387216 | controller | skipping: Conditional result was False
2026-03-02 00:35:15.396808 | 
2026-03-02 00:35:15.396906 | TASK [Run our-ensure-python role (Fedora only for now)]
2026-03-02 00:35:15.422851 | controller | ok
2026-03-02 00:35:15.446757 | 
2026-03-02 00:35:15.446963 | TASK [our-ensure-python : Install the right Python version (rpm)]
2026-03-02 00:35:21.799886 | controller | ok: Nothing to do
2026-03-02 00:35:21.811892 | 
2026-03-02 00:35:21.812079 | TASK [our-ensure-python : Also install python3-devel]
2026-03-02 00:35:38.204471 | controller | changed
2026-03-02 00:35:38.214896 | 
2026-03-02 00:35:38.214958 | TASK [Run ensure-virtualenv role]
2026-03-02 00:35:38.233770 | controller | ok
2026-03-02 00:35:38.253601 | 
2026-03-02 00:35:38.253678 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2026-03-02 00:35:39.060011 | controller | /usr/bin/virtualenv
2026-03-02 00:35:39.610850 | controller | ok: Runtime: 0:00:00.011182
2026-03-02 00:35:39.625360 | 
2026-03-02 00:35:39.625536 | LOOP [ensure-virtualenv : Install virtualenv package]
2026-03-02 00:35:39.662870 | controller | skipping: Conditional result was False
2026-03-02 00:35:39.663668 | controller | changed: All items complete
2026-03-02 00:35:39.663752 | 
2026-03-02 00:35:39.698851 | 
2026-03-02 00:35:39.699076 | TASK [Find the full path of the Python interpreter]
2026-03-02 00:35:40.531447 | controller | /usr/bin/python3.8
2026-03-02 00:35:41.076073 | controller | ok
2026-03-02 00:35:41.088322 | 
2026-03-02 00:35:41.088462 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2026-03-02 00:35:43.738271 | controller | created virtual environment CPython3.8.18.final.0-64 in 1142ms
2026-03-02 00:35:43.924207 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2026-03-02 00:35:43.924261 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2026-03-02 00:35:43.924276 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2026-03-02 00:35:43.924296 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2026-03-02 00:35:44.481776 | controller | changed
2026-03-02 00:35:44.489661 | 
2026-03-02 00:35:44.489725 | TASK [Set selinux package]
2026-03-02 00:35:44.519360 | controller | ok
2026-03-02 00:35:44.524235 | 
2026-03-02 00:35:44.524295 | TASK [Set selinux package (Fedora)]
2026-03-02 00:35:44.571680 | controller | ok
2026-03-02 00:35:44.583319 | 
2026-03-02 00:35:44.583452 | TASK [Install selinux into virtualenv]
2026-03-02 00:35:50.739828 | controller | Collecting selinux-please-lie-to-me
2026-03-02 00:35:50.847567 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2026-03-02 00:35:51.626055 | controller | Collecting setuptools<50.0.0
2026-03-02 00:35:51.632754 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2026-03-02 00:35:51.723333 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.7 MB/s eta 0:00:00
2026-03-02 00:35:51.851526 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2026-03-02 00:35:51.853827 | controller |   Attempting uninstall: setuptools
2026-03-02 00:35:51.855815 | controller |     Found existing installation: setuptools 62.6.0
2026-03-02 00:35:51.986476 | controller |     Uninstalling setuptools-62.6.0:
2026-03-02 00:35:52.003333 | controller |       Successfully uninstalled setuptools-62.6.0
2026-03-02 00:35:52.745756 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2026-03-02 00:35:52.963442 | controller |
2026-03-02 00:35:53.200844 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-02 00:35:53.200900 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-02 00:35:53.493983 | controller | ok: Runtime: 0:00:06.911354
2026-03-02 00:35:53.507859 | 
2026-03-02 00:35:53.507996 | TASK [Install pytest-forked into virtualenv]
2026-03-02 00:35:55.315301 | controller | Collecting pytest-forked
2026-03-02 00:35:55.379265 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2026-03-02 00:35:55.451777 | controller | Collecting py
2026-03-02 00:35:55.458165 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2026-03-02 00:35:55.490813 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.4 MB/s eta 0:00:00
2026-03-02 00:35:55.688568 | controller | Collecting pytest>=3.10
2026-03-02 00:35:55.696000 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2026-03-02 00:35:55.718355 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 21.7 MB/s eta 0:00:00
2026-03-02 00:35:55.838376 | controller | Collecting tomli>=1
2026-03-02 00:35:55.849072 | controller |   Downloading tomli-2.4.0-py3-none-any.whl (14 kB)
2026-03-02 00:35:55.931239 | controller | Collecting packaging
2026-03-02 00:35:55.938690 | controller |   Downloading packaging-26.0-py3-none-any.whl (74 kB)
2026-03-02 00:35:55.952724 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 8.5 MB/s eta 0:00:00
2026-03-02 00:35:56.009666 | controller | Collecting pluggy<2,>=1.5
2026-03-02 00:35:56.017878 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2026-03-02 00:35:56.061432 | controller | Collecting iniconfig
2026-03-02 00:35:56.066712 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2026-03-02 00:35:56.124983 | controller | Collecting exceptiongroup>=1.0.0rc8
2026-03-02 00:35:56.131643 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2026-03-02 00:35:56.221493 | controller | Collecting typing-extensions>=4.6.0
2026-03-02 00:35:56.228246 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2026-03-02 00:35:56.241488 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 3.9 MB/s eta 0:00:00
2026-03-02 00:35:56.352165 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2026-03-02 00:35:57.259250 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.13.2
2026-03-02 00:35:57.267340 | controller |
2026-03-02 00:35:57.488802 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2026-03-02 00:35:57.488828 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2026-03-02 00:35:57.897362 | controller | ok: Runtime: 0:00:03.039398
2026-03-02 00:35:57.911442 | 
2026-03-02 00:35:57.911627 | TASK [Update pip]
2026-03-02 00:36:00.071449 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2026-03-02 00:36:00.588663 | controller | Collecting pip
2026-03-02 00:36:00.664021 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2026-03-02 00:36:00.757654 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 20.6 MB/s eta 0:00:00
2026-03-02 00:36:00.916967 | controller | Installing collected packages: pip
2026-03-02 00:36:00.920053 | controller |   Attempting uninstall: pip
2026-03-02 00:36:00.921792 | controller |     Found existing installation: pip 22.2.2
2026-03-02 00:36:01.431444 | controller |     Uninstalling pip-22.2.2:
2026-03-02 00:36:01.462202 | controller |       Successfully uninstalled pip-22.2.2
2026-03-02 00:36:04.274328 | controller | Successfully installed pip-25.0.1
2026-03-02 00:36:04.592866 | controller | ok: Runtime: 0:00:05.454809
2026-03-02 00:36:04.606334 | 
2026-03-02 00:36:04.606498 | TASK [Ensure pip temp directory exists in /var/tmp]
2026-03-02 00:36:05.801370 | controller | changed
2026-03-02 00:36:05.813710 | 
2026-03-02 00:36:05.813881 | TASK [Install ansible into virtualenv]
2026-03-02 00:36:07.914489 | controller | Processing ./src/github.com/ansible/ansible
2026-03-02 00:36:07.925269 | controller |   Preparing metadata (setup.py): started
2026-03-02 00:36:08.910837 | controller |   Preparing metadata (setup.py): finished with status 'done'
2026-03-02 00:36:08.987578 | controller | Collecting jinja2 (from ansible-base==2.10.17.post0)
2026-03-02 00:36:09.005270 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2026-03-02 00:36:09.110945 | controller | Collecting PyYAML (from ansible-base==2.10.17.post0)
2026-03-02 00:36:09.120419 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2026-03-02 00:36:09.675885 | controller | Collecting cryptography (from ansible-base==2.10.17.post0)
2026-03-02 00:36:09.685336 | controller |   Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2026-03-02 00:36:09.710510 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.8/site-packages (from ansible-base==2.10.17.post0) (26.0)
2026-03-02 00:36:09.983658 | controller | Collecting cffi>=1.14 (from cryptography->ansible-base==2.10.17.post0)
2026-03-02 00:36:09.992337 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2026-03-02 00:36:10.005232 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible-base==2.10.17.post0) (4.13.2)
2026-03-02 00:36:10.128818 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-base==2.10.17.post0)
2026-03-02 00:36:10.137020 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2026-03-02 00:36:10.174095 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible-base==2.10.17.post0)
2026-03-02 00:36:10.180620 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2026-03-02 00:36:10.202920 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2026-03-02 00:36:10.266962 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 84.7 MB/s eta 0:00:00
2026-03-02 00:36:10.275699 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2026-03-02 00:36:10.297550 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2026-03-02 00:36:10.327625 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 26.9 MB/s eta 0:00:00
2026-03-02 00:36:10.340685 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2026-03-02 00:36:10.370868 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2026-03-02 00:36:10.386373 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2026-03-02 00:36:10.431995 | controller | Building wheels for collected packages: ansible-base
2026-03-02 00:36:10.433020 | controller |   Building wheel for ansible-base (setup.py): started
2026-03-02 00:36:12.867880 | controller |   Building wheel for ansible-base (setup.py): finished with status 'done'
2026-03-02 00:36:12.873911 | controller |   Created wheel for ansible-base: filename=ansible_base-2.10.17.post0-py3-none-any.whl size=1881569 sha256=481ad575fd1a26d68d77c1064665cb444b8a25583da330125e1dc27e258249d2
2026-03-02 00:36:12.874209 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-dltx8ed5/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2026-03-02 00:36:12.890651 | controller | Successfully built ansible-base
2026-03-02 00:36:12.952987 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-base
2026-03-02 00:36:15.912302 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-base-2.10.17.post0 cffi-1.17.1 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23
2026-03-02 00:36:16.715937 | controller | ok: Runtime: 0:00:09.396326
2026-03-02 00:36:16.728931 | 
2026-03-02 00:36:16.729118 | TASK [Setup download-artifact-fork role]
2026-03-02 00:36:16.758373 | controller | ok
2026-03-02 00:36:16.791302 | 
2026-03-02 00:36:16.791395 | TASK [download-artifact-fork : Parse inventory response]
2026-03-02 00:36:16.844962 | controller | ok
2026-03-02 00:36:16.853278 | 
2026-03-02 00:36:16.853378 | TASK [download-artifact-fork : Fetch info from Zuul API]
2026-03-02 00:36:16.889249 | controller | skipping: Conditional result was False
2026-03-02 00:36:16.902378 | 
2026-03-02 00:36:16.902518 | TASK [download-artifact-fork : Parse build response]
2026-03-02 00:36:16.939824 | controller | skipping: Conditional result was False
2026-03-02 00:36:16.952741 | 
2026-03-02 00:36:16.952881 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2026-03-02 00:36:17.814627 | controller | changed
2026-03-02 00:36:17.823427 | 
2026-03-02 00:36:17.823534 | LOOP [download-artifact-fork : Download archive by type]
2026-03-02 00:36:17.860393 | controller | skipping: Conditional result was False
2026-03-02 00:36:17.860981 | 
2026-03-02 00:36:19.989920 | controller | changed: OK (20182 bytes)
2026-03-02 00:36:20.026593 | 
2026-03-02 00:36:20.026824 | TASK [deploy-artifacts : define __collections]
2026-03-02 00:36:20.067339 | controller | ok
2026-03-02 00:36:20.079296 | 
2026-03-02 00:36:20.079457 | LOOP [deploy-artifacts : Create list of collections]
2026-03-02 00:36:20.118384 | controller | skipping: Conditional result was False
2026-03-02 00:36:20.139362 | controller | ok:
2026-03-02 00:36:20.139731 | controller | {
2026-03-02 00:36:20.139796 | controller |   "branch": "main",
2026-03-02 00:36:20.139837 | controller |   "job": "build-ansible-collection",
2026-03-02 00:36:20.139873 | controller |   "metadata": {
2026-03-02 00:36:20.139908 | controller |     "type": "ansible_collection",
2026-03-02 00:36:20.139942 | controller |     "version": "5.0.0"
2026-03-02 00:36:20.139975 | controller |   },
2026-03-02 00:36:20.140008 | controller |   "name": "ansible.network",
2026-03-02 00:36:20.140088 | controller |   "project": "ansible-collections/ansible.network",
2026-03-02 00:36:20.140126 | controller |   "url": "https://2e1b3211811fcf7d2fb6-d8a56f80e3332c3b9ca3775f8ade2a16.ssl.cf5.rackcdn.com/ansible/09b6e6ef1c9449949ca2a16b853b926a/artifacts/ansible-network-5.0.0.tar.gz"
2026-03-02 00:36:20.140171 | controller | }
2026-03-02 00:36:20.164660 | 
2026-03-02 00:36:20.164858 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2026-03-02 00:36:21.914250 | controller | Starting galaxy collection install process
2026-03-02 00:36:21.914739 | controller | Process install dependency map
2026-03-02 00:36:34.323570 | controller | Starting collection install process
2026-03-02 00:36:34.323890 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2026-03-02 00:36:34.342829 | controller | ansible.network (5.0.0) was installed successfully
2026-03-02 00:36:34.342978 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-03-02 00:36:34.342995 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40561tsnv13p/tmplj6m8gin
2026-03-02 00:36:35.970274 | controller | ansible.netcommon (8.4.0) was installed successfully
2026-03-02 00:36:37.783310 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-03-02 00:36:37.783365 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40561tsnv13p/tmplj6m8gin
2026-03-02 00:36:37.783389 | controller | ansible.utils (6.0.1) was installed successfully
2026-03-02 00:36:37.783482 | controller | Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2026-03-02 00:36:37.783713 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40561tsnv13p/tmplj6m8gin
2026-03-02 00:36:40.544652 | controller | arista.eos (12.0.1) was installed successfully
2026-03-02 00:36:40.544783 | controller | Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2026-03-02 00:36:40.545018 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40561tsnv13p/tmplj6m8gin
2026-03-02 00:36:43.441305 | controller | cisco.ios (11.3.0) was installed successfully
2026-03-02 00:36:43.441444 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2026-03-02 00:36:43.441676 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40561tsnv13p/tmplj6m8gin
2026-03-02 00:36:46.142539 | controller | cisco.iosxr (12.1.1) was installed successfully
2026-03-02 00:36:46.142779 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2026-03-02 00:36:46.142793 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-40561tsnv13p/tmplj6m8gin
2026-03-02 00:36:50.650143 | controller | cisco.nxos (11.1.3) was installed successfully
2026-03-02 00:36:50.650224 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2026-03-02 00:36:50.650771 | 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-40561tsnv13p/tmplj6m8gin
2026-03-02 00:36:53.360865 | controller | junipernetworks.junos (11.0.0) was installed successfully
2026-03-02 00:36:53.617500 | controller | ok: Runtime: 0:00:32.414483
2026-03-02 00:36:53.634004 | 
2026-03-02 00:36:53.634152 | TASK [podman may need iptables kernel modules]
2026-03-02 00:36:54.991639 | controller | ok: Runtime: 0:00:00.006968
2026-03-02 00:36:55.015188 | 
2026-03-02 00:36:55.015401 | PLAY RECAP
2026-03-02 00:36:55.015476 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2026-03-02 00:36:55.015506 | 
2026-03-02 00:36:55.229474 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-03-02 00:36:55.230457 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-02 00:36:55.912864 | 
2026-03-02 00:36:55.912975 | PLAY [controller]
2026-03-02 00:36:55.938321 | 
2026-03-02 00:36:55.938402 | TASK [Copy the galaxy.yml on the controller]
2026-03-02 00:36:59.669045 | controller | changed
2026-03-02 00:36:59.674763 | 
2026-03-02 00:36:59.674835 | TASK [Load information from galaxy.yml]
2026-03-02 00:36:59.724898 | controller | ok
2026-03-02 00:36:59.733469 | 
2026-03-02 00:36:59.733594 | LOOP [Enable FIPS mode]
2026-03-02 00:36:59.761159 | 
2026-03-02 00:36:59.761332 | LOOP [ansible-test : Include branch specific variables.]
2026-03-02 00:36:59.791253 | controller | ok: "/var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2026-03-02 00:36:59.791440 | 
2026-03-02 00:36:59.802131 | 
2026-03-02 00:36:59.802258 | TASK [ansible-test : Setup --skip-tags for test_options]
2026-03-02 00:36:59.832284 | controller | ok
2026-03-02 00:36:59.837803 | 
2026-03-02 00:36:59.837866 | TASK [ansible-test : Enable --inventory for network-integration]
2026-03-02 00:36:59.862623 | controller | skipping: Conditional result was False
2026-03-02 00:36:59.871332 | 
2026-03-02 00:36:59.871493 | TASK [ansible-test : Enable --retry-on-error]
2026-03-02 00:36:59.895570 | controller | skipping: Conditional result was False
2026-03-02 00:36:59.902570 | 
2026-03-02 00:36:59.902638 | TASK [ansible-test : Enable --continue-on-error]
2026-03-02 00:36:59.932923 | controller | ok
2026-03-02 00:36:59.939203 | 
2026-03-02 00:36:59.939267 | TASK [ansible-test : Enable --requirements]
2026-03-02 00:36:59.953563 | controller | skipping: Conditional result was False
2026-03-02 00:36:59.960218 | 
2026-03-02 00:36:59.960285 | TASK [ansible-test : Adjust options for unit tests]
2026-03-02 00:36:59.975073 | controller | skipping: Conditional result was False
2026-03-02 00:36:59.981925 | 
2026-03-02 00:36:59.981996 | TASK [ansible-test : Install requirements with unit tests]
2026-03-02 00:37:00.046047 | controller | ok
2026-03-02 00:37:00.051533 | 
2026-03-02 00:37:00.051593 | TASK [ansible-test : Set --skip-test]
2026-03-02 00:37:00.097275 | controller | skipping: Conditional result was False
2026-03-02 00:37:00.105696 | 
2026-03-02 00:37:00.105780 | TASK [ansible-test : Reduce verbosity]
2026-03-02 00:37:00.138005 | controller | ok
2026-03-02 00:37:00.144318 | 
2026-03-02 00:37:00.144384 | TASK [ansible-test : Setup --python option]
2026-03-02 00:37:00.169787 | controller | skipping: Conditional result was False
2026-03-02 00:37:00.176442 | 
2026-03-02 00:37:00.176509 | TASK [ansible-test : Setup --docker option]
2026-03-02 00:37:00.206965 | controller | ok
2026-03-02 00:37:00.214162 | 
2026-03-02 00:37:00.214225 | TASK [ansible-test : check if MANIFEST.json exists]
2026-03-02 00:37:01.300705 | controller | ok
2026-03-02 00:37:01.313955 | 
2026-03-02 00:37:01.314114 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2026-03-02 00:37:01.338813 | controller | skipping: Conditional result was False
2026-03-02 00:37:01.348535 | 
2026-03-02 00:37:01.348623 | TASK [ansible-test : Load information from MANIFEST.json]
2026-03-02 00:37:01.373237 | controller | skipping: Conditional result was False
2026-03-02 00:37:01.383238 | 
2026-03-02 00:37:01.389622 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-02 00:37:01.414276 | controller | skipping: Conditional result was False
2026-03-02 00:37:01.424113 | 
2026-03-02 00:37:01.424219 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2026-03-02 00:37:02.789200 | controller | ok
2026-03-02 00:37:02.802167 | 
2026-03-02 00:37:02.802329 | TASK [ansible-test : Load information from galaxy.yml]
2026-03-02 00:37:02.853433 | controller | ok
2026-03-02 00:37:02.866799 | 
2026-03-02 00:37:02.866952 | TASK [ansible-test : Setup location of project for integration tests]
2026-03-02 00:37:02.916447 | controller | ok
2026-03-02 00:37:02.928955 | 
2026-03-02 00:37:02.929155 | TASK [ansible-test : Setup minimum test requirements]
2026-03-02 00:37:02.977495 | controller | ok
2026-03-02 00:37:02.989895 | 
2026-03-02 00:37:02.990085 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2026-03-02 00:37:03.931923 | controller | ok
2026-03-02 00:37:03.937960 | 
2026-03-02 00:37:03.938036 | TASK [ansible-test : Install python requirements]
2026-03-02 00:37:05.462505 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-02 00:37:05.482931 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2026-03-02 00:37:05.533673 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2026-03-02 00:37:05.539424 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2026-03-02 00:37:05.552635 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2026-03-02 00:37:05.560940 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2026-03-02 00:37:05.584435 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2026-03-02 00:37:05.643133 | controller | Installing collected packages: pathspec, yamllint
2026-03-02 00:37:05.758750 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2026-03-02 00:37:06.327301 | controller | ok: Runtime: 0:00:01.158491
2026-03-02 00:37:06.340416 | 
2026-03-02 00:37:06.340548 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2026-03-02 00:37:07.206686 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2026-03-02 00:37:07.716236 | controller | ERROR
2026-03-02 00:37:07.716579 | controller | {
2026-03-02 00:37:07.716646 | controller |   "delta": "0:00:00.010621",
2026-03-02 00:37:07.716689 | controller |   "end": "2026-03-02 00:37:07.206978",
2026-03-02 00:37:07.716725 | controller |   "msg": "non-zero return code",
2026-03-02 00:37:07.716760 | controller |   "rc": 1,
2026-03-02 00:37:07.716796 | controller |   "start": "2026-03-02 00:37:07.196357"
2026-03-02 00:37:07.716831 | controller | }
2026-03-02 00:37:07.716879 | controller | ERROR: Ignoring Errors
2026-03-02 00:37:07.729996 | 
2026-03-02 00:37:07.730163 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2026-03-02 00:37:08.095002 | controller | skipping: Conditional result was False
2026-03-02 00:37:08.109693 | 
2026-03-02 00:37:08.109873 | TASK [ansible-test : Enable persistent connection logging]
2026-03-02 00:37:09.539279 | controller | changed: section and option added
2026-03-02 00:37:09.547306 | 
2026-03-02 00:37:09.547427 | TASK [ansible-test : Set persistent ssh_type value]
2026-03-02 00:37:10.822319 | controller | changed: option added
2026-03-02 00:37:10.828210 | 
2026-03-02 00:37:10.828274 | TASK [ansible-test : Set ansible log path]
2026-03-02 00:37:11.941835 | controller | changed: section and option added
2026-03-02 00:37:11.955055 | 
2026-03-02 00:37:11.955194 | TASK [ansible-test : Identify the changed targets]
2026-03-02 00:37:12.339451 | controller | skipping: Conditional result was False
2026-03-02 00:37:12.354164 | 
2026-03-02 00:37:12.354324 | TASK [ansible-test : set_fact]
2026-03-02 00:37:12.452740 | controller | skipping: Conditional result was False
2026-03-02 00:37:12.466448 | 
2026-03-02 00:37:12.466616 | TASK [ansible-test : Set the targets]
2026-03-02 00:37:12.543294 | controller | ok
2026-03-02 00:37:12.550926 | 
2026-03-02 00:37:12.551007 | TASK [ansible-test : set_fact]
2026-03-02 00:37:12.577671 | controller | skipping: Conditional result was False
2026-03-02 00:37:12.586927 | 
2026-03-02 00:37:12.587058 | TASK [ansible-test : List the targets]
2026-03-02 00:37:12.952238 | controller | skipping: Conditional result was False
2026-03-02 00:37:12.969792 | 
2026-03-02 00:37:12.969950 | TASK [ansible-test : set_fact]
2026-03-02 00:37:12.996951 | controller | skipping: Conditional result was False
2026-03-02 00:37:13.010574 | 
2026-03-02 00:37:13.010717 | TASK [ansible-test : set_fact]
2026-03-02 00:37:13.037646 | controller | skipping: Conditional result was False
2026-03-02 00:37:13.051216 | 
2026-03-02 00:37:13.051358 | LOOP [ansible-test : set_fact]
2026-03-02 00:37:13.123541 | 
2026-03-02 00:37:13.123814 | TASK [ansible-test : set_fact]
2026-03-02 00:37:13.161506 | controller | skipping: Conditional result was False
2026-03-02 00:37:13.176210 | 
2026-03-02 00:37:13.176362 | TASK [ansible-test : debug]
2026-03-02 00:37:13.203350 | controller | skipping: Conditional result was False
2026-03-02 00:37:13.219746 | 
2026-03-02 00:37:13.219886 | TASK [ansible-test : debug]
2026-03-02 00:37:13.268066 | About to run: ansible-test sanity --requirements --docker -v
2026-03-02 00:37:13.280113 | 
2026-03-02 00:37:13.280247 | TASK [ansible-test : Run the test suite]
2026-03-02 00:37:14.809144 | controller | Run command: docker images quay.io/ansible/default-test-container:2.9.0 --format '{{json .}}'
2026-03-02 00:37:14.853286 | controller | Run command: docker pull quay.io/ansible/default-test-container:2.9.0
2026-03-02 00:37:16.357813 | controller | 2.9.0: Pulling from ansible/default-test-container
2026-03-02 00:37:16.357948 | controller | 171857c49d0f: Pulling fs layer
2026-03-02 00:37:16.357975 | controller | 419640447d26: Pulling fs layer
2026-03-02 00:37:16.357984 | controller | 61e52f862619: Pulling fs layer
2026-03-02 00:37:16.357990 | controller | 851de0426e26: Pulling fs layer
2026-03-02 00:37:16.358000 | controller | 37425716ea5b: Pulling fs layer
2026-03-02 00:37:16.358009 | controller | cd9b0c439616: Pulling fs layer
2026-03-02 00:37:16.358015 | controller | d4612dbd8855: Pulling fs layer
2026-03-02 00:37:16.358021 | controller | 554699364730: Pulling fs layer
2026-03-02 00:37:16.358027 | controller | 1ff15d4bdb3e: Pulling fs layer
2026-03-02 00:37:16.358033 | controller | 5f2e4724adea: Pulling fs layer
2026-03-02 00:37:16.358039 | controller | a02d9a95ccb8: Pulling fs layer
2026-03-02 00:37:16.358045 | controller | 989f4ce370cb: Pulling fs layer
2026-03-02 00:37:16.358051 | controller | 0d7f52cdf9df: Pulling fs layer
2026-03-02 00:37:16.358057 | controller | c423742351d5: Pulling fs layer
2026-03-02 00:37:16.358063 | controller | 109236341d21: Pulling fs layer
2026-03-02 00:37:16.358069 | controller | 07a2f61c96b8: Pulling fs layer
2026-03-02 00:37:16.358075 | controller | 041f122f27ae: Pulling fs layer
2026-03-02 00:37:16.358081 | controller | ca6f5098b12e: Pulling fs layer
2026-03-02 00:37:16.358087 | controller | 1eb3e7b2afd9: Pulling fs layer
2026-03-02 00:37:16.358093 | controller | 18a0b750727a: Pulling fs layer
2026-03-02 00:37:16.358099 | controller | 17f2f596716c: Pulling fs layer
2026-03-02 00:37:16.358108 | controller | f35a9998a9bd: Pulling fs layer
2026-03-02 00:37:16.358115 | controller | fdfc10566405: Pulling fs layer
2026-03-02 00:37:16.358121 | controller | 1ff1c442c5fe: Pulling fs layer
2026-03-02 00:37:16.358127 | controller | a3443c4a17ed: Pulling fs layer
2026-03-02 00:37:16.358133 | controller | 0edcf9fb2b15: Pulling fs layer
2026-03-02 00:37:16.358139 | controller | 5af43b69e845: Pulling fs layer
2026-03-02 00:37:16.358146 | controller | 1651773effb2: Pulling fs layer
2026-03-02 00:37:16.358152 | controller | 79e23c5c0fe4: Pulling fs layer
2026-03-02 00:37:16.358162 | controller | e5058b1faaa6: Pulling fs layer
2026-03-02 00:37:16.358169 | controller | 846775490569: Pulling fs layer
2026-03-02 00:37:16.358175 | controller | 851de0426e26: Waiting
2026-03-02 00:37:16.358229 | controller | 37425716ea5b: Waiting
2026-03-02 00:37:16.358240 | controller | cd9b0c439616: Waiting
2026-03-02 00:37:16.358247 | controller | d4612dbd8855: Waiting
2026-03-02 00:37:16.358253 | controller | 554699364730: Waiting
2026-03-02 00:37:16.358259 | controller | 1ff15d4bdb3e: Waiting
2026-03-02 00:37:16.358268 | controller | 5f2e4724adea: Waiting
2026-03-02 00:37:16.358274 | controller | a02d9a95ccb8: Waiting
2026-03-02 00:37:16.358280 | controller | 989f4ce370cb: Waiting
2026-03-02 00:37:16.358286 | controller | 0d7f52cdf9df: Waiting
2026-03-02 00:37:16.358292 | controller | c423742351d5: Waiting
2026-03-02 00:37:16.358298 | controller | 109236341d21: Waiting
2026-03-02 00:37:16.358304 | controller | 07a2f61c96b8: Waiting
2026-03-02 00:37:16.358310 | controller | ca6f5098b12e: Waiting
2026-03-02 00:37:16.358316 | controller | 1eb3e7b2afd9: Waiting
2026-03-02 00:37:16.358322 | controller | 18a0b750727a: Waiting
2026-03-02 00:37:16.358328 | controller | 17f2f596716c: Waiting
2026-03-02 00:37:16.358334 | controller | f35a9998a9bd: Waiting
2026-03-02 00:37:16.358343 | controller | fdfc10566405: Waiting
2026-03-02 00:37:16.358351 | controller | 1ff1c442c5fe: Waiting
2026-03-02 00:37:16.358361 | controller | a3443c4a17ed: Waiting
2026-03-02 00:37:17.363784 | controller | 0edcf9fb2b15: Waiting
2026-03-02 00:37:17.363842 | controller | 5af43b69e845: Waiting
2026-03-02 00:37:17.363850 | controller | 1651773effb2: Waiting
2026-03-02 00:37:17.363857 | controller | 79e23c5c0fe4: Waiting
2026-03-02 00:37:17.363863 | controller | e5058b1faaa6: Waiting
2026-03-02 00:37:17.363870 | controller | 846775490569: Waiting
2026-03-02 00:37:17.363886 | controller | 61e52f862619: Verifying Checksum
2026-03-02 00:37:17.396826 | controller | 61e52f862619: Download complete
2026-03-02 00:37:17.396894 | controller | 419640447d26: Verifying Checksum
2026-03-02 00:37:18.174789 | controller | 419640447d26: Download complete
2026-03-02 00:37:18.174836 | controller | 851de0426e26: Verifying Checksum
2026-03-02 00:37:18.951253 | controller | 851de0426e26: Download complete
2026-03-02 00:37:18.951316 | controller | cd9b0c439616: Verifying Checksum
2026-03-02 00:37:19.777692 | controller | cd9b0c439616: Download complete
2026-03-02 00:37:19.777744 | controller | d4612dbd8855: Verifying Checksum
2026-03-02 00:37:21.360527 | controller | d4612dbd8855: Download complete
2026-03-02 00:37:21.360605 | controller | 171857c49d0f: Verifying Checksum
2026-03-02 00:37:22.186371 | controller | 171857c49d0f: Download complete
2026-03-02 00:37:22.186440 | controller | 1ff15d4bdb3e: Verifying Checksum
2026-03-02 00:37:22.186579 | controller | 1ff15d4bdb3e: Download complete
2026-03-02 00:37:22.997805 | controller | 5f2e4724adea: Verifying Checksum
2026-03-02 00:37:24.347351 | controller | 5f2e4724adea: Download complete
2026-03-02 00:37:24.347407 | controller | a02d9a95ccb8: Verifying Checksum
2026-03-02 00:37:24.767934 | controller | a02d9a95ccb8: Download complete
2026-03-02 00:37:24.768061 | controller | 171857c49d0f: Pull complete
2026-03-02 00:37:24.803219 | controller | 419640447d26: Pull complete
2026-03-02 00:37:24.836135 | controller | 61e52f862619: Pull complete
2026-03-02 00:37:24.868673 | controller | 851de0426e26: Pull complete
2026-03-02 00:37:25.107952 | controller | 989f4ce370cb: Verifying Checksum
2026-03-02 00:37:25.917261 | controller | 989f4ce370cb: Download complete
2026-03-02 00:37:25.917359 | controller | 0d7f52cdf9df: Download complete
2026-03-02 00:37:26.691049 | controller | c423742351d5: Verifying Checksum
2026-03-02 00:37:32.853619 | controller | c423742351d5: Download complete
2026-03-02 00:37:32.853738 | controller | 554699364730: Verifying Checksum
2026-03-02 00:37:32.853937 | controller | 554699364730: Download complete
2026-03-02 00:37:33.624739 | controller | 07a2f61c96b8: Verifying Checksum
2026-03-02 00:37:33.624824 | controller | 07a2f61c96b8: Download complete
2026-03-02 00:37:34.413261 | controller | 041f122f27ae: Verifying Checksum
2026-03-02 00:37:34.416282 | controller | 041f122f27ae: Download complete
2026-03-02 00:37:36.074140 | controller | ca6f5098b12e: Verifying Checksum
2026-03-02 00:37:36.074363 | controller | ca6f5098b12e: Download complete
2026-03-02 00:37:36.142198 | controller | 109236341d21: Verifying Checksum
2026-03-02 00:37:37.460274 | controller | 109236341d21: Download complete
2026-03-02 00:37:37.460363 | controller | 1eb3e7b2afd9: Download complete
2026-03-02 00:37:37.680165 | controller | 18a0b750727a: Verifying Checksum
2026-03-02 00:37:38.170616 | controller | 18a0b750727a: Download complete
2026-03-02 00:37:38.170725 | controller | 17f2f596716c: Verifying Checksum
2026-03-02 00:37:38.437007 | controller | 17f2f596716c: Download complete
2026-03-02 00:37:38.437108 | controller | f35a9998a9bd: Verifying Checksum
2026-03-02 00:37:38.986976 | controller | f35a9998a9bd: Download complete
2026-03-02 00:37:38.987078 | controller | fdfc10566405: Verifying Checksum
2026-03-02 00:37:39.139058 | controller | fdfc10566405: Download complete
2026-03-02 00:37:39.139148 | controller | 1ff1c442c5fe: Verifying Checksum
2026-03-02 00:37:45.297638 | controller | 1ff1c442c5fe: Download complete
2026-03-02 00:37:45.297772 | controller | 0edcf9fb2b15: Verifying Checksum
2026-03-02 00:37:45.297916 | controller | 0edcf9fb2b15: Download complete
2026-03-02 00:37:45.936494 | controller | a3443c4a17ed: Verifying Checksum
2026-03-02 00:37:45.936692 | controller | a3443c4a17ed: Download complete
2026-03-02 00:37:49.214157 | controller | 1651773effb2: Verifying Checksum
2026-03-02 00:37:49.214362 | controller | 1651773effb2: Download complete
2026-03-02 00:37:52.110735 | controller | 5af43b69e845: Verifying Checksum
2026-03-02 00:37:52.877340 | controller | 5af43b69e845: Download complete
2026-03-02 00:37:52.877440 | controller | 79e23c5c0fe4: Verifying Checksum
2026-03-02 00:37:52.877910 | controller | 79e23c5c0fe4: Download complete
2026-03-02 00:37:52.985501 | controller | 37425716ea5b: Verifying Checksum
2026-03-02 00:37:55.066243 | controller | 37425716ea5b: Download complete
2026-03-02 00:37:55.066365 | controller | e5058b1faaa6: Verifying Checksum
2026-03-02 00:37:55.066403 | controller | e5058b1faaa6: Download complete
2026-03-02 00:37:58.931898 | controller | 846775490569: Verifying Checksum
2026-03-02 00:38:12.156270 | controller | 846775490569: Download complete
2026-03-02 00:38:12.156387 | controller | 37425716ea5b: Pull complete
2026-03-02 00:38:12.185920 | controller | cd9b0c439616: Pull complete
2026-03-02 00:38:12.218632 | controller | d4612dbd8855: Pull complete
2026-03-02 00:38:17.307987 | controller | 554699364730: Pull complete
2026-03-02 00:38:17.341409 | controller | 1ff15d4bdb3e: Pull complete
2026-03-02 00:38:17.378612 | controller | 5f2e4724adea: Pull complete
2026-03-02 00:38:17.434595 | controller | a02d9a95ccb8: Pull complete
2026-03-02 00:38:17.457900 | controller | 989f4ce370cb: Pull complete
2026-03-02 00:38:17.488687 | controller | 0d7f52cdf9df: Pull complete
2026-03-02 00:38:17.516049 | controller | c423742351d5: Pull complete
2026-03-02 00:38:19.886857 | controller | 109236341d21: Pull complete
2026-03-02 00:38:19.916791 | controller | 07a2f61c96b8: Pull complete
2026-03-02 00:38:19.950083 | controller | 041f122f27ae: Pull complete
2026-03-02 00:38:20.210767 | controller | ca6f5098b12e: Pull complete
2026-03-02 00:38:20.267760 | controller | 1eb3e7b2afd9: Pull complete
2026-03-02 00:38:20.327087 | controller | 18a0b750727a: Pull complete
2026-03-02 00:38:20.356091 | controller | 17f2f596716c: Pull complete
2026-03-02 00:38:20.383274 | controller | f35a9998a9bd: Pull complete
2026-03-02 00:38:20.437524 | controller | fdfc10566405: Pull complete
2026-03-02 00:38:20.480165 | controller | 1ff1c442c5fe: Pull complete
2026-03-02 00:38:22.371116 | controller | a3443c4a17ed: Pull complete
2026-03-02 00:38:24.358963 | controller | 0edcf9fb2b15: Pull complete
2026-03-02 00:38:28.028760 | controller | 5af43b69e845: Pull complete
2026-03-02 00:38:32.653502 | controller | 1651773effb2: Pull complete
2026-03-02 00:38:35.456117 | controller | 79e23c5c0fe4: Pull complete
2026-03-02 00:38:37.522220 | controller | e5058b1faaa6: Pull complete
2026-03-02 00:38:40.315915 | controller | 846775490569: Pull complete
2026-03-02 00:38:40.336439 | controller | Digest: sha256:923f4b465456ae05243474faf9061abbefa2c2c5b88f24455fa2d5ce3d8d2ab4
2026-03-02 00:38:40.342811 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:2.9.0
2026-03-02 00:38:40.353212 | controller | quay.io/ansible/default-test-container:2.9.0
2026-03-02 00:38:40.359385 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2026-03-02 00:38:40.391455 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2026-03-02 00:38:41.728320 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2026-03-02 00:38:41.728559 | controller | 04a5f4cda3ee: Pulling fs layer
2026-03-02 00:38:41.728576 | controller | ff496a88c8ed: Pulling fs layer
2026-03-02 00:38:41.728584 | controller | 0ce83f459fe7: Pulling fs layer
2026-03-02 00:38:41.728593 | controller | 2e5170e1f099: Pulling fs layer
2026-03-02 00:38:41.728599 | controller | 7641eb41b08c: Pulling fs layer
2026-03-02 00:38:41.728608 | controller | ad15fa9da398: Pulling fs layer
2026-03-02 00:38:41.728637 | controller | 087d91352424: Pulling fs layer
2026-03-02 00:38:41.728649 | controller | 8b92efd6a100: Pulling fs layer
2026-03-02 00:38:41.728656 | controller | 7641eb41b08c: Waiting
2026-03-02 00:38:41.728662 | controller | ad15fa9da398: Waiting
2026-03-02 00:38:41.728686 | controller | 087d91352424: Waiting
2026-03-02 00:38:41.728698 | controller | 8b92efd6a100: Waiting
2026-03-02 00:38:41.729159 | controller | 2e5170e1f099: Waiting
2026-03-02 00:38:42.451882 | controller | 0ce83f459fe7: Verifying Checksum
2026-03-02 00:38:42.623922 | controller | 0ce83f459fe7: Download complete
2026-03-02 00:38:42.624011 | controller | ff496a88c8ed: Verifying Checksum
2026-03-02 00:38:43.356564 | controller | ff496a88c8ed: Download complete
2026-03-02 00:38:43.356668 | controller | 7641eb41b08c: Verifying Checksum
2026-03-02 00:38:44.169979 | controller | 7641eb41b08c: Download complete
2026-03-02 00:38:44.170075 | controller | ad15fa9da398: Verifying Checksum
2026-03-02 00:38:45.688348 | controller | ad15fa9da398: Download complete
2026-03-02 00:38:45.688450 | controller | 087d91352424: Verifying Checksum
2026-03-02 00:38:46.460204 | controller | 087d91352424: Download complete
2026-03-02 00:38:46.460343 | controller | 8b92efd6a100: Verifying Checksum
2026-03-02 00:38:48.542166 | controller | 8b92efd6a100: Download complete
2026-03-02 00:38:48.542292 | controller | 04a5f4cda3ee: Verifying Checksum
2026-03-02 00:38:48.915849 | controller | 04a5f4cda3ee: Download complete
2026-03-02 00:38:48.915935 | controller | 2e5170e1f099: Verifying Checksum
2026-03-02 00:38:53.277638 | controller | 2e5170e1f099: Download complete
2026-03-02 00:38:53.278874 | controller | 04a5f4cda3ee: Pull complete
2026-03-02 00:38:53.420530 | controller | ff496a88c8ed: Pull complete
2026-03-02 00:38:53.505949 | controller | 0ce83f459fe7: Pull complete
2026-03-02 00:38:55.399261 | controller | 2e5170e1f099: Pull complete
2026-03-02 00:38:55.451379 | controller | 7641eb41b08c: Pull complete
2026-03-02 00:38:55.499758 | controller | ad15fa9da398: Pull complete
2026-03-02 00:38:56.591559 | controller | 087d91352424: Pull complete
2026-03-02 00:38:56.649151 | controller | 8b92efd6a100: Pull complete
2026-03-02 00:38:56.688101 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2026-03-02 00:38:56.698403 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2026-03-02 00:38:56.735353 | controller | quay.io/ansible/pypi-test-container:1.0.0
2026-03-02 00:38:56.741309 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2026-03-02 00:38:59.907355 | controller | Run command: docker inspect 72718952d55806508cf0cba70a2cdf83aa56a0ce6bf4d0b099ef5a9028945d89
2026-03-02 00:38:59.982843 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2026-03-02 00:38:59.998478 | controller | Including collection: ansible.netcommon (278 files)
2026-03-02 00:39:00.016186 | controller | Including collection: ansible.network (23 files)
2026-03-02 00:39:00.016275 | controller | Including collection: ansible.utils (441 files)
2026-03-02 00:39:00.066947 | controller | Including collection: arista.eos (1031 files)
2026-03-02 00:39:00.130101 | controller | Including collection: cisco.ios (1215 files)
2026-03-02 00:39:00.186437 | controller | Including collection: cisco.iosxr (1044 files)
2026-03-02 00:39:00.279900 | controller | Including collection: cisco.nxos (1687 files)
2026-03-02 00:39:00.332381 | controller | Including collection: junipernetworks.junos (963 files)
2026-03-02 00:39:00.382702 | controller | Creating a payload archive containing 7408 files...
2026-03-02 00:39:02.832262 | controller | Created a 6042013 byte payload archive containing 7408 files in 2 seconds.
2026-03-02 00:39:02.836230 | controller | Assuming Docker is available on localhost.
2026-03-02 00:39:02.839380 | controller | Run command: docker run --detach --volume /sys/fs/cgroup:/sys/fs/cgroup:ro --privileged=false --security-opt seccomp=unconfined --volume /var/run/docker.sock:/var/run/docker.sock quay.io/ansible/default-test-container:2.9.0
2026-03-02 00:39:03.518453 | controller | Run command: docker exec -i 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf dd of=/root/docker.sh bs=65536
2026-03-02 00:39:03.620621 | controller | Run command: docker exec 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf /bin/bash /root/docker.sh
2026-03-02 00:39:03.720781 | controller | Run command: docker exec -i 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf dd of=/root/test.tgz bs=65536
2026-03-02 00:39:03.853114 | controller | Run command: docker exec 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf tar oxzf /root/test.tgz -C /root
2026-03-02 00:39:05.025831 | controller | Run command: docker exec 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf /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-rln2q637.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2026-03-02 00:39:05.492083 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2026-03-02 00:39:05.492562 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2026-03-02 00:39:05.492773 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2026-03-02 00:39:05.494991 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2026-03-02 00:39:05.495789 | controller | Read 0 sanity test ignore line(s) for Ansible 2.10 from: tests/sanity/ignore-2.10.txt
2026-03-02 00:39:05.506425 | controller | No tests applicable.
2026-03-02 00:39:05.506605 | controller | Running sanity test 'ansible-doc' with Python 3.6
2026-03-02 00:39:05.506636 | controller | No tests applicable.
2026-03-02 00:39:05.507097 | controller | Running sanity test 'changelog' with Python 3.6
2026-03-02 00:39:05.507118 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/ansible-test.txt
2026-03-02 00:39:06.628689 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2026-03-02 00:39:07.307015 | controller | Collecting antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-02 00:39:22.328605 | controller |   WARNING: Retrying (Retry(total=4, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-02 00:39:37.842698 | controller |   WARNING: Retrying (Retry(total=3, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-02 00:39:53.860345 | controller |   WARNING: Retrying (Retry(total=2, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-02 00:40:10.877928 | controller |   WARNING: Retrying (Retry(total=1, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-02 00:40:29.900492 | controller |   WARNING: Retrying (Retry(total=0, connect=None, read=None, redirect=None, status=None)) after connection broken by 'ReadTimeoutError("HTTPConnectionPool(host='172.17.0.2', port=3141): Read timed out. (read timeout=15)",)': /root/pypi/+simple/antsibull-changelog/
2026-03-02 00:40:44.918701 | controller |   ERROR: Could not find a version that satisfies the requirement antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2)) (from versions: none)
2026-03-02 00:40:44.919666 | controller | ERROR: No matching distribution found for antsibull-changelog==0.9.0 (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt (line 2))
2026-03-02 00:40:45.036717 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2026-03-02 00:40:45.106290 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2026-03-02 00:40:45.106369 | controller | Removing custom PyPI hosts entries: /etc/hosts
2026-03-02 00:40:45.106378 | controller | ERROR: Command "/usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/quiet_pip.py install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.changelog.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2026-03-02 00:40:45.106405 | controller | Run command: docker exec 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf tar czf /root/output.tgz --exclude .tmp -C /root/ansible_collections/ansible/network/tests output
2026-03-02 00:40:45.217330 | controller | Run command: docker exec -i 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf dd if=/root/output.tgz bs=65536
2026-03-02 00:40:45.311104 | controller | Run command: tar oxzf /tmp/ansible-result-2xzb79nf.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2026-03-02 00:40:45.328339 | controller | Run command: docker rm -f 72718952d55806508cf0cba70a2cdf83aa56a0ce6bf4d0b099ef5a9028945d89
2026-03-02 00:40:45.577854 | controller | Run command: docker rm -f 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf
2026-03-02 00:40:46.355230 | controller | ERROR: Command "docker exec 67ce5bab63fb709cd070e78a10800e544538b7cefff1cb45fcf7657f29127bbf /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-rln2q637.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2026-03-02 00:40:48.755109 | controller | ERROR
2026-03-02 00:40:48.755333 | controller | {
2026-03-02 00:40:48.755361 | controller |   "delta": "0:03:32.085106",
2026-03-02 00:40:48.755379 | controller |   "end": "2026-03-02 00:40:46.447774",
2026-03-02 00:40:48.755396 | controller |   "msg": "non-zero return code",
2026-03-02 00:40:48.755415 | controller |   "rc": 1,
2026-03-02 00:40:48.755439 | controller |   "start": "2026-03-02 00:37:14.362668"
2026-03-02 00:40:48.755461 | controller | }
failure
2026-03-02 00:40:48.771616 | 
2026-03-02 00:40:48.771737 | PLAY RECAP
2026-03-02 00:40:48.771785 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2026-03-02 00:40:48.771805 | 
2026-03-02 00:40:48.897353 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2026-03-02 00:40:48.898385 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-02 00:40:49.480328 | 
2026-03-02 00:40:49.480441 | PLAY [all]
2026-03-02 00:40:49.501761 | 
2026-03-02 00:40:49.501843 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-03-02 00:40:58.319503 | controller | changed: non-zero return code
2026-03-02 00:40:58.327248 | 
2026-03-02 00:40:58.327349 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-03-02 00:40:58.351594 | controller | skipping: Conditional result was False
2026-03-02 00:40:58.359418 | 
2026-03-02 00:40:58.359521 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-03-02 00:40:58.392534 | 
2026-03-02 00:40:58.392719 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-03-02 00:40:58.425516 | 
2026-03-02 00:40:58.425682 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-03-02 00:40:58.450335 | controller | skipping: Conditional result was False
2026-03-02 00:40:58.460261 | 
2026-03-02 00:40:58.460356 | LOOP [fetch-subunit-output : Generate subunit file]
2026-03-02 00:40:58.493254 | 
2026-03-02 00:40:58.493437 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-03-02 00:40:58.518058 | controller | skipping: Conditional result was False
2026-03-02 00:40:58.525918 | 
2026-03-02 00:40:58.526007 | TASK [fetch-subunit-output : Remove the temporary file]
2026-03-02 00:40:58.550424 | controller | skipping: Conditional result was False
2026-03-02 00:40:58.558511 | 
2026-03-02 00:40:58.558598 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-03-02 00:40:58.583282 | controller | skipping: Conditional result was False
2026-03-02 00:40:58.619063 | 
2026-03-02 00:40:58.619183 | PLAY RECAP
2026-03-02 00:40:58.619240 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-03-02 00:40:58.619268 | 
2026-03-02 00:40:58.728685 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-03-02 00:40:58.729565 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-03-02 00:40:59.290337 | 
2026-03-02 00:40:59.290439 | PLAY [all:!appliance*]
2026-03-02 00:40:59.311073 | 
2026-03-02 00:40:59.311147 | TASK [unregister the node]
2026-03-02 00:40:59.668141 | controller | skipping: Conditional result was False
2026-03-02 00:40:59.680260 | 
2026-03-02 00:40:59.680420 | TASK [include_role : fetch-output]
2026-03-02 00:40:59.729256 | controller | ok
2026-03-02 00:40:59.767393 | 
2026-03-02 00:40:59.767480 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-02 00:40:59.843304 | controller | skipping: Conditional result was False
2026-03-02 00:40:59.855559 | 
2026-03-02 00:40:59.855705 | TASK [fetch-output : Set log path for single node]
2026-03-02 00:40:59.913598 | controller | ok
2026-03-02 00:40:59.925090 | 
2026-03-02 00:40:59.925224 | LOOP [fetch-output : Ensure local output dirs]
2026-03-02 00:41:00.361522 | controller -> localhost | ok: "/var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/logs"
2026-03-02 00:41:00.620533 | controller -> localhost | changed: "/var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/artifacts"
2026-03-02 00:41:00.835366 | controller -> localhost | changed: "/var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/work/docs"
2026-03-02 00:41:00.856092 | 
2026-03-02 00:41:00.856274 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-02 00:41:03.131313 | controller | changed:
2026-03-02 00:41:03.131647 | controller | .d..t...... ./
2026-03-02 00:41:03.131705 | controller | cd+++++++++ controller/
2026-03-02 00:41:03.131785 | controller | changed: All items complete
2026-03-02 00:41:03.131830 | 
2026-03-02 00:41:05.254802 | controller | changed: .d..t...... ./
2026-03-02 00:41:07.352466 | controller | changed: .d..t...... ./
2026-03-02 00:41:07.381929 | 
2026-03-02 00:41:07.382197 | TASK [include_role : fetch-output-openshift]
2026-03-02 00:41:07.409941 | controller | skipping: Conditional result was False
2026-03-02 00:41:07.427502 | 
2026-03-02 00:41:07.427749 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-02 00:41:07.476156 | controller | skipping: Conditional result was False
2026-03-02 00:41:07.486949 | controller | skipping: Conditional result was False
2026-03-02 00:41:07.547117 | 
2026-03-02 00:41:07.547242 | PLAY [localhost]
2026-03-02 00:41:07.568950 | 
2026-03-02 00:41:07.569082 | TASK [Run Zuul manifest role]
2026-03-02 00:41:07.592634 | localhost | ok
2026-03-02 00:41:07.612247 | 
2026-03-02 00:41:07.612355 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-02 00:41:08.009976 | localhost | changed
2026-03-02 00:41:08.016367 | 
2026-03-02 00:41:08.016455 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-02 00:41:08.057122 | localhost | ok
2026-03-02 00:41:08.068651 | 
2026-03-02 00:41:08.068731 | TASK [Set zuul-log-path fact]
2026-03-02 00:41:08.117215 | localhost | ok
2026-03-02 00:41:08.131547 | 
2026-03-02 00:41:08.131611 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-02 00:41:08.169723 | localhost | ok
2026-03-02 00:41:08.177293 | 
2026-03-02 00:41:08.177351 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-03-02 00:41:08.213590 | localhost | ok:
2026-03-02 00:41:08.213820 | localhost | {
2026-03-02 00:41:08.213846 | localhost |   "auth": {
2026-03-02 00:41:08.213866 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-03-02 00:41:08.213883 | localhost |     "username": "9LF91O0RK7JU"
2026-03-02 00:41:08.213900 | localhost |   },
2026-03-02 00:41:08.213916 | localhost |   "profile": "rackspace",
2026-03-02 00:41:08.213932 | localhost |   "region_name": "ORD"
2026-03-02 00:41:08.213948 | localhost | }
2026-03-02 00:41:08.213974 | 
2026-03-02 00:41:08.227162 | 
2026-03-02 00:41:08.227279 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-03-02 00:41:08.245187 | localhost | Output suppressed because no_log was given
2026-03-02 00:41:08.257837 | 
2026-03-02 00:41:08.257907 | TASK [upload-logs-failover : Initialize control vars]
2026-03-02 00:41:08.290462 | localhost | ok
2026-03-02 00:41:08.297072 | 
2026-03-02 00:41:08.297165 | LOOP [upload-logs-failover : Perform log upload]
2026-03-02 00:41:08.333569 | localhost | Output suppressed because no_log was given
2026-03-02 00:41:08.342451 | localhost | included: /var/lib/zuul/builds/31863ff63c664dc3876399e40bdb58b8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-03-02 00:41:08.351292 | 
2026-03-02 00:41:08.351388 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-03-02 00:41:08.461326 | localhost | Output suppressed because no_log was given
2026-03-02 00:41:08.472644 | 
2026-03-02 00:41:08.472798 | TASK [Upload logs to swift]
2026-03-02 00:41:08.511224 | localhost | ok
2026-03-02 00:41:08.553320 | 
2026-03-02 00:41:08.553412 | TASK [Set zuul-log-path fact]
2026-03-02 00:41:08.588994 | localhost | skipping: Conditional result was False
2026-03-02 00:41:08.596100 | 
2026-03-02 00:41:08.596199 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-03-02 00:41:09.018816 | localhost -> localhost | ok: Runtime: 0:00:00.008644
2026-03-02 00:41:09.024308 | 
2026-03-02 00:41:09.024374 | TASK [upload-logs-swift : Upload logs to swift]