2026-03-14 00:15:48.377472 | Job console starting... 2026-03-14 00:15:48.388781 | Updating repositories 2026-03-14 00:15:48.532550 | Preparing job workspace 2026-03-14 00:15:52.165231 | Running Ansible setup... 2026-03-14 00:15:57.250269 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-14 00:15:57.950701 | 2026-03-14 00:15:57.950857 | PLAY [localhost] 2026-03-14 00:15:57.960952 | 2026-03-14 00:15:57.961085 | TASK [Gathering Facts] 2026-03-14 00:15:59.150580 | localhost | ok 2026-03-14 00:15:59.179565 | 2026-03-14 00:15:59.179774 | TASK [Setup log path fact] 2026-03-14 00:15:59.202225 | localhost | ok 2026-03-14 00:15:59.221709 | 2026-03-14 00:15:59.222080 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 00:15:59.255259 | localhost | ok 2026-03-14 00:15:59.267808 | 2026-03-14 00:15:59.267921 | TASK [emit-job-header : Print job information] 2026-03-14 00:15:59.307816 | # Job Information 2026-03-14 00:15:59.307983 | Ansible Version: 2.15.12 2026-03-14 00:15:59.308016 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-14 00:15:59.308039 | Pipeline: periodic 2026-03-14 00:15:59.308060 | Executor: ze04.softwarefactory-project.io 2026-03-14 00:15:59.308079 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-14 00:15:59.308100 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/dc9/ansible/dc96230153d54fa4a045e3b265d657d2/ 2026-03-14 00:15:59.308119 | Event ID: 5eb9dcce8b23451ebec78fa1a4f594cb 2026-03-14 00:15:59.312384 | 2026-03-14 00:15:59.312449 | LOOP [emit-job-header : Print node information] 2026-03-14 00:15:59.432555 | localhost | ok: 2026-03-14 00:15:59.432831 | localhost | # Node Information 2026-03-14 00:15:59.432870 | localhost | Inventory Hostname: controller 2026-03-14 00:15:59.432899 | localhost | Hostname: np0005646806 2026-03-14 00:15:59.432925 | localhost | Username: zuul 2026-03-14 00:15:59.432956 | localhost | Distro: Fedora 37 2026-03-14 00:15:59.432982 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-14 00:15:59.433006 | localhost | Region: ca-ymq-1 2026-03-14 00:15:59.433030 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-14 00:15:59.433054 | localhost | Product Name: OpenStack Nova 2026-03-14 00:15:59.433078 | localhost | Interface IP: 162.253.55.52 2026-03-14 00:15:59.457246 | 2026-03-14 00:15:59.457600 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-14 00:16:00.030546 | localhost -> localhost | changed 2026-03-14 00:16:00.044127 | 2026-03-14 00:16:00.044452 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-14 00:16:01.145203 | localhost -> localhost | changed 2026-03-14 00:16:01.169236 | 2026-03-14 00:16:01.169383 | PLAY [all:!appliance*] 2026-03-14 00:16:01.187612 | 2026-03-14 00:16:01.187801 | TASK [include_role : start-zuul-console] 2026-03-14 00:16:01.214328 | controller | ok 2026-03-14 00:16:01.230013 | 2026-03-14 00:16:01.230120 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-14 00:16:01.656531 | controller | ok 2026-03-14 00:16:01.684052 | 2026-03-14 00:16:01.684297 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-14 00:16:02.699165 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-14 00:16:02.715277 | 2026-03-14 00:16:02.715447 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-14 00:16:03.268260 | controller | skipping: Conditional result was False 2026-03-14 00:16:03.275456 | 2026-03-14 00:16:03.275552 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-14 00:16:03.310382 | controller | skipping: Conditional result was False 2026-03-14 00:16:03.321436 | 2026-03-14 00:16:03.321609 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-14 00:16:03.347116 | controller | skipping: Conditional result was False 2026-03-14 00:16:03.358189 | 2026-03-14 00:16:03.358316 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-14 00:16:03.382819 | controller | skipping: Conditional result was False 2026-03-14 00:16:03.391150 | 2026-03-14 00:16:03.391329 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-14 00:16:03.416505 | controller | skipping: Conditional result was False 2026-03-14 00:16:03.428110 | 2026-03-14 00:16:03.428299 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-14 00:16:03.454637 | controller | skipping: Conditional result was False 2026-03-14 00:16:03.472415 | 2026-03-14 00:16:03.472600 | TASK [Disable Fedora Modular] 2026-03-14 00:16:03.719369 | controller | changed 2026-03-14 00:16:03.727265 | 2026-03-14 00:16:03.727346 | TASK [Enable EPEL] 2026-03-14 00:16:03.754073 | controller | skipping: Conditional result was False 2026-03-14 00:16:03.764385 | 2026-03-14 00:16:03.764529 | TASK [Register the RHEL node] 2026-03-14 00:16:04.322029 | 2026-03-14 00:16:04.322345 | TASK [Show the subscription-manager status] 2026-03-14 00:16:04.919162 | controller | skipping: Conditional result was False 2026-03-14 00:16:04.926062 | 2026-03-14 00:16:04.926141 | TASK [Enable EPEL on RHEL] 2026-03-14 00:16:05.465967 | controller | skipping: Conditional result was False 2026-03-14 00:16:05.480365 | 2026-03-14 00:16:05.480556 | TASK [Install git and tox] 2026-03-14 00:17:25.819459 | controller | changed 2026-03-14 00:17:25.828575 | 2026-03-14 00:17:25.828741 | TASK [include_role : prepare-workspace] 2026-03-14 00:17:25.859645 | controller | ok 2026-03-14 00:17:25.909598 | 2026-03-14 00:17:25.909833 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-14 00:17:26.165591 | controller | ok 2026-03-14 00:17:26.181206 | 2026-03-14 00:17:26.181492 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-14 00:17:38.376506 | controller | Output suppressed because no_log was given 2026-03-14 00:17:38.431643 | 2026-03-14 00:17:38.431812 | TASK [include_role : prepare-workspace-openshift] 2026-03-14 00:17:38.456929 | controller | skipping: Conditional result was False 2026-03-14 00:17:38.486999 | 2026-03-14 00:17:38.487109 | PLAY [all:!appliance] 2026-03-14 00:17:38.510669 | 2026-03-14 00:17:38.510866 | TASK [Run add-build-sshkey role (RSA)] 2026-03-14 00:17:38.541807 | controller | ok 2026-03-14 00:17:38.556770 | 2026-03-14 00:17:38.556859 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-14 00:17:38.826236 | controller -> localhost | ok 2026-03-14 00:17:38.836108 | 2026-03-14 00:17:38.836235 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-14 00:17:38.867924 | controller | ok 2026-03-14 00:17:38.906284 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-14 00:17:38.921159 | 2026-03-14 00:17:38.921312 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-14 00:17:39.368828 | controller -> localhost | Generating public/private rsa key pair. 2026-03-14 00:17:39.369212 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/dc96230153d54fa4a045e3b265d657d2_id_rsa. 2026-03-14 00:17:39.369273 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/dc96230153d54fa4a045e3b265d657d2_id_rsa.pub. 2026-03-14 00:17:39.369320 | controller -> localhost | The key fingerprint is: 2026-03-14 00:17:39.369363 | controller -> localhost | SHA256:yko4oYTUv0vbQ4XTLBQET7pBqjeN7lJUmU3OiriNy8c zuul-build-sshkey 2026-03-14 00:17:39.369415 | controller -> localhost | The key's randomart image is: 2026-03-14 00:17:39.369473 | controller -> localhost | +---[RSA 2048]----+ 2026-03-14 00:17:39.369530 | controller -> localhost | | oB=. | 2026-03-14 00:17:39.369586 | controller -> localhost | | . o+*o | 2026-03-14 00:17:39.369645 | controller -> localhost | | . o.o.++ | 2026-03-14 00:17:39.369753 | controller -> localhost | |o o.= ++ + | 2026-03-14 00:17:39.369801 | controller -> localhost | |.+o= = S | 2026-03-14 00:17:39.369857 | controller -> localhost | |..*+...o | 2026-03-14 00:17:39.369912 | controller -> localhost | |.o=o.o+ | 2026-03-14 00:17:39.369956 | controller -> localhost | |..oE..+. | 2026-03-14 00:17:39.369998 | controller -> localhost | | oo..o .. | 2026-03-14 00:17:39.370044 | controller -> localhost | +----[SHA256]-----+ 2026-03-14 00:17:39.370144 | controller -> localhost | ok: Runtime: 0:00:00.090458 2026-03-14 00:17:39.385712 | 2026-03-14 00:17:39.385893 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-14 00:17:39.417717 | controller | ok 2026-03-14 00:17:39.438215 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-14 00:17:39.452670 | 2026-03-14 00:17:39.452850 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-14 00:17:39.468891 | controller | skipping: Conditional result was False 2026-03-14 00:17:39.479313 | 2026-03-14 00:17:39.479472 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-14 00:17:39.918581 | controller | changed 2026-03-14 00:17:39.933361 | 2026-03-14 00:17:39.933569 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-14 00:17:40.163870 | controller | ok 2026-03-14 00:17:40.170611 | 2026-03-14 00:17:40.170713 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-14 00:17:40.775086 | controller | changed 2026-03-14 00:17:40.781529 | 2026-03-14 00:17:40.781613 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-14 00:17:41.396903 | controller | changed 2026-03-14 00:17:41.406229 | 2026-03-14 00:17:41.406332 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-14 00:17:41.432386 | controller | skipping: Conditional result was False 2026-03-14 00:17:41.447374 | 2026-03-14 00:17:41.447511 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-14 00:17:41.913729 | controller -> localhost | changed 2026-03-14 00:17:41.943917 | 2026-03-14 00:17:41.944151 | TASK [add-build-sshkey : Add back temp key] 2026-03-14 00:17:42.320669 | controller -> localhost | Identity added: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/dc96230153d54fa4a045e3b265d657d2_id_rsa (zuul-build-sshkey) 2026-03-14 00:17:42.320961 | controller -> localhost | ok: Runtime: 0:00:00.014697 2026-03-14 00:17:42.327770 | 2026-03-14 00:17:42.327839 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-14 00:17:42.708187 | controller | ok 2026-03-14 00:17:42.714860 | 2026-03-14 00:17:42.714934 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-14 00:17:42.760298 | controller | skipping: Conditional result was False 2026-03-14 00:17:42.784028 | 2026-03-14 00:17:42.784170 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-14 00:17:42.819295 | controller | ok 2026-03-14 00:17:42.841952 | 2026-03-14 00:17:42.842088 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-14 00:17:43.091338 | controller -> localhost | ok 2026-03-14 00:17:43.101453 | 2026-03-14 00:17:43.101569 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-14 00:17:43.127251 | controller | ok 2026-03-14 00:17:43.143262 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-14 00:17:43.154018 | 2026-03-14 00:17:43.154178 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-14 00:17:43.439626 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-14 00:17:43.440056 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/dc96230153d54fa4a045e3b265d657d2_id_ecdsa. 2026-03-14 00:17:43.440123 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/dc96230153d54fa4a045e3b265d657d2_id_ecdsa.pub. 2026-03-14 00:17:43.440200 | controller -> localhost | The key fingerprint is: 2026-03-14 00:17:43.440255 | controller -> localhost | SHA256:pyBgVr4FILj7lTop7W02OAUBF65UIbionJey+I8sOCQ zuul-build-sshkey 2026-03-14 00:17:43.440300 | controller -> localhost | The key's randomart image is: 2026-03-14 00:17:43.440345 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-14 00:17:43.440386 | controller -> localhost | |=o+=+ | 2026-03-14 00:17:43.440428 | controller -> localhost | |o++o . | 2026-03-14 00:17:43.440469 | controller -> localhost | |.+=.. . | 2026-03-14 00:17:43.440509 | controller -> localhost | |=+.. o | 2026-03-14 00:17:43.440549 | controller -> localhost | |+...+.. S . | 2026-03-14 00:17:43.440589 | controller -> localhost | |E= o+. . o | 2026-03-14 00:17:43.440629 | controller -> localhost | |=o+* . | 2026-03-14 00:17:43.440669 | controller -> localhost | |*oOo+ | 2026-03-14 00:17:43.440783 | controller -> localhost | | *=*o. | 2026-03-14 00:17:43.440828 | controller -> localhost | +----[SHA256]-----+ 2026-03-14 00:17:43.440923 | controller -> localhost | ok: Runtime: 0:00:00.015443 2026-03-14 00:17:43.457336 | 2026-03-14 00:17:43.457516 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-14 00:17:43.494668 | controller | ok 2026-03-14 00:17:43.507089 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-14 00:17:43.521533 | 2026-03-14 00:17:43.521650 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-14 00:17:43.558580 | controller | skipping: Conditional result was False 2026-03-14 00:17:43.575881 | 2026-03-14 00:17:43.576054 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-14 00:17:43.848297 | controller | changed 2026-03-14 00:17:43.854510 | 2026-03-14 00:17:43.854586 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-14 00:17:44.067930 | controller | ok 2026-03-14 00:17:44.074006 | 2026-03-14 00:17:44.074073 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-14 00:17:44.698302 | controller | changed 2026-03-14 00:17:44.704808 | 2026-03-14 00:17:44.704899 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-14 00:17:45.355873 | controller | changed 2026-03-14 00:17:45.369793 | 2026-03-14 00:17:45.369943 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-14 00:17:45.398929 | controller | skipping: Conditional result was False 2026-03-14 00:17:45.414510 | 2026-03-14 00:17:45.414675 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-14 00:17:45.718487 | controller -> localhost | changed 2026-03-14 00:17:45.793343 | 2026-03-14 00:17:45.793589 | TASK [add-build-sshkey : Add back temp key] 2026-03-14 00:17:46.115664 | controller -> localhost | Identity added: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/dc96230153d54fa4a045e3b265d657d2_id_ecdsa (zuul-build-sshkey) 2026-03-14 00:17:46.115940 | controller -> localhost | ok: Runtime: 0:00:00.007648 2026-03-14 00:17:46.122997 | 2026-03-14 00:17:46.123074 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-14 00:17:46.324495 | controller | ok 2026-03-14 00:17:46.338432 | 2026-03-14 00:17:46.338740 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-14 00:17:46.386957 | controller | skipping: Conditional result was False 2026-03-14 00:17:46.400979 | 2026-03-14 00:17:46.401089 | TASK [include_role : remove-zuul-sshkey] 2026-03-14 00:17:46.426150 | controller | skipping: Conditional result was False 2026-03-14 00:17:46.433848 | 2026-03-14 00:17:46.433928 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-14 00:17:46.667840 | controller | ok: "logs" 2026-03-14 00:17:46.668416 | controller | ok: All items complete 2026-03-14 00:17:46.668466 | 2026-03-14 00:17:46.882825 | controller | ok: "artifacts" 2026-03-14 00:17:47.102931 | controller | ok: "docs" 2026-03-14 00:17:47.120058 | 2026-03-14 00:17:47.120233 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-14 00:17:47.368769 | controller | changed: "logs" 2026-03-14 00:17:47.571091 | controller | changed: "artifacts" 2026-03-14 00:17:47.785037 | controller | changed: "docs" 2026-03-14 00:17:47.847134 | 2026-03-14 00:17:47.847278 | PLAY RECAP 2026-03-14 00:17:47.847339 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-14 00:17:47.847376 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-14 00:17:47.847401 | 2026-03-14 00:17:48.009668 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-14 00:17:48.011108 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-14 00:17:48.606003 | 2026-03-14 00:17:48.606139 | PLAY [all] 2026-03-14 00:17:48.629042 | 2026-03-14 00:17:48.629179 | TASK [Install binary dependencies] 2026-03-14 00:17:48.693041 | controller | ok 2026-03-14 00:17:48.723375 | 2026-03-14 00:17:48.723587 | TASK [bindep : Include find tasks] 2026-03-14 00:17:48.756841 | controller | ok 2026-03-14 00:17:48.768815 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-14 00:17:48.778092 | 2026-03-14 00:17:48.778205 | TASK [bindep : Look for bindep.txt] 2026-03-14 00:17:49.156412 | controller | ok 2026-03-14 00:17:49.162718 | 2026-03-14 00:17:49.162791 | TASK [bindep : Define bindep_file fact] 2026-03-14 00:17:49.176837 | controller | skipping: Conditional result was False 2026-03-14 00:17:49.184118 | 2026-03-14 00:17:49.184214 | TASK [bindep : Look for other-requirements.txt] 2026-03-14 00:17:49.387634 | controller | ok 2026-03-14 00:17:49.393702 | 2026-03-14 00:17:49.393785 | TASK [bindep : Define bindep_file fact] 2026-03-14 00:17:49.418470 | controller | skipping: Conditional result was False 2026-03-14 00:17:49.431555 | 2026-03-14 00:17:49.431754 | TASK [bindep : Look for bindep fallback file] 2026-03-14 00:17:49.459144 | controller | skipping: Conditional result was False 2026-03-14 00:17:49.477870 | 2026-03-14 00:17:49.478076 | TASK [bindep : Define bindep_file fact] 2026-03-14 00:17:49.505846 | controller | skipping: Conditional result was False 2026-03-14 00:17:49.522380 | 2026-03-14 00:17:49.522601 | TASK [bindep : Include bindep tasks] 2026-03-14 00:17:49.560827 | controller | skipping: Conditional result was False 2026-03-14 00:17:49.576662 | 2026-03-14 00:17:49.576933 | TASK [bindep : Include install tasks] 2026-03-14 00:17:49.605127 | controller | skipping: Conditional result was False 2026-03-14 00:17:49.616404 | 2026-03-14 00:17:49.616555 | LOOP [bindep : Include package tasks] 2026-03-14 00:17:49.707293 | 2026-03-14 00:17:49.707591 | TASK [Run test-setup role] 2026-03-14 00:17:49.733650 | controller | ok 2026-03-14 00:17:49.759963 | 2026-03-14 00:17:49.760140 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-14 00:17:49.965392 | controller | ok 2026-03-14 00:17:49.972146 | 2026-03-14 00:17:49.972242 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-14 00:17:50.503950 | controller | skipping: Conditional result was False 2026-03-14 00:17:50.567853 | 2026-03-14 00:17:50.567983 | PLAY RECAP 2026-03-14 00:17:50.568046 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-14 00:17:50.568078 | 2026-03-14 00:17:50.725266 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-14 00:17:50.727318 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-14 00:17:51.394436 | 2026-03-14 00:17:51.394594 | PLAY [controller] 2026-03-14 00:17:51.415014 | 2026-03-14 00:17:51.415114 | TASK [Create the /root directory] 2026-03-14 00:17:51.826639 | controller | ok 2026-03-14 00:17:51.834764 | 2026-03-14 00:17:51.834864 | TASK [Install glibc-langpack-en] 2026-03-14 00:17:55.792523 | controller | ok: Nothing to do 2026-03-14 00:17:55.804476 | 2026-03-14 00:17:55.804640 | TASK [Ensure controller directory exists] 2026-03-14 00:17:56.070558 | controller | changed 2026-03-14 00:17:56.086132 | 2026-03-14 00:17:56.086284 | TASK [Install container runtime] 2026-03-14 00:17:56.164908 | controller | ok 2026-03-14 00:17:56.223911 | 2026-03-14 00:17:56.224054 | LOOP [ensure-docker : Gather variables for each operating system] 2026-03-14 00:17:56.268030 | controller | ok: "/var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-03-14 00:17:56.276564 | 2026-03-14 00:17:56.276670 | TASK [ensure-docker : Sanity check for distro selection] 2026-03-14 00:17:56.301453 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.312541 | 2026-03-14 00:17:56.312727 | TASK [ensure-docker : Sanity check for distro install] 2026-03-14 00:17:56.330537 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.341715 | 2026-03-14 00:17:56.341888 | TASK [ensure-docker : Sanity check for upstream install] 2026-03-14 00:17:56.379163 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.388592 | 2026-03-14 00:17:56.388739 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-03-14 00:17:56.414291 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.431496 | 2026-03-14 00:17:56.431726 | TASK [ensure-docker : Include tasks for docker install] 2026-03-14 00:17:56.463071 | controller | ok 2026-03-14 00:17:56.477641 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-03-14 00:17:56.494281 | 2026-03-14 00:17:56.494450 | TASK [ensure-docker : Not Implemented] 2026-03-14 00:17:56.510751 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.521223 | 2026-03-14 00:17:56.521377 | TASK [Set up docker mirrors] 2026-03-14 00:17:56.552255 | controller | ok 2026-03-14 00:17:56.589831 | 2026-03-14 00:17:56.590001 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-03-14 00:17:56.617573 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.626877 | 2026-03-14 00:17:56.626976 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-03-14 00:17:56.652484 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.661724 | 2026-03-14 00:17:56.661884 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-03-14 00:17:56.688504 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.695421 | 2026-03-14 00:17:56.695517 | TASK [use-docker-mirror : Set up docker mirrors] 2026-03-14 00:17:56.719544 | controller | skipping: Conditional result was False 2026-03-14 00:17:56.733004 | 2026-03-14 00:17:56.733100 | TASK [Add all repositories] 2026-03-14 00:17:56.752119 | controller | ok 2026-03-14 00:17:56.774937 | 2026-03-14 00:17:56.775053 | LOOP [ensure-package-repositories : Install repositories] 2026-03-14 00:17:56.802080 | controller | ok: "/var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-03-14 00:17:56.802227 | controller | ok: All items complete 2026-03-14 00:17:56.802253 | 2026-03-14 00:17:56.855018 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-03-14 00:17:56.862229 | 2026-03-14 00:17:56.862340 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-03-14 00:17:57.193536 | controller | changed 2026-03-14 00:17:57.201781 | 2026-03-14 00:17:57.201920 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-03-14 00:17:58.159336 | controller | changed: 2026-03-14 00:17:58.159658 | controller | { 2026-03-14 00:17:58.159776 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-03-14 00:17:58.159821 | controller | } 2026-03-14 00:17:58.178281 | 2026-03-14 00:17:58.178446 | LOOP [ensure-package-repositories : Import GPG keys] 2026-03-14 00:17:58.816895 | controller | changed: 2026-03-14 00:17:58.817345 | controller | { 2026-03-14 00:17:58.817421 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-03-14 00:17:58.817476 | controller | } 2026-03-14 00:17:58.817536 | controller | changed: All items complete 2026-03-14 00:17:58.817579 | 2026-03-14 00:17:58.841376 | 2026-03-14 00:17:58.841809 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-03-14 00:17:59.089316 | controller | changed 2026-03-14 00:17:59.101945 | 2026-03-14 00:17:59.102147 | LOOP [ensure-package-repositories : Add repositories] 2026-03-14 00:17:59.475256 | controller | changed: 2026-03-14 00:17:59.475476 | controller | { 2026-03-14 00:17:59.475521 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-03-14 00:17:59.475556 | controller | "description": "Docker CE Stable - $basearch", 2026-03-14 00:17:59.475584 | controller | "gpgcheck": true, 2026-03-14 00:17:59.475609 | controller | "name": "docker-ce-stable" 2026-03-14 00:17:59.475634 | controller | } 2026-03-14 00:17:59.489653 | 2026-03-14 00:17:59.489823 | TASK [ensure-docker : Install pre-reqs] 2026-03-14 00:18:02.429515 | controller | ok: Nothing to do 2026-03-14 00:18:02.443451 | 2026-03-14 00:18:02.443602 | TASK [ensure-docker : Remove packages] 2026-03-14 00:18:04.001420 | controller | ok: Nothing to do 2026-03-14 00:18:04.015915 | 2026-03-14 00:18:04.016121 | TASK [ensure-docker : Install upstream docker using package] 2026-03-14 00:18:50.188561 | controller | changed 2026-03-14 00:18:50.201422 | 2026-03-14 00:18:50.201551 | TASK [ensure-docker : Setup docker] 2026-03-14 00:18:50.227736 | controller | ok 2026-03-14 00:18:50.244280 | controller | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-03-14 00:18:50.264196 | 2026-03-14 00:18:50.264328 | TASK [ensure-docker : Ensure "docker" group exists] 2026-03-14 00:18:50.667945 | controller | ok 2026-03-14 00:18:50.674384 | 2026-03-14 00:18:50.674483 | TASK [ensure-docker : Add user to docker group] 2026-03-14 00:18:51.191594 | controller | changed 2026-03-14 00:18:51.201872 | 2026-03-14 00:18:51.201981 | TASK [Update docker daemon proxy configuration] 2026-03-14 00:18:51.229873 | controller | skipping: Conditional result was False 2026-03-14 00:18:51.239965 | 2026-03-14 00:18:51.240071 | TASK [Lower default MTU] 2026-03-14 00:18:51.270569 | controller | skipping: Conditional result was False 2026-03-14 00:18:51.281109 | 2026-03-14 00:18:51.281219 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-03-14 00:18:51.695181 | controller | inactive 2026-03-14 00:18:51.887936 | controller | ok: Runtime: 0:00:00.014685 2026-03-14 00:18:51.904464 | 2026-03-14 00:18:51.904713 | TASK [ensure-docker : Get the status of the docket unit] 2026-03-14 00:18:52.159235 | controller | inactive 2026-03-14 00:18:52.469760 | controller | ok: Runtime: 0:00:00.013439 2026-03-14 00:18:52.477655 | 2026-03-14 00:18:52.477809 | TASK [ensure-docker : Restart docker] 2026-03-14 00:18:52.522167 | Trigger a Docker restart 2026-03-14 00:18:52.528952 | 2026-03-14 00:18:52.529050 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-03-14 00:18:52.562950 | 2026-03-14 00:18:52.563054 | TASK [ensure-docker : Install docker-compose] 2026-03-14 00:18:52.577863 | controller | skipping: Conditional result was False 2026-03-14 00:18:52.586120 | 2026-03-14 00:18:52.586269 | TASK [ensure-docker : Flush handlers before role exit] 2026-03-14 00:18:52.600302 | 2026-03-14 00:18:52.600412 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-03-14 00:18:53.816471 | controller | changed 2026-03-14 00:18:53.843083 | 2026-03-14 00:18:53.843259 | TASK [ensure-docker : Assure docker service is running] 2026-03-14 00:18:55.834134 | controller | changed 2026-03-14 00:18:55.852128 | 2026-03-14 00:18:55.852269 | TASK [ensure-docker : Assure docker.socket service is running] 2026-03-14 00:18:57.071133 | controller | ok 2026-03-14 00:18:57.088455 | 2026-03-14 00:18:57.088562 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-03-14 00:18:57.706738 | controller | ok 2026-03-14 00:18:57.723991 | 2026-03-14 00:18:57.724199 | TASK [ensure-docker : Validate ability to talk with docker] 2026-03-14 00:18:58.120365 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-03-14 00:18:58.278659 | controller | ok: Runtime: 0:00:00.041806 2026-03-14 00:18:58.296972 | 2026-03-14 00:18:58.297231 | TASK [ensure-docker : Get version details] 2026-03-14 00:18:58.872460 | controller | Client: Docker Engine - Community 2026-03-14 00:18:58.874405 | controller | Version: 24.0.7 2026-03-14 00:18:58.874418 | controller | API version: 1.43 2026-03-14 00:18:58.874423 | controller | Go version: go1.20.10 2026-03-14 00:18:58.874434 | controller | Git commit: afdd53b 2026-03-14 00:18:58.874439 | controller | Built: Thu Oct 26 09:09:39 2023 2026-03-14 00:18:58.874444 | controller | OS/Arch: linux/amd64 2026-03-14 00:18:58.874450 | controller | Context: default 2026-03-14 00:18:58.874456 | controller | 2026-03-14 00:18:58.874461 | controller | Server: Docker Engine - Community 2026-03-14 00:18:58.874465 | controller | Engine: 2026-03-14 00:18:58.874470 | controller | Version: 24.0.7 2026-03-14 00:18:58.874474 | controller | API version: 1.43 (minimum version 1.12) 2026-03-14 00:18:58.874478 | controller | Go version: go1.20.10 2026-03-14 00:18:58.874482 | controller | Git commit: 311b9ff 2026-03-14 00:18:58.874486 | controller | Built: Thu Oct 26 09:08:11 2023 2026-03-14 00:18:58.874491 | controller | OS/Arch: linux/amd64 2026-03-14 00:18:58.874495 | controller | Experimental: false 2026-03-14 00:18:58.874499 | controller | containerd: 2026-03-14 00:18:58.874503 | controller | Version: 1.6.25 2026-03-14 00:18:58.874507 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-03-14 00:18:58.874511 | controller | runc: 2026-03-14 00:18:58.874516 | controller | Version: 1.1.10 2026-03-14 00:18:58.874520 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-03-14 00:18:58.874525 | controller | docker-init: 2026-03-14 00:18:58.874529 | controller | Version: 0.19.0 2026-03-14 00:18:58.874534 | controller | GitCommit: de40ad0 2026-03-14 00:18:59.359292 | controller | ok: Runtime: 0:00:00.066857 2026-03-14 00:18:59.369474 | 2026-03-14 00:18:59.369695 | TASK [ensure-docker : Dump installed docker details] 2026-03-14 00:18:59.422893 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-03-14 00:18:58.807904', 'end': '2026-03-14 00:18:58.874761', 'delta': '0:00:00.066857', 'msg': '', 'zuul_log_id': 'fa163e3b-3c83-4b57-7ff5-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2026-03-14 00:18:59.434964 | 2026-03-14 00:18:59.435113 | TASK [Use buildset registry] 2026-03-14 00:18:59.466377 | controller | skipping: Conditional result was False 2026-03-14 00:18:59.486399 | 2026-03-14 00:18:59.486595 | TASK [Ensure python3.8 is present] 2026-03-14 00:18:59.514378 | controller | skipping: Conditional result was False 2026-03-14 00:18:59.523065 | 2026-03-14 00:18:59.523153 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-14 00:18:59.549854 | controller | ok 2026-03-14 00:18:59.573098 | 2026-03-14 00:18:59.573607 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-14 00:19:01.491524 | controller | ok: Nothing to do 2026-03-14 00:19:01.505092 | 2026-03-14 00:19:01.505360 | TASK [our-ensure-python : Also install python3-devel] 2026-03-14 00:19:12.725539 | controller | changed 2026-03-14 00:19:12.741948 | 2026-03-14 00:19:12.742102 | TASK [Run ensure-virtualenv role] 2026-03-14 00:19:12.769634 | controller | ok 2026-03-14 00:19:12.803628 | 2026-03-14 00:19:12.803828 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-03-14 00:19:13.018369 | controller | /usr/bin/virtualenv 2026-03-14 00:19:13.339854 | controller | ok: Runtime: 0:00:00.005011 2026-03-14 00:19:13.358322 | 2026-03-14 00:19:13.358629 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-03-14 00:19:13.401153 | controller | skipping: Conditional result was False 2026-03-14 00:19:13.401822 | controller | changed: All items complete 2026-03-14 00:19:13.401897 | 2026-03-14 00:19:13.440405 | 2026-03-14 00:19:13.440655 | TASK [Find the full path of the Python interpreter] 2026-03-14 00:19:13.678635 | controller | /usr/bin/python3.9 2026-03-14 00:19:13.992964 | controller | ok 2026-03-14 00:19:14.007047 | 2026-03-14 00:19:14.007201 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-03-14 00:19:15.663776 | controller | created virtual environment CPython3.9.18.final.0-64 in 825ms 2026-03-14 00:19:15.708829 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-03-14 00:19:15.708846 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-03-14 00:19:15.708856 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-03-14 00:19:15.708875 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-03-14 00:19:16.068063 | controller | changed 2026-03-14 00:19:16.076588 | 2026-03-14 00:19:16.076736 | TASK [Set selinux package] 2026-03-14 00:19:16.107133 | controller | ok 2026-03-14 00:19:16.119237 | 2026-03-14 00:19:16.119359 | TASK [Set selinux package (Fedora)] 2026-03-14 00:19:16.153914 | controller | ok 2026-03-14 00:19:16.162921 | 2026-03-14 00:19:16.163065 | TASK [Install selinux into virtualenv] 2026-03-14 00:19:54.856747 | controller | Collecting selinux-please-lie-to-me 2026-03-14 00:19:57.968508 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-03-14 00:19:58.332063 | controller | Collecting setuptools<50.0.0 2026-03-14 00:19:58.360046 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-03-14 00:19:58.460854 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.3 MB/s eta 0:00:00 2026-03-14 00:19:58.539326 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-03-14 00:19:58.539455 | controller | Attempting uninstall: setuptools 2026-03-14 00:19:58.540191 | controller | Found existing installation: setuptools 62.6.0 2026-03-14 00:19:58.611578 | controller | Uninstalling setuptools-62.6.0: 2026-03-14 00:19:58.620380 | controller | Successfully uninstalled setuptools-62.6.0 2026-03-14 00:19:58.952875 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-03-14 00:20:17.169577 | controller | 2026-03-14 00:20:17.255141 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-14 00:20:17.255198 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-14 00:20:17.731435 | controller | ok: Runtime: 0:01:00.764033 2026-03-14 00:20:17.738080 | 2026-03-14 00:20:17.738159 | TASK [Install pytest-forked into virtualenv] 2026-03-14 00:20:30.640337 | controller | Collecting pytest-forked 2026-03-14 00:20:33.735834 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-03-14 00:20:33.780039 | controller | Collecting py 2026-03-14 00:20:33.804020 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-03-14 00:20:33.847152 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.4 MB/s eta 0:00:00 2026-03-14 00:20:33.966533 | controller | Collecting pytest>=3.10 2026-03-14 00:20:33.991449 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-03-14 00:20:34.029924 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 10.4 MB/s eta 0:00:00 2026-03-14 00:20:34.093575 | controller | Collecting pygments>=2.7.2 2026-03-14 00:20:34.116462 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-03-14 00:20:34.157704 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 33.3 MB/s eta 0:00:00 2026-03-14 00:20:34.188655 | controller | Collecting iniconfig>=1 2026-03-14 00:20:34.211170 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2026-03-14 00:20:34.249889 | controller | Collecting exceptiongroup>=1 2026-03-14 00:20:34.272584 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-03-14 00:20:34.310297 | controller | Collecting pluggy<2,>=1.5 2026-03-14 00:20:34.333624 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-03-14 00:20:34.383560 | controller | Collecting packaging>=20 2026-03-14 00:20:34.407284 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-03-14 00:20:34.417383 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 10.1 MB/s eta 0:00:00 2026-03-14 00:20:34.475547 | controller | Collecting tomli>=1 2026-03-14 00:20:34.499016 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-03-14 00:20:34.556546 | controller | Collecting typing-extensions>=4.6.0 2026-03-14 00:20:34.580097 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-03-14 00:20:34.587800 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.9 MB/s eta 0:00:00 2026-03-14 00:20:34.656424 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-03-14 00:20:35.704109 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.1.0 packaging-26.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-03-14 00:20:35.708442 | controller | 2026-03-14 00:20:35.784268 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-14 00:20:35.784300 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-14 00:20:35.833865 | controller | ok: Runtime: 0:00:17.794733 2026-03-14 00:20:35.845132 | 2026-03-14 00:20:35.845231 | TASK [Update pip] 2026-03-14 00:20:36.369695 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2026-03-14 00:21:12.704291 | controller | Collecting pip 2026-03-14 00:21:15.917351 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-03-14 00:21:16.919932 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 1.8 MB/s eta 0:00:00 2026-03-14 00:21:16.976903 | controller | Installing collected packages: pip 2026-03-14 00:21:16.977144 | controller | Attempting uninstall: pip 2026-03-14 00:21:16.977785 | controller | Found existing installation: pip 22.2.2 2026-03-14 00:21:17.140353 | controller | Uninstalling pip-22.2.2: 2026-03-14 00:21:17.162182 | controller | Successfully uninstalled pip-22.2.2 2026-03-14 00:21:17.948459 | controller | Successfully installed pip-26.0.1 2026-03-14 00:21:18.500536 | controller | ok: Runtime: 0:00:41.957423 2026-03-14 00:21:18.513400 | 2026-03-14 00:21:18.513540 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-03-14 00:21:18.736170 | controller | changed 2026-03-14 00:21:18.748111 | 2026-03-14 00:21:18.748242 | TASK [Install ansible into virtualenv] 2026-03-14 00:21:19.251247 | controller | Processing ./src/github.com/ansible/ansible 2026-03-14 00:21:19.254325 | controller | Installing build dependencies: started 2026-03-14 00:21:35.376868 | controller | Installing build dependencies: finished with status 'done' 2026-03-14 00:21:35.379067 | controller | Getting requirements to build wheel: started 2026-03-14 00:21:35.997789 | controller | Getting requirements to build wheel: finished with status 'done' 2026-03-14 00:21:36.000505 | controller | Preparing metadata (pyproject.toml): started 2026-03-14 00:21:36.453429 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-03-14 00:21:48.897043 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2026-03-14 00:21:51.957908 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-03-14 00:21:52.035000 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2026-03-14 00:21:52.062090 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-03-14 00:21:52.346829 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2026-03-14 00:21:52.373137 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-03-14 00:21:52.388423 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (26.0) 2026-03-14 00:21:52.402908 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2026-03-14 00:21:52.435600 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-03-14 00:21:52.602375 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2026-03-14 00:21:52.629470 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-03-14 00:21:52.638309 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.9/site-packages (from cryptography->ansible-core==2.11.12.post0) (4.15.0) 2026-03-14 00:21:52.656264 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2026-03-14 00:21:52.682063 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2026-03-14 00:21:52.779562 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2026-03-14 00:21:52.809043 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-03-14 00:21:52.845610 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-03-14 00:21:52.879758 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-03-14 00:21:53.054378 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 41.7 MB/s 0:00:00 2026-03-14 00:21:53.085066 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-03-14 00:21:53.119960 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-03-14 00:21:53.157899 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-03-14 00:21:53.193768 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2026-03-14 00:21:53.229377 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2026-03-14 00:21:53.244685 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 44.2 MB/s 0:00:00 2026-03-14 00:21:53.258398 | controller | Building wheels for collected packages: ansible-core 2026-03-14 00:21:53.259362 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-03-14 00:21:54.733655 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-03-14 00:21:54.736685 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=88b43b5a67b1b42df52ff20b01499dddd27b62d0069729d59a8ea297868685f0 2026-03-14 00:21:54.736844 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-q826edak/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2026-03-14 00:21:54.745428 | controller | Successfully built ansible-core 2026-03-14 00:21:54.776380 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-03-14 00:21:56.212046 | controller | 2026-03-14 00:21:56.217112 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.5 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2026-03-14 00:21:56.831209 | controller | ok: Runtime: 0:00:37.373555 2026-03-14 00:21:56.845333 | 2026-03-14 00:21:56.845511 | TASK [Setup download-artifact-fork role] 2026-03-14 00:21:56.875457 | controller | ok 2026-03-14 00:21:56.910059 | 2026-03-14 00:21:56.910178 | TASK [download-artifact-fork : Parse inventory response] 2026-03-14 00:21:56.967507 | controller | ok 2026-03-14 00:21:56.980486 | 2026-03-14 00:21:56.980621 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-03-14 00:21:57.005591 | controller | skipping: Conditional result was False 2026-03-14 00:21:57.014070 | 2026-03-14 00:21:57.014160 | TASK [download-artifact-fork : Parse build response] 2026-03-14 00:21:57.039207 | controller | skipping: Conditional result was False 2026-03-14 00:21:57.055897 | 2026-03-14 00:21:57.056078 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-03-14 00:21:57.275601 | controller | changed 2026-03-14 00:21:57.283848 | 2026-03-14 00:21:57.283951 | LOOP [download-artifact-fork : Download archive by type] 2026-03-14 00:21:57.326455 | controller | skipping: Conditional result was False 2026-03-14 00:21:57.326900 | 2026-03-14 00:21:58.089308 | controller | changed: OK (20202 bytes) 2026-03-14 00:21:58.110835 | 2026-03-14 00:21:58.111030 | TASK [deploy-artifacts : define __collections] 2026-03-14 00:21:58.145215 | controller | ok 2026-03-14 00:21:58.153106 | 2026-03-14 00:21:58.153476 | LOOP [deploy-artifacts : Create list of collections] 2026-03-14 00:21:58.194330 | controller | skipping: Conditional result was False 2026-03-14 00:21:58.221837 | controller | ok: 2026-03-14 00:21:58.222050 | controller | { 2026-03-14 00:21:58.222141 | controller | "branch": "main", 2026-03-14 00:21:58.222220 | controller | "job": "build-ansible-collection", 2026-03-14 00:21:58.222287 | controller | "metadata": { 2026-03-14 00:21:58.222365 | controller | "type": "ansible_collection", 2026-03-14 00:21:58.222581 | controller | "version": "5.0.0" 2026-03-14 00:21:58.222664 | controller | }, 2026-03-14 00:21:58.222778 | controller | "name": "ansible.network", 2026-03-14 00:21:58.222839 | controller | "project": "ansible-collections/ansible.network", 2026-03-14 00:21:58.222896 | controller | "url": "https://37583fd57b2f38de0882-ca5bce7feff73ce685e7c8109acd08a6.ssl.cf1.rackcdn.com/ansible/ea4b63013917479e88daaa7bf1fb1aec/artifacts/ansible-network-5.0.0.tar.gz" 2026-03-14 00:21:58.222968 | controller | } 2026-03-14 00:21:58.242626 | 2026-03-14 00:21:58.242805 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-03-14 00:21:58.897106 | controller | Starting galaxy collection install process 2026-03-14 00:21:58.897311 | controller | Process install dependency map 2026-03-14 00:22:52.907330 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2026-03-14 00:22:52.908276 | controller | unexpected error when getting available versions of collection 2026-03-14 00:22:52.908300 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2026-03-14 00:22:52.908305 | controller | ansible/netcommon/versions/' 2026-03-14 00:22:52.908321 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2026-03-14 00:22:52.947394 | controller | to see the full traceback, use -vvv 2026-03-14 00:22:53.364284 | controller | ERROR 2026-03-14 00:22:53.364521 | controller | { 2026-03-14 00:22:53.364553 | controller | "delta": "0:00:54.438161", 2026-03-14 00:22:53.364573 | controller | "end": "2026-03-14 00:22:52.948049", 2026-03-14 00:22:53.364590 | controller | "msg": "non-zero return code", 2026-03-14 00:22:53.364605 | controller | "rc": 250, 2026-03-14 00:22:53.364622 | controller | "start": "2026-03-14 00:21:58.509888" 2026-03-14 00:22:53.364637 | controller | } failure 2026-03-14 00:22:53.366514 | 2026-03-14 00:22:53.366572 | PLAY RECAP 2026-03-14 00:22:53.366623 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2026-03-14 00:22:53.366651 | 2026-03-14 00:22:53.494160 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-14 00:22:53.495540 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-14 00:22:54.093525 | 2026-03-14 00:22:54.093975 | PLAY [all] 2026-03-14 00:22:54.118601 | 2026-03-14 00:22:54.118765 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-14 00:22:54.573199 | controller | changed: non-zero return code 2026-03-14 00:22:54.597309 | 2026-03-14 00:22:54.597577 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-14 00:22:54.622384 | controller | skipping: Conditional result was False 2026-03-14 00:22:54.630996 | 2026-03-14 00:22:54.631090 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-14 00:22:54.665089 | 2026-03-14 00:22:54.665272 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-14 00:22:54.698270 | 2026-03-14 00:22:54.698438 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-14 00:22:54.723732 | controller | skipping: Conditional result was False 2026-03-14 00:22:54.733282 | 2026-03-14 00:22:54.733371 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-14 00:22:54.765847 | 2026-03-14 00:22:54.766016 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-14 00:22:54.790555 | controller | skipping: Conditional result was False 2026-03-14 00:22:54.798723 | 2026-03-14 00:22:54.798809 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-14 00:22:54.823209 | controller | skipping: Conditional result was False 2026-03-14 00:22:54.831422 | 2026-03-14 00:22:54.831508 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-14 00:22:54.855977 | controller | skipping: Conditional result was False 2026-03-14 00:22:54.892388 | 2026-03-14 00:22:54.892465 | PLAY RECAP 2026-03-14 00:22:54.892517 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-14 00:22:54.892545 | 2026-03-14 00:22:54.992610 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-14 00:22:54.995198 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-14 00:22:55.616165 | 2026-03-14 00:22:55.616290 | PLAY [all:!appliance*] 2026-03-14 00:22:55.652272 | 2026-03-14 00:22:55.652390 | TASK [unregister the node] 2026-03-14 00:22:56.185600 | controller | skipping: Conditional result was False 2026-03-14 00:22:56.198557 | 2026-03-14 00:22:56.198755 | TASK [include_role : fetch-output] 2026-03-14 00:22:56.244997 | controller | ok 2026-03-14 00:22:56.275083 | 2026-03-14 00:22:56.275232 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-14 00:22:56.341950 | controller | skipping: Conditional result was False 2026-03-14 00:22:56.352119 | 2026-03-14 00:22:56.355394 | TASK [fetch-output : Set log path for single node] 2026-03-14 00:22:56.412602 | controller | ok 2026-03-14 00:22:56.420779 | 2026-03-14 00:22:56.421076 | LOOP [fetch-output : Ensure local output dirs] 2026-03-14 00:22:56.857793 | controller -> localhost | ok: "/var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/logs" 2026-03-14 00:22:57.133994 | controller -> localhost | changed: "/var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/artifacts" 2026-03-14 00:22:57.391325 | controller -> localhost | changed: "/var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/work/docs" 2026-03-14 00:22:57.408519 | 2026-03-14 00:22:57.408674 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-14 00:22:58.140739 | controller | changed: 2026-03-14 00:22:58.141146 | controller | .d..t...... ./ 2026-03-14 00:22:58.141200 | controller | cd+++++++++ controller/ 2026-03-14 00:22:58.141253 | controller | changed: All items complete 2026-03-14 00:22:58.141285 | 2026-03-14 00:22:58.699806 | controller | changed: .d..t...... ./ 2026-03-14 00:22:59.224784 | controller | changed: .d..t...... ./ 2026-03-14 00:22:59.252088 | 2026-03-14 00:22:59.252317 | TASK [include_role : fetch-output-openshift] 2026-03-14 00:22:59.282170 | controller | skipping: Conditional result was False 2026-03-14 00:22:59.292499 | 2026-03-14 00:22:59.292617 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-14 00:22:59.322617 | controller | skipping: Conditional result was False 2026-03-14 00:22:59.334936 | controller | skipping: Conditional result was False 2026-03-14 00:22:59.385150 | 2026-03-14 00:22:59.385296 | PLAY [localhost] 2026-03-14 00:22:59.400282 | 2026-03-14 00:22:59.400391 | TASK [Run Zuul manifest role] 2026-03-14 00:22:59.420552 | localhost | ok 2026-03-14 00:22:59.444562 | 2026-03-14 00:22:59.444818 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-14 00:22:59.876917 | localhost | changed 2026-03-14 00:22:59.882150 | 2026-03-14 00:22:59.882218 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-14 00:22:59.937054 | localhost | ok 2026-03-14 00:22:59.945947 | 2026-03-14 00:22:59.946014 | TASK [Set zuul-log-path fact] 2026-03-14 00:22:59.972598 | localhost | ok 2026-03-14 00:22:59.989919 | 2026-03-14 00:22:59.990079 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-14 00:23:00.019158 | localhost | ok 2026-03-14 00:23:00.027391 | 2026-03-14 00:23:00.027459 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-14 00:23:00.072274 | localhost | ok: 2026-03-14 00:23:00.072468 | localhost | { 2026-03-14 00:23:00.072506 | localhost | "auth": { 2026-03-14 00:23:00.072556 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-14 00:23:00.072584 | localhost | "username": "9LF91O0RK7JU" 2026-03-14 00:23:00.072607 | localhost | }, 2026-03-14 00:23:00.072631 | localhost | "profile": "rackspace", 2026-03-14 00:23:00.072656 | localhost | "region_name": "IAD" 2026-03-14 00:23:00.072692 | localhost | } 2026-03-14 00:23:00.072732 | 2026-03-14 00:23:00.079876 | 2026-03-14 00:23:00.080055 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-14 00:23:00.101011 | localhost | Output suppressed because no_log was given 2026-03-14 00:23:00.117063 | 2026-03-14 00:23:00.117194 | TASK [upload-logs-failover : Initialize control vars] 2026-03-14 00:23:00.148753 | localhost | ok 2026-03-14 00:23:00.153864 | 2026-03-14 00:23:00.153936 | LOOP [upload-logs-failover : Perform log upload] 2026-03-14 00:23:00.264133 | localhost | Output suppressed because no_log was given 2026-03-14 00:23:00.278602 | localhost | included: /var/lib/zuul/builds/dc96230153d54fa4a045e3b265d657d2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-14 00:23:00.294799 | 2026-03-14 00:23:00.295065 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-14 00:23:00.430545 | localhost | Output suppressed because no_log was given 2026-03-14 00:23:00.438603 | 2026-03-14 00:23:00.438746 | TASK [Upload logs to swift] 2026-03-14 00:23:00.470232 | localhost | ok 2026-03-14 00:23:00.507018 | 2026-03-14 00:23:00.507237 | TASK [Set zuul-log-path fact] 2026-03-14 00:23:00.536782 | localhost | skipping: Conditional result was False 2026-03-14 00:23:00.543948 | 2026-03-14 00:23:00.544067 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-14 00:23:00.951575 | localhost -> localhost | ok: Runtime: 0:00:00.004936 2026-03-14 00:23:00.957928 | 2026-03-14 00:23:00.958007 | TASK [upload-logs-swift : Upload logs to swift]