2025-11-10 00:21:03.635299 | Job console starting...
2025-11-10 00:21:03.649085 | Updating repositories
2025-11-10 00:21:03.787067 | Preparing job workspace
2025-11-10 00:21:09.499460 | Running Ansible setup...
2025-11-10 00:21:17.600854 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-10 00:21:18.235033 | 
2025-11-10 00:21:18.235211 | PLAY [localhost]
2025-11-10 00:21:18.245801 | 
2025-11-10 00:21:18.245922 | TASK [Gathering Facts]
2025-11-10 00:21:19.303580 | localhost | ok
2025-11-10 00:21:19.330310 | 
2025-11-10 00:21:19.330491 | TASK [Setup log path fact]
2025-11-10 00:21:19.352555 | localhost | ok
2025-11-10 00:21:19.371709 | 
2025-11-10 00:21:19.371840 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-10 00:21:19.416255 | localhost | ok
2025-11-10 00:21:19.430813 | 
2025-11-10 00:21:19.430938 | TASK [emit-job-header : Print job information]
2025-11-10 00:21:19.482216 | # Job Information
2025-11-10 00:21:19.482430 | Ansible Version: 2.15.12
2025-11-10 00:21:19.482475 | Job: ansible-test-sanity-docker-stable-2.9
2025-11-10 00:21:19.482506 | Pipeline: periodic
2025-11-10 00:21:19.482533 | Executor: ze04.softwarefactory-project.io
2025-11-10 00:21:19.482559 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-11-10 00:21:19.482588 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/a15/ansible/a15d23cd525b4b22aafcd062db41ba3c/
2025-11-10 00:21:19.482615 | Event ID: e7ed9acfd5d246fc9eb02e45c4dd8a00
2025-11-10 00:21:19.488017 | 
2025-11-10 00:21:19.488105 | LOOP [emit-job-header : Print node information]
2025-11-10 00:21:19.593370 | localhost | ok:
2025-11-10 00:21:19.593766 | localhost | # Node Information
2025-11-10 00:21:19.593804 | localhost | Inventory Hostname: controller
2025-11-10 00:21:19.593825 | localhost | Hostname: np0005516261
2025-11-10 00:21:19.593844 | localhost | Username: zuul
2025-11-10 00:21:19.593867 | localhost | Distro: Fedora 37
2025-11-10 00:21:19.593886 | localhost | Provider: ansible-vexxhost-ams1
2025-11-10 00:21:19.593903 | localhost | Region: ams1
2025-11-10 00:21:19.593920 | localhost | Label: ansible-fedora-37-1vcpu
2025-11-10 00:21:19.593936 | localhost | Product Name: OpenStack Nova
2025-11-10 00:21:19.593952 | localhost | Interface IP: 38.129.16.135
2025-11-10 00:21:19.612863 | 
2025-11-10 00:21:19.613008 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-11-10 00:21:20.048910 | localhost -> localhost | changed
2025-11-10 00:21:20.062467 | 
2025-11-10 00:21:20.062633 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-11-10 00:21:21.005337 | localhost -> localhost | changed
2025-11-10 00:21:21.027505 | 
2025-11-10 00:21:21.027586 | PLAY [all:!appliance*]
2025-11-10 00:21:21.045910 | 
2025-11-10 00:21:21.046005 | TASK [include_role : start-zuul-console]
2025-11-10 00:21:21.077288 | controller | ok
2025-11-10 00:21:21.093471 | 
2025-11-10 00:21:21.093623 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-11-10 00:21:22.212594 | controller | ok
2025-11-10 00:21:22.236043 | 
2025-11-10 00:21:22.236241 | TASK [use-our-mirror : Retrieve the IP address]
2025-11-10 00:21:24.677169 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-11-10 00:21:24.691741 | 
2025-11-10 00:21:24.691878 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-11-10 00:21:25.068836 | controller | skipping: Conditional result was False
2025-11-10 00:21:25.078373 | 
2025-11-10 00:21:25.078459 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-11-10 00:21:25.105158 | controller | skipping: Conditional result was False
2025-11-10 00:21:25.114379 | 
2025-11-10 00:21:25.114464 | TASK [use-our-mirror : Create the podman configuration directory]
2025-11-10 00:21:25.151152 | controller | skipping: Conditional result was False
2025-11-10 00:21:25.161581 | 
2025-11-10 00:21:25.161709 | TASK [use-our-mirror : Copy the podman configuration]
2025-11-10 00:21:25.189870 | controller | skipping: Conditional result was False
2025-11-10 00:21:25.206123 | 
2025-11-10 00:21:25.206373 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-11-10 00:21:25.246244 | controller | skipping: Conditional result was False
2025-11-10 00:21:25.263363 | 
2025-11-10 00:21:25.263716 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-11-10 00:21:25.302410 | controller | skipping: Conditional result was False
2025-11-10 00:21:25.329857 | 
2025-11-10 00:21:25.330088 | TASK [Disable Fedora Modular]
2025-11-10 00:21:26.433019 | controller | changed
2025-11-10 00:21:26.447519 | 
2025-11-10 00:21:26.447788 | TASK [Enable EPEL]
2025-11-10 00:21:26.478149 | controller | skipping: Conditional result was False
2025-11-10 00:21:26.490571 | 
2025-11-10 00:21:26.490726 | TASK [Register the RHEL node]
2025-11-10 00:21:26.915526 | 
2025-11-10 00:21:26.915850 | TASK [Show the subscription-manager status]
2025-11-10 00:21:27.312058 | controller | skipping: Conditional result was False
2025-11-10 00:21:27.326462 | 
2025-11-10 00:21:27.326645 | TASK [Enable EPEL on RHEL]
2025-11-10 00:21:27.728190 | controller | skipping: Conditional result was False
2025-11-10 00:21:27.734927 | 
2025-11-10 00:21:27.735072 | TASK [Install git and tox]
2025-11-10 00:23:47.537293 | controller | changed
2025-11-10 00:23:47.555152 | 
2025-11-10 00:23:47.555305 | TASK [include_role : prepare-workspace]
2025-11-10 00:23:47.595072 | controller | ok
2025-11-10 00:23:47.634067 | 
2025-11-10 00:23:47.634212 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-11-10 00:23:48.511178 | controller | ok
2025-11-10 00:23:48.526550 | 
2025-11-10 00:23:48.526755 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-11-10 00:24:09.082725 | controller | Output suppressed because no_log was given
2025-11-10 00:24:09.097806 | 
2025-11-10 00:24:09.097976 | TASK [include_role : prepare-workspace-openshift]
2025-11-10 00:24:09.143824 | controller | skipping: Conditional result was False
2025-11-10 00:24:09.179370 | 
2025-11-10 00:24:09.179493 | PLAY [all:!appliance]
2025-11-10 00:24:09.203659 | 
2025-11-10 00:24:09.203828 | TASK [Run add-build-sshkey role (RSA)]
2025-11-10 00:24:09.237401 | controller | ok
2025-11-10 00:24:09.257781 | 
2025-11-10 00:24:09.257965 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-10 00:24:09.542875 | controller -> localhost | ok
2025-11-10 00:24:09.558613 | 
2025-11-10 00:24:09.558870 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-10 00:24:09.602195 | controller | ok
2025-11-10 00:24:09.632212 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-10 00:24:09.642612 | 
2025-11-10 00:24:09.642776 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-10 00:24:10.251576 | controller -> localhost | Generating public/private rsa key pair.
2025-11-10 00:24:10.251951 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/a15d23cd525b4b22aafcd062db41ba3c_id_rsa.
2025-11-10 00:24:10.251998 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/a15d23cd525b4b22aafcd062db41ba3c_id_rsa.pub.
2025-11-10 00:24:10.252024 | controller -> localhost | The key fingerprint is:
2025-11-10 00:24:10.252046 | controller -> localhost | SHA256:YWpSnboAjHASbkjbq1OIIXpVVYwXppbnKYqIsaChOWI zuul-build-sshkey
2025-11-10 00:24:10.252066 | controller -> localhost | The key's randomart image is:
2025-11-10 00:24:10.252085 | controller -> localhost | +---[RSA 2048]----+
2025-11-10 00:24:10.252104 | controller -> localhost | |+o.   ...++.     |
2025-11-10 00:24:10.252144 | controller -> localhost | |==o  . ..=o      |
2025-11-10 00:24:10.252181 | controller -> localhost | |=+o.. . O..      |
2025-11-10 00:24:10.252207 | controller -> localhost | |=..o.. = + .     |
2025-11-10 00:24:10.252227 | controller -> localhost | |*..oo + S o      |
2025-11-10 00:24:10.252263 | controller -> localhost | |+Bo. = o .       |
2025-11-10 00:24:10.252288 | controller -> localhost | |OE. . o          |
2025-11-10 00:24:10.252309 | controller -> localhost | |o..              |
2025-11-10 00:24:10.252328 | controller -> localhost | |                 |
2025-11-10 00:24:10.252348 | controller -> localhost | +----[SHA256]-----+
2025-11-10 00:24:10.252411 | controller -> localhost | ok: Runtime: 0:00:00.080461
2025-11-10 00:24:10.261425 | 
2025-11-10 00:24:10.261538 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-10 00:24:10.308611 | controller | ok
2025-11-10 00:24:10.321132 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-10 00:24:10.330814 | 
2025-11-10 00:24:10.330900 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-10 00:24:10.367094 | controller | skipping: Conditional result was False
2025-11-10 00:24:10.378738 | 
2025-11-10 00:24:10.378922 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-10 00:24:11.483937 | controller | changed
2025-11-10 00:24:11.497165 | 
2025-11-10 00:24:11.497296 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-10 00:24:12.155374 | controller | ok
2025-11-10 00:24:12.170026 | 
2025-11-10 00:24:12.170164 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-10 00:24:15.282855 | controller | changed
2025-11-10 00:24:15.296477 | 
2025-11-10 00:24:15.296643 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-10 00:24:18.423417 | controller | changed
2025-11-10 00:24:18.436525 | 
2025-11-10 00:24:18.436752 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-10 00:24:18.466778 | controller | skipping: Conditional result was False
2025-11-10 00:24:18.481098 | 
2025-11-10 00:24:18.481270 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-10 00:24:18.939979 | controller -> localhost | changed
2025-11-10 00:24:19.006647 | 
2025-11-10 00:24:19.006836 | TASK [add-build-sshkey : Add back temp key]
2025-11-10 00:24:19.371556 | controller -> localhost | Identity added: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/a15d23cd525b4b22aafcd062db41ba3c_id_rsa (zuul-build-sshkey)
2025-11-10 00:24:19.372005 | controller -> localhost | ok: Runtime: 0:00:00.017240
2025-11-10 00:24:19.388103 | 
2025-11-10 00:24:19.388236 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-10 00:24:20.375179 | controller | ok
2025-11-10 00:24:20.389660 | 
2025-11-10 00:24:20.389856 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-10 00:24:20.438751 | controller | skipping: Conditional result was False
2025-11-10 00:24:20.468220 | 
2025-11-10 00:24:20.468419 | TASK [Run add-build-sshkey role (ECDSA)]
2025-11-10 00:24:20.506952 | controller | ok
2025-11-10 00:24:20.531575 | 
2025-11-10 00:24:20.531797 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-11-10 00:24:20.844671 | controller -> localhost | ok
2025-11-10 00:24:20.860090 | 
2025-11-10 00:24:20.860233 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-11-10 00:24:20.906384 | controller | ok
2025-11-10 00:24:20.930466 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-11-10 00:24:20.942269 | 
2025-11-10 00:24:20.942428 | TASK [add-build-sshkey : Create Temp SSH key]
2025-11-10 00:24:21.257611 | controller -> localhost | Generating public/private ecdsa key pair.
2025-11-10 00:24:21.257919 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/a15d23cd525b4b22aafcd062db41ba3c_id_ecdsa.
2025-11-10 00:24:21.257960 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/a15d23cd525b4b22aafcd062db41ba3c_id_ecdsa.pub.
2025-11-10 00:24:21.258002 | controller -> localhost | The key fingerprint is:
2025-11-10 00:24:21.258031 | controller -> localhost | SHA256:yxzABKEd8pXYv4o6LiPQeSp5XxssgEkd51Ul+zGz9H4 zuul-build-sshkey
2025-11-10 00:24:21.258059 | controller -> localhost | The key's randomart image is:
2025-11-10 00:24:21.258086 | controller -> localhost | +---[ECDSA 521]---+
2025-11-10 00:24:21.258113 | controller -> localhost | |  ..+*oo.o..     |
2025-11-10 00:24:21.258139 | controller -> localhost | |  .===+   o      |
2025-11-10 00:24:21.258165 | controller -> localhost | | ...o.o. . =     |
2025-11-10 00:24:21.258190 | controller -> localhost | |.o     .. o *    |
2025-11-10 00:24:21.258215 | controller -> localhost | |o...    S. o .   |
2025-11-10 00:24:21.258240 | controller -> localhost | |. o... o.o  .    |
2025-11-10 00:24:21.258264 | controller -> localhost | |.. o..+.+    . E |
2025-11-10 00:24:21.258289 | controller -> localhost | |* + .o.o      .  |
2025-11-10 00:24:21.258313 | controller -> localhost | |.*o+. .          |
2025-11-10 00:24:21.258338 | controller -> localhost | +----[SHA256]-----+
2025-11-10 00:24:21.258404 | controller -> localhost | ok: Runtime: 0:00:00.009846
2025-11-10 00:24:21.269606 | 
2025-11-10 00:24:21.269750 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-11-10 00:24:21.313459 | controller | ok
2025-11-10 00:24:21.328475 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-11-10 00:24:21.343530 | 
2025-11-10 00:24:21.343725 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-11-10 00:24:21.370401 | controller | skipping: Conditional result was False
2025-11-10 00:24:21.382178 | 
2025-11-10 00:24:21.382352 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-11-10 00:24:22.348575 | controller | changed
2025-11-10 00:24:22.361353 | 
2025-11-10 00:24:22.361498 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-11-10 00:24:23.034579 | controller | ok
2025-11-10 00:24:23.047790 | 
2025-11-10 00:24:23.047928 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-11-10 00:24:26.161012 | controller | changed
2025-11-10 00:24:26.174819 | 
2025-11-10 00:24:26.175076 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-11-10 00:24:29.410437 | controller | changed
2025-11-10 00:24:29.420420 | 
2025-11-10 00:24:29.420608 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-11-10 00:24:29.447531 | controller | skipping: Conditional result was False
2025-11-10 00:24:29.457506 | 
2025-11-10 00:24:29.457673 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-11-10 00:24:29.709318 | controller -> localhost | changed
2025-11-10 00:24:29.736840 | 
2025-11-10 00:24:29.736997 | TASK [add-build-sshkey : Add back temp key]
2025-11-10 00:24:30.049657 | controller -> localhost | Identity added: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/a15d23cd525b4b22aafcd062db41ba3c_id_ecdsa (zuul-build-sshkey)
2025-11-10 00:24:30.049901 | controller -> localhost | ok: Runtime: 0:00:00.008715
2025-11-10 00:24:30.056757 | 
2025-11-10 00:24:30.056819 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-11-10 00:24:30.763749 | controller | ok
2025-11-10 00:24:30.771871 | 
2025-11-10 00:24:30.771954 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-11-10 00:24:30.814181 | controller | skipping: Conditional result was False
2025-11-10 00:24:30.831115 | 
2025-11-10 00:24:30.831247 | TASK [include_role : remove-zuul-sshkey]
2025-11-10 00:24:30.858102 | controller | skipping: Conditional result was False
2025-11-10 00:24:30.866568 | 
2025-11-10 00:24:30.866652 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-11-10 00:24:31.543658 | controller | ok: "logs"
2025-11-10 00:24:31.544189 | controller | ok: All items complete
2025-11-10 00:24:31.544254 | 
2025-11-10 00:24:32.191891 | controller | ok: "artifacts"
2025-11-10 00:24:32.825780 | controller | ok: "docs"
2025-11-10 00:24:32.849173 | 
2025-11-10 00:24:32.849375 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-11-10 00:24:33.514309 | controller | changed: "logs"
2025-11-10 00:24:34.146942 | controller | changed: "artifacts"
2025-11-10 00:24:34.790194 | controller | changed: "docs"
2025-11-10 00:24:34.860725 | 
2025-11-10 00:24:34.860867 | PLAY RECAP
2025-11-10 00:24:34.860910 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-11-10 00:24:34.860936 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-11-10 00:24:34.860953 | 
2025-11-10 00:24:35.003034 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-11-10 00:24:35.004041 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-10 00:24:35.635799 | 
2025-11-10 00:24:35.635981 | PLAY [all]
2025-11-10 00:24:35.660157 | 
2025-11-10 00:24:35.660297 | TASK [Install binary dependencies]
2025-11-10 00:24:35.749145 | controller | ok
2025-11-10 00:24:35.777274 | 
2025-11-10 00:24:35.777382 | TASK [bindep : Include find tasks]
2025-11-10 00:24:35.820208 | controller | ok
2025-11-10 00:24:35.830965 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-11-10 00:24:35.839492 | 
2025-11-10 00:24:35.839577 | TASK [bindep : Look for bindep.txt]
2025-11-10 00:24:36.866249 | controller | ok
2025-11-10 00:24:36.887009 | 
2025-11-10 00:24:36.887270 | TASK [bindep : Define bindep_file fact]
2025-11-10 00:24:36.914667 | controller | skipping: Conditional result was False
2025-11-10 00:24:36.925640 | 
2025-11-10 00:24:36.925855 | TASK [bindep : Look for other-requirements.txt]
2025-11-10 00:24:37.585074 | controller | ok
2025-11-10 00:24:37.594621 | 
2025-11-10 00:24:37.594761 | TASK [bindep : Define bindep_file fact]
2025-11-10 00:24:37.631252 | controller | skipping: Conditional result was False
2025-11-10 00:24:37.642116 | 
2025-11-10 00:24:37.642230 | TASK [bindep : Look for bindep fallback file]
2025-11-10 00:24:37.689459 | controller | skipping: Conditional result was False
2025-11-10 00:24:37.699177 | 
2025-11-10 00:24:37.699295 | TASK [bindep : Define bindep_file fact]
2025-11-10 00:24:37.735890 | controller | skipping: Conditional result was False
2025-11-10 00:24:37.745911 | 
2025-11-10 00:24:37.746019 | TASK [bindep : Include bindep tasks]
2025-11-10 00:24:37.776608 | controller | skipping: Conditional result was False
2025-11-10 00:24:37.786872 | 
2025-11-10 00:24:37.787096 | TASK [bindep : Include install tasks]
2025-11-10 00:24:37.813934 | controller | skipping: Conditional result was False
2025-11-10 00:24:37.823958 | 
2025-11-10 00:24:37.824081 | LOOP [bindep : Include package tasks]
2025-11-10 00:24:37.886395 | 
2025-11-10 00:24:37.886601 | TASK [Run test-setup role]
2025-11-10 00:24:37.908068 | controller | ok
2025-11-10 00:24:37.935367 | 
2025-11-10 00:24:37.935559 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-11-10 00:24:38.579631 | controller | ok
2025-11-10 00:24:38.595174 | 
2025-11-10 00:24:38.595354 | TASK [test-setup : Run tools/test-setup.sh]
2025-11-10 00:24:38.971155 | controller | skipping: Conditional result was False
2025-11-10 00:24:39.036345 | 
2025-11-10 00:24:39.036522 | PLAY RECAP
2025-11-10 00:24:39.036612 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-10 00:24:39.036655 | 
2025-11-10 00:24:39.187511 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-11-10 00:24:39.188736 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-10 00:24:39.848875 | 
2025-11-10 00:24:39.849029 | PLAY [controller]
2025-11-10 00:24:39.869597 | 
2025-11-10 00:24:39.869695 | TASK [Create the /root directory]
2025-11-10 00:24:41.021740 | controller | ok
2025-11-10 00:24:41.037320 | 
2025-11-10 00:24:41.037516 | TASK [Install glibc-langpack-en]
2025-11-10 00:24:49.285433 | controller | ok: Nothing to do
2025-11-10 00:24:49.302631 | 
2025-11-10 00:24:49.302846 | TASK [Ensure controller directory exists]
2025-11-10 00:24:50.112143 | controller | changed
2025-11-10 00:24:50.128230 | 
2025-11-10 00:24:50.128771 | TASK [Install container runtime]
2025-11-10 00:24:50.197016 | controller | ok
2025-11-10 00:24:50.273189 | 
2025-11-10 00:24:50.273398 | LOOP [ensure-docker : Gather variables for each operating system]
2025-11-10 00:24:50.325029 | controller | ok: "/var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-11-10 00:24:50.341179 | 
2025-11-10 00:24:50.341357 | TASK [ensure-docker : Sanity check for distro selection]
2025-11-10 00:24:50.368094 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.385534 | 
2025-11-10 00:24:50.385733 | TASK [ensure-docker : Sanity check for distro install]
2025-11-10 00:24:50.413712 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.428438 | 
2025-11-10 00:24:50.428749 | TASK [ensure-docker : Sanity check for upstream install]
2025-11-10 00:24:50.471986 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.488623 | 
2025-11-10 00:24:50.488845 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-11-10 00:24:50.524872 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.533485 | 
2025-11-10 00:24:50.533630 | TASK [ensure-docker : Include tasks for docker install]
2025-11-10 00:24:50.568991 | controller | ok
2025-11-10 00:24:50.579087 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-11-10 00:24:50.590079 | 
2025-11-10 00:24:50.590165 | TASK [ensure-docker : Not Implemented]
2025-11-10 00:24:50.616006 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.624809 | 
2025-11-10 00:24:50.624905 | TASK [Set up docker mirrors]
2025-11-10 00:24:50.645760 | controller | ok
2025-11-10 00:24:50.670626 | 
2025-11-10 00:24:50.670787 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-11-10 00:24:50.706921 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.717937 | 
2025-11-10 00:24:50.718073 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-11-10 00:24:50.815140 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.857284 | 
2025-11-10 00:24:50.857421 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-11-10 00:24:50.893291 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.900484 | 
2025-11-10 00:24:50.900641 | TASK [use-docker-mirror : Set up docker mirrors]
2025-11-10 00:24:50.928151 | controller | skipping: Conditional result was False
2025-11-10 00:24:50.940028 | 
2025-11-10 00:24:50.940092 | TASK [Add all repositories]
2025-11-10 00:24:50.960485 | controller | ok
2025-11-10 00:24:50.984904 | 
2025-11-10 00:24:50.984974 | LOOP [ensure-package-repositories : Install repositories]
2025-11-10 00:24:51.022930 | controller | ok: "/var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-11-10 00:24:51.026524 | controller | ok: All items complete
2025-11-10 00:24:51.026582 | 
2025-11-10 00:24:51.045334 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-11-10 00:24:51.054916 | 
2025-11-10 00:24:51.055199 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-11-10 00:24:52.025331 | controller | changed
2025-11-10 00:24:52.041456 | 
2025-11-10 00:24:52.041629 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-11-10 00:24:55.441180 | controller | changed:
2025-11-10 00:24:55.441520 | controller | {
2025-11-10 00:24:55.441584 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-10 00:24:55.441635 | controller | }
2025-11-10 00:24:55.468115 | 
2025-11-10 00:24:55.468327 | LOOP [ensure-package-repositories : Import GPG keys]
2025-11-10 00:24:56.865294 | controller | changed:
2025-11-10 00:24:56.865535 | controller | {
2025-11-10 00:24:56.865567 | controller |   "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n"
2025-11-10 00:24:56.865595 | controller | }
2025-11-10 00:24:56.865635 | controller | changed: All items complete
2025-11-10 00:24:56.865660 | 
2025-11-10 00:24:56.883089 | 
2025-11-10 00:24:56.883253 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-11-10 00:24:57.597985 | controller | changed
2025-11-10 00:24:57.615463 | 
2025-11-10 00:24:57.615600 | LOOP [ensure-package-repositories : Add repositories]
2025-11-10 00:24:58.562825 | controller | changed:
2025-11-10 00:24:58.563176 | controller | {
2025-11-10 00:24:58.563234 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-11-10 00:24:58.563282 | controller |   "description": "Docker CE Stable - $basearch",
2025-11-10 00:24:58.563321 | controller |   "gpgcheck": true,
2025-11-10 00:24:58.563357 | controller |   "name": "docker-ce-stable"
2025-11-10 00:24:58.563394 | controller | }
2025-11-10 00:24:58.602329 | 
2025-11-10 00:24:58.602582 | TASK [ensure-docker : Install pre-reqs]
2025-11-10 00:25:03.512564 | controller | ok: Nothing to do
2025-11-10 00:25:03.522261 | 
2025-11-10 00:25:03.522364 | TASK [ensure-docker : Remove packages]
2025-11-10 00:25:06.629116 | controller | ok: Nothing to do
2025-11-10 00:25:06.635799 | 
2025-11-10 00:25:06.635942 | TASK [ensure-docker : Install upstream docker using package]
2025-11-10 00:26:30.145960 | controller | changed
2025-11-10 00:26:30.159501 | 
2025-11-10 00:26:30.159648 | TASK [ensure-docker : Setup docker]
2025-11-10 00:26:30.204632 | controller | ok
2025-11-10 00:26:30.227135 | controller | included: /var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-11-10 00:26:30.243651 | 
2025-11-10 00:26:30.243827 | TASK [ensure-docker : Ensure "docker" group exists]
2025-11-10 00:26:31.291874 | controller | ok
2025-11-10 00:26:31.304670 | 
2025-11-10 00:26:31.304837 | TASK [ensure-docker : Add user to docker group]
2025-11-10 00:26:32.414059 | controller | changed
2025-11-10 00:26:32.429381 | 
2025-11-10 00:26:32.429535 | TASK [Update docker daemon proxy configuration]
2025-11-10 00:26:32.460001 | controller | skipping: Conditional result was False
2025-11-10 00:26:32.475578 | 
2025-11-10 00:26:32.475781 | TASK [Lower default MTU]
2025-11-10 00:26:32.497012 | controller | skipping: Conditional result was False
2025-11-10 00:26:32.516225 | 
2025-11-10 00:26:32.516394 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-11-10 00:26:33.445471 | controller | inactive
2025-11-10 00:26:33.895973 | controller | ok: Runtime: 0:00:00.026809
2025-11-10 00:26:33.905384 | 
2025-11-10 00:26:33.905703 | TASK [ensure-docker : Get the status of the docket unit]
2025-11-10 00:26:34.737396 | controller | inactive
2025-11-10 00:26:35.272187 | controller | ok: Runtime: 0:00:00.022221
2025-11-10 00:26:35.279453 | 
2025-11-10 00:26:35.279542 | TASK [ensure-docker : Restart docker]
2025-11-10 00:26:35.332010 | Trigger a Docker restart
2025-11-10 00:26:35.344476 | 
2025-11-10 00:26:35.344584 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-11-10 00:26:35.388228 | 
2025-11-10 00:26:35.388427 | TASK [ensure-docker : Install docker-compose]
2025-11-10 00:26:35.417559 | controller | skipping: Conditional result was False
2025-11-10 00:26:35.427710 | 
2025-11-10 00:26:35.427813 | TASK [ensure-docker : Flush handlers before role exit]
2025-11-10 00:26:35.442319 | 
2025-11-10 00:26:35.442488 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-11-10 00:26:38.727407 | controller | changed
2025-11-10 00:26:38.746124 | 
2025-11-10 00:26:38.746285 | TASK [ensure-docker : Assure docker service is running]
2025-11-10 00:26:42.459525 | controller | changed
2025-11-10 00:26:42.481443 | 
2025-11-10 00:26:42.482481 | TASK [ensure-docker : Assure docker.socket service is running]
2025-11-10 00:26:44.143133 | controller | ok
2025-11-10 00:26:44.160201 | 
2025-11-10 00:26:44.160336 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-11-10 00:26:45.079460 | controller | ok
2025-11-10 00:26:45.093426 | 
2025-11-10 00:26:45.093640 | TASK [ensure-docker : Validate ability to talk with docker]
2025-11-10 00:26:45.798895 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-11-10 00:26:45.966626 | controller | ok: Runtime: 0:00:00.047456
2025-11-10 00:26:45.980564 | 
2025-11-10 00:26:45.980834 | TASK [ensure-docker : Get version details]
2025-11-10 00:26:46.641322 | controller | Client: Docker Engine - Community
2025-11-10 00:26:46.643047 | controller |  Version:           24.0.7
2025-11-10 00:26:46.643065 | controller |  API version:       1.43
2025-11-10 00:26:46.643073 | controller |  Go version:        go1.20.10
2025-11-10 00:26:46.643092 | controller |  Git commit:        afdd53b
2025-11-10 00:26:46.643100 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-11-10 00:26:46.643106 | controller |  OS/Arch:           linux/amd64
2025-11-10 00:26:46.643115 | controller |  Context:           default
2025-11-10 00:26:46.643124 | controller |
2025-11-10 00:26:46.643130 | controller | Server: Docker Engine - Community
2025-11-10 00:26:46.643136 | controller |  Engine:
2025-11-10 00:26:46.643142 | controller |   Version:          24.0.7
2025-11-10 00:26:46.643148 | controller |   API version:      1.43 (minimum version 1.12)
2025-11-10 00:26:46.643154 | controller |   Go version:       go1.20.10
2025-11-10 00:26:46.643160 | controller |   Git commit:       311b9ff
2025-11-10 00:26:46.643166 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-11-10 00:26:46.643172 | controller |   OS/Arch:          linux/amd64
2025-11-10 00:26:46.643178 | controller |   Experimental:     false
2025-11-10 00:26:46.643185 | controller |  containerd:
2025-11-10 00:26:46.643191 | controller |   Version:          1.6.25
2025-11-10 00:26:46.643197 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-11-10 00:26:46.643203 | controller |  runc:
2025-11-10 00:26:46.643209 | controller |   Version:          1.1.10
2025-11-10 00:26:46.643215 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-11-10 00:26:46.643224 | controller |  docker-init:
2025-11-10 00:26:46.643230 | controller |   Version:          0.19.0
2025-11-10 00:26:46.643236 | controller |   GitCommit:        de40ad0
2025-11-10 00:26:46.860480 | controller | ok: Runtime: 0:00:00.049227
2025-11-10 00:26:46.870145 | 
2025-11-10 00:26:46.870263 | TASK [ensure-docker : Dump installed docker details]
2025-11-10 00:26:46.913477 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version:           24.0.7\n API version:       1.43\n Go version:        go1.20.10\n Git commit:        afdd53b\n Built:             Thu Oct 26 09:09:39 2023\n OS/Arch:           linux/amd64\n Context:           default\n\nServer: Docker Engine - Community\n Engine:\n  Version:          24.0.7\n  API version:      1.43 (minimum version 1.12)\n  Go version:       go1.20.10\n  Git commit:       311b9ff\n  Built:            Thu Oct 26 09:08:11 2023\n  OS/Arch:          linux/amd64\n  Experimental:     false\n containerd:\n  Version:          1.6.25\n  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n  Version:          1.1.10\n  GitCommit:        v1.1.10-0-g18a0cb0\n docker-init:\n  Version:          0.19.0\n  GitCommit:        de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-11-10 00:26:46.596477', 'end': '2025-11-10 00:26:46.645704', 'delta': '0:00:00.049227', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-028b-edff-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version:           24.0.7', ' API version:       1.43', ' Go version:        go1.20.10', ' Git commit:        afdd53b', ' Built:             Thu Oct 26 09:09:39 2023', ' OS/Arch:           linux/amd64', ' Context:           default', '', 'Server: Docker Engine - Community', ' Engine:', '  Version:          24.0.7', '  API version:      1.43 (minimum version 1.12)', '  Go version:       go1.20.10', '  Git commit:       311b9ff', '  Built:            Thu Oct 26 09:08:11 2023', '  OS/Arch:          linux/amd64', '  Experimental:     false', ' containerd:', '  Version:          1.6.25', '  GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', '  Version:          1.1.10', '  GitCommit:        v1.1.10-0-g18a0cb0', ' docker-init:', '  Version:          0.19.0', '  GitCommit:        de40ad0'], 'stderr_lines': [], 'failed': False}
2025-11-10 00:26:46.919447 | 
2025-11-10 00:26:46.919514 | TASK [Use buildset registry]
2025-11-10 00:26:46.945502 | controller | skipping: Conditional result was False
2025-11-10 00:26:46.965326 | 
2025-11-10 00:26:46.965463 | TASK [Ensure python3.8 is present]
2025-11-10 00:26:46.994189 | controller | skipping: Conditional result was False
2025-11-10 00:26:47.008766 | 
2025-11-10 00:26:47.008852 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-11-10 00:26:47.029003 | controller | ok
2025-11-10 00:26:47.050084 | 
2025-11-10 00:26:47.050245 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-11-10 00:26:53.092346 | controller | ok: Nothing to do
2025-11-10 00:26:53.105725 | 
2025-11-10 00:26:53.105953 | TASK [our-ensure-python : Also install python3-devel]
2025-11-10 00:27:09.275303 | controller | changed
2025-11-10 00:27:09.301941 | 
2025-11-10 00:27:09.302096 | TASK [Run ensure-virtualenv role]
2025-11-10 00:27:09.335750 | controller | ok
2025-11-10 00:27:09.368713 | 
2025-11-10 00:27:09.368888 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-11-10 00:27:10.173805 | controller | /usr/bin/virtualenv
2025-11-10 00:27:10.730217 | controller | ok: Runtime: 0:00:00.008843
2025-11-10 00:27:10.744913 | 
2025-11-10 00:27:10.745064 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-11-10 00:27:10.783184 | controller | skipping: Conditional result was False
2025-11-10 00:27:10.784003 | controller | changed: All items complete
2025-11-10 00:27:10.784092 | 
2025-11-10 00:27:10.825451 | 
2025-11-10 00:27:10.825737 | TASK [Find the full path of the Python interpreter]
2025-11-10 00:27:11.692589 | controller | /usr/bin/python3.8
2025-11-10 00:27:12.196733 | controller | ok
2025-11-10 00:27:12.210232 | 
2025-11-10 00:27:12.210382 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-11-10 00:27:14.331634 | controller | created virtual environment CPython3.8.18.final.0-64 in 1072ms
2025-11-10 00:27:14.444952 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-11-10 00:27:14.445033 | controller |   seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv)
2025-11-10 00:27:14.445054 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-11-10 00:27:14.445077 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-11-10 00:27:14.604925 | controller | changed
2025-11-10 00:27:14.618060 | 
2025-11-10 00:27:14.618264 | TASK [Set selinux package]
2025-11-10 00:27:14.648558 | controller | ok
2025-11-10 00:27:14.661757 | 
2025-11-10 00:27:14.662177 | TASK [Set selinux package (Fedora)]
2025-11-10 00:27:14.707135 | controller | ok
2025-11-10 00:27:14.715735 | 
2025-11-10 00:27:14.715832 | TASK [Install selinux into virtualenv]
2025-11-10 00:27:20.604945 | controller | Collecting selinux-please-lie-to-me
2025-11-10 00:27:20.693125 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-11-10 00:27:21.919411 | controller | Collecting setuptools<50.0.0
2025-11-10 00:27:21.929332 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-11-10 00:27:21.990399 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 16.0 MB/s eta 0:00:00
2025-11-10 00:27:22.149090 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-11-10 00:27:22.149189 | controller |   Attempting uninstall: setuptools
2025-11-10 00:27:22.150341 | controller |     Found existing installation: setuptools 62.6.0
2025-11-10 00:27:22.281034 | controller |     Uninstalling setuptools-62.6.0:
2025-11-10 00:27:22.297208 | controller |       Successfully uninstalled setuptools-62.6.0
2025-11-10 00:27:23.031887 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-11-10 00:27:23.238935 | controller |
2025-11-10 00:27:23.492464 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-10 00:27:23.492494 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-10 00:27:24.104183 | controller | ok: Runtime: 0:00:07.461243
2025-11-10 00:27:24.117423 | 
2025-11-10 00:27:24.117578 | TASK [Install pytest-forked into virtualenv]
2025-11-10 00:27:25.643535 | controller | Collecting pytest-forked
2025-11-10 00:27:25.703479 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-11-10 00:27:25.764520 | controller | Collecting py
2025-11-10 00:27:25.769042 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-11-10 00:27:25.798805 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 3.5 MB/s eta 0:00:00
2025-11-10 00:27:25.985888 | controller | Collecting pytest>=3.10
2025-11-10 00:27:25.989205 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-11-10 00:27:26.008834 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 22.3 MB/s eta 0:00:00
2025-11-10 00:27:26.069666 | controller | Collecting iniconfig
2025-11-10 00:27:26.076625 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-11-10 00:27:26.135006 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-11-10 00:27:26.138174 | controller |   Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB)
2025-11-10 00:27:26.195269 | controller | Collecting pluggy<2,>=1.5
2025-11-10 00:27:26.198825 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-11-10 00:27:26.270878 | controller | Collecting packaging
2025-11-10 00:27:26.273804 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-11-10 00:27:26.292131 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 3.8 MB/s eta 0:00:00
2025-11-10 00:27:26.368004 | controller | Collecting tomli>=1
2025-11-10 00:27:26.374379 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-11-10 00:27:26.457035 | controller | Collecting typing-extensions>=4.6.0
2025-11-10 00:27:26.462165 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-11-10 00:27:26.473869 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 6.2 MB/s eta 0:00:00
2025-11-10 00:27:26.580902 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-11-10 00:27:27.416937 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.5.0 py-1.11.0 pytest-8.3.5 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.13.2
2025-11-10 00:27:27.421701 | controller |
2025-11-10 00:27:27.642875 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-11-10 00:27:27.642935 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-11-10 00:27:28.000724 | controller | ok: Runtime: 0:00:02.727149
2025-11-10 00:27:28.015982 | 
2025-11-10 00:27:28.016147 | TASK [Update pip]
2025-11-10 00:27:30.110186 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-11-10 00:27:30.494912 | controller | Collecting pip
2025-11-10 00:27:30.577153 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-11-10 00:27:30.689672 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 18.3 MB/s eta 0:00:00
2025-11-10 00:27:30.805506 | controller | Installing collected packages: pip
2025-11-10 00:27:30.805713 | controller |   Attempting uninstall: pip
2025-11-10 00:27:30.813028 | controller |     Found existing installation: pip 22.2.2
2025-11-10 00:27:31.574838 | controller |     Uninstalling pip-22.2.2:
2025-11-10 00:27:31.655920 | controller |       Successfully uninstalled pip-22.2.2
2025-11-10 00:27:34.910298 | controller | Successfully installed pip-25.0.1
2025-11-10 00:27:35.406561 | controller | ok: Runtime: 0:00:06.124543
2025-11-10 00:27:35.413476 | 
2025-11-10 00:27:35.413558 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-11-10 00:27:36.714378 | controller | changed
2025-11-10 00:27:36.721464 | 
2025-11-10 00:27:36.721587 | TASK [Install ansible into virtualenv]
2025-11-10 00:27:38.549471 | controller | Processing ./src/github.com/ansible/ansible
2025-11-10 00:27:38.550887 | controller |   Preparing metadata (setup.py): started
2025-11-10 00:27:40.620162 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-11-10 00:27:40.693524 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-11-10 00:27:40.708861 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-11-10 00:27:40.802413 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-11-10 00:27:40.807836 | controller |   Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.1 kB)
2025-11-10 00:27:41.240620 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-11-10 00:27:41.246352 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-11-10 00:27:41.514250 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-11-10 00:27:41.520921 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-11-10 00:27:41.531285 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.8/site-packages (from cryptography->ansible==2.9.27.post0) (4.13.2)
2025-11-10 00:27:41.659586 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-11-10 00:27:41.666448 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-11-10 00:27:41.706078 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-11-10 00:27:41.713199 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-11-10 00:27:41.737431 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-11-10 00:27:41.799091 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 85.6 MB/s eta 0:00:00
2025-11-10 00:27:41.804226 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-11-10 00:27:41.829564 | controller | Downloading PyYAML-6.0.3-cp38-cp38-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (806 kB)
2025-11-10 00:27:41.849131 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 41.9 MB/s eta 0:00:00
2025-11-10 00:27:41.855482 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-11-10 00:27:41.878801 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-11-10 00:27:41.902876 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-11-10 00:27:41.942755 | controller | Building wheels for collected packages: ansible
2025-11-10 00:27:41.947680 | controller |   Building wheel for ansible (setup.py): started
2025-11-10 00:27:52.576639 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-11-10 00:27:52.600838 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=5bce6f93810fc0ce427897f31301f50d16593b7e82dc1efc7f340a166e0061da
2025-11-10 00:27:52.601228 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-e61roenp/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-11-10 00:27:52.707884 | controller | Successfully built ansible
2025-11-10 00:27:52.906551 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-11-10 00:28:07.540216 | controller | Successfully installed MarkupSafe-2.1.5 PyYAML-6.0.3 ansible-2.9.27.post0 cffi-1.17.1 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23
2025-11-10 00:28:08.132438 | controller | ok: Runtime: 0:00:29.947235
2025-11-10 00:28:08.144480 | 
2025-11-10 00:28:08.144662 | TASK [Setup download-artifact-fork role]
2025-11-10 00:28:08.179997 | controller | ok
2025-11-10 00:28:08.208927 | 
2025-11-10 00:28:08.209080 | TASK [download-artifact-fork : Parse inventory response]
2025-11-10 00:28:08.279647 | controller | ok
2025-11-10 00:28:08.290092 | 
2025-11-10 00:28:08.290197 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-11-10 00:28:08.326392 | controller | skipping: Conditional result was False
2025-11-10 00:28:08.335436 | 
2025-11-10 00:28:08.335527 | TASK [download-artifact-fork : Parse build response]
2025-11-10 00:28:08.385499 | controller | skipping: Conditional result was False
2025-11-10 00:28:08.393987 | 
2025-11-10 00:28:08.394160 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-11-10 00:28:09.241055 | controller | changed
2025-11-10 00:28:09.255867 | 
2025-11-10 00:28:09.256178 | LOOP [download-artifact-fork : Download archive by type]
2025-11-10 00:28:09.299191 | controller | skipping: Conditional result was False
2025-11-10 00:28:09.299647 | 
2025-11-10 00:28:11.321125 | controller | changed: OK (20212 bytes)
2025-11-10 00:28:11.365279 | 
2025-11-10 00:28:11.365550 | TASK [deploy-artifacts : define __collections]
2025-11-10 00:28:11.411157 | controller | ok
2025-11-10 00:28:11.469609 | 
2025-11-10 00:28:11.469828 | LOOP [deploy-artifacts : Create list of collections]
2025-11-10 00:28:11.515306 | controller | skipping: Conditional result was False
2025-11-10 00:28:11.534926 | controller | ok:
2025-11-10 00:28:11.535085 | controller | {
2025-11-10 00:28:11.535107 | controller |   "branch": "main",
2025-11-10 00:28:11.535124 | controller |   "job": "build-ansible-collection",
2025-11-10 00:28:11.535139 | controller |   "metadata": {
2025-11-10 00:28:11.535155 | controller |     "type": "ansible_collection",
2025-11-10 00:28:11.535170 | controller |     "version": "5.0.0"
2025-11-10 00:28:11.535184 | controller |   },
2025-11-10 00:28:11.535199 | controller |   "name": "ansible.network",
2025-11-10 00:28:11.535214 | controller |   "project": "ansible-collections/ansible.network",
2025-11-10 00:28:11.535228 | controller |   "url": "https://4e584d751c724d9fcfef-cdbe3e6088114cf153ba8d2a81fbe483.ssl.cf5.rackcdn.com/ansible/d166085815d3438ea5baa90da800959a/artifacts/ansible-network-5.0.0.tar.gz"
2025-11-10 00:28:11.535250 | controller | }
2025-11-10 00:28:11.554462 | 
2025-11-10 00:28:11.555041 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-11-10 00:28:12.699745 | controller | Process install dependency map
2025-11-10 00:29:06.533202 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/Starting collection install process
2025-11-10 00:29:06.635106 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-11-10 00:29:06.635176 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-11-10 00:29:08.243611 | controller | /-\|/-\|/-\|/-\|Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-11-10 00:29:09.951602 | controller | /-\|/-\|/-\|/-\|/Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-11-10 00:29:12.264363 | controller | -\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-11-10 00:29:15.128255 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-11-10 00:29:17.947061 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-11-10 00:29:22.302651 | controller | /-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-11-10 00:29:25.477304 | controller | ok: Runtime: 0:01:12.634397
2025-11-10 00:29:25.501921 | 
2025-11-10 00:29:25.502111 | TASK [podman may need iptables kernel modules]
2025-11-10 00:29:26.878250 | controller | ok: Runtime: 0:00:00.006397
2025-11-10 00:29:26.930744 | 
2025-11-10 00:29:26.930899 | PLAY RECAP
2025-11-10 00:29:26.930976 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-11-10 00:29:26.931026 | 
2025-11-10 00:29:27.114556 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-11-10 00:29:27.115615 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-10 00:29:27.810367 | 
2025-11-10 00:29:27.810588 | PLAY [controller]
2025-11-10 00:29:27.843795 | 
2025-11-10 00:29:27.844028 | TASK [Copy the galaxy.yml on the controller]
2025-11-10 00:29:31.629175 | controller | changed
2025-11-10 00:29:31.642707 | 
2025-11-10 00:29:31.642880 | TASK [Load information from galaxy.yml]
2025-11-10 00:29:31.696440 | controller | ok
2025-11-10 00:29:31.709598 | 
2025-11-10 00:29:31.709806 | LOOP [Enable FIPS mode]
2025-11-10 00:29:31.753108 | 
2025-11-10 00:29:31.753380 | LOOP [ansible-test : Include branch specific variables.]
2025-11-10 00:29:31.811965 | controller | ok: "/var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-11-10 00:29:31.812524 | 
2025-11-10 00:29:31.842195 | 
2025-11-10 00:29:31.842480 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-11-10 00:29:31.903001 | controller | ok
2025-11-10 00:29:31.912428 | 
2025-11-10 00:29:31.912667 | TASK [ansible-test : Enable --inventory for network-integration]
2025-11-10 00:29:31.942188 | controller | skipping: Conditional result was False
2025-11-10 00:29:31.956184 | 
2025-11-10 00:29:31.956458 | TASK [ansible-test : Enable --retry-on-error]
2025-11-10 00:29:31.985842 | controller | skipping: Conditional result was False
2025-11-10 00:29:31.996988 | 
2025-11-10 00:29:31.997217 | TASK [ansible-test : Enable --continue-on-error]
2025-11-10 00:29:32.063467 | controller | ok
2025-11-10 00:29:32.069492 | 
2025-11-10 00:29:32.069556 | TASK [ansible-test : Enable --requirements]
2025-11-10 00:29:32.095213 | controller | skipping: Conditional result was False
2025-11-10 00:29:32.101926 | 
2025-11-10 00:29:32.101990 | TASK [ansible-test : Adjust options for unit tests]
2025-11-10 00:29:32.126868 | controller | skipping: Conditional result was False
2025-11-10 00:29:32.133797 | 
2025-11-10 00:29:32.133867 | TASK [ansible-test : Install requirements with unit tests]
2025-11-10 00:29:32.209219 | controller | ok
2025-11-10 00:29:32.215004 | 
2025-11-10 00:29:32.215069 | TASK [ansible-test : Set --skip-test]
2025-11-10 00:29:32.260798 | controller | skipping: Conditional result was False
2025-11-10 00:29:32.268523 | 
2025-11-10 00:29:32.268603 | TASK [ansible-test : Reduce verbosity]
2025-11-10 00:29:32.314637 | controller | ok
2025-11-10 00:29:32.321430 | 
2025-11-10 00:29:32.321497 | TASK [ansible-test : Setup --python option]
2025-11-10 00:29:32.346240 | controller | skipping: Conditional result was False
2025-11-10 00:29:32.353133 | 
2025-11-10 00:29:32.353201 | TASK [ansible-test : Setup --docker option]
2025-11-10 00:29:32.394791 | controller | ok
2025-11-10 00:29:32.403008 | 
2025-11-10 00:29:32.403076 | TASK [ansible-test : check if MANIFEST.json exists]
2025-11-10 00:29:33.604630 | controller | ok
2025-11-10 00:29:33.617398 | 
2025-11-10 00:29:33.617568 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-11-10 00:29:33.644892 | controller | skipping: Conditional result was False
2025-11-10 00:29:33.655050 | 
2025-11-10 00:29:33.655176 | TASK [ansible-test : Load information from MANIFEST.json]
2025-11-10 00:29:33.681850 | controller | skipping: Conditional result was False
2025-11-10 00:29:33.697794 | 
2025-11-10 00:29:33.697987 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-10 00:29:33.727717 | controller | skipping: Conditional result was False
2025-11-10 00:29:33.742251 | 
2025-11-10 00:29:33.742431 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-11-10 00:29:35.047443 | controller | ok
2025-11-10 00:29:35.061579 | 
2025-11-10 00:29:35.061815 | TASK [ansible-test : Load information from galaxy.yml]
2025-11-10 00:29:35.113311 | controller | ok
2025-11-10 00:29:35.122636 | 
2025-11-10 00:29:35.122763 | TASK [ansible-test : Setup location of project for integration tests]
2025-11-10 00:29:35.166081 | controller | ok
2025-11-10 00:29:35.171860 | 
2025-11-10 00:29:35.171927 | TASK [ansible-test : Setup minimum test requirements]
2025-11-10 00:29:35.202778 | controller | ok
2025-11-10 00:29:35.208513 | 
2025-11-10 00:29:35.208593 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-11-10 00:29:36.158315 | controller | ok
2025-11-10 00:29:36.165645 | 
2025-11-10 00:29:36.165832 | TASK [ansible-test : Install python requirements]
2025-11-10 00:29:37.649402 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-10 00:29:37.667914 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-11-10 00:29:37.701678 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-11-10 00:29:37.707081 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-11-10 00:29:37.718385 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.8/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3)
2025-11-10 00:29:37.724474 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-11-10 00:29:37.739323 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-11-10 00:29:37.802616 | controller | Installing collected packages: pathspec, yamllint
2025-11-10 00:29:37.925401 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-11-10 00:29:38.530933 | controller | ok: Runtime: 0:00:01.062518
2025-11-10 00:29:38.546708 | 
2025-11-10 00:29:38.546899 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-11-10 00:29:39.406352 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-11-10 00:29:39.925664 | controller | ERROR
2025-11-10 00:29:39.926102 | controller | {
2025-11-10 00:29:39.926166 | controller |   "delta": "0:00:00.009438",
2025-11-10 00:29:39.926214 | controller |   "end": "2025-11-10 00:29:39.406652",
2025-11-10 00:29:39.926269 | controller |   "msg": "non-zero return code",
2025-11-10 00:29:39.926313 | controller |   "rc": 1,
2025-11-10 00:29:39.926353 | controller |   "start": "2025-11-10 00:29:39.397214"
2025-11-10 00:29:39.926392 | controller | }
2025-11-10 00:29:39.926444 | controller | ERROR: Ignoring Errors
2025-11-10 00:29:39.943907 | 
2025-11-10 00:29:39.944127 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-11-10 00:29:40.323826 | controller | skipping: Conditional result was False
2025-11-10 00:29:40.341967 | 
2025-11-10 00:29:40.342152 | TASK [ansible-test : Enable persistent connection logging]
2025-11-10 00:29:42.185510 | controller | changed: section and option added
2025-11-10 00:29:42.198209 | 
2025-11-10 00:29:42.198371 | TASK [ansible-test : Set persistent ssh_type value]
2025-11-10 00:29:43.525967 | controller | changed: option added
2025-11-10 00:29:43.535019 | 
2025-11-10 00:29:43.535192 | TASK [ansible-test : Set ansible log path]
2025-11-10 00:29:44.909592 | controller | changed: section and option added
2025-11-10 00:29:44.925942 | 
2025-11-10 00:29:44.926195 | TASK [ansible-test : Identify the changed targets]
2025-11-10 00:29:45.299632 | controller | skipping: Conditional result was False
2025-11-10 00:29:45.310186 | 
2025-11-10 00:29:45.310317 | TASK [ansible-test : set_fact]
2025-11-10 00:29:45.416992 | controller | skipping: Conditional result was False
2025-11-10 00:29:45.474912 | 
2025-11-10 00:29:45.475100 | TASK [ansible-test : Set the targets]
2025-11-10 00:29:45.519305 | controller | ok
2025-11-10 00:29:45.527745 | 
2025-11-10 00:29:45.527884 | TASK [ansible-test : set_fact]
2025-11-10 00:29:45.553665 | controller | skipping: Conditional result was False
2025-11-10 00:29:45.564138 | 
2025-11-10 00:29:45.564343 | TASK [ansible-test : List the targets]
2025-11-10 00:29:45.943076 | controller | skipping: Conditional result was False
2025-11-10 00:29:45.953601 | 
2025-11-10 00:29:45.953750 | TASK [ansible-test : set_fact]
2025-11-10 00:29:45.979206 | controller | skipping: Conditional result was False
2025-11-10 00:29:45.990009 | 
2025-11-10 00:29:45.990116 | TASK [ansible-test : set_fact]
2025-11-10 00:29:46.016419 | controller | skipping: Conditional result was False
2025-11-10 00:29:46.027186 | 
2025-11-10 00:29:46.027306 | LOOP [ansible-test : set_fact]
2025-11-10 00:29:46.100741 | 
2025-11-10 00:29:46.101082 | TASK [ansible-test : set_fact]
2025-11-10 00:29:46.129808 | controller | skipping: Conditional result was False
2025-11-10 00:29:46.148520 | 
2025-11-10 00:29:46.148751 | TASK [ansible-test : debug]
2025-11-10 00:29:46.176811 | controller | skipping: Conditional result was False
2025-11-10 00:29:46.191013 | 
2025-11-10 00:29:46.191159 | TASK [ansible-test : debug]
2025-11-10 00:29:46.240453 | About to run: ansible-test sanity --requirements --docker -v
2025-11-10 00:29:46.252954 | 
2025-11-10 00:29:46.253111 | TASK [ansible-test : Run the test suite]
2025-11-10 00:29:48.159003 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-11-10 00:29:48.273275 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-11-10 00:29:50.047139 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-11-10 00:29:50.051025 | controller | [DEPRECATION NOTICE] Docker Image Format v1, and Docker Image manifest version 2, schema 1 support will be removed in an upcoming release. Suggest the author of quay.io/ansible/default-test-container:1.10.1 to upgrade the image to the OCI Format, or Docker Image manifest v2, schema 2. More information at https://docs.docker.com/go/deprecated-image-specs/
2025-11-10 00:29:50.051107 | controller | 5667fdb72017: Pulling fs layer
2025-11-10 00:29:50.051127 | controller | d83811f270d5: Pulling fs layer
2025-11-10 00:29:50.051383 | controller | ee671aafb583: Pulling fs layer
2025-11-10 00:29:50.051399 | controller | 7fc152dfb3a6: Pulling fs layer
2025-11-10 00:29:50.051409 | controller | 3fb967a6fd03: Pulling fs layer
2025-11-10 00:29:50.051415 | controller | b3e63023ca5f: Pulling fs layer
2025-11-10 00:29:50.051422 | controller | 653cb3e65dbf: Pulling fs layer
2025-11-10 00:29:50.051431 | controller | f4712b586375: Pulling fs layer
2025-11-10 00:29:50.053678 | controller | d696ed7812f8: Pulling fs layer
2025-11-10 00:29:50.053696 | controller | 29bf28764f14: Pulling fs layer
2025-11-10 00:29:50.053703 | controller | 47d7edb3c2b7: Pulling fs layer
2025-11-10 00:29:50.053709 | controller | 07550ad4f6e4: Pulling fs layer
2025-11-10 00:29:50.053716 | controller | 7e79526e64c5: Pulling fs layer
2025-11-10 00:29:50.053722 | controller | 4fffb32882b7: Pulling fs layer
2025-11-10 00:29:50.053728 | controller | fc7a38b06f4e: Pulling fs layer
2025-11-10 00:29:50.053735 | controller | 6d9d912e060e: Pulling fs layer
2025-11-10 00:29:50.053744 | controller | 5dd542dd28d9: Pulling fs layer
2025-11-10 00:29:50.053751 | controller | cef8beb99552: Pulling fs layer
2025-11-10 00:29:50.053758 | controller | bd5557e101ad: Pulling fs layer
2025-11-10 00:29:50.053764 | controller | e057c20fabdd: Pulling fs layer
2025-11-10 00:29:50.053772 | controller | 7fc152dfb3a6: Waiting
2025-11-10 00:29:50.053778 | controller | 3fb967a6fd03: Waiting
2025-11-10 00:29:50.053784 | controller | b3e63023ca5f: Waiting
2025-11-10 00:29:50.053791 | controller | 653cb3e65dbf: Waiting
2025-11-10 00:29:50.053797 | controller | f4712b586375: Waiting
2025-11-10 00:29:50.053803 | controller | d696ed7812f8: Waiting
2025-11-10 00:29:50.053809 | controller | 29bf28764f14: Waiting
2025-11-10 00:29:50.053815 | controller | 47d7edb3c2b7: Waiting
2025-11-10 00:29:50.053824 | controller | 07550ad4f6e4: Waiting
2025-11-10 00:29:50.053831 | controller | 7e79526e64c5: Waiting
2025-11-10 00:29:50.053837 | controller | 4fffb32882b7: Waiting
2025-11-10 00:29:50.053843 | controller | fc7a38b06f4e: Waiting
2025-11-10 00:29:50.053849 | controller | 6d9d912e060e: Waiting
2025-11-10 00:29:50.053855 | controller | 5dd542dd28d9: Waiting
2025-11-10 00:29:50.053862 | controller | cef8beb99552: Waiting
2025-11-10 00:29:50.053872 | controller | bd5557e101ad: Waiting
2025-11-10 00:29:50.903134 | controller | d16059cdae2e: Pulling fs layer
2025-11-10 00:29:50.903180 | controller | 431e42d906a3: Pulling fs layer
2025-11-10 00:29:50.903187 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-11-10 00:29:50.903194 | controller | aacdb6db0c8c: Pulling fs layer
2025-11-10 00:29:50.903200 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-11-10 00:29:50.903206 | controller | 4cd343027a44: Pulling fs layer
2025-11-10 00:29:50.903212 | controller | 0b4379883f90: Pulling fs layer
2025-11-10 00:29:50.903218 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-11-10 00:29:50.903224 | controller | 57d6314bd293: Pulling fs layer
2025-11-10 00:29:50.903230 | controller | 774036afeb7f: Pulling fs layer
2025-11-10 00:29:50.903236 | controller | 54b10f0e1645: Pulling fs layer
2025-11-10 00:29:50.903242 | controller | 78cf439d9fdb: Pulling fs layer
2025-11-10 00:29:50.903251 | controller | 36e5e20969c7: Pulling fs layer
2025-11-10 00:29:50.903257 | controller | 4b19ac3f17a8: Pulling fs layer
2025-11-10 00:29:50.903262 | controller | 7a0fd3b1d7c3: Waiting
2025-11-10 00:29:50.903268 | controller | e057c20fabdd: Waiting
2025-11-10 00:29:50.903274 | controller | d16059cdae2e: Waiting
2025-11-10 00:29:50.903280 | controller | 431e42d906a3: Waiting
2025-11-10 00:29:50.903286 | controller | 774036afeb7f: Waiting
2025-11-10 00:29:50.903292 | controller | 2e7f04ae8c8b: Waiting
2025-11-10 00:29:50.903298 | controller | 4cd343027a44: Waiting
2025-11-10 00:29:50.903319 | controller | 0b4379883f90: Waiting
2025-11-10 00:29:50.903326 | controller | 3d9d6d9dfc91: Waiting
2025-11-10 00:29:50.903332 | controller | 54b10f0e1645: Waiting
2025-11-10 00:29:50.903340 | controller | 57d6314bd293: Waiting
2025-11-10 00:29:50.903354 | controller | 4b19ac3f17a8: Waiting
2025-11-10 00:29:50.903362 | controller | 78cf439d9fdb: Waiting
2025-11-10 00:29:50.903368 | controller | 36e5e20969c7: Waiting
2025-11-10 00:29:50.903383 | controller | ee671aafb583: Verifying Checksum
2025-11-10 00:29:50.903994 | controller | ee671aafb583: Download complete
2025-11-10 00:29:51.050213 | controller | d83811f270d5: Verifying Checksum
2025-11-10 00:29:51.617369 | controller | d83811f270d5: Download complete
2025-11-10 00:29:51.617420 | controller | 7fc152dfb3a6: Verifying Checksum
2025-11-10 00:29:52.751780 | controller | 7fc152dfb3a6: Download complete
2025-11-10 00:29:52.751846 | controller | b3e63023ca5f: Verifying Checksum
2025-11-10 00:29:53.762064 | controller | b3e63023ca5f: Download complete
2025-11-10 00:29:53.762126 | controller | 653cb3e65dbf: Verifying Checksum
2025-11-10 00:29:56.918282 | controller | 653cb3e65dbf: Download complete
2025-11-10 00:29:56.918417 | controller | 5667fdb72017: Download complete
2025-11-10 00:29:58.021999 | controller | d696ed7812f8: Verifying Checksum
2025-11-10 00:29:58.731781 | controller | d696ed7812f8: Download complete
2025-11-10 00:29:58.731838 | controller | 3fb967a6fd03: Verifying Checksum
2025-11-10 00:29:59.126520 | controller | 3fb967a6fd03: Download complete
2025-11-10 00:29:59.126583 | controller | 29bf28764f14: Verifying Checksum
2025-11-10 00:29:59.500304 | controller | 29bf28764f14: Download complete
2025-11-10 00:29:59.500387 | controller | 5667fdb72017: Pull complete
2025-11-10 00:29:59.536068 | controller | d83811f270d5: Pull complete
2025-11-10 00:29:59.563798 | controller | ee671aafb583: Pull complete
2025-11-10 00:29:59.592508 | controller | 7fc152dfb3a6: Pull complete
2025-11-10 00:30:00.168779 | controller | 07550ad4f6e4: Verifying Checksum
2025-11-10 00:30:00.520516 | controller | 07550ad4f6e4: Download complete
2025-11-10 00:30:00.520582 | controller | 47d7edb3c2b7: Verifying Checksum
2025-11-10 00:30:00.555973 | controller | 47d7edb3c2b7: Download complete
2025-11-10 00:30:00.556038 | controller | 3fb967a6fd03: Pull complete
2025-11-10 00:30:00.588156 | controller | b3e63023ca5f: Pull complete
2025-11-10 00:30:00.622639 | controller | 653cb3e65dbf: Pull complete
2025-11-10 00:30:01.114962 | controller | 7e79526e64c5: Verifying Checksum
2025-11-10 00:30:01.603514 | controller | 7e79526e64c5: Download complete
2025-11-10 00:30:01.603622 | controller | 4fffb32882b7: Verifying Checksum
2025-11-10 00:30:02.487020 | controller | 4fffb32882b7: Download complete
2025-11-10 00:30:02.487119 | controller | fc7a38b06f4e: Verifying Checksum
2025-11-10 00:30:02.699924 | controller | fc7a38b06f4e: Download complete
2025-11-10 00:30:02.700026 | controller | 6d9d912e060e: Verifying Checksum
2025-11-10 00:30:03.665851 | controller | 6d9d912e060e: Download complete
2025-11-10 00:30:03.665959 | controller | 5dd542dd28d9: Verifying Checksum
2025-11-10 00:30:04.498017 | controller | 5dd542dd28d9: Download complete
2025-11-10 00:30:04.498111 | controller | bd5557e101ad: Verifying Checksum
2025-11-10 00:30:05.669821 | controller | bd5557e101ad: Download complete
2025-11-10 00:30:05.669919 | controller | e057c20fabdd: Verifying Checksum
2025-11-10 00:30:06.599600 | controller | e057c20fabdd: Download complete
2025-11-10 00:30:06.599730 | controller | d16059cdae2e: Verifying Checksum
2025-11-10 00:30:06.599835 | controller | d16059cdae2e: Download complete
2025-11-10 00:30:08.636398 | controller | 431e42d906a3: Verifying Checksum
2025-11-10 00:30:09.991717 | controller | 431e42d906a3: Download complete
2025-11-10 00:30:09.991819 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-11-10 00:30:09.991969 | controller | 7a0fd3b1d7c3: Download complete
2025-11-10 00:30:11.047146 | controller | aacdb6db0c8c: Verifying Checksum
2025-11-10 00:30:11.048095 | controller | aacdb6db0c8c: Download complete
2025-11-10 00:30:11.921171 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-11-10 00:30:11.921290 | controller | 2e7f04ae8c8b: Download complete
2025-11-10 00:30:12.989120 | controller | 4cd343027a44: Verifying Checksum
2025-11-10 00:30:12.992007 | controller | 4cd343027a44: Download complete
2025-11-10 00:30:14.085226 | controller | 0b4379883f90: Verifying Checksum
2025-11-10 00:30:15.167226 | controller | 0b4379883f90: Download complete
2025-11-10 00:30:15.167345 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-11-10 00:30:19.726232 | controller | 3d9d6d9dfc91: Download complete
2025-11-10 00:30:19.726344 | controller | f4712b586375: Verifying Checksum
2025-11-10 00:30:19.726474 | controller | f4712b586375: Download complete
2025-11-10 00:30:21.536616 | controller | cef8beb99552: Verifying Checksum
2025-11-10 00:30:21.536925 | controller | cef8beb99552: Download complete
2025-11-10 00:30:26.591069 | controller | 57d6314bd293: Verifying Checksum
2025-11-10 00:30:26.591378 | controller | 57d6314bd293: Download complete
2025-11-10 00:30:32.763731 | controller | 774036afeb7f: Verifying Checksum
2025-11-10 00:30:32.763827 | controller | 774036afeb7f: Download complete
2025-11-10 00:30:37.478009 | controller | 54b10f0e1645: Verifying Checksum
2025-11-10 00:30:37.478193 | controller | 54b10f0e1645: Download complete
2025-11-10 00:30:38.299839 | controller | 78cf439d9fdb: Verifying Checksum
2025-11-10 00:30:38.299969 | controller | 78cf439d9fdb: Download complete
2025-11-10 00:30:39.695511 | controller | 4b19ac3f17a8: Verifying Checksum
2025-11-10 00:30:39.695596 | controller | 4b19ac3f17a8: Download complete
2025-11-10 00:30:44.833585 | controller | 36e5e20969c7: Verifying Checksum
2025-11-10 00:30:44.833733 | controller | 36e5e20969c7: Download complete
2025-11-10 00:30:44.854355 | controller | f4712b586375: Pull complete
2025-11-10 00:30:44.880679 | controller | d696ed7812f8: Pull complete
2025-11-10 00:30:44.900217 | controller | 29bf28764f14: Pull complete
2025-11-10 00:30:44.950837 | controller | 47d7edb3c2b7: Pull complete
2025-11-10 00:30:44.979596 | controller | 07550ad4f6e4: Pull complete
2025-11-10 00:30:45.011822 | controller | 7e79526e64c5: Pull complete
2025-11-10 00:30:45.036500 | controller | 4fffb32882b7: Pull complete
2025-11-10 00:30:45.096435 | controller | fc7a38b06f4e: Pull complete
2025-11-10 00:30:45.120922 | controller | 6d9d912e060e: Pull complete
2025-11-10 00:30:45.151604 | controller | 5dd542dd28d9: Pull complete
2025-11-10 00:31:06.212251 | controller | cef8beb99552: Pull complete
2025-11-10 00:31:06.240866 | controller | bd5557e101ad: Pull complete
2025-11-10 00:31:06.273105 | controller | e057c20fabdd: Pull complete
2025-11-10 00:31:06.308580 | controller | d16059cdae2e: Pull complete
2025-11-10 00:31:08.694093 | controller | 431e42d906a3: Pull complete
2025-11-10 00:31:08.755737 | controller | 7a0fd3b1d7c3: Pull complete
2025-11-10 00:31:08.823597 | controller | aacdb6db0c8c: Pull complete
2025-11-10 00:31:08.867925 | controller | 2e7f04ae8c8b: Pull complete
2025-11-10 00:31:08.902451 | controller | 4cd343027a44: Pull complete
2025-11-10 00:31:08.932582 | controller | 0b4379883f90: Pull complete
2025-11-10 00:31:08.962260 | controller | 3d9d6d9dfc91: Pull complete
2025-11-10 00:31:14.607783 | controller | 57d6314bd293: Pull complete
2025-11-10 00:31:37.376866 | controller | 774036afeb7f: Pull complete
2025-11-10 00:32:06.065582 | controller | 54b10f0e1645: Pull complete
2025-11-10 00:32:34.823516 | controller | 78cf439d9fdb: Pull complete
2025-11-10 00:33:03.603334 | controller | 36e5e20969c7: Pull complete
2025-11-10 00:33:31.288265 | controller | 4b19ac3f17a8: Pull complete
2025-11-10 00:33:31.305749 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-11-10 00:33:31.308304 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-11-10 00:33:31.314685 | controller | quay.io/ansible/default-test-container:1.10.1
2025-11-10 00:33:31.317760 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-11-10 00:33:31.347816 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:33:32.723704 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-11-10 00:33:32.724320 | controller | 04a5f4cda3ee: Pulling fs layer
2025-11-10 00:33:32.724348 | controller | ff496a88c8ed: Pulling fs layer
2025-11-10 00:33:32.724393 | controller | 0ce83f459fe7: Pulling fs layer
2025-11-10 00:33:32.724437 | controller | 2e5170e1f099: Pulling fs layer
2025-11-10 00:33:32.724482 | controller | 7641eb41b08c: Pulling fs layer
2025-11-10 00:33:32.724528 | controller | ad15fa9da398: Pulling fs layer
2025-11-10 00:33:32.724582 | controller | 087d91352424: Pulling fs layer
2025-11-10 00:33:32.724612 | controller | 8b92efd6a100: Pulling fs layer
2025-11-10 00:33:32.724665 | controller | 7641eb41b08c: Waiting
2025-11-10 00:33:32.724724 | controller | ad15fa9da398: Waiting
2025-11-10 00:33:32.724763 | controller | 087d91352424: Waiting
2025-11-10 00:33:32.725573 | controller | 8b92efd6a100: Waiting
2025-11-10 00:33:33.085035 | controller | 2e5170e1f099: Waiting
2025-11-10 00:33:33.085131 | controller | ff496a88c8ed: Verifying Checksum
2025-11-10 00:33:33.110225 | controller | ff496a88c8ed: Download complete
2025-11-10 00:33:33.110340 | controller | 0ce83f459fe7: Verifying Checksum
2025-11-10 00:33:33.407241 | controller | 0ce83f459fe7: Download complete
2025-11-10 00:33:33.407365 | controller | 04a5f4cda3ee: Verifying Checksum
2025-11-10 00:33:33.410914 | controller | 04a5f4cda3ee: Download complete
2025-11-10 00:33:33.548969 | controller | 7641eb41b08c: Verifying Checksum
2025-11-10 00:33:33.550196 | controller | 7641eb41b08c: Download complete
2025-11-10 00:33:33.652266 | controller | 2e5170e1f099: Verifying Checksum
2025-11-10 00:33:33.797039 | controller | 2e5170e1f099: Download complete
2025-11-10 00:33:33.797110 | controller | ad15fa9da398: Verifying Checksum
2025-11-10 00:33:34.020685 | controller | ad15fa9da398: Download complete
2025-11-10 00:33:34.020745 | controller | 087d91352424: Verifying Checksum
2025-11-10 00:33:34.029070 | controller | 087d91352424: Download complete
2025-11-10 00:33:34.029127 | controller | 8b92efd6a100: Verifying Checksum
2025-11-10 00:33:35.523562 | controller | 8b92efd6a100: Download complete
2025-11-10 00:33:35.523623 | controller | 04a5f4cda3ee: Pull complete
2025-11-10 00:33:35.560087 | controller | ff496a88c8ed: Pull complete
2025-11-10 00:33:35.588037 | controller | 0ce83f459fe7: Pull complete
2025-11-10 00:33:37.007415 | controller | 2e5170e1f099: Pull complete
2025-11-10 00:33:37.034074 | controller | 7641eb41b08c: Pull complete
2025-11-10 00:33:37.060082 | controller | ad15fa9da398: Pull complete
2025-11-10 00:33:38.078243 | controller | 087d91352424: Pull complete
2025-11-10 00:33:38.462509 | controller | 8b92efd6a100: Pull complete
2025-11-10 00:33:38.482467 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-11-10 00:33:38.487447 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:33:38.493155 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:33:38.495528 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-11-10 00:33:47.173482 | controller | Run command: docker inspect 3610ecba4853cfcded24f9d56e9bf7e5358d1238d958e381e88299668b91d526
2025-11-10 00:33:47.415389 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-11-10 00:33:47.431034 | controller | Including collection: ansible.netcommon (292 files)
2025-11-10 00:33:47.445365 | controller | Including collection: ansible.network (23 files)
2025-11-10 00:33:47.445438 | controller | Including collection: ansible.utils (442 files)
2025-11-10 00:33:47.484234 | controller | Including collection: arista.eos (1022 files)
2025-11-10 00:33:47.535414 | controller | Including collection: cisco.ios (1172 files)
2025-11-10 00:33:47.593768 | controller | Including collection: cisco.iosxr (1043 files)
2025-11-10 00:33:47.674966 | controller | Including collection: cisco.nxos (1684 files)
2025-11-10 00:33:47.724048 | controller | Including collection: junipernetworks.junos (963 files)
2025-11-10 00:33:47.767890 | controller | Creating a payload archive containing 8378 files...
2025-11-10 00:33:50.257744 | controller | Created a 6889899 byte payload archive containing 8378 files in 2 seconds.
2025-11-10 00:33:50.263543 | controller | Assuming Docker is available on localhost.
2025-11-10 00:33:50.264118 | 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:1.10.1
2025-11-10 00:33:50.915694 | controller | Run command: docker exec -i f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e dd of=/root/docker.sh bs=65536
2025-11-10 00:33:51.011383 | controller | Run command: docker exec f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e /bin/bash /root/docker.sh
2025-11-10 00:33:51.116413 | controller | Run command: docker exec -i f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e dd of=/root/ansible.tgz bs=65536
2025-11-10 00:33:51.243420 | controller | Run command: docker exec f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e mkdir /root/ansible
2025-11-10 00:33:51.319256 | controller | Run command: docker exec f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e tar oxzf /root/ansible.tgz -C /root/ansible
2025-11-10 00:33:52.705753 | controller | Run command: docker exec f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-xxa120w3.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-11-10 00:33:53.247536 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-11-10 00:33:53.247951 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-11-10 00:33:53.248352 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-11-10 00:33:53.251442 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-10 00:33:53.332898 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-11-10 00:33:53.336387 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-11-10 00:33:53.345521 | controller | No tests applicable.
2025-11-10 00:33:53.345645 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-11-10 00:33:53.345885 | controller | No tests applicable.
2025-11-10 00:33:53.346208 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-10 00:33:53.429822 | controller | Running sanity test 'compile' with Python 2.6
2025-11-10 00:33:53.430161 | controller | No tests applicable.
2025-11-10 00:33:53.430575 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-10 00:33:53.644084 | controller | Running sanity test 'compile' with Python 2.7
2025-11-10 00:33:53.644346 | controller | No tests applicable.
2025-11-10 00:33:53.644368 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-10 00:33:53.793396 | controller | Running sanity test 'compile' with Python 3.5
2025-11-10 00:33:53.793797 | controller | No tests applicable.
2025-11-10 00:33:53.793815 | controller | Running sanity test 'compile' with Python 3.6
2025-11-10 00:33:53.794063 | controller | No tests applicable.
2025-11-10 00:33:53.794493 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-10 00:33:53.911513 | controller | Running sanity test 'compile' with Python 3.7
2025-11-10 00:33:53.911893 | controller | No tests applicable.
2025-11-10 00:33:53.912270 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-11-10 00:33:54.035184 | controller | Running sanity test 'compile' with Python 3.8
2025-11-10 00:33:54.036660 | controller | No tests applicable.
2025-11-10 00:33:54.036685 | controller | Running sanity test 'empty-init' with Python 3.6
2025-11-10 00:33:54.036692 | controller | No tests applicable.
2025-11-10 00:33:54.036698 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-11-10 00:33:54.036704 | controller | No tests applicable.
2025-11-10 00:33:54.036710 | controller | Running sanity test 'ignores'
2025-11-10 00:33:54.036722 | controller | Run command: /usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt
2025-11-10 00:33:54.850966 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-11-10 00:33:54.855133 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-11-10 00:33:54.856556 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.861721 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-11-10 00:33:54.863466 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.864852 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.869298 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.876167 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.877859 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.882435 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.886187 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.888232 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-11-10 00:33:54.889960 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.893676 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.902066 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.903656 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.904997 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.907514 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.908836 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.912434 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.913743 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.915263 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-11-10 00:33:54.917687 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-11-10 00:33:54.922293 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-11-10 00:33:54.927273 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-11-10 00:33:54.928589 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-11-10 00:33:54.948421 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-11-10 00:33:54.949337 | controller | Requirement already satisfied: cryptography<3.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (2.7)
2025-11-10 00:33:54.983795 | controller | Requirement already satisfied: rstcheck==3.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (3.3.1)
2025-11-10 00:33:54.986261 | controller | Requirement already satisfied: voluptuous>=0.11.0 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 30)) (0.11.7)
2025-11-10 00:33:54.991915 | controller | Requirement already satisfied: cffi!=1.14.4 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (1.13.0)
2025-11-10 00:33:54.994773 | controller | Requirement already satisfied: pylint==2.3.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 58)) (2.3.1)
2025-11-10 00:33:55.001350 | controller | Requirement already satisfied: jinja2 in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 2)) (2.10.3)
2025-11-10 00:33:55.005353 | controller | Requirement already satisfied: pycodestyle in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 3)) (2.5.0)
2025-11-10 00:33:55.009208 | controller | Requirement already satisfied: pyyaml in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 5)) (5.1.2)
2025-11-10 00:33:55.011150 | controller | Requirement already satisfied: virtualenv in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 7)) (16.7.5)
2025-11-10 00:33:55.033597 | controller | Requirement already satisfied: yamllint in /usr/local/lib/python3.6/dist-packages (from -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt (line 9)) (1.18.0)
2025-11-10 00:33:55.037672 | controller | Requirement already satisfied: six>=1.4.1 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.12.0)
2025-11-10 00:33:55.039742 | controller | Requirement already satisfied: asn1crypto>=0.21.0 in /usr/local/lib/python3.6/dist-packages (from cryptography<3.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 5)) (1.1.0)
2025-11-10 00:33:55.042032 | controller | Requirement already satisfied: docutils>=0.7 in /usr/local/lib/python3.6/dist-packages (from rstcheck==3.3.1->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 12)) (0.15.2)
2025-11-10 00:33:55.044732 | controller | Requirement already satisfied: pycparser in /usr/local/lib/python3.6/dist-packages (from cffi!=1.14.4->-c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 49)) (2.19)
2025-11-10 00:33:55.046705 | controller | Collecting isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2025-11-10 00:34:10.106737 | 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/isort/
2025-11-10 00:34:25.605583 | 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/isort/
2025-11-10 00:34:41.627982 | 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/isort/
2025-11-10 00:34:58.646457 | 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/isort/
2025-11-10 00:35:17.666944 | 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/isort/
2025-11-10 00:35:32.684749 | controller |   ERROR: Could not find a version that satisfies the requirement isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55)) (from versions: none)
2025-11-10 00:35:32.685784 | controller | ERROR: No matching distribution found for isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2025-11-10 00:35:32.854648 | controller | ERROR: Command "/usr/bin/python3.6 -m pip.__main__ install --disable-pip-version-check -r /root/ansible/test/lib/ansible_test/_data/requirements/sanity.txt -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt" returned exit status 1.
2025-11-10 00:35:32.856041 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-11-10 00:35:32.858889 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-11-10 00:35:32.859140 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-11-10 00:35:32.942021 | controller | Run command: docker exec f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-11-10 00:35:33.062082 | controller | Run command: docker exec -i f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e dd if=/root/output.tgz bs=65536
2025-11-10 00:35:33.159142 | controller | Run command: tar oxzf /tmp/ansible-result-vxgo__be.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-11-10 00:35:33.183397 | controller | Run command: docker rm -f 3610ecba4853cfcded24f9d56e9bf7e5358d1238d958e381e88299668b91d526
2025-11-10 00:35:33.526929 | controller | Run command: docker rm -f f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e
2025-11-10 00:35:34.286872 | controller | ERROR: Command "docker exec f59fa72185d4461b74b5f6869a6b7b7ec0e39c9ec5a0fc054b0bc372813b127e /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.6 /root/ansible/bin/ansible-test sanity -v --metadata metadata-xxa120w3.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-11-10 00:35:34.872438 | controller | ERROR
2025-11-10 00:35:34.872745 | controller | {
2025-11-10 00:35:34.872781 | controller |   "delta": "0:05:46.717522",
2025-11-10 00:35:34.872801 | controller |   "end": "2025-11-10 00:35:34.349985",
2025-11-10 00:35:34.872819 | controller |   "msg": "non-zero return code",
2025-11-10 00:35:34.872835 | controller |   "rc": 1,
2025-11-10 00:35:34.872862 | controller |   "start": "2025-11-10 00:29:47.632463"
2025-11-10 00:35:34.872878 | controller | }
failure
2025-11-10 00:35:34.877527 | 
2025-11-10 00:35:34.877582 | PLAY RECAP
2025-11-10 00:35:34.877629 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-11-10 00:35:34.877649 | 
2025-11-10 00:35:35.025479 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-11-10 00:35:35.026548 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-10 00:35:35.720671 | 
2025-11-10 00:35:35.720875 | PLAY [all]
2025-11-10 00:35:35.743007 | 
2025-11-10 00:35:35.743132 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-11-10 00:35:38.847653 | controller | changed: non-zero return code
2025-11-10 00:35:38.860915 | 
2025-11-10 00:35:38.861065 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-11-10 00:35:38.891215 | controller | skipping: Conditional result was False
2025-11-10 00:35:38.901852 | 
2025-11-10 00:35:38.902077 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-11-10 00:35:38.940269 | 
2025-11-10 00:35:38.940705 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-11-10 00:35:38.979142 | 
2025-11-10 00:35:38.979417 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-11-10 00:35:39.007526 | controller | skipping: Conditional result was False
2025-11-10 00:35:39.018883 | 
2025-11-10 00:35:39.018999 | LOOP [fetch-subunit-output : Generate subunit file]
2025-11-10 00:35:39.054446 | 
2025-11-10 00:35:39.054668 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-11-10 00:35:39.080284 | controller | skipping: Conditional result was False
2025-11-10 00:35:39.088591 | 
2025-11-10 00:35:39.088659 | TASK [fetch-subunit-output : Remove the temporary file]
2025-11-10 00:35:39.113936 | controller | skipping: Conditional result was False
2025-11-10 00:35:39.121099 | 
2025-11-10 00:35:39.121169 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-11-10 00:35:39.146292 | controller | skipping: Conditional result was False
2025-11-10 00:35:39.172929 | 
2025-11-10 00:35:39.172989 | PLAY RECAP
2025-11-10 00:35:39.173035 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-11-10 00:35:39.173055 | 
2025-11-10 00:35:39.294224 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-11-10 00:35:39.296042 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-11-10 00:35:39.925170 | 
2025-11-10 00:35:39.925335 | PLAY [all:!appliance*]
2025-11-10 00:35:39.949493 | 
2025-11-10 00:35:39.949666 | TASK [unregister the node]
2025-11-10 00:35:40.313235 | controller | skipping: Conditional result was False
2025-11-10 00:35:40.329841 | 
2025-11-10 00:35:40.330090 | TASK [include_role : fetch-output]
2025-11-10 00:35:40.370540 | controller | ok
2025-11-10 00:35:40.411223 | 
2025-11-10 00:35:40.411401 | TASK [fetch-output : Set log path for multiple nodes]
2025-11-10 00:35:40.492992 | controller | skipping: Conditional result was False
2025-11-10 00:35:40.512791 | 
2025-11-10 00:35:40.513030 | TASK [fetch-output : Set log path for single node]
2025-11-10 00:35:40.575730 | controller | ok
2025-11-10 00:35:40.581469 | 
2025-11-10 00:35:40.581539 | LOOP [fetch-output : Ensure local output dirs]
2025-11-10 00:35:41.097064 | controller -> localhost | ok: "/var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/logs"
2025-11-10 00:35:41.358778 | controller -> localhost | changed: "/var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/artifacts"
2025-11-10 00:35:41.586051 | controller -> localhost | changed: "/var/lib/zuul/builds/a15d23cd525b4b22aafcd062db41ba3c/work/docs"
2025-11-10 00:35:41.607172 | 
2025-11-10 00:35:41.607412 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-11-10 00:35:43.894798 | controller | changed:
2025-11-10 00:35:43.895034 | controller | .d..t...... ./
2025-11-10 00:35:43.895062 | controller | cd+++++++++ controller/
2025-11-10 00:35:43.895097 | controller | changed: All items complete
2025-11-10 00:35:43.895118 | 
2025-11-10 00:35:45.993504 | controller | changed: .d..t...... ./
2025-11-10 00:35:48.090736 | controller | changed: .d..t...... ./
2025-11-10 00:35:48.124066 | 
2025-11-10 00:35:48.124330 | TASK [include_role : fetch-output-openshift]
2025-11-10 00:35:48.153925 | controller | skipping: Conditional result was False
2025-11-10 00:35:48.169357 | 
2025-11-10 00:35:48.169503 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-11-10 00:35:48.221021 | controller | skipping: Conditional result was False
2025-11-10 00:35:48.231473 | controller | skipping: Conditional result was False
2025-11-10 00:35:48.276383 | 
2025-11-10 00:35:48.276591 | PLAY [localhost]
2025-11-10 00:35:48.294766 | 
2025-11-10 00:35:48.294929 | TASK [Run Zuul manifest role]
2025-11-10 00:35:48.328021 | localhost | ok
2025-11-10 00:35:48.347062 | 
2025-11-10 00:35:48.347150 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-11-10 00:35:48.802038 | localhost | changed
2025-11-10 00:35:48.813737 | 
2025-11-10 00:35:48.813895 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-11-10 00:35:48.861006 | localhost | ok
2025-11-10 00:35:48.874856 | 
2025-11-10 00:35:48.874954 | TASK [Set zuul-log-path fact]
2025-11-10 00:35:48.930067 | localhost | ok
2025-11-10 00:35:48.942943 | 
2025-11-10 00:35:48.943008 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-11-10 00:35:48.971646 | localhost | ok
2025-11-10 00:35:48.979229 | 
2025-11-10 00:35:48.979286 | LOOP [Run upload-logs-swift role]
2025-11-10 00:35:49.014286 | localhost | Output suppressed because no_log was given
2025-11-10 00:35:49.038885 | 
2025-11-10 00:35:49.038942 | TASK [Set zuul-log-path fact]
2025-11-10 00:35:49.073295 | localhost | skipping: Conditional result was False
2025-11-10 00:35:49.080703 | 
2025-11-10 00:35:49.080797 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-11-10 00:35:49.535636 | localhost -> localhost | ok: Runtime: 0:00:00.008907
2025-11-10 00:35:49.546724 | 
2025-11-10 00:35:49.546852 | TASK [upload-logs-swift : Upload logs to swift]