2025-12-03 00:06:51.979317 | Job console starting...
2025-12-03 00:06:51.993426 | Updating repositories
2025-12-03 00:06:54.760109 | Preparing job workspace
2025-12-03 00:07:00.208353 | Running Ansible setup...
2025-12-03 00:07:08.001001 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-03 00:07:08.664859 | 
2025-12-03 00:07:08.665038 | PLAY [localhost]
2025-12-03 00:07:08.673196 | 
2025-12-03 00:07:08.673265 | TASK [Gathering Facts]
2025-12-03 00:07:09.719957 | localhost | ok
2025-12-03 00:07:09.752535 | 
2025-12-03 00:07:09.752935 | TASK [Setup log path fact]
2025-12-03 00:07:09.776686 | localhost | ok
2025-12-03 00:07:09.797082 | 
2025-12-03 00:07:09.797226 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 00:07:09.828154 | localhost | ok
2025-12-03 00:07:09.837705 | 
2025-12-03 00:07:09.837792 | TASK [emit-job-header : Print job information]
2025-12-03 00:07:09.886616 | # Job Information
2025-12-03 00:07:09.886784 | Ansible Version: 2.15.12
2025-12-03 00:07:09.886818 | Job: ansible-test-sanity-docker-stable-2.9
2025-12-03 00:07:09.886840 | Pipeline: periodic
2025-12-03 00:07:09.886860 | Executor: ze03.softwarefactory-project.io
2025-12-03 00:07:09.886880 | Triggered by: https://github.com/ansible-collections/ansible.network
2025-12-03 00:07:09.886902 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/7e1/ansible/7e19b4e055944ca4b1055d7b5e53a593/
2025-12-03 00:07:09.886923 | Event ID: 08d03c5eb6c54d3f91ad96751eb57522
2025-12-03 00:07:09.890809 | 
2025-12-03 00:07:09.890874 | LOOP [emit-job-header : Print node information]
2025-12-03 00:07:10.002835 | localhost | ok:
2025-12-03 00:07:10.003102 | localhost | # Node Information
2025-12-03 00:07:10.003132 | localhost | Inventory Hostname: controller
2025-12-03 00:07:10.003153 | localhost | Hostname: np0005543024
2025-12-03 00:07:10.003172 | localhost | Username: zuul
2025-12-03 00:07:10.003198 | localhost | Distro: Fedora 37
2025-12-03 00:07:10.003218 | localhost | Provider: ansible-vexxhost-ams1
2025-12-03 00:07:10.003236 | localhost | Region: ams1
2025-12-03 00:07:10.003254 | localhost | Label: ansible-fedora-37-1vcpu
2025-12-03 00:07:10.003272 | localhost | Product Name: OpenStack Nova
2025-12-03 00:07:10.003289 | localhost | Interface IP: 38.129.16.157
2025-12-03 00:07:10.018184 | 
2025-12-03 00:07:10.018359 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2025-12-03 00:07:10.492736 | localhost -> localhost | changed
2025-12-03 00:07:10.498540 | 
2025-12-03 00:07:10.498608 | TASK [log-inventory : Copy ansible inventory to logs dir]
2025-12-03 00:07:11.371285 | localhost -> localhost | changed
2025-12-03 00:07:11.412909 | 
2025-12-03 00:07:11.413003 | PLAY [all:!appliance*]
2025-12-03 00:07:11.439007 | 
2025-12-03 00:07:11.439121 | TASK [include_role : start-zuul-console]
2025-12-03 00:07:11.471426 | controller | ok
2025-12-03 00:07:11.490631 | 
2025-12-03 00:07:11.490753 | TASK [start-zuul-console : Start zuul_console daemon.]
2025-12-03 00:07:12.505929 | controller | ok
2025-12-03 00:07:12.537152 | 
2025-12-03 00:07:12.537302 | TASK [use-our-mirror : Retrieve the IP address]
2025-12-03 00:07:14.990558 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2025-12-03 00:07:15.000469 | 
2025-12-03 00:07:15.000565 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2025-12-03 00:07:15.367516 | controller | skipping: Conditional result was False
2025-12-03 00:07:15.383503 | 
2025-12-03 00:07:15.383680 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2025-12-03 00:07:15.411562 | controller | skipping: Conditional result was False
2025-12-03 00:07:15.429390 | 
2025-12-03 00:07:15.429546 | TASK [use-our-mirror : Create the podman configuration directory]
2025-12-03 00:07:15.457862 | controller | skipping: Conditional result was False
2025-12-03 00:07:15.472837 | 
2025-12-03 00:07:15.472992 | TASK [use-our-mirror : Copy the podman configuration]
2025-12-03 00:07:15.501032 | controller | skipping: Conditional result was False
2025-12-03 00:07:15.520055 | 
2025-12-03 00:07:15.520215 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2025-12-03 00:07:15.547443 | controller | skipping: Conditional result was False
2025-12-03 00:07:15.560963 | 
2025-12-03 00:07:15.561112 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2025-12-03 00:07:15.589699 | controller | skipping: Conditional result was False
2025-12-03 00:07:15.617454 | 
2025-12-03 00:07:15.617627 | TASK [Disable Fedora Modular]
2025-12-03 00:07:16.770025 | controller | changed
2025-12-03 00:07:16.782394 | 
2025-12-03 00:07:16.782520 | TASK [Enable EPEL]
2025-12-03 00:07:16.809772 | controller | skipping: Conditional result was False
2025-12-03 00:07:16.822515 | 
2025-12-03 00:07:16.822669 | TASK [Register the RHEL node]
2025-12-03 00:07:17.251969 | 
2025-12-03 00:07:17.252234 | TASK [Show the subscription-manager status]
2025-12-03 00:07:17.614686 | controller | skipping: Conditional result was False
2025-12-03 00:07:17.629701 | 
2025-12-03 00:07:17.629898 | TASK [Enable EPEL on RHEL]
2025-12-03 00:07:18.011169 | controller | skipping: Conditional result was False
2025-12-03 00:07:18.025242 | 
2025-12-03 00:07:18.025435 | TASK [Install git and tox]
2025-12-03 00:09:05.399475 | controller | changed
2025-12-03 00:09:05.415407 | 
2025-12-03 00:09:05.415575 | TASK [include_role : prepare-workspace]
2025-12-03 00:09:05.467318 | controller | ok
2025-12-03 00:09:05.506780 | 
2025-12-03 00:09:05.506915 | TASK [prepare-workspace : Start zuul_console daemon.]
2025-12-03 00:09:06.332883 | controller | ok
2025-12-03 00:09:06.344773 | 
2025-12-03 00:09:06.344872 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2025-12-03 00:09:26.487141 | controller | Output suppressed because no_log was given
2025-12-03 00:09:26.502614 | 
2025-12-03 00:09:26.502746 | TASK [include_role : prepare-workspace-openshift]
2025-12-03 00:09:26.533291 | controller | skipping: Conditional result was False
2025-12-03 00:09:26.566041 | 
2025-12-03 00:09:26.566161 | PLAY [all:!appliance]
2025-12-03 00:09:26.590341 | 
2025-12-03 00:09:26.590441 | TASK [Run add-build-sshkey role (RSA)]
2025-12-03 00:09:26.622534 | controller | ok
2025-12-03 00:09:26.640372 | 
2025-12-03 00:09:26.640498 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 00:09:26.961402 | controller -> localhost | ok
2025-12-03 00:09:26.968442 | 
2025-12-03 00:09:26.968530 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 00:09:26.998900 | controller | ok
2025-12-03 00:09:27.015712 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 00:09:27.023052 | 
2025-12-03 00:09:27.023155 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 00:09:27.601669 | controller -> localhost | Generating public/private rsa key pair.
2025-12-03 00:09:27.601886 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/7e19b4e055944ca4b1055d7b5e53a593_id_rsa.
2025-12-03 00:09:27.601916 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/7e19b4e055944ca4b1055d7b5e53a593_id_rsa.pub.
2025-12-03 00:09:27.601938 | controller -> localhost | The key fingerprint is:
2025-12-03 00:09:27.601958 | controller -> localhost | SHA256:Vgd7TlN96wBJ/FA6nu7JQlmKm+JzkDofRM81DtO13tY zuul-build-sshkey
2025-12-03 00:09:27.601977 | controller -> localhost | The key's randomart image is:
2025-12-03 00:09:27.601997 | controller -> localhost | +---[RSA 2048]----+
2025-12-03 00:09:27.602015 | controller -> localhost | |          +oo... |
2025-12-03 00:09:27.602034 | controller -> localhost | |        . .Bo.  o|
2025-12-03 00:09:27.602051 | controller -> localhost | |     . o +o+O   o|
2025-12-03 00:09:27.602069 | controller -> localhost | |    . o =.+*++.. |
2025-12-03 00:09:27.602087 | controller -> localhost | |     ..oS.++.ooE |
2025-12-03 00:09:27.602114 | controller -> localhost | |    .o o +. .  . |
2025-12-03 00:09:27.602139 | controller -> localhost | |    ... +  .     |
2025-12-03 00:09:27.602158 | controller -> localhost | |   o o.+ .o .    |
2025-12-03 00:09:27.602177 | controller -> localhost | |    +o+   .+     |
2025-12-03 00:09:27.602198 | controller -> localhost | +----[SHA256]-----+
2025-12-03 00:09:27.602246 | controller -> localhost | ok: Runtime: 0:00:00.073899
2025-12-03 00:09:27.623385 | 
2025-12-03 00:09:27.623614 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 00:09:27.655956 | controller | ok
2025-12-03 00:09:27.667472 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 00:09:27.680101 | 
2025-12-03 00:09:27.680267 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 00:09:27.706282 | controller | skipping: Conditional result was False
2025-12-03 00:09:27.714269 | 
2025-12-03 00:09:27.714351 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 00:09:28.756303 | controller | changed
2025-12-03 00:09:28.765421 | 
2025-12-03 00:09:28.765553 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 00:09:29.391164 | controller | ok
2025-12-03 00:09:29.399798 | 
2025-12-03 00:09:29.399894 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 00:09:32.338197 | controller | changed
2025-12-03 00:09:32.355631 | 
2025-12-03 00:09:32.355844 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 00:09:35.283889 | controller | changed
2025-12-03 00:09:35.297380 | 
2025-12-03 00:09:35.297548 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 00:09:35.325196 | controller | skipping: Conditional result was False
2025-12-03 00:09:35.335370 | 
2025-12-03 00:09:35.335523 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 00:09:35.770023 | controller -> localhost | changed
2025-12-03 00:09:35.784840 | 
2025-12-03 00:09:35.785011 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 00:09:36.084892 | controller -> localhost | Identity added: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/7e19b4e055944ca4b1055d7b5e53a593_id_rsa (zuul-build-sshkey)
2025-12-03 00:09:36.085305 | controller -> localhost | ok: Runtime: 0:00:00.008249
2025-12-03 00:09:36.101516 | 
2025-12-03 00:09:36.101710 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 00:09:37.081848 | controller | ok
2025-12-03 00:09:37.088026 | 
2025-12-03 00:09:37.088102 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 00:09:37.133803 | controller | skipping: Conditional result was False
2025-12-03 00:09:37.147426 | 
2025-12-03 00:09:37.147528 | TASK [Run add-build-sshkey role (ECDSA)]
2025-12-03 00:09:37.181124 | controller | ok
2025-12-03 00:09:37.201059 | 
2025-12-03 00:09:37.201169 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2025-12-03 00:09:37.505813 | controller -> localhost | ok
2025-12-03 00:09:37.520808 | 
2025-12-03 00:09:37.520972 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2025-12-03 00:09:37.562855 | controller | ok
2025-12-03 00:09:37.587187 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2025-12-03 00:09:37.595686 | 
2025-12-03 00:09:37.595974 | TASK [add-build-sshkey : Create Temp SSH key]
2025-12-03 00:09:37.950627 | controller -> localhost | Generating public/private ecdsa key pair.
2025-12-03 00:09:37.951138 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/7e19b4e055944ca4b1055d7b5e53a593_id_ecdsa.
2025-12-03 00:09:37.951210 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/7e19b4e055944ca4b1055d7b5e53a593_id_ecdsa.pub.
2025-12-03 00:09:37.951280 | controller -> localhost | The key fingerprint is:
2025-12-03 00:09:37.951329 | controller -> localhost | SHA256:oVMC0l2JyFsuW6QxVQS8HLG9dkPFIdujzTu/JkWeEQw zuul-build-sshkey
2025-12-03 00:09:37.951374 | controller -> localhost | The key's randomart image is:
2025-12-03 00:09:37.951419 | controller -> localhost | +---[ECDSA 521]---+
2025-12-03 00:09:37.951462 | controller -> localhost | |  .o.=+B+...E+   |
2025-12-03 00:09:37.951504 | controller -> localhost | |   .*.Bo.  =. o  |
2025-12-03 00:09:37.951546 | controller -> localhost | |     Xoo+ o o  . |
2025-12-03 00:09:37.951586 | controller -> localhost | |    + =+ + + .o  |
2025-12-03 00:09:37.951628 | controller -> localhost | |     +o S + oo o |
2025-12-03 00:09:37.951670 | controller -> localhost | |    .  o . . .+  |
2025-12-03 00:09:37.951711 | controller -> localhost | |            o.   |
2025-12-03 00:09:37.952031 | controller -> localhost | |            .o.  |
2025-12-03 00:09:37.952112 | controller -> localhost | |             oo. |
2025-12-03 00:09:37.952179 | controller -> localhost | +----[SHA256]-----+
2025-12-03 00:09:37.952301 | controller -> localhost | ok: Runtime: 0:00:00.010810
2025-12-03 00:09:37.971906 | 
2025-12-03 00:09:37.972065 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2025-12-03 00:09:38.015902 | controller | ok
2025-12-03 00:09:38.034008 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2025-12-03 00:09:38.048351 | 
2025-12-03 00:09:38.048441 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2025-12-03 00:09:38.074508 | controller | skipping: Conditional result was False
2025-12-03 00:09:38.084822 | 
2025-12-03 00:09:38.084913 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2025-12-03 00:09:38.968421 | controller | changed
2025-12-03 00:09:38.983014 | 
2025-12-03 00:09:38.983153 | TASK [add-build-sshkey : Make sure user has a .ssh]
2025-12-03 00:09:39.625720 | controller | ok
2025-12-03 00:09:39.639558 | 
2025-12-03 00:09:39.639714 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2025-12-03 00:09:42.596100 | controller | changed
2025-12-03 00:09:42.610477 | 
2025-12-03 00:09:42.610621 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2025-12-03 00:09:45.521089 | controller | changed
2025-12-03 00:09:45.530674 | 
2025-12-03 00:09:45.530807 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2025-12-03 00:09:45.546704 | controller | skipping: Conditional result was False
2025-12-03 00:09:45.560010 | 
2025-12-03 00:09:45.560134 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2025-12-03 00:09:45.800026 | controller -> localhost | changed
2025-12-03 00:09:45.813538 | 
2025-12-03 00:09:45.813637 | TASK [add-build-sshkey : Add back temp key]
2025-12-03 00:09:46.126706 | controller -> localhost | Identity added: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/7e19b4e055944ca4b1055d7b5e53a593_id_ecdsa (zuul-build-sshkey)
2025-12-03 00:09:46.127270 | controller -> localhost | ok: Runtime: 0:00:00.014653
2025-12-03 00:09:46.148182 | 
2025-12-03 00:09:46.148383 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2025-12-03 00:09:46.831575 | controller | ok
2025-12-03 00:09:46.848852 | 
2025-12-03 00:09:46.849068 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2025-12-03 00:09:46.880109 | controller | skipping: Conditional result was False
2025-12-03 00:09:46.900003 | 
2025-12-03 00:09:46.900188 | TASK [include_role : remove-zuul-sshkey]
2025-12-03 00:09:46.927416 | controller | skipping: Conditional result was False
2025-12-03 00:09:46.937569 | 
2025-12-03 00:09:46.937709 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2025-12-03 00:09:47.553523 | controller | ok: "logs"
2025-12-03 00:09:47.553791 | controller | ok: All items complete
2025-12-03 00:09:47.553826 | 
2025-12-03 00:09:48.147045 | controller | ok: "artifacts"
2025-12-03 00:09:48.733520 | controller | ok: "docs"
2025-12-03 00:09:48.756135 | 
2025-12-03 00:09:48.756486 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2025-12-03 00:09:49.393214 | controller | changed: "logs"
2025-12-03 00:09:49.973362 | controller | changed: "artifacts"
2025-12-03 00:09:50.554237 | controller | changed: "docs"
2025-12-03 00:09:50.587953 | 
2025-12-03 00:09:50.588067 | PLAY RECAP
2025-12-03 00:09:50.588114 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2025-12-03 00:09:50.588141 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2025-12-03 00:09:50.588159 | 
2025-12-03 00:09:50.719626 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2025-12-03 00:09:50.720858 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-03 00:09:51.359577 | 
2025-12-03 00:09:51.359700 | PLAY [all]
2025-12-03 00:09:51.382474 | 
2025-12-03 00:09:51.382597 | TASK [Install binary dependencies]
2025-12-03 00:09:51.423441 | controller | ok
2025-12-03 00:09:51.443919 | 
2025-12-03 00:09:51.444052 | TASK [bindep : Include find tasks]
2025-12-03 00:09:51.473784 | controller | ok
2025-12-03 00:09:51.481884 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2025-12-03 00:09:51.488285 | 
2025-12-03 00:09:51.488353 | TASK [bindep : Look for bindep.txt]
2025-12-03 00:09:52.447985 | controller | ok
2025-12-03 00:09:52.462330 | 
2025-12-03 00:09:52.462520 | TASK [bindep : Define bindep_file fact]
2025-12-03 00:09:52.480128 | controller | skipping: Conditional result was False
2025-12-03 00:09:52.494468 | 
2025-12-03 00:09:52.494629 | TASK [bindep : Look for other-requirements.txt]
2025-12-03 00:09:53.095917 | controller | ok
2025-12-03 00:09:53.110400 | 
2025-12-03 00:09:53.110558 | TASK [bindep : Define bindep_file fact]
2025-12-03 00:09:53.147874 | controller | skipping: Conditional result was False
2025-12-03 00:09:53.161756 | 
2025-12-03 00:09:53.161915 | TASK [bindep : Look for bindep fallback file]
2025-12-03 00:09:53.198845 | controller | skipping: Conditional result was False
2025-12-03 00:09:53.213314 | 
2025-12-03 00:09:53.213491 | TASK [bindep : Define bindep_file fact]
2025-12-03 00:09:53.250848 | controller | skipping: Conditional result was False
2025-12-03 00:09:53.264863 | 
2025-12-03 00:09:53.265155 | TASK [bindep : Include bindep tasks]
2025-12-03 00:09:53.301720 | controller | skipping: Conditional result was False
2025-12-03 00:09:53.316135 | 
2025-12-03 00:09:53.316439 | TASK [bindep : Include install tasks]
2025-12-03 00:09:53.342700 | controller | skipping: Conditional result was False
2025-12-03 00:09:53.358637 | 
2025-12-03 00:09:53.358977 | LOOP [bindep : Include package tasks]
2025-12-03 00:09:53.445133 | 
2025-12-03 00:09:53.445397 | TASK [Run test-setup role]
2025-12-03 00:09:53.473585 | controller | ok
2025-12-03 00:09:53.506193 | 
2025-12-03 00:09:53.506317 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2025-12-03 00:09:54.195507 | controller | ok
2025-12-03 00:09:54.209018 | 
2025-12-03 00:09:54.209301 | TASK [test-setup : Run tools/test-setup.sh]
2025-12-03 00:09:54.586881 | controller | skipping: Conditional result was False
2025-12-03 00:09:54.638809 | 
2025-12-03 00:09:54.638909 | PLAY RECAP
2025-12-03 00:09:54.638967 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-03 00:09:54.639001 | 
2025-12-03 00:09:54.738275 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2025-12-03 00:09:54.739652 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-03 00:09:55.339922 | 
2025-12-03 00:09:55.340047 | PLAY [controller]
2025-12-03 00:09:55.361319 | 
2025-12-03 00:09:55.361401 | TASK [Create the /root directory]
2025-12-03 00:09:56.388980 | controller | ok
2025-12-03 00:09:56.401769 | 
2025-12-03 00:09:56.401940 | TASK [Install glibc-langpack-en]
2025-12-03 00:10:03.922424 | controller | ok: Nothing to do
2025-12-03 00:10:03.939221 | 
2025-12-03 00:10:03.939463 | TASK [Ensure controller directory exists]
2025-12-03 00:10:04.782168 | controller | changed
2025-12-03 00:10:04.795777 | 
2025-12-03 00:10:04.796001 | TASK [Install container runtime]
2025-12-03 00:10:04.880388 | controller | ok
2025-12-03 00:10:04.951198 | 
2025-12-03 00:10:04.951337 | LOOP [ensure-docker : Gather variables for each operating system]
2025-12-03 00:10:05.024007 | controller | ok: "/var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml"
2025-12-03 00:10:05.047987 | 
2025-12-03 00:10:05.048197 | TASK [ensure-docker : Sanity check for distro selection]
2025-12-03 00:10:05.076046 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.091126 | 
2025-12-03 00:10:05.091370 | TASK [ensure-docker : Sanity check for distro install]
2025-12-03 00:10:05.120024 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.135868 | 
2025-12-03 00:10:05.136045 | TASK [ensure-docker : Sanity check for upstream install]
2025-12-03 00:10:05.174562 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.188524 | 
2025-12-03 00:10:05.188672 | TASK [ensure-docker : Sanity check for upstream install mirrors]
2025-12-03 00:10:05.215833 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.229451 | 
2025-12-03 00:10:05.229596 | TASK [ensure-docker : Include tasks for docker install]
2025-12-03 00:10:05.270002 | controller | ok
2025-12-03 00:10:05.290483 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml
2025-12-03 00:10:05.304343 | 
2025-12-03 00:10:05.304488 | TASK [ensure-docker : Not Implemented]
2025-12-03 00:10:05.330868 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.341672 | 
2025-12-03 00:10:05.341794 | TASK [Set up docker mirrors]
2025-12-03 00:10:05.366226 | controller | ok
2025-12-03 00:10:05.403115 | 
2025-12-03 00:10:05.403252 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info]
2025-12-03 00:10:05.428628 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.435164 | 
2025-12-03 00:10:05.435245 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info]
2025-12-03 00:10:05.459938 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.469836 | 
2025-12-03 00:10:05.469956 | TASK [use-docker-mirror : Set docker_mirror fact]
2025-12-03 00:10:05.505932 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.552949 | 
2025-12-03 00:10:05.553103 | TASK [use-docker-mirror : Set up docker mirrors]
2025-12-03 00:10:05.588224 | controller | skipping: Conditional result was False
2025-12-03 00:10:05.600533 | 
2025-12-03 00:10:05.600623 | TASK [Add all repositories]
2025-12-03 00:10:05.619628 | controller | ok
2025-12-03 00:10:05.643096 | 
2025-12-03 00:10:05.643184 | LOOP [ensure-package-repositories : Install repositories]
2025-12-03 00:10:05.675676 | controller | ok: "/var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml"
2025-12-03 00:10:05.675840 | controller | ok: All items complete
2025-12-03 00:10:05.675866 | 
2025-12-03 00:10:05.685043 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml
2025-12-03 00:10:05.691089 | 
2025-12-03 00:10:05.691149 | TASK [ensure-package-repositories : Create GPG key temp directory]
2025-12-03 00:10:06.602960 | controller | changed
2025-12-03 00:10:06.617866 | 
2025-12-03 00:10:06.618054 | LOOP [ensure-package-repositories : Upload GPG keys]
2025-12-03 00:10:09.806327 | controller | changed:
2025-12-03 00:10:09.806618 | controller | {
2025-12-03 00:10:09.806675 | 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-12-03 00:10:09.806757 | controller | }
2025-12-03 00:10:09.825817 | 
2025-12-03 00:10:09.826025 | LOOP [ensure-package-repositories : Import GPG keys]
2025-12-03 00:10:11.101320 | controller | changed:
2025-12-03 00:10:11.101516 | controller | {
2025-12-03 00:10:11.101544 | 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-12-03 00:10:11.101566 | controller | }
2025-12-03 00:10:11.101595 | controller | changed: All items complete
2025-12-03 00:10:11.101615 | 
2025-12-03 00:10:11.110104 | 
2025-12-03 00:10:11.110179 | TASK [ensure-package-repositories : Remove GPG temp directory]
2025-12-03 00:10:11.814044 | controller | changed
2025-12-03 00:10:11.832878 | 
2025-12-03 00:10:11.833033 | LOOP [ensure-package-repositories : Add repositories]
2025-12-03 00:10:12.720096 | controller | changed:
2025-12-03 00:10:12.720446 | controller | {
2025-12-03 00:10:12.720516 | controller |   "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable",
2025-12-03 00:10:12.720569 | controller |   "description": "Docker CE Stable - $basearch",
2025-12-03 00:10:12.720615 | controller |   "gpgcheck": true,
2025-12-03 00:10:12.720660 | controller |   "name": "docker-ce-stable"
2025-12-03 00:10:12.720703 | controller | }
2025-12-03 00:10:12.762704 | 
2025-12-03 00:10:12.762944 | TASK [ensure-docker : Install pre-reqs]
2025-12-03 00:10:18.052638 | controller | ok: Nothing to do
2025-12-03 00:10:18.061027 | 
2025-12-03 00:10:18.061127 | TASK [ensure-docker : Remove packages]
2025-12-03 00:10:21.234518 | controller | ok: Nothing to do
2025-12-03 00:10:21.247127 | 
2025-12-03 00:10:21.247281 | TASK [ensure-docker : Install upstream docker using package]
2025-12-03 00:11:41.295462 | controller | changed
2025-12-03 00:11:41.304071 | 
2025-12-03 00:11:41.304161 | TASK [ensure-docker : Setup docker]
2025-12-03 00:11:41.328247 | controller | ok
2025-12-03 00:11:41.343709 | controller | included: /var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml
2025-12-03 00:11:41.360886 | 
2025-12-03 00:11:41.360992 | TASK [ensure-docker : Ensure "docker" group exists]
2025-12-03 00:11:42.365896 | controller | ok
2025-12-03 00:11:42.371615 | 
2025-12-03 00:11:42.371681 | TASK [ensure-docker : Add user to docker group]
2025-12-03 00:11:43.370494 | controller | changed
2025-12-03 00:11:43.380365 | 
2025-12-03 00:11:43.380534 | TASK [Update docker daemon proxy configuration]
2025-12-03 00:11:43.418052 | controller | skipping: Conditional result was False
2025-12-03 00:11:43.428530 | 
2025-12-03 00:11:43.428681 | TASK [Lower default MTU]
2025-12-03 00:11:43.455058 | controller | skipping: Conditional result was False
2025-12-03 00:11:43.464773 | 
2025-12-03 00:11:43.464857 | TASK [ensure-docker : Get the status of the docket.socket unit]
2025-12-03 00:11:44.242434 | controller | inactive
2025-12-03 00:11:44.856912 | controller | ok: Runtime: 0:00:00.021555
2025-12-03 00:11:44.873907 | 
2025-12-03 00:11:44.874125 | TASK [ensure-docker : Get the status of the docket unit]
2025-12-03 00:11:45.653713 | controller | inactive
2025-12-03 00:11:46.278416 | controller | ok: Runtime: 0:00:00.021674
2025-12-03 00:11:46.296024 | 
2025-12-03 00:11:46.296275 | TASK [ensure-docker : Restart docker]
2025-12-03 00:11:46.371543 | Trigger a Docker restart
2025-12-03 00:11:46.390098 | 
2025-12-03 00:11:46.390335 | TASK [ensure-docker : Reset ssh connection to pick up docker group]
2025-12-03 00:11:46.434624 | 
2025-12-03 00:11:46.434851 | TASK [ensure-docker : Install docker-compose]
2025-12-03 00:11:46.462596 | controller | skipping: Conditional result was False
2025-12-03 00:11:46.472985 | 
2025-12-03 00:11:46.473130 | TASK [ensure-docker : Flush handlers before role exit]
2025-12-03 00:11:46.490345 | 
2025-12-03 00:11:46.490537 | TASK [ensure-docker : Stop docker.socket to avoid any conflict]
2025-12-03 00:11:49.888014 | controller | changed
2025-12-03 00:11:49.904619 | 
2025-12-03 00:11:49.904792 | TASK [ensure-docker : Assure docker service is running]
2025-12-03 00:11:53.663913 | controller | changed
2025-12-03 00:11:53.687368 | 
2025-12-03 00:11:53.687589 | TASK [ensure-docker : Assure docker.socket service is running]
2025-12-03 00:11:55.255334 | controller | ok
2025-12-03 00:11:55.263530 | 
2025-12-03 00:11:55.263597 | TASK [ensure-docker : Correct group ownership on docker sock]
2025-12-03 00:11:56.058004 | controller | ok
2025-12-03 00:11:56.076239 | 
2025-12-03 00:11:56.076370 | TASK [ensure-docker : Validate ability to talk with docker]
2025-12-03 00:11:56.737340 | controller | CONTAINER ID   IMAGE     COMMAND   CREATED   STATUS    PORTS     NAMES
2025-12-03 00:11:56.946893 | controller | ok: Runtime: 0:00:00.051896
2025-12-03 00:11:56.959636 | 
2025-12-03 00:11:56.959817 | TASK [ensure-docker : Get version details]
2025-12-03 00:11:57.622137 | controller | Client: Docker Engine - Community
2025-12-03 00:11:57.622237 | controller |  Version:           24.0.7
2025-12-03 00:11:57.622247 | controller |  API version:       1.43
2025-12-03 00:11:57.622255 | controller |  Go version:        go1.20.10
2025-12-03 00:11:57.622276 | controller |  Git commit:        afdd53b
2025-12-03 00:11:57.622288 | controller |  Built:             Thu Oct 26 09:09:39 2023
2025-12-03 00:11:57.622296 | controller |  OS/Arch:           linux/amd64
2025-12-03 00:11:57.622305 | controller |  Context:           default
2025-12-03 00:11:57.622315 | controller |
2025-12-03 00:11:57.622322 | controller | Server: Docker Engine - Community
2025-12-03 00:11:57.622333 | controller |  Engine:
2025-12-03 00:11:57.622369 | controller |   Version:          24.0.7
2025-12-03 00:11:57.622401 | controller |   API version:      1.43 (minimum version 1.12)
2025-12-03 00:11:57.622407 | controller |   Go version:       go1.20.10
2025-12-03 00:11:57.622413 | controller |   Git commit:       311b9ff
2025-12-03 00:11:57.622432 | controller |   Built:            Thu Oct 26 09:08:11 2023
2025-12-03 00:11:57.622438 | controller |   OS/Arch:          linux/amd64
2025-12-03 00:11:57.622444 | controller |   Experimental:     false
2025-12-03 00:11:57.622450 | controller |  containerd:
2025-12-03 00:11:57.622456 | controller |   Version:          1.6.25
2025-12-03 00:11:57.622462 | controller |   GitCommit:        d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f
2025-12-03 00:11:57.622468 | controller |  runc:
2025-12-03 00:11:57.622474 | controller |   Version:          1.1.10
2025-12-03 00:11:57.622480 | controller |   GitCommit:        v1.1.10-0-g18a0cb0
2025-12-03 00:11:57.622488 | controller |  docker-init:
2025-12-03 00:11:57.622509 | controller |   Version:          0.19.0
2025-12-03 00:11:57.624763 | controller |   GitCommit:        de40ad0
2025-12-03 00:11:57.838316 | controller | ok: Runtime: 0:00:00.044640
2025-12-03 00:11:57.851118 | 
2025-12-03 00:11:57.851300 | TASK [ensure-docker : Dump installed docker details]
2025-12-03 00:11:57.889953 | {'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-12-03 00:11:57.580815', 'end': '2025-12-03 00:11:57.625455', 'delta': '0:00:00.044640', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-55a5-d1a2-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-12-03 00:11:57.901543 | 
2025-12-03 00:11:57.901671 | TASK [Use buildset registry]
2025-12-03 00:11:57.928436 | controller | skipping: Conditional result was False
2025-12-03 00:11:57.945024 | 
2025-12-03 00:11:57.945154 | TASK [Ensure python3.8 is present]
2025-12-03 00:11:57.970488 | controller | skipping: Conditional result was False
2025-12-03 00:11:57.978979 | 
2025-12-03 00:11:57.979087 | TASK [Run our-ensure-python role (Fedora only for now)]
2025-12-03 00:11:58.002407 | controller | ok
2025-12-03 00:11:58.025402 | 
2025-12-03 00:11:58.025513 | TASK [our-ensure-python : Install the right Python version (rpm)]
2025-12-03 00:12:05.761170 | controller | ok: Nothing to do
2025-12-03 00:12:05.769531 | 
2025-12-03 00:12:05.769615 | TASK [our-ensure-python : Also install python3-devel]
2025-12-03 00:12:21.328902 | controller | changed
2025-12-03 00:12:21.352479 | 
2025-12-03 00:12:21.352584 | TASK [Run ensure-virtualenv role]
2025-12-03 00:12:21.376321 | controller | ok
2025-12-03 00:12:21.410329 | 
2025-12-03 00:12:21.410509 | TASK [ensure-virtualenv : Check if virtualenv is installed]
2025-12-03 00:12:22.154300 | controller | /usr/bin/virtualenv
2025-12-03 00:12:22.772719 | controller | ok: Runtime: 0:00:00.010910
2025-12-03 00:12:22.779356 | 
2025-12-03 00:12:22.779426 | LOOP [ensure-virtualenv : Install virtualenv package]
2025-12-03 00:12:22.815051 | controller | skipping: Conditional result was False
2025-12-03 00:12:22.815711 | controller | changed: All items complete
2025-12-03 00:12:22.815860 | 
2025-12-03 00:12:22.837799 | 
2025-12-03 00:12:22.837913 | TASK [Find the full path of the Python interpreter]
2025-12-03 00:12:23.704286 | controller | /usr/bin/python3.8
2025-12-03 00:12:24.196388 | controller | ok
2025-12-03 00:12:24.210688 | 
2025-12-03 00:12:24.210873 | TASK [Create virtualenv for ansible-test with a specific interpreter]
2025-12-03 00:12:26.837892 | controller | created virtual environment CPython3.8.18.final.0-64 in 1173ms
2025-12-03 00:12:26.891015 | controller |   creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False)
2025-12-03 00:12:26.891042 | 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-12-03 00:12:26.891057 | controller |     added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1
2025-12-03 00:12:26.891087 | controller |   activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator
2025-12-03 00:12:27.107853 | controller | changed
2025-12-03 00:12:27.126806 | 
2025-12-03 00:12:27.127054 | TASK [Set selinux package]
2025-12-03 00:12:27.166423 | controller | ok
2025-12-03 00:12:27.177158 | 
2025-12-03 00:12:27.177289 | TASK [Set selinux package (Fedora)]
2025-12-03 00:12:27.218026 | controller | ok
2025-12-03 00:12:27.228191 | 
2025-12-03 00:12:27.228382 | TASK [Install selinux into virtualenv]
2025-12-03 00:12:33.062982 | controller | Collecting selinux-please-lie-to-me
2025-12-03 00:12:33.192539 | controller |   Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB)
2025-12-03 00:12:34.342395 | controller | Collecting setuptools<50.0.0
2025-12-03 00:12:34.349230 | controller |   Downloading setuptools-49.6.0-py3-none-any.whl (803 kB)
2025-12-03 00:12:34.441999 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 9.2 MB/s eta 0:00:00
2025-12-03 00:12:34.561394 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me
2025-12-03 00:12:34.561816 | controller |   Attempting uninstall: setuptools
2025-12-03 00:12:34.563193 | controller |     Found existing installation: setuptools 62.6.0
2025-12-03 00:12:34.679553 | controller |     Uninstalling setuptools-62.6.0:
2025-12-03 00:12:34.695330 | controller |       Successfully uninstalled setuptools-62.6.0
2025-12-03 00:12:35.421667 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0
2025-12-03 00:12:35.613895 | controller |
2025-12-03 00:12:36.026165 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-03 00:12:36.026243 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-03 00:12:36.607349 | controller | ok: Runtime: 0:00:07.795685
2025-12-03 00:12:36.621775 | 
2025-12-03 00:12:36.621948 | TASK [Install pytest-forked into virtualenv]
2025-12-03 00:12:38.035749 | controller | Collecting pytest-forked
2025-12-03 00:12:38.102882 | controller |   Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB)
2025-12-03 00:12:38.285518 | controller | Collecting pytest>=3.10
2025-12-03 00:12:38.291975 | controller |   Downloading pytest-8.3.5-py3-none-any.whl (343 kB)
2025-12-03 00:12:38.334746 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 343.6/343.6 kB 8.8 MB/s eta 0:00:00
2025-12-03 00:12:38.387098 | controller | Collecting py
2025-12-03 00:12:38.392736 | controller |   Downloading py-1.11.0-py2.py3-none-any.whl (98 kB)
2025-12-03 00:12:38.402510 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 14.5 MB/s eta 0:00:00
2025-12-03 00:12:38.455244 | controller | Collecting iniconfig
2025-12-03 00:12:38.460124 | controller |   Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB)
2025-12-03 00:12:38.527209 | controller | Collecting tomli>=1
2025-12-03 00:12:38.533765 | controller |   Downloading tomli-2.3.0-py3-none-any.whl (14 kB)
2025-12-03 00:12:38.581085 | controller | Collecting exceptiongroup>=1.0.0rc8
2025-12-03 00:12:38.585331 | controller |   Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB)
2025-12-03 00:12:38.633881 | controller | Collecting pluggy<2,>=1.5
2025-12-03 00:12:38.639473 | controller |   Downloading pluggy-1.5.0-py3-none-any.whl (20 kB)
2025-12-03 00:12:38.700369 | controller | Collecting packaging
2025-12-03 00:12:38.707171 | controller |   Downloading packaging-25.0-py3-none-any.whl (66 kB)
2025-12-03 00:12:38.716206 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.4 MB/s eta 0:00:00
2025-12-03 00:12:38.788086 | controller | Collecting typing-extensions>=4.6.0
2025-12-03 00:12:38.793663 | controller |   Downloading typing_extensions-4.13.2-py3-none-any.whl (45 kB)
2025-12-03 00:12:38.804978 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 45.8/45.8 kB 4.6 MB/s eta 0:00:00
2025-12-03 00:12:38.892891 | controller | Installing collected packages: typing-extensions, tomli, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked
2025-12-03 00:12:39.581828 | controller | Successfully installed exceptiongroup-1.3.1 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-12-03 00:12:39.590996 | controller |
2025-12-03 00:12:39.834670 | controller | [notice] A new release of pip available: 22.2.2 -> 25.0.1
2025-12-03 00:12:39.834750 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip
2025-12-03 00:12:40.020670 | controller | ok: Runtime: 0:00:02.450820
2025-12-03 00:12:40.027334 | 
2025-12-03 00:12:40.027423 | TASK [Update pip]
2025-12-03 00:12:42.750269 | controller | Requirement already satisfied: pip in ./venv/lib/python3.8/site-packages (22.2.2)
2025-12-03 00:12:43.233818 | controller | Collecting pip
2025-12-03 00:12:43.362713 | controller |   Downloading pip-25.0.1-py3-none-any.whl (1.8 MB)
2025-12-03 00:12:43.491033 | controller |      ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 15.5 MB/s eta 0:00:00
2025-12-03 00:12:43.710927 | controller | Installing collected packages: pip
2025-12-03 00:12:43.716760 | controller |   Attempting uninstall: pip
2025-12-03 00:12:43.719091 | controller |     Found existing installation: pip 22.2.2
2025-12-03 00:12:44.280143 | controller |     Uninstalling pip-22.2.2:
2025-12-03 00:12:44.358280 | controller |       Successfully uninstalled pip-22.2.2
2025-12-03 00:12:47.252176 | controller | Successfully installed pip-25.0.1
2025-12-03 00:12:47.909227 | controller | ok: Runtime: 0:00:06.317124
2025-12-03 00:12:47.918381 | 
2025-12-03 00:12:47.918529 | TASK [Ensure pip temp directory exists in /var/tmp]
2025-12-03 00:12:49.315277 | controller | changed
2025-12-03 00:12:49.325374 | 
2025-12-03 00:12:49.325476 | TASK [Install ansible into virtualenv]
2025-12-03 00:12:50.753459 | controller | Processing ./src/github.com/ansible/ansible
2025-12-03 00:12:50.754884 | controller |   Preparing metadata (setup.py): started
2025-12-03 00:12:52.541901 | controller |   Preparing metadata (setup.py): finished with status 'done'
2025-12-03 00:12:52.609538 | controller | Collecting jinja2 (from ansible==2.9.27.post0)
2025-12-03 00:12:52.624312 | controller |   Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB)
2025-12-03 00:12:52.698680 | controller | Collecting PyYAML (from ansible==2.9.27.post0)
2025-12-03 00:12:52.704320 | 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-12-03 00:12:53.105375 | controller | Collecting cryptography (from ansible==2.9.27.post0)
2025-12-03 00:12:53.110996 | controller |   Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB)
2025-12-03 00:12:53.339849 | controller | Collecting cffi>=1.14 (from cryptography->ansible==2.9.27.post0)
2025-12-03 00:12:53.345529 | controller |   Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (1.5 kB)
2025-12-03 00:12:53.353221 | 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-12-03 00:12:53.459464 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible==2.9.27.post0)
2025-12-03 00:12:53.465628 | controller |   Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl.metadata (3.0 kB)
2025-12-03 00:12:53.503714 | controller | Collecting pycparser (from cffi>=1.14->cryptography->ansible==2.9.27.post0)
2025-12-03 00:12:53.508582 | controller |   Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes)
2025-12-03 00:12:53.530162 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB)
2025-12-03 00:12:53.583746 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 95.8 MB/s eta 0:00:00
2025-12-03 00:12:53.589189 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB)
2025-12-03 00:12:53.606259 | 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-12-03 00:12:53.626767 | controller |    ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 806.0/806.0 kB 41.2 MB/s eta 0:00:00
2025-12-03 00:12:53.632111 | controller | Downloading cffi-1.17.1-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (446 kB)
2025-12-03 00:12:53.649368 | controller | Downloading MarkupSafe-2.1.5-cp38-cp38-manylinux_2_17_x86_64.manylinux2014_x86_64.whl (26 kB)
2025-12-03 00:12:53.664195 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB)
2025-12-03 00:12:53.687249 | controller | Building wheels for collected packages: ansible
2025-12-03 00:12:53.688220 | controller |   Building wheel for ansible (setup.py): started
2025-12-03 00:13:03.199837 | controller |   Building wheel for ansible (setup.py): finished with status 'done'
2025-12-03 00:13:03.219482 | controller |   Created wheel for ansible: filename=ansible-2.9.27.post0-py3-none-any.whl size=16207514 sha256=734b6e1ab72c63b72c1117908d8da445e08593820b8e6b24bfdc6091f1664326
2025-12-03 00:13:03.219883 | controller |   Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-bp9qbbcf/wheels/a5/4b/fa/b6deaae105422b0b3b92831e0369d44bf7fca5bb3413d79be7
2025-12-03 00:13:03.295936 | controller | Successfully built ansible
2025-12-03 00:13:03.481654 | controller | Installing collected packages: PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible
2025-12-03 00:13:16.315127 | 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-12-03 00:13:16.705148 | controller | ok: Runtime: 0:00:26.306139
2025-12-03 00:13:16.711918 | 
2025-12-03 00:13:16.712040 | TASK [Setup download-artifact-fork role]
2025-12-03 00:13:16.732498 | controller | ok
2025-12-03 00:13:16.752626 | 
2025-12-03 00:13:16.752787 | TASK [download-artifact-fork : Parse inventory response]
2025-12-03 00:13:16.785314 | controller | ok
2025-12-03 00:13:16.792177 | 
2025-12-03 00:13:16.792268 | TASK [download-artifact-fork : Fetch info from Zuul API]
2025-12-03 00:13:16.816487 | controller | skipping: Conditional result was False
2025-12-03 00:13:16.822980 | 
2025-12-03 00:13:16.823054 | TASK [download-artifact-fork : Parse build response]
2025-12-03 00:13:16.846499 | controller | skipping: Conditional result was False
2025-12-03 00:13:16.852898 | 
2025-12-03 00:13:16.852968 | TASK [download-artifact-fork : Ensure artifacts directory exists]
2025-12-03 00:13:17.684324 | controller | changed
2025-12-03 00:13:17.711774 | 
2025-12-03 00:13:17.712191 | LOOP [download-artifact-fork : Download archive by type]
2025-12-03 00:13:17.754152 | controller | skipping: Conditional result was False
2025-12-03 00:13:17.754407 | 
2025-12-03 00:13:19.724318 | controller | changed: OK (20188 bytes)
2025-12-03 00:13:19.818440 | 
2025-12-03 00:13:19.818603 | TASK [deploy-artifacts : define __collections]
2025-12-03 00:13:19.852265 | controller | ok
2025-12-03 00:13:19.859001 | 
2025-12-03 00:13:19.859104 | LOOP [deploy-artifacts : Create list of collections]
2025-12-03 00:13:19.894737 | controller | skipping: Conditional result was False
2025-12-03 00:13:19.915456 | controller | ok:
2025-12-03 00:13:19.915560 | controller | {
2025-12-03 00:13:19.915585 | controller |   "branch": "main",
2025-12-03 00:13:19.915603 | controller |   "job": "build-ansible-collection",
2025-12-03 00:13:19.915620 | controller |   "metadata": {
2025-12-03 00:13:19.915635 | controller |     "type": "ansible_collection",
2025-12-03 00:13:19.915651 | controller |     "version": "5.0.0"
2025-12-03 00:13:19.915666 | controller |   },
2025-12-03 00:13:19.915705 | controller |   "name": "ansible.network",
2025-12-03 00:13:19.915822 | controller |   "project": "ansible-collections/ansible.network",
2025-12-03 00:13:19.915849 | controller |   "url": "https://577bed6a164abcf20ded-3b84c870e0b5e05985daf965164e2101.ssl.cf1.rackcdn.com/ansible/bb2bdb988b774a2aa4476743e2b80bbb/artifacts/ansible-network-5.0.0.tar.gz"
2025-12-03 00:13:19.915875 | controller | }
2025-12-03 00:13:19.931904 | 
2025-12-03 00:13:19.931993 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy]
2025-12-03 00:13:21.039437 | controller | Process install dependency map
2025-12-03 00:14:11.980005 | controller | |/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Starting collection install process
2025-12-03 00:14:12.081431 | controller | |Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network'
2025-12-03 00:14:12.081639 | controller | Installing 'ansible.netcommon:8.2.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2025-12-03 00:14:13.823107 | controller | /-\|/-\|/-\|/-\|/Installing 'ansible.utils:6.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2025-12-03 00:14:15.742634 | controller | -\|/-\|/-\|/-\|/-\|Installing 'arista.eos:12.0.0' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos'
2025-12-03 00:14:17.958645 | controller | /-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.ios:11.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios'
2025-12-03 00:14:20.676901 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/Installing 'cisco.iosxr:12.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr'
2025-12-03 00:14:23.202044 | controller | -\|/-\|/-\|/-\|/-\|/-\|/-Installing 'cisco.nxos:11.1.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos'
2025-12-03 00:14:27.262222 | controller | \|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-\|/-Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos'
2025-12-03 00:14:30.342748 | controller | ok: Runtime: 0:01:09.165920
2025-12-03 00:14:30.359387 | 
2025-12-03 00:14:30.359519 | TASK [podman may need iptables kernel modules]
2025-12-03 00:14:31.726957 | controller | ok: Runtime: 0:00:00.012029
2025-12-03 00:14:31.771005 | 
2025-12-03 00:14:31.771099 | PLAY RECAP
2025-12-03 00:14:31.771142 | controller | ok: 46 changed: 26 unreachable: 0 failed: 0 skipped: 17 rescued: 0 ignored: 0
2025-12-03 00:14:31.771164 | 
2025-12-03 00:14:31.930503 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2025-12-03 00:14:31.932487 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-03 00:14:32.622231 | 
2025-12-03 00:14:32.622347 | PLAY [controller]
2025-12-03 00:14:32.644252 | 
2025-12-03 00:14:32.644338 | TASK [Copy the galaxy.yml on the controller]
2025-12-03 00:14:36.535309 | controller | changed
2025-12-03 00:14:36.550874 | 
2025-12-03 00:14:36.551060 | TASK [Load information from galaxy.yml]
2025-12-03 00:14:36.589205 | controller | ok
2025-12-03 00:14:36.597955 | 
2025-12-03 00:14:36.598074 | LOOP [Enable FIPS mode]
2025-12-03 00:14:36.633050 | 
2025-12-03 00:14:36.633230 | LOOP [ansible-test : Include branch specific variables.]
2025-12-03 00:14:36.681602 | controller | ok: "/var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml"
2025-12-03 00:14:36.681812 | 
2025-12-03 00:14:36.690871 | 
2025-12-03 00:14:36.690962 | TASK [ansible-test : Setup --skip-tags for test_options]
2025-12-03 00:14:36.733920 | controller | ok
2025-12-03 00:14:36.741668 | 
2025-12-03 00:14:36.741780 | TASK [ansible-test : Enable --inventory for network-integration]
2025-12-03 00:14:36.767242 | controller | skipping: Conditional result was False
2025-12-03 00:14:36.776819 | 
2025-12-03 00:14:36.776920 | TASK [ansible-test : Enable --retry-on-error]
2025-12-03 00:14:36.802516 | controller | skipping: Conditional result was False
2025-12-03 00:14:36.818567 | 
2025-12-03 00:14:36.818687 | TASK [ansible-test : Enable --continue-on-error]
2025-12-03 00:14:36.857157 | controller | ok
2025-12-03 00:14:36.867001 | 
2025-12-03 00:14:36.867084 | TASK [ansible-test : Enable --requirements]
2025-12-03 00:14:36.892015 | controller | skipping: Conditional result was False
2025-12-03 00:14:36.901056 | 
2025-12-03 00:14:36.901148 | TASK [ansible-test : Adjust options for unit tests]
2025-12-03 00:14:36.927096 | controller | skipping: Conditional result was False
2025-12-03 00:14:36.935862 | 
2025-12-03 00:14:36.935954 | TASK [ansible-test : Install requirements with unit tests]
2025-12-03 00:14:37.003769 | controller | ok
2025-12-03 00:14:37.010129 | 
2025-12-03 00:14:37.010217 | TASK [ansible-test : Set --skip-test]
2025-12-03 00:14:37.065416 | controller | skipping: Conditional result was False
2025-12-03 00:14:37.079912 | 
2025-12-03 00:14:37.080054 | TASK [ansible-test : Reduce verbosity]
2025-12-03 00:14:37.122294 | controller | ok
2025-12-03 00:14:37.133614 | 
2025-12-03 00:14:37.133707 | TASK [ansible-test : Setup --python option]
2025-12-03 00:14:37.159857 | controller | skipping: Conditional result was False
2025-12-03 00:14:37.168081 | 
2025-12-03 00:14:37.168388 | TASK [ansible-test : Setup --docker option]
2025-12-03 00:14:37.211290 | controller | ok
2025-12-03 00:14:37.221049 | 
2025-12-03 00:14:37.221152 | TASK [ansible-test : check if MANIFEST.json exists]
2025-12-03 00:14:38.357074 | controller | ok
2025-12-03 00:14:38.370012 | 
2025-12-03 00:14:38.370147 | TASK [ansible-test : Copy the MANIFEST.json on the controller]
2025-12-03 00:14:38.387681 | controller | skipping: Conditional result was False
2025-12-03 00:14:38.402556 | 
2025-12-03 00:14:38.402702 | TASK [ansible-test : Load information from MANIFEST.json]
2025-12-03 00:14:38.421005 | controller | skipping: Conditional result was False
2025-12-03 00:14:38.436448 | 
2025-12-03 00:14:38.436590 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-03 00:14:38.453674 | controller | skipping: Conditional result was False
2025-12-03 00:14:38.462903 | 
2025-12-03 00:14:38.462992 | TASK [ansible-test : Copy the galaxy.yml on the controller]
2025-12-03 00:14:40.105361 | controller | ok
2025-12-03 00:14:40.117985 | 
2025-12-03 00:14:40.118222 | TASK [ansible-test : Load information from galaxy.yml]
2025-12-03 00:14:40.173838 | controller | ok
2025-12-03 00:14:40.185714 | 
2025-12-03 00:14:40.185897 | TASK [ansible-test : Setup location of project for integration tests]
2025-12-03 00:14:40.244904 | controller | ok
2025-12-03 00:14:40.256902 | 
2025-12-03 00:14:40.257061 | TASK [ansible-test : Setup minimum test requirements]
2025-12-03 00:14:40.291531 | controller | ok
2025-12-03 00:14:40.297447 | 
2025-12-03 00:14:40.297523 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp]
2025-12-03 00:14:41.274182 | controller | ok
2025-12-03 00:14:41.280429 | 
2025-12-03 00:14:41.280492 | TASK [ansible-test : Install python requirements]
2025-12-03 00:14:42.842408 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-03 00:14:42.861624 | controller |   Downloading yamllint-1.35.1-py3-none-any.whl.metadata (4.2 kB)
2025-12-03 00:14:42.893040 | controller | Collecting pathspec>=0.5.3 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1))
2025-12-03 00:14:42.898476 | controller |   Downloading pathspec-0.12.1-py3-none-any.whl.metadata (21 kB)
2025-12-03 00:14:42.908816 | 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-12-03 00:14:42.916689 | controller | Downloading yamllint-1.35.1-py3-none-any.whl (66 kB)
2025-12-03 00:14:42.931760 | controller | Downloading pathspec-0.12.1-py3-none-any.whl (31 kB)
2025-12-03 00:14:42.991857 | controller | Installing collected packages: pathspec, yamllint
2025-12-03 00:14:43.076147 | controller | Successfully installed pathspec-0.12.1 yamllint-1.35.1
2025-12-03 00:14:43.639144 | controller | ok: Runtime: 0:00:01.069889
2025-12-03 00:14:43.646173 | 
2025-12-03 00:14:43.646255 | TASK [ansible-test : Copy the integration_config.yml file from the controller]
2025-12-03 00:14:44.422924 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory
2025-12-03 00:14:44.998311 | controller | ERROR
2025-12-03 00:14:44.998598 | controller | {
2025-12-03 00:14:44.998661 | controller |   "delta": "0:00:00.008353",
2025-12-03 00:14:44.998704 | controller |   "end": "2025-12-03 00:14:44.423216",
2025-12-03 00:14:44.998793 | controller |   "msg": "non-zero return code",
2025-12-03 00:14:44.998834 | controller |   "rc": 1,
2025-12-03 00:14:44.998870 | controller |   "start": "2025-12-03 00:14:44.414863"
2025-12-03 00:14:44.998905 | controller | }
2025-12-03 00:14:44.998952 | controller | ERROR: Ignoring Errors
2025-12-03 00:14:45.011920 | 
2025-12-03 00:14:45.012055 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection]
2025-12-03 00:14:45.388979 | controller | skipping: Conditional result was False
2025-12-03 00:14:45.397831 | 
2025-12-03 00:14:45.397950 | TASK [ansible-test : Enable persistent connection logging]
2025-12-03 00:14:46.643722 | controller | changed: section and option added
2025-12-03 00:14:46.650897 | 
2025-12-03 00:14:46.651049 | TASK [ansible-test : Set persistent ssh_type value]
2025-12-03 00:14:47.875561 | controller | changed: option added
2025-12-03 00:14:47.890442 | 
2025-12-03 00:14:47.890629 | TASK [ansible-test : Set ansible log path]
2025-12-03 00:14:48.901705 | controller | changed: section and option added
2025-12-03 00:14:48.918036 | 
2025-12-03 00:14:48.918186 | TASK [ansible-test : Identify the changed targets]
2025-12-03 00:14:49.302774 | controller | skipping: Conditional result was False
2025-12-03 00:14:49.314614 | 
2025-12-03 00:14:49.314786 | TASK [ansible-test : set_fact]
2025-12-03 00:14:49.443632 | controller | skipping: Conditional result was False
2025-12-03 00:14:49.454428 | 
2025-12-03 00:14:49.454571 | TASK [ansible-test : Set the targets]
2025-12-03 00:14:49.558488 | controller | ok
2025-12-03 00:14:49.565062 | 
2025-12-03 00:14:49.565265 | TASK [ansible-test : set_fact]
2025-12-03 00:14:49.591292 | controller | skipping: Conditional result was False
2025-12-03 00:14:49.598669 | 
2025-12-03 00:14:49.598771 | TASK [ansible-test : List the targets]
2025-12-03 00:14:49.959638 | controller | skipping: Conditional result was False
2025-12-03 00:14:49.975962 | 
2025-12-03 00:14:49.976193 | TASK [ansible-test : set_fact]
2025-12-03 00:14:50.006290 | controller | skipping: Conditional result was False
2025-12-03 00:14:50.025323 | 
2025-12-03 00:14:50.025478 | TASK [ansible-test : set_fact]
2025-12-03 00:14:50.056468 | controller | skipping: Conditional result was False
2025-12-03 00:14:50.075693 | 
2025-12-03 00:14:50.075887 | LOOP [ansible-test : set_fact]
2025-12-03 00:14:50.156220 | 
2025-12-03 00:14:50.156466 | TASK [ansible-test : set_fact]
2025-12-03 00:14:50.184228 | controller | skipping: Conditional result was False
2025-12-03 00:14:50.200795 | 
2025-12-03 00:14:50.200982 | TASK [ansible-test : debug]
2025-12-03 00:14:50.231565 | controller | skipping: Conditional result was False
2025-12-03 00:14:50.247664 | 
2025-12-03 00:14:50.247820 | TASK [ansible-test : debug]
2025-12-03 00:14:50.289526 | About to run: ansible-test sanity --requirements --docker -v
2025-12-03 00:14:50.298252 | 
2025-12-03 00:14:50.298345 | TASK [ansible-test : Run the test suite]
2025-12-03 00:14:51.900242 | controller | Run command: docker images quay.io/ansible/default-test-container:1.10.1 --format '{{json .}}'
2025-12-03 00:14:51.974022 | controller | Run command: docker pull quay.io/ansible/default-test-container:1.10.1
2025-12-03 00:14:53.522686 | controller | 1.10.1: Pulling from ansible/default-test-container
2025-12-03 00:14:53.522815 | 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-12-03 00:14:53.524594 | controller | 5667fdb72017: Pulling fs layer
2025-12-03 00:14:53.524614 | controller | d83811f270d5: Pulling fs layer
2025-12-03 00:14:53.524625 | controller | ee671aafb583: Pulling fs layer
2025-12-03 00:14:53.524638 | controller | 7fc152dfb3a6: Pulling fs layer
2025-12-03 00:14:53.524647 | controller | 3fb967a6fd03: Pulling fs layer
2025-12-03 00:14:53.524654 | controller | b3e63023ca5f: Pulling fs layer
2025-12-03 00:14:53.524660 | controller | 653cb3e65dbf: Pulling fs layer
2025-12-03 00:14:53.524667 | controller | f4712b586375: Pulling fs layer
2025-12-03 00:14:53.524673 | controller | d696ed7812f8: Pulling fs layer
2025-12-03 00:14:53.524680 | controller | 7fc152dfb3a6: Waiting
2025-12-03 00:14:53.524687 | controller | 3fb967a6fd03: Waiting
2025-12-03 00:14:53.524693 | controller | b3e63023ca5f: Waiting
2025-12-03 00:14:53.524700 | controller | 653cb3e65dbf: Waiting
2025-12-03 00:14:53.524709 | controller | f4712b586375: Waiting
2025-12-03 00:14:53.524715 | controller | 29bf28764f14: Pulling fs layer
2025-12-03 00:14:53.524725 | controller | 47d7edb3c2b7: Pulling fs layer
2025-12-03 00:14:53.524731 | controller | 07550ad4f6e4: Pulling fs layer
2025-12-03 00:14:53.524737 | controller | d696ed7812f8: Waiting
2025-12-03 00:14:53.524743 | controller | 29bf28764f14: Waiting
2025-12-03 00:14:53.524749 | controller | 47d7edb3c2b7: Waiting
2025-12-03 00:14:53.524763 | controller | 7e79526e64c5: Pulling fs layer
2025-12-03 00:14:53.524770 | controller | 4fffb32882b7: Pulling fs layer
2025-12-03 00:14:53.524776 | controller | fc7a38b06f4e: Pulling fs layer
2025-12-03 00:14:53.524783 | controller | 07550ad4f6e4: Waiting
2025-12-03 00:14:53.524789 | controller | 7e79526e64c5: Waiting
2025-12-03 00:14:53.524795 | controller | 4fffb32882b7: Waiting
2025-12-03 00:14:53.524802 | controller | 6d9d912e060e: Pulling fs layer
2025-12-03 00:14:53.524809 | controller | fc7a38b06f4e: Waiting
2025-12-03 00:14:53.524819 | controller | 5dd542dd28d9: Pulling fs layer
2025-12-03 00:14:53.524826 | controller | cef8beb99552: Pulling fs layer
2025-12-03 00:14:53.524835 | controller | bd5557e101ad: Pulling fs layer
2025-12-03 00:14:53.524841 | controller | 6d9d912e060e: Waiting
2025-12-03 00:14:53.524847 | controller | 5dd542dd28d9: Waiting
2025-12-03 00:14:53.524854 | controller | cef8beb99552: Waiting
2025-12-03 00:14:53.524860 | controller | bd5557e101ad: Waiting
2025-12-03 00:14:53.524865 | controller | e057c20fabdd: Pulling fs layer
2025-12-03 00:14:53.524874 | controller | d16059cdae2e: Pulling fs layer
2025-12-03 00:14:53.524880 | controller | 431e42d906a3: Pulling fs layer
2025-12-03 00:14:53.524886 | controller | 7a0fd3b1d7c3: Pulling fs layer
2025-12-03 00:14:53.524892 | controller | e057c20fabdd: Waiting
2025-12-03 00:14:53.524898 | controller | d16059cdae2e: Waiting
2025-12-03 00:14:53.524904 | controller | 431e42d906a3: Waiting
2025-12-03 00:14:53.524910 | controller | aacdb6db0c8c: Pulling fs layer
2025-12-03 00:14:53.524916 | controller | 2e7f04ae8c8b: Pulling fs layer
2025-12-03 00:14:53.524922 | controller | 4cd343027a44: Pulling fs layer
2025-12-03 00:14:53.524928 | controller | 0b4379883f90: Pulling fs layer
2025-12-03 00:14:53.524933 | controller | 7a0fd3b1d7c3: Waiting
2025-12-03 00:14:53.524939 | controller | aacdb6db0c8c: Waiting
2025-12-03 00:14:53.524951 | controller | 2e7f04ae8c8b: Waiting
2025-12-03 00:14:54.341820 | controller | 4cd343027a44: Waiting
2025-12-03 00:14:54.341903 | controller | 3d9d6d9dfc91: Pulling fs layer
2025-12-03 00:14:54.341910 | controller | 57d6314bd293: Pulling fs layer
2025-12-03 00:14:54.341917 | controller | 774036afeb7f: Pulling fs layer
2025-12-03 00:14:54.341923 | controller | 0b4379883f90: Waiting
2025-12-03 00:14:54.341930 | controller | 3d9d6d9dfc91: Waiting
2025-12-03 00:14:54.341936 | controller | 57d6314bd293: Waiting
2025-12-03 00:14:54.341942 | controller | 54b10f0e1645: Pulling fs layer
2025-12-03 00:14:54.341950 | controller | 774036afeb7f: Waiting
2025-12-03 00:14:54.341956 | controller | 78cf439d9fdb: Pulling fs layer
2025-12-03 00:14:54.341984 | controller | 36e5e20969c7: Pulling fs layer
2025-12-03 00:14:54.341994 | controller | 54b10f0e1645: Waiting
2025-12-03 00:14:54.342023 | controller | 78cf439d9fdb: Waiting
2025-12-03 00:14:54.342032 | controller | 4b19ac3f17a8: Pulling fs layer
2025-12-03 00:14:54.342038 | controller | 36e5e20969c7: Waiting
2025-12-03 00:14:54.342058 | controller | ee671aafb583: Download complete
2025-12-03 00:14:54.563298 | controller | d83811f270d5: Download complete
2025-12-03 00:14:55.114370 | controller | 7fc152dfb3a6: Verifying Checksum
2025-12-03 00:14:55.913520 | controller | 7fc152dfb3a6: Download complete
2025-12-03 00:14:55.913836 | controller | b3e63023ca5f: Verifying Checksum
2025-12-03 00:14:56.691508 | controller | b3e63023ca5f: Download complete
2025-12-03 00:14:56.691602 | controller | 653cb3e65dbf: Verifying Checksum
2025-12-03 00:14:59.776284 | controller | 653cb3e65dbf: Download complete
2025-12-03 00:14:59.776382 | controller | 5667fdb72017: Download complete
2025-12-03 00:15:00.563956 | controller | d696ed7812f8: Verifying Checksum
2025-12-03 00:15:01.386225 | controller | d696ed7812f8: Download complete
2025-12-03 00:15:01.386339 | controller | 29bf28764f14: Verifying Checksum
2025-12-03 00:15:01.386379 | controller | 29bf28764f14: Download complete
2025-12-03 00:15:02.151935 | controller | 3fb967a6fd03: Verifying Checksum
2025-12-03 00:15:02.152191 | controller | 3fb967a6fd03: Download complete
2025-12-03 00:15:02.692121 | controller | 5667fdb72017: Pull complete
2025-12-03 00:15:02.730344 | controller | d83811f270d5: Pull complete
2025-12-03 00:15:02.755992 | controller | ee671aafb583: Pull complete
2025-12-03 00:15:02.779371 | controller | 47d7edb3c2b7: Verifying Checksum
2025-12-03 00:15:02.793381 | controller | 47d7edb3c2b7: Download complete
2025-12-03 00:15:02.793450 | controller | 7fc152dfb3a6: Pull complete
2025-12-03 00:15:02.934285 | controller | 07550ad4f6e4: Verifying Checksum
2025-12-03 00:15:02.934348 | controller | 07550ad4f6e4: Download complete
2025-12-03 00:15:03.525669 | controller | 7e79526e64c5: Verifying Checksum
2025-12-03 00:15:03.525749 | controller | 7e79526e64c5: Download complete
2025-12-03 00:15:03.555783 | controller | 3fb967a6fd03: Pull complete
2025-12-03 00:15:03.583034 | controller | b3e63023ca5f: Pull complete
2025-12-03 00:15:03.603364 | controller | 653cb3e65dbf: Pull complete
2025-12-03 00:15:03.660179 | controller | 4fffb32882b7: Verifying Checksum
2025-12-03 00:15:03.660263 | controller | 4fffb32882b7: Download complete
2025-12-03 00:15:04.469211 | controller | 6d9d912e060e: Verifying Checksum
2025-12-03 00:15:04.469378 | controller | 6d9d912e060e: Download complete
2025-12-03 00:15:04.872469 | controller | fc7a38b06f4e: Verifying Checksum
2025-12-03 00:15:04.872698 | controller | fc7a38b06f4e: Download complete
2025-12-03 00:15:05.228777 | controller | 5dd542dd28d9: Verifying Checksum
2025-12-03 00:15:05.228953 | controller | 5dd542dd28d9: Download complete
2025-12-03 00:15:05.939000 | controller | bd5557e101ad: Verifying Checksum
2025-12-03 00:15:05.940706 | controller | bd5557e101ad: Download complete
2025-12-03 00:15:06.779460 | controller | e057c20fabdd: Verifying Checksum
2025-12-03 00:15:06.779584 | controller | e057c20fabdd: Download complete
2025-12-03 00:15:07.501367 | controller | d16059cdae2e: Verifying Checksum
2025-12-03 00:15:07.501490 | controller | d16059cdae2e: Download complete
2025-12-03 00:15:09.332692 | controller | 431e42d906a3: Verifying Checksum
2025-12-03 00:15:09.332824 | controller | 431e42d906a3: Download complete
2025-12-03 00:15:10.793140 | controller | 7a0fd3b1d7c3: Verifying Checksum
2025-12-03 00:15:12.216464 | controller | 7a0fd3b1d7c3: Download complete
2025-12-03 00:15:12.216557 | controller | aacdb6db0c8c: Verifying Checksum
2025-12-03 00:15:12.943182 | controller | aacdb6db0c8c: Download complete
2025-12-03 00:15:12.943305 | controller | 2e7f04ae8c8b: Verifying Checksum
2025-12-03 00:15:12.943388 | controller | 2e7f04ae8c8b: Download complete
2025-12-03 00:15:13.801589 | controller | 4cd343027a44: Verifying Checksum
2025-12-03 00:15:13.802171 | controller | 4cd343027a44: Download complete
2025-12-03 00:15:14.543811 | controller | 0b4379883f90: Verifying Checksum
2025-12-03 00:15:14.544010 | controller | 0b4379883f90: Download complete
2025-12-03 00:15:15.305649 | controller | 3d9d6d9dfc91: Verifying Checksum
2025-12-03 00:15:20.056606 | controller | 3d9d6d9dfc91: Download complete
2025-12-03 00:15:20.056724 | controller | f4712b586375: Verifying Checksum
2025-12-03 00:15:20.056763 | controller | f4712b586375: Download complete
2025-12-03 00:15:27.188538 | controller | 57d6314bd293: Verifying Checksum
2025-12-03 00:15:36.399918 | controller | 57d6314bd293: Download complete
2025-12-03 00:15:36.400080 | controller | cef8beb99552: Verifying Checksum
2025-12-03 00:15:36.400141 | controller | cef8beb99552: Download complete
2025-12-03 00:15:37.442455 | controller | 774036afeb7f: Verifying Checksum
2025-12-03 00:15:37.442943 | controller | 774036afeb7f: Download complete
2025-12-03 00:15:38.818873 | controller | 54b10f0e1645: Verifying Checksum
2025-12-03 00:15:38.819039 | controller | 54b10f0e1645: Download complete
2025-12-03 00:15:42.298879 | controller | f4712b586375: Pull complete
2025-12-03 00:15:42.323189 | controller | d696ed7812f8: Pull complete
2025-12-03 00:15:42.362838 | controller | 29bf28764f14: Pull complete
2025-12-03 00:15:42.413807 | controller | 47d7edb3c2b7: Pull complete
2025-12-03 00:15:42.442152 | controller | 07550ad4f6e4: Pull complete
2025-12-03 00:15:42.472097 | controller | 7e79526e64c5: Pull complete
2025-12-03 00:15:42.512900 | controller | 4fffb32882b7: Pull complete
2025-12-03 00:15:42.579196 | controller | fc7a38b06f4e: Pull complete
2025-12-03 00:15:42.609296 | controller | 6d9d912e060e: Pull complete
2025-12-03 00:15:42.631922 | controller | 5dd542dd28d9: Pull complete
2025-12-03 00:15:49.587230 | controller | 4b19ac3f17a8: Verifying Checksum
2025-12-03 00:15:50.848359 | controller | 4b19ac3f17a8: Download complete
2025-12-03 00:15:50.849590 | controller | 36e5e20969c7: Verifying Checksum
2025-12-03 00:15:51.570828 | controller | 36e5e20969c7: Download complete
2025-12-03 00:15:51.570895 | controller | 78cf439d9fdb: Verifying Checksum
2025-12-03 00:16:04.176252 | controller | 78cf439d9fdb: Download complete
2025-12-03 00:16:04.177319 | controller | cef8beb99552: Pull complete
2025-12-03 00:16:04.208853 | controller | bd5557e101ad: Pull complete
2025-12-03 00:16:04.238655 | controller | e057c20fabdd: Pull complete
2025-12-03 00:16:04.274009 | controller | d16059cdae2e: Pull complete
2025-12-03 00:16:06.279011 | controller | 431e42d906a3: Pull complete
2025-12-03 00:16:06.329479 | controller | 7a0fd3b1d7c3: Pull complete
2025-12-03 00:16:06.390665 | controller | aacdb6db0c8c: Pull complete
2025-12-03 00:16:06.438749 | controller | 2e7f04ae8c8b: Pull complete
2025-12-03 00:16:06.474721 | controller | 4cd343027a44: Pull complete
2025-12-03 00:16:06.516951 | controller | 0b4379883f90: Pull complete
2025-12-03 00:16:06.559610 | controller | 3d9d6d9dfc91: Pull complete
2025-12-03 00:16:12.542048 | controller | 57d6314bd293: Pull complete
2025-12-03 00:16:32.126997 | controller | 774036afeb7f: Pull complete
2025-12-03 00:17:02.261581 | controller | 54b10f0e1645: Pull complete
2025-12-03 00:17:30.550365 | controller | 78cf439d9fdb: Pull complete
2025-12-03 00:17:54.251279 | controller | 36e5e20969c7: Pull complete
2025-12-03 00:18:24.393369 | controller | 4b19ac3f17a8: Pull complete
2025-12-03 00:18:24.419495 | controller | Digest: sha256:954c4edffb0905df64e23c8611b1c4a62db9af13062f51cad5b658e727238d41
2025-12-03 00:18:24.425540 | controller | Status: Downloaded newer image for quay.io/ansible/default-test-container:1.10.1
2025-12-03 00:18:24.435103 | controller | quay.io/ansible/default-test-container:1.10.1
2025-12-03 00:18:24.441177 | controller | Run command: docker images quay.io/ansible/pypi-test-container:1.0.0 --format '{{json .}}'
2025-12-03 00:18:24.475103 | controller | Run command: docker pull quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:18:26.155173 | controller | 1.0.0: Pulling from ansible/pypi-test-container
2025-12-03 00:18:26.155278 | controller | 04a5f4cda3ee: Pulling fs layer
2025-12-03 00:18:26.155321 | controller | ff496a88c8ed: Pulling fs layer
2025-12-03 00:18:26.155356 | controller | 0ce83f459fe7: Pulling fs layer
2025-12-03 00:18:26.155408 | controller | 2e5170e1f099: Pulling fs layer
2025-12-03 00:18:26.155444 | controller | 7641eb41b08c: Pulling fs layer
2025-12-03 00:18:26.155478 | controller | ad15fa9da398: Pulling fs layer
2025-12-03 00:18:26.155513 | controller | 087d91352424: Pulling fs layer
2025-12-03 00:18:26.155546 | controller | 8b92efd6a100: Pulling fs layer
2025-12-03 00:18:26.156105 | controller | 2e5170e1f099: Waiting
2025-12-03 00:18:26.156237 | controller | 7641eb41b08c: Waiting
2025-12-03 00:18:26.156331 | controller | ad15fa9da398: Waiting
2025-12-03 00:18:26.156413 | controller | 087d91352424: Waiting
2025-12-03 00:18:26.156535 | controller | 8b92efd6a100: Waiting
2025-12-03 00:18:26.864252 | controller | ff496a88c8ed: Verifying Checksum
2025-12-03 00:18:27.221343 | controller | ff496a88c8ed: Download complete
2025-12-03 00:18:27.221407 | controller | 04a5f4cda3ee: Verifying Checksum
2025-12-03 00:18:27.223322 | controller | 04a5f4cda3ee: Download complete
2025-12-03 00:18:27.237685 | controller | 0ce83f459fe7: Verifying Checksum
2025-12-03 00:18:27.237734 | controller | 0ce83f459fe7: Download complete
2025-12-03 00:18:27.457505 | controller | 2e5170e1f099: Verifying Checksum
2025-12-03 00:18:27.457554 | controller | 2e5170e1f099: Download complete
2025-12-03 00:18:27.636539 | controller | ad15fa9da398: Verifying Checksum
2025-12-03 00:18:27.636586 | controller | ad15fa9da398: Download complete
2025-12-03 00:18:27.653474 | controller | 7641eb41b08c: Verifying Checksum
2025-12-03 00:18:27.933705 | controller | 7641eb41b08c: Download complete
2025-12-03 00:18:27.933780 | controller | 087d91352424: Verifying Checksum
2025-12-03 00:18:28.273984 | controller | 087d91352424: Download complete
2025-12-03 00:18:28.274043 | controller | 8b92efd6a100: Verifying Checksum
2025-12-03 00:18:29.126661 | controller | 8b92efd6a100: Download complete
2025-12-03 00:18:29.126726 | controller | 04a5f4cda3ee: Pull complete
2025-12-03 00:18:29.168643 | controller | ff496a88c8ed: Pull complete
2025-12-03 00:18:29.207561 | controller | 0ce83f459fe7: Pull complete
2025-12-03 00:18:30.632676 | controller | 2e5170e1f099: Pull complete
2025-12-03 00:18:30.668499 | controller | 7641eb41b08c: Pull complete
2025-12-03 00:18:30.705763 | controller | ad15fa9da398: Pull complete
2025-12-03 00:18:31.619240 | controller | 087d91352424: Pull complete
2025-12-03 00:18:31.655386 | controller | 8b92efd6a100: Pull complete
2025-12-03 00:18:31.683605 | controller | Digest: sha256:71042ab0a14971b5608fe75706de54f367fc31db573e3b3955182037f73cadb6
2025-12-03 00:18:31.689939 | controller | Status: Downloaded newer image for quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:18:31.699334 | controller | quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:18:31.702016 | controller | Run command: docker run --detach quay.io/ansible/pypi-test-container:1.0.0
2025-12-03 00:18:35.295148 | controller | Run command: docker inspect c275737aa67b4686b031b3f600638e73eada69140f9e01ba679666bc4beb3170
2025-12-03 00:18:35.491768 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections
2025-12-03 00:18:35.505437 | controller | Including collection: ansible.netcommon (292 files)
2025-12-03 00:18:35.505518 | controller | Including collection: ansible.network (23 files)
2025-12-03 00:18:35.519335 | controller | Including collection: ansible.utils (442 files)
2025-12-03 00:18:35.558471 | controller | Including collection: arista.eos (1022 files)
2025-12-03 00:18:35.606343 | controller | Including collection: cisco.ios (1172 files)
2025-12-03 00:18:35.651702 | controller | Including collection: cisco.iosxr (1043 files)
2025-12-03 00:18:35.726815 | controller | Including collection: cisco.nxos (1684 files)
2025-12-03 00:18:35.770172 | controller | Including collection: junipernetworks.junos (963 files)
2025-12-03 00:18:35.809854 | controller | Creating a payload archive containing 8378 files...
2025-12-03 00:18:38.026677 | controller | Created a 6890128 byte payload archive containing 8378 files in 2 seconds.
2025-12-03 00:18:38.031097 | controller | Assuming Docker is available on localhost.
2025-12-03 00:18:38.031632 | 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-12-03 00:18:38.560144 | controller | Run command: docker exec -i 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 dd of=/root/docker.sh bs=65536
2025-12-03 00:18:38.659150 | controller | Run command: docker exec 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 /bin/bash /root/docker.sh
2025-12-03 00:18:38.742085 | controller | Run command: docker exec -i 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 dd of=/root/ansible.tgz bs=65536
2025-12-03 00:18:38.848056 | controller | Run command: docker exec 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 mkdir /root/ansible
2025-12-03 00:18:38.916271 | controller | Run command: docker exec 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 tar oxzf /root/ansible.tgz -C /root/ansible
2025-12-03 00:18:39.973879 | controller | Run command: docker exec 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 /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-p0ai269d.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/
2025-12-03 00:18:40.452637 | controller | Injecting custom PyPI hosts entries: /etc/hosts
2025-12-03 00:18:40.452919 | controller | Injecting custom PyPI config: /root/.pip/pip.conf
2025-12-03 00:18:40.453322 | controller | Injecting custom PyPI config: /root/.pydistutils.cfg
2025-12-03 00:18:40.455246 | controller | Run command: /usr/bin/python3.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-03 00:18:40.528874 | controller | Running sanity test 'action-plugin-docs' with Python 3.6
2025-12-03 00:18:40.532201 | controller | Read 0 sanity test ignore line(s) for Ansible 2.9 from: tests/sanity/ignore-2.9.txt
2025-12-03 00:18:40.539394 | controller | No tests applicable.
2025-12-03 00:18:40.539712 | controller | Running sanity test 'ansible-doc' with Python 3.6
2025-12-03 00:18:40.539729 | controller | No tests applicable.
2025-12-03 00:18:40.540008 | controller | Run command: /usr/bin/python2.6 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-03 00:18:40.615492 | controller | Running sanity test 'compile' with Python 2.6
2025-12-03 00:18:40.615904 | controller | No tests applicable.
2025-12-03 00:18:40.616274 | controller | Run command: /usr/bin/python2.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-03 00:18:40.819744 | controller | Running sanity test 'compile' with Python 2.7
2025-12-03 00:18:40.820066 | controller | No tests applicable.
2025-12-03 00:18:40.820452 | controller | Run command: /usr/bin/python3.5 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-03 00:18:40.938829 | controller | Running sanity test 'compile' with Python 3.5
2025-12-03 00:18:40.939153 | controller | No tests applicable.
2025-12-03 00:18:40.939262 | controller | Running sanity test 'compile' with Python 3.6
2025-12-03 00:18:40.939628 | controller | No tests applicable.
2025-12-03 00:18:40.940111 | controller | Run command: /usr/bin/python3.7 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-03 00:18:41.034351 | controller | Running sanity test 'compile' with Python 3.7
2025-12-03 00:18:41.034743 | controller | No tests applicable.
2025-12-03 00:18:41.035066 | controller | Run command: /usr/bin/python3.8 /root/ansible/test/lib/ansible_test/_data/yamlcheck.py
2025-12-03 00:18:41.137047 | controller | Running sanity test 'compile' with Python 3.8
2025-12-03 00:18:41.137377 | controller | No tests applicable.
2025-12-03 00:18:41.137554 | controller | Running sanity test 'empty-init' with Python 3.6
2025-12-03 00:18:41.137728 | controller | No tests applicable.
2025-12-03 00:18:41.137877 | controller | Running sanity test 'future-import-boilerplate' with Python 3.6
2025-12-03 00:18:41.138138 | controller | No tests applicable.
2025-12-03 00:18:41.139394 | controller | Running sanity test 'ignores'
2025-12-03 00:18:41.139419 | 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-12-03 00:18:41.854012 | controller | Ignoring packaging: markers 'python_version < "3.6"' don't match your environment
2025-12-03 00:18:41.857398 | controller | Ignoring coverage: markers 'python_version > "3.7"' don't match your environment
2025-12-03 00:18:41.858855 | controller | Ignoring cryptography: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.861669 | controller | Ignoring deepdiff: markers 'python_version < "3"' don't match your environment
2025-12-03 00:18:41.863280 | controller | Ignoring jinja2: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.864722 | controller | Ignoring urllib3: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.867871 | controller | Ignoring sphinx: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.872298 | controller | Ignoring wheel: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.873837 | controller | Ignoring yamllint: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.879713 | controller | Ignoring paramiko: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.882292 | controller | Ignoring pytest: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.883827 | controller | Ignoring pytest: markers 'python_version == "2.7"' don't match your environment
2025-12-03 00:18:41.885184 | controller | Ignoring pytest-forked: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.888885 | controller | Ignoring requests: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.895504 | controller | Ignoring virtualenv: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.897039 | controller | Ignoring pathspec: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.898442 | controller | Ignoring pyopenssl: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.901052 | controller | Ignoring pyyaml: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.902430 | controller | Ignoring pycparser: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.905674 | controller | Ignoring xmltodict: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.907112 | controller | Ignoring lxml: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.909771 | controller | Ignoring pyvmomi: markers 'python_version < "2.7"' don't match your environment
2025-12-03 00:18:41.911072 | controller | Ignoring MarkupSafe: markers 'python_version < "3.6"' don't match your environment
2025-12-03 00:18:41.915321 | controller | Ignoring setuptools: markers 'python_version <= "2.7"' don't match your environment
2025-12-03 00:18:41.919051 | controller | Ignoring websocket-client: markers 'python_version < "3"' don't match your environment
2025-12-03 00:18:41.920411 | controller | Ignoring certifi: markers 'python_version < "3"' don't match your environment
2025-12-03 00:18:41.936016 | controller | Looking in indexes: http://172.17.0.2:3141/root/pypi/+simple/
2025-12-03 00:18:41.937262 | 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-12-03 00:18:41.963312 | 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-12-03 00:18:41.966812 | 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-12-03 00:18:41.970038 | 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-12-03 00:18:41.972391 | 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-12-03 00:18:41.978301 | 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-12-03 00:18:41.981901 | 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-12-03 00:18:41.985602 | 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-12-03 00:18:41.987450 | 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-12-03 00:18:42.004542 | 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-12-03 00:18:42.008080 | 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-12-03 00:18:42.010082 | 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-12-03 00:18:42.012320 | 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-12-03 00:18:42.014354 | 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-12-03 00:18:42.016136 | controller | Requirement already satisfied: mccabe==0.6.1 in /usr/local/lib/python3.6/dist-packages (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 57)) (0.6.1)
2025-12-03 00:18:42.018742 | controller | Collecting isort==4.3.16 (from -c /root/ansible/test/lib/ansible_test/_data/requirements/constraints.txt (line 55))
2025-12-03 00:18:57.040381 | 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-12-03 00:19:12.557791 | 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-12-03 00:19:28.573308 | 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-12-03 00:19:45.591424 | 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-12-03 00:20:04.612050 | 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-12-03 00:20:19.631154 | 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-12-03 00:20:19.632167 | 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-12-03 00:20:19.738053 | 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-12-03 00:20:19.738325 | controller | Removing custom PyPI config: /root/.pydistutils.cfg
2025-12-03 00:20:19.738659 | controller | Removing custom PyPI config: /root/.pip/pip.conf
2025-12-03 00:20:19.738838 | controller | Removing custom PyPI hosts entries: /etc/hosts
2025-12-03 00:20:19.778128 | controller | Run command: docker exec 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 tar czf /root/output.tgz --exclude .tmp -C /root/ansible/ansible_collections/ansible/network/tests output
2025-12-03 00:20:19.848877 | controller | Run command: docker exec -i 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 dd if=/root/output.tgz bs=65536
2025-12-03 00:20:19.913374 | controller | Run command: tar oxzf /tmp/ansible-result-1glxn3o_.tgz -C /home/zuul/.ansible/collections/ansible_collections/ansible/network/tests
2025-12-03 00:20:19.926777 | controller | Run command: docker rm -f c275737aa67b4686b031b3f600638e73eada69140f9e01ba679666bc4beb3170
2025-12-03 00:20:20.150828 | controller | Run command: docker rm -f 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7
2025-12-03 00:20:20.888193 | controller | ERROR: Command "docker exec 66123dda131ee359ea72282202454552481e3ff6a55f3218629f8577b970acc7 /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-p0ai269d.json --truncate 0 --redact --color no --requirements --pypi-endpoint http://172.17.0.2:3141/root/pypi/+simple/" returned exit status 1.
2025-12-03 00:20:21.357873 | controller | ERROR
2025-12-03 00:20:21.358249 | controller | {
2025-12-03 00:20:21.358350 | controller |   "delta": "0:05:29.492619",
2025-12-03 00:20:21.358418 | controller |   "end": "2025-12-03 00:20:20.947299",
2025-12-03 00:20:21.358482 | controller |   "msg": "non-zero return code",
2025-12-03 00:20:21.358569 | controller |   "rc": 1,
2025-12-03 00:20:21.358616 | controller |   "start": "2025-12-03 00:14:51.454680"
2025-12-03 00:20:21.358650 | controller | }
failure
2025-12-03 00:20:21.364373 | 
2025-12-03 00:20:21.364443 | PLAY RECAP
2025-12-03 00:20:21.364502 | controller | ok: 21 changed: 6 unreachable: 0 failed: 1 skipped: 20 rescued: 0 ignored: 1
2025-12-03 00:20:21.364530 | 
2025-12-03 00:20:21.495679 | RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master]
2025-12-03 00:20:21.496654 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-03 00:20:22.084698 | 
2025-12-03 00:20:22.084829 | PLAY [all]
2025-12-03 00:20:22.107386 | 
2025-12-03 00:20:22.107503 | TASK [fetch-subunit-output : Find stestr or testr executable]
2025-12-03 00:20:25.082905 | controller | changed: non-zero return code
2025-12-03 00:20:25.099512 | 
2025-12-03 00:20:25.099781 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2025-12-03 00:20:25.128235 | controller | skipping: Conditional result was False
2025-12-03 00:20:25.145426 | 
2025-12-03 00:20:25.145896 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2025-12-03 00:20:25.192977 | 
2025-12-03 00:20:25.193292 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2025-12-03 00:20:25.234073 | 
2025-12-03 00:20:25.234340 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2025-12-03 00:20:25.260776 | controller | skipping: Conditional result was False
2025-12-03 00:20:25.272894 | 
2025-12-03 00:20:25.273045 | LOOP [fetch-subunit-output : Generate subunit file]
2025-12-03 00:20:25.311306 | 
2025-12-03 00:20:25.311544 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2025-12-03 00:20:25.337102 | controller | skipping: Conditional result was False
2025-12-03 00:20:25.353334 | 
2025-12-03 00:20:25.353484 | TASK [fetch-subunit-output : Remove the temporary file]
2025-12-03 00:20:25.378076 | controller | skipping: Conditional result was False
2025-12-03 00:20:25.386926 | 
2025-12-03 00:20:25.387036 | TASK [fetch-subunit-output : Process and fetch subunit results]
2025-12-03 00:20:25.402551 | controller | skipping: Conditional result was False
2025-12-03 00:20:25.443996 | 
2025-12-03 00:20:25.444115 | PLAY RECAP
2025-12-03 00:20:25.444170 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2025-12-03 00:20:25.444199 | 
2025-12-03 00:20:25.583204 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2025-12-03 00:20:25.585445 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2025-12-03 00:20:26.138559 | 
2025-12-03 00:20:26.138681 | PLAY [all:!appliance*]
2025-12-03 00:20:26.161376 | 
2025-12-03 00:20:26.161474 | TASK [unregister the node]
2025-12-03 00:20:26.517710 | controller | skipping: Conditional result was False
2025-12-03 00:20:26.530582 | 
2025-12-03 00:20:26.530804 | TASK [include_role : fetch-output]
2025-12-03 00:20:26.590853 | controller | ok
2025-12-03 00:20:26.639819 | 
2025-12-03 00:20:26.640120 | TASK [fetch-output : Set log path for multiple nodes]
2025-12-03 00:20:26.710313 | controller | skipping: Conditional result was False
2025-12-03 00:20:26.727534 | 
2025-12-03 00:20:26.727691 | TASK [fetch-output : Set log path for single node]
2025-12-03 00:20:26.772551 | controller | ok
2025-12-03 00:20:26.782099 | 
2025-12-03 00:20:26.782248 | LOOP [fetch-output : Ensure local output dirs]
2025-12-03 00:20:27.278768 | controller -> localhost | ok: "/var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/logs"
2025-12-03 00:20:27.537841 | controller -> localhost | changed: "/var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/artifacts"
2025-12-03 00:20:27.781176 | controller -> localhost | changed: "/var/lib/zuul/builds/7e19b4e055944ca4b1055d7b5e53a593/work/docs"
2025-12-03 00:20:27.810144 | 
2025-12-03 00:20:27.810425 | LOOP [fetch-output : Collect logs, artifacts and docs]
2025-12-03 00:20:30.148669 | controller | changed:
2025-12-03 00:20:30.148887 | controller | .d..t...... ./
2025-12-03 00:20:30.148914 | controller | cd+++++++++ controller/
2025-12-03 00:20:30.148946 | controller | changed: All items complete
2025-12-03 00:20:30.148966 | 
2025-12-03 00:20:32.217337 | controller | changed: .d..t...... ./
2025-12-03 00:20:34.310956 | controller | changed: .d..t...... ./
2025-12-03 00:20:34.333577 | 
2025-12-03 00:20:34.333779 | TASK [include_role : fetch-output-openshift]
2025-12-03 00:20:34.358824 | controller | skipping: Conditional result was False
2025-12-03 00:20:34.371135 | 
2025-12-03 00:20:34.371251 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2025-12-03 00:20:34.404008 | controller | skipping: Conditional result was False
2025-12-03 00:20:34.416957 | controller | skipping: Conditional result was False
2025-12-03 00:20:34.469054 | 
2025-12-03 00:20:34.469250 | PLAY [localhost]
2025-12-03 00:20:34.489572 | 
2025-12-03 00:20:34.489681 | TASK [Run Zuul manifest role]
2025-12-03 00:20:34.511499 | localhost | ok
2025-12-03 00:20:34.531862 | 
2025-12-03 00:20:34.531979 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2025-12-03 00:20:34.927090 | localhost | changed
2025-12-03 00:20:34.931719 | 
2025-12-03 00:20:34.931817 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2025-12-03 00:20:34.971573 | localhost | ok
2025-12-03 00:20:34.980369 | 
2025-12-03 00:20:34.980431 | TASK [Set zuul-log-path fact]
2025-12-03 00:20:35.030008 | localhost | ok
2025-12-03 00:20:35.043146 | 
2025-12-03 00:20:35.043217 | TASK [set-zuul-log-path-fact : Set log path for a build]
2025-12-03 00:20:35.081570 | localhost | ok
2025-12-03 00:20:35.089562 | 
2025-12-03 00:20:35.089630 | LOOP [Run upload-logs-swift role]
2025-12-03 00:20:35.134578 | localhost | Output suppressed because no_log was given
2025-12-03 00:20:35.161631 | 
2025-12-03 00:20:35.161752 | TASK [Set zuul-log-path fact]
2025-12-03 00:20:35.196117 | localhost | skipping: Conditional result was False
2025-12-03 00:20:35.201318 | 
2025-12-03 00:20:35.201384 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2025-12-03 00:20:35.630933 | localhost -> localhost | ok: Runtime: 0:00:00.005390
2025-12-03 00:20:35.636846 | 
2025-12-03 00:20:35.636927 | TASK [upload-logs-swift : Upload logs to swift]