2025-10-09 00:18:38.911285 | Job console starting... 2025-10-09 00:18:38.924200 | Updating repositories 2025-10-09 00:18:39.043052 | Preparing job workspace 2025-10-09 00:18:42.671898 | Running Ansible setup... 2025-10-09 00:18:47.567474 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-09 00:18:48.209601 | 2025-10-09 00:18:48.209897 | PLAY [localhost] 2025-10-09 00:18:48.219031 | 2025-10-09 00:18:48.219161 | TASK [Gathering Facts] 2025-10-09 00:18:49.198556 | localhost | ok 2025-10-09 00:18:49.234042 | 2025-10-09 00:18:49.234246 | TASK [Setup log path fact] 2025-10-09 00:18:49.255652 | localhost | ok 2025-10-09 00:18:49.272995 | 2025-10-09 00:18:49.273132 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-09 00:18:49.294565 | localhost | ok 2025-10-09 00:18:49.307180 | 2025-10-09 00:18:49.307333 | TASK [emit-job-header : Print job information] 2025-10-09 00:18:49.336156 | # Job Information 2025-10-09 00:18:49.336313 | Ansible Version: 2.15.12 2025-10-09 00:18:49.336344 | Job: ansible-test-sanity-docker-stable-2.11 2025-10-09 00:18:49.336365 | Pipeline: periodic 2025-10-09 00:18:49.336393 | Executor: ze02.softwarefactory-project.io 2025-10-09 00:18:49.336412 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-09 00:18:49.336434 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/7ee/ansible/7ee9952ea6b64438b9541d31333ae1be/ 2025-10-09 00:18:49.336453 | Event ID: 7e5f5b9352a645a081c4b0c8a3c28952 2025-10-09 00:18:49.340184 | 2025-10-09 00:18:49.340263 | LOOP [emit-job-header : Print node information] 2025-10-09 00:18:49.443978 | localhost | ok: 2025-10-09 00:18:49.444272 | localhost | # Node Information 2025-10-09 00:18:49.444313 | localhost | Inventory Hostname: controller 2025-10-09 00:18:49.444344 | localhost | Hostname: np0005477946 2025-10-09 00:18:49.444373 | localhost | Username: zuul 2025-10-09 00:18:49.444406 | localhost | Distro: Fedora 37 2025-10-09 00:18:49.444433 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-10-09 00:18:49.444459 | localhost | Region: ca-ymq-1 2025-10-09 00:18:49.444483 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-09 00:18:49.444723 | localhost | Product Name: OpenStack Nova 2025-10-09 00:18:49.444818 | localhost | Interface IP: 199.204.45.162 2025-10-09 00:18:49.466545 | 2025-10-09 00:18:49.466777 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-09 00:18:49.890038 | localhost -> localhost | changed 2025-10-09 00:18:49.896489 | 2025-10-09 00:18:49.896590 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-09 00:18:50.780335 | localhost -> localhost | changed 2025-10-09 00:18:50.823146 | 2025-10-09 00:18:50.823268 | PLAY [all:!appliance*] 2025-10-09 00:18:50.864977 | 2025-10-09 00:18:50.865200 | TASK [include_role : start-zuul-console] 2025-10-09 00:18:50.897849 | controller | ok 2025-10-09 00:18:50.919601 | 2025-10-09 00:18:50.919739 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-09 00:18:51.294560 | controller | ok 2025-10-09 00:18:51.313651 | 2025-10-09 00:18:51.313798 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-09 00:18:52.359128 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-09 00:18:52.368607 | 2025-10-09 00:18:52.368775 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-09 00:18:52.906755 | controller | skipping: Conditional result was False 2025-10-09 00:18:52.913647 | 2025-10-09 00:18:52.913796 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-09 00:18:52.939379 | controller | skipping: Conditional result was False 2025-10-09 00:18:52.946223 | 2025-10-09 00:18:52.946448 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-09 00:18:52.971178 | controller | skipping: Conditional result was False 2025-10-09 00:18:52.986407 | 2025-10-09 00:18:52.986572 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-09 00:18:53.011719 | controller | skipping: Conditional result was False 2025-10-09 00:18:53.032797 | 2025-10-09 00:18:53.032976 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-09 00:18:53.058490 | controller | skipping: Conditional result was False 2025-10-09 00:18:53.067737 | 2025-10-09 00:18:53.067845 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-09 00:18:53.092091 | controller | skipping: Conditional result was False 2025-10-09 00:18:53.108773 | 2025-10-09 00:18:53.108898 | TASK [Disable Fedora Modular] 2025-10-09 00:18:53.392955 | controller | changed 2025-10-09 00:18:53.400376 | 2025-10-09 00:18:53.400495 | TASK [Enable EPEL] 2025-10-09 00:18:53.425375 | controller | skipping: Conditional result was False 2025-10-09 00:18:53.431974 | 2025-10-09 00:18:53.432065 | TASK [Register the RHEL node] 2025-10-09 00:18:53.977611 | 2025-10-09 00:18:53.977827 | TASK [Show the subscription-manager status] 2025-10-09 00:18:54.549145 | controller | skipping: Conditional result was False 2025-10-09 00:18:54.558555 | 2025-10-09 00:18:54.558686 | TASK [Enable EPEL on RHEL] 2025-10-09 00:18:55.102467 | controller | skipping: Conditional result was False 2025-10-09 00:18:55.113692 | 2025-10-09 00:18:55.113857 | TASK [Install git and tox] 2025-10-09 00:20:15.389777 | controller | changed 2025-10-09 00:20:15.400219 | 2025-10-09 00:20:15.400361 | TASK [include_role : prepare-workspace] 2025-10-09 00:20:15.434290 | controller | ok 2025-10-09 00:20:15.461651 | 2025-10-09 00:20:15.461835 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-09 00:20:15.688414 | controller | ok 2025-10-09 00:20:15.697032 | 2025-10-09 00:20:15.697120 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-09 00:20:28.882178 | controller | Output suppressed because no_log was given 2025-10-09 00:20:28.892498 | 2025-10-09 00:20:28.892584 | TASK [include_role : prepare-workspace-openshift] 2025-10-09 00:20:28.927192 | controller | skipping: Conditional result was False 2025-10-09 00:20:28.952092 | 2025-10-09 00:20:28.952179 | PLAY [all:!appliance] 2025-10-09 00:20:28.969377 | 2025-10-09 00:20:28.969479 | TASK [Run add-build-sshkey role (RSA)] 2025-10-09 00:20:28.999222 | controller | ok 2025-10-09 00:20:29.014918 | 2025-10-09 00:20:29.015028 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-09 00:20:29.298434 | controller -> localhost | ok 2025-10-09 00:20:29.327207 | 2025-10-09 00:20:29.327383 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-09 00:20:29.363347 | controller | ok 2025-10-09 00:20:29.383694 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-09 00:20:29.392555 | 2025-10-09 00:20:29.392700 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-09 00:20:29.929515 | controller -> localhost | Generating public/private rsa key pair. 2025-10-09 00:20:29.929809 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/7ee9952ea6b64438b9541d31333ae1be_id_rsa. 2025-10-09 00:20:29.929847 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/7ee9952ea6b64438b9541d31333ae1be_id_rsa.pub. 2025-10-09 00:20:29.929869 | controller -> localhost | The key fingerprint is: 2025-10-09 00:20:29.929889 | controller -> localhost | SHA256:vtrleBq8Pzf/l6cto+0I0NQpTs2s0Q8MEMnpcs1EUXo zuul-build-sshkey 2025-10-09 00:20:29.929909 | controller -> localhost | The key's randomart image is: 2025-10-09 00:20:29.929929 | controller -> localhost | +---[RSA 2048]----+ 2025-10-09 00:20:29.929948 | controller -> localhost | | .o*+o. | 2025-10-09 00:20:29.929967 | controller -> localhost | | + .X . | 2025-10-09 00:20:29.929986 | controller -> localhost | | . +* E | 2025-10-09 00:20:29.930008 | controller -> localhost | | . o=o= o | 2025-10-09 00:20:29.930034 | controller -> localhost | | oS + . | 2025-10-09 00:20:29.930074 | controller -> localhost | | o . | 2025-10-09 00:20:29.930108 | controller -> localhost | | + o .| 2025-10-09 00:20:29.930131 | controller -> localhost | | . Bo.ooooo| 2025-10-09 00:20:29.930150 | controller -> localhost | | ..*+ooo====| 2025-10-09 00:20:29.930170 | controller -> localhost | +----[SHA256]-----+ 2025-10-09 00:20:29.930223 | controller -> localhost | ok: Runtime: 0:00:00.105134 2025-10-09 00:20:29.936688 | 2025-10-09 00:20:29.936805 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-09 00:20:29.966201 | controller | ok 2025-10-09 00:20:29.976134 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-09 00:20:29.985928 | 2025-10-09 00:20:29.986021 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-09 00:20:30.010228 | controller | skipping: Conditional result was False 2025-10-09 00:20:30.016770 | 2025-10-09 00:20:30.016853 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-09 00:20:30.481488 | controller | changed 2025-10-09 00:20:30.494645 | 2025-10-09 00:20:30.494820 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-09 00:20:30.734804 | controller | ok 2025-10-09 00:20:30.745036 | 2025-10-09 00:20:30.745191 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-09 00:20:31.409549 | controller | changed 2025-10-09 00:20:31.418290 | 2025-10-09 00:20:31.418412 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-09 00:20:32.082293 | controller | changed 2025-10-09 00:20:32.088624 | 2025-10-09 00:20:32.088743 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-09 00:20:32.113139 | controller | skipping: Conditional result was False 2025-10-09 00:20:32.120458 | 2025-10-09 00:20:32.120559 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-09 00:20:32.511316 | controller -> localhost | changed 2025-10-09 00:20:32.523998 | 2025-10-09 00:20:32.524109 | TASK [add-build-sshkey : Add back temp key] 2025-10-09 00:20:32.855651 | controller -> localhost | Identity added: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/7ee9952ea6b64438b9541d31333ae1be_id_rsa (zuul-build-sshkey) 2025-10-09 00:20:32.855883 | controller -> localhost | ok: Runtime: 0:00:00.015926 2025-10-09 00:20:32.862827 | 2025-10-09 00:20:32.862912 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-09 00:20:33.201524 | controller | ok 2025-10-09 00:20:33.207432 | 2025-10-09 00:20:33.207505 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-09 00:20:33.241922 | controller | skipping: Conditional result was False 2025-10-09 00:20:33.256219 | 2025-10-09 00:20:33.256319 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-09 00:20:33.286282 | controller | ok 2025-10-09 00:20:33.303232 | 2025-10-09 00:20:33.303351 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-09 00:20:33.593014 | controller -> localhost | ok 2025-10-09 00:20:33.599627 | 2025-10-09 00:20:33.599750 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-09 00:20:33.623579 | controller | ok 2025-10-09 00:20:33.637256 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-09 00:20:33.644923 | 2025-10-09 00:20:33.645037 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-09 00:20:33.915644 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-09 00:20:33.916108 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/7ee9952ea6b64438b9541d31333ae1be_id_ecdsa. 2025-10-09 00:20:33.916172 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/7ee9952ea6b64438b9541d31333ae1be_id_ecdsa.pub. 2025-10-09 00:20:33.916231 | controller -> localhost | The key fingerprint is: 2025-10-09 00:20:33.916263 | controller -> localhost | SHA256:zFpFC6knRIGQBY2/woK0fhbWvmWFAK41iq58HDEoOZ4 zuul-build-sshkey 2025-10-09 00:20:33.916313 | controller -> localhost | The key's randomart image is: 2025-10-09 00:20:33.916377 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-09 00:20:33.916414 | controller -> localhost | | oO.oo.... | 2025-10-09 00:20:33.916441 | controller -> localhost | | + + . .o . | 2025-10-09 00:20:33.916467 | controller -> localhost | | ..= o . o | 2025-10-09 00:20:33.916492 | controller -> localhost | |=o+oo +oo. | 2025-10-09 00:20:33.916516 | controller -> localhost | |*=o +. +S. | 2025-10-09 00:20:33.916541 | controller -> localhost | |+Eo+.. o. | 2025-10-09 00:20:33.916565 | controller -> localhost | |.oo.+ .o | 2025-10-09 00:20:33.916589 | controller -> localhost | |o. = .o | 2025-10-09 00:20:33.916612 | controller -> localhost | |..+ .. | 2025-10-09 00:20:33.916636 | controller -> localhost | +----[SHA256]-----+ 2025-10-09 00:20:33.916735 | controller -> localhost | ok: Runtime: 0:00:00.008413 2025-10-09 00:20:33.927190 | 2025-10-09 00:20:33.927323 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-09 00:20:33.959264 | controller | ok 2025-10-09 00:20:33.968557 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-09 00:20:33.978329 | 2025-10-09 00:20:33.978426 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-09 00:20:34.003092 | controller | skipping: Conditional result was False 2025-10-09 00:20:34.009869 | 2025-10-09 00:20:34.009954 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-09 00:20:34.309329 | controller | changed 2025-10-09 00:20:34.316127 | 2025-10-09 00:20:34.316233 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-09 00:20:34.544852 | controller | ok 2025-10-09 00:20:34.554196 | 2025-10-09 00:20:34.554319 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-09 00:20:35.201154 | controller | changed 2025-10-09 00:20:35.210231 | 2025-10-09 00:20:35.210381 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-09 00:20:35.866000 | controller | changed 2025-10-09 00:20:35.923626 | 2025-10-09 00:20:35.923800 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-09 00:20:35.948277 | controller | skipping: Conditional result was False 2025-10-09 00:20:35.955657 | 2025-10-09 00:20:35.955804 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-09 00:20:36.215518 | controller -> localhost | changed 2025-10-09 00:20:36.231116 | 2025-10-09 00:20:36.231283 | TASK [add-build-sshkey : Add back temp key] 2025-10-09 00:20:36.567062 | controller -> localhost | Identity added: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/7ee9952ea6b64438b9541d31333ae1be_id_ecdsa (zuul-build-sshkey) 2025-10-09 00:20:36.567324 | controller -> localhost | ok: Runtime: 0:00:00.030605 2025-10-09 00:20:36.576403 | 2025-10-09 00:20:36.576523 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-09 00:20:36.792504 | controller | ok 2025-10-09 00:20:36.799306 | 2025-10-09 00:20:36.799408 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-09 00:20:36.834883 | controller | skipping: Conditional result was False 2025-10-09 00:20:36.847728 | 2025-10-09 00:20:36.847845 | TASK [include_role : remove-zuul-sshkey] 2025-10-09 00:20:36.862537 | controller | skipping: Conditional result was False 2025-10-09 00:20:36.870974 | 2025-10-09 00:20:36.871089 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-09 00:20:37.135063 | controller | ok: "logs" 2025-10-09 00:20:37.135422 | controller | ok: All items complete 2025-10-09 00:20:37.135465 | 2025-10-09 00:20:37.348495 | controller | ok: "artifacts" 2025-10-09 00:20:37.574942 | controller | ok: "docs" 2025-10-09 00:20:37.614459 | 2025-10-09 00:20:37.614730 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-09 00:20:37.895446 | controller | changed: "logs" 2025-10-09 00:20:38.087795 | controller | changed: "artifacts" 2025-10-09 00:20:38.293133 | controller | changed: "docs" 2025-10-09 00:20:38.330215 | 2025-10-09 00:20:38.330335 | PLAY RECAP 2025-10-09 00:20:38.330388 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-09 00:20:38.330416 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-09 00:20:38.330434 | 2025-10-09 00:20:38.478599 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-09 00:20:38.479836 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-09 00:20:39.084522 | 2025-10-09 00:20:39.084658 | PLAY [all] 2025-10-09 00:20:39.110173 | 2025-10-09 00:20:39.110305 | TASK [Install binary dependencies] 2025-10-09 00:20:39.181199 | controller | ok 2025-10-09 00:20:39.200819 | 2025-10-09 00:20:39.200923 | TASK [bindep : Include find tasks] 2025-10-09 00:20:39.230056 | controller | ok 2025-10-09 00:20:39.237920 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-09 00:20:39.244651 | 2025-10-09 00:20:39.244766 | TASK [bindep : Look for bindep.txt] 2025-10-09 00:20:39.602660 | controller | ok 2025-10-09 00:20:39.615099 | 2025-10-09 00:20:39.615259 | TASK [bindep : Define bindep_file fact] 2025-10-09 00:20:39.644651 | controller | skipping: Conditional result was False 2025-10-09 00:20:39.652107 | 2025-10-09 00:20:39.652241 | TASK [bindep : Look for other-requirements.txt] 2025-10-09 00:20:39.875794 | controller | ok 2025-10-09 00:20:39.891475 | 2025-10-09 00:20:39.891692 | TASK [bindep : Define bindep_file fact] 2025-10-09 00:20:39.928787 | controller | skipping: Conditional result was False 2025-10-09 00:20:39.944844 | 2025-10-09 00:20:39.945070 | TASK [bindep : Look for bindep fallback file] 2025-10-09 00:20:40.004867 | controller | skipping: Conditional result was False 2025-10-09 00:20:40.016221 | 2025-10-09 00:20:40.016369 | TASK [bindep : Define bindep_file fact] 2025-10-09 00:20:40.041168 | controller | skipping: Conditional result was False 2025-10-09 00:20:40.048805 | 2025-10-09 00:20:40.049033 | TASK [bindep : Include bindep tasks] 2025-10-09 00:20:40.073550 | controller | skipping: Conditional result was False 2025-10-09 00:20:40.080836 | 2025-10-09 00:20:40.080942 | TASK [bindep : Include install tasks] 2025-10-09 00:20:40.105100 | controller | skipping: Conditional result was False 2025-10-09 00:20:40.112429 | 2025-10-09 00:20:40.112533 | LOOP [bindep : Include package tasks] 2025-10-09 00:20:40.170076 | 2025-10-09 00:20:40.170274 | TASK [Run test-setup role] 2025-10-09 00:20:40.191504 | controller | ok 2025-10-09 00:20:40.221908 | 2025-10-09 00:20:40.222050 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-09 00:20:40.446163 | controller | ok 2025-10-09 00:20:40.453753 | 2025-10-09 00:20:40.453877 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-09 00:20:41.005617 | controller | skipping: Conditional result was False 2025-10-09 00:20:41.036315 | 2025-10-09 00:20:41.036501 | PLAY RECAP 2025-10-09 00:20:41.036563 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-09 00:20:41.036585 | 2025-10-09 00:20:41.253199 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-09 00:20:41.254356 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-09 00:20:41.888524 | 2025-10-09 00:20:41.888723 | PLAY [controller] 2025-10-09 00:20:41.913558 | 2025-10-09 00:20:41.913720 | TASK [Create the /root directory] 2025-10-09 00:20:42.303262 | controller | ok 2025-10-09 00:20:42.326528 | 2025-10-09 00:20:42.326850 | TASK [Install glibc-langpack-en] 2025-10-09 00:20:46.409727 | controller | ok: Nothing to do 2025-10-09 00:20:46.417638 | 2025-10-09 00:20:46.417783 | TASK [Ensure controller directory exists] 2025-10-09 00:20:46.657740 | controller | changed 2025-10-09 00:20:46.668427 | 2025-10-09 00:20:46.668598 | TASK [Install container runtime] 2025-10-09 00:20:46.725572 | controller | ok 2025-10-09 00:20:46.782188 | 2025-10-09 00:20:46.782320 | LOOP [ensure-docker : Gather variables for each operating system] 2025-10-09 00:20:46.838468 | controller | ok: "/var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-10-09 00:20:46.858158 | 2025-10-09 00:20:46.858297 | TASK [ensure-docker : Sanity check for distro selection] 2025-10-09 00:20:46.884119 | controller | skipping: Conditional result was False 2025-10-09 00:20:46.891790 | 2025-10-09 00:20:46.891923 | TASK [ensure-docker : Sanity check for distro install] 2025-10-09 00:20:46.916539 | controller | skipping: Conditional result was False 2025-10-09 00:20:46.924085 | 2025-10-09 00:20:46.924227 | TASK [ensure-docker : Sanity check for upstream install] 2025-10-09 00:20:46.949150 | controller | skipping: Conditional result was False 2025-10-09 00:20:46.956082 | 2025-10-09 00:20:46.956188 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-10-09 00:20:46.981062 | controller | skipping: Conditional result was False 2025-10-09 00:20:46.994933 | 2025-10-09 00:20:46.995108 | TASK [ensure-docker : Include tasks for docker install] 2025-10-09 00:20:47.018699 | controller | ok 2025-10-09 00:20:47.030388 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-10-09 00:20:47.041613 | 2025-10-09 00:20:47.041768 | TASK [ensure-docker : Not Implemented] 2025-10-09 00:20:47.056850 | controller | skipping: Conditional result was False 2025-10-09 00:20:47.065351 | 2025-10-09 00:20:47.065445 | TASK [Set up docker mirrors] 2025-10-09 00:20:47.091608 | controller | ok 2025-10-09 00:20:47.116451 | 2025-10-09 00:20:47.116594 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-10-09 00:20:47.140866 | controller | skipping: Conditional result was False 2025-10-09 00:20:47.148053 | 2025-10-09 00:20:47.148155 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-10-09 00:20:47.172660 | controller | skipping: Conditional result was False 2025-10-09 00:20:47.179605 | 2025-10-09 00:20:47.179759 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-10-09 00:20:47.214899 | controller | skipping: Conditional result was False 2025-10-09 00:20:47.222215 | 2025-10-09 00:20:47.222298 | TASK [use-docker-mirror : Set up docker mirrors] 2025-10-09 00:20:47.246609 | controller | skipping: Conditional result was False 2025-10-09 00:20:47.262643 | 2025-10-09 00:20:47.262795 | TASK [Add all repositories] 2025-10-09 00:20:47.284110 | controller | ok 2025-10-09 00:20:47.316304 | 2025-10-09 00:20:47.316450 | LOOP [ensure-package-repositories : Install repositories] 2025-10-09 00:20:47.340578 | controller | ok: "/var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-10-09 00:20:47.340891 | controller | ok: All items complete 2025-10-09 00:20:47.340932 | 2025-10-09 00:20:47.394186 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-10-09 00:20:47.401703 | 2025-10-09 00:20:47.401793 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-10-09 00:20:47.721834 | controller | changed 2025-10-09 00:20:47.731471 | 2025-10-09 00:20:47.731597 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-10-09 00:20:48.581737 | controller | changed: 2025-10-09 00:20:48.582013 | controller | { 2025-10-09 00:20:48.582044 | 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-10-09 00:20:48.582070 | controller | } 2025-10-09 00:20:48.604513 | 2025-10-09 00:20:48.604737 | LOOP [ensure-package-repositories : Import GPG keys] 2025-10-09 00:20:49.194271 | controller | changed: 2025-10-09 00:20:49.194540 | controller | { 2025-10-09 00:20:49.194586 | 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-10-09 00:20:49.194630 | controller | } 2025-10-09 00:20:49.194716 | controller | changed: All items complete 2025-10-09 00:20:49.194762 | 2025-10-09 00:20:49.214395 | 2025-10-09 00:20:49.214745 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-10-09 00:20:49.462742 | controller | changed 2025-10-09 00:20:49.482544 | 2025-10-09 00:20:49.482754 | LOOP [ensure-package-repositories : Add repositories] 2025-10-09 00:20:49.836932 | controller | changed: 2025-10-09 00:20:49.837197 | controller | { 2025-10-09 00:20:49.837234 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-10-09 00:20:49.837257 | controller | "description": "Docker CE Stable - $basearch", 2025-10-09 00:20:49.837274 | controller | "gpgcheck": true, 2025-10-09 00:20:49.837290 | controller | "name": "docker-ce-stable" 2025-10-09 00:20:49.837307 | controller | } 2025-10-09 00:20:49.893750 | 2025-10-09 00:20:49.893893 | TASK [ensure-docker : Install pre-reqs] 2025-10-09 00:20:52.808512 | controller | ok: Nothing to do 2025-10-09 00:20:52.815990 | 2025-10-09 00:20:52.816064 | TASK [ensure-docker : Remove packages] 2025-10-09 00:20:54.127286 | controller | ok: Nothing to do 2025-10-09 00:20:54.134637 | 2025-10-09 00:20:54.134804 | TASK [ensure-docker : Install upstream docker using package] 2025-10-09 00:21:40.595789 | controller | changed 2025-10-09 00:21:40.610230 | 2025-10-09 00:21:40.610421 | TASK [ensure-docker : Setup docker] 2025-10-09 00:21:40.642442 | controller | ok 2025-10-09 00:21:40.665505 | controller | included: /var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-10-09 00:21:40.684641 | 2025-10-09 00:21:40.684813 | TASK [ensure-docker : Ensure "docker" group exists] 2025-10-09 00:21:41.072025 | controller | ok 2025-10-09 00:21:41.085516 | 2025-10-09 00:21:41.085660 | TASK [ensure-docker : Add user to docker group] 2025-10-09 00:21:41.611589 | controller | changed 2025-10-09 00:21:41.626353 | 2025-10-09 00:21:41.626510 | TASK [Update docker daemon proxy configuration] 2025-10-09 00:21:41.664998 | controller | skipping: Conditional result was False 2025-10-09 00:21:41.680488 | 2025-10-09 00:21:41.680636 | TASK [Lower default MTU] 2025-10-09 00:21:41.708048 | controller | skipping: Conditional result was False 2025-10-09 00:21:41.724130 | 2025-10-09 00:21:41.724284 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-10-09 00:21:42.131845 | controller | inactive 2025-10-09 00:21:42.304010 | controller | ok: Runtime: 0:00:00.013471 2025-10-09 00:21:42.321960 | 2025-10-09 00:21:42.322169 | TASK [ensure-docker : Get the status of the docket unit] 2025-10-09 00:21:42.583692 | controller | inactive 2025-10-09 00:21:42.880374 | controller | ok: Runtime: 0:00:00.011861 2025-10-09 00:21:42.889783 | 2025-10-09 00:21:42.889878 | TASK [ensure-docker : Restart docker] 2025-10-09 00:21:42.959120 | Trigger a Docker restart 2025-10-09 00:21:42.984430 | 2025-10-09 00:21:42.984552 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-10-09 00:21:43.025634 | 2025-10-09 00:21:43.025882 | TASK [ensure-docker : Install docker-compose] 2025-10-09 00:21:43.052180 | controller | skipping: Conditional result was False 2025-10-09 00:21:43.062136 | 2025-10-09 00:21:43.062233 | TASK [ensure-docker : Flush handlers before role exit] 2025-10-09 00:21:43.076145 | 2025-10-09 00:21:43.076234 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-10-09 00:21:44.523917 | controller | changed 2025-10-09 00:21:44.536473 | 2025-10-09 00:21:44.536583 | TASK [ensure-docker : Assure docker service is running] 2025-10-09 00:21:46.713151 | controller | changed 2025-10-09 00:21:46.722076 | 2025-10-09 00:21:46.722207 | TASK [ensure-docker : Assure docker.socket service is running] 2025-10-09 00:21:47.756793 | controller | ok 2025-10-09 00:21:47.779265 | 2025-10-09 00:21:47.779480 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-10-09 00:21:48.438196 | controller | ok 2025-10-09 00:21:48.459024 | 2025-10-09 00:21:48.459509 | TASK [ensure-docker : Validate ability to talk with docker] 2025-10-09 00:21:49.118611 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-10-09 00:21:49.521800 | controller | ok: Runtime: 0:00:00.042079 2025-10-09 00:21:49.537780 | 2025-10-09 00:21:49.537971 | TASK [ensure-docker : Get version details] 2025-10-09 00:21:50.218286 | controller | Client: Docker Engine - Community 2025-10-09 00:21:50.218324 | controller | Version: 24.0.7 2025-10-09 00:21:50.218338 | controller | API version: 1.43 2025-10-09 00:21:50.218343 | controller | Go version: go1.20.10 2025-10-09 00:21:50.218353 | controller | Git commit: afdd53b 2025-10-09 00:21:50.218358 | controller | Built: Thu Oct 26 09:09:39 2023 2025-10-09 00:21:50.218363 | controller | OS/Arch: linux/amd64 2025-10-09 00:21:50.218369 | controller | Context: default 2025-10-09 00:21:50.218375 | controller | 2025-10-09 00:21:50.218379 | controller | Server: Docker Engine - Community 2025-10-09 00:21:50.218396 | controller | Engine: 2025-10-09 00:21:50.218401 | controller | Version: 24.0.7 2025-10-09 00:21:50.218405 | controller | API version: 1.43 (minimum version 1.12) 2025-10-09 00:21:50.218409 | controller | Go version: go1.20.10 2025-10-09 00:21:50.218420 | controller | Git commit: 311b9ff 2025-10-09 00:21:50.218424 | controller | Built: Thu Oct 26 09:08:11 2023 2025-10-09 00:21:50.218430 | controller | OS/Arch: linux/amd64 2025-10-09 00:21:50.218448 | controller | Experimental: false 2025-10-09 00:21:50.218454 | controller | containerd: 2025-10-09 00:21:50.218460 | controller | Version: 1.6.25 2025-10-09 00:21:50.218464 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-10-09 00:21:50.218469 | controller | runc: 2025-10-09 00:21:50.218474 | controller | Version: 1.1.10 2025-10-09 00:21:50.218479 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-10-09 00:21:50.218490 | controller | docker-init: 2025-10-09 00:21:50.218496 | controller | Version: 0.19.0 2025-10-09 00:21:50.219630 | controller | GitCommit: de40ad0 2025-10-09 00:21:50.597141 | controller | ok: Runtime: 0:00:00.067105 2025-10-09 00:21:50.612042 | 2025-10-09 00:21:50.612222 | TASK [ensure-docker : Dump installed docker details] 2025-10-09 00:21:50.668250 | {'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-10-09 00:21:50.152712', 'end': '2025-10-09 00:21:50.219817', 'delta': '0:00:00.067105', 'msg': '', 'zuul_log_id': 'fa163efc-24cc-1069-24e3-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-10-09 00:21:50.683879 | 2025-10-09 00:21:50.684068 | TASK [Use buildset registry] 2025-10-09 00:21:50.710961 | controller | skipping: Conditional result was False 2025-10-09 00:21:50.730278 | 2025-10-09 00:21:50.730420 | TASK [Ensure python3.8 is present] 2025-10-09 00:21:50.756332 | controller | skipping: Conditional result was False 2025-10-09 00:21:50.766234 | 2025-10-09 00:21:50.766332 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-09 00:21:50.793301 | controller | ok 2025-10-09 00:21:50.822279 | 2025-10-09 00:21:50.822401 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-09 00:21:53.122207 | controller | ok: Nothing to do 2025-10-09 00:21:53.131057 | 2025-10-09 00:21:53.131306 | TASK [our-ensure-python : Also install python3-devel] 2025-10-09 00:22:04.259214 | controller | changed 2025-10-09 00:22:04.281027 | 2025-10-09 00:22:04.281209 | TASK [Run ensure-virtualenv role] 2025-10-09 00:22:04.311485 | controller | ok 2025-10-09 00:22:04.352163 | 2025-10-09 00:22:04.352396 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-09 00:22:04.580566 | controller | /usr/bin/virtualenv 2025-10-09 00:22:04.892070 | controller | ok: Runtime: 0:00:00.003217 2025-10-09 00:22:04.905377 | 2025-10-09 00:22:04.905513 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-09 00:22:04.943160 | controller | skipping: Conditional result was False 2025-10-09 00:22:04.943741 | controller | changed: All items complete 2025-10-09 00:22:04.943826 | 2025-10-09 00:22:04.967791 | 2025-10-09 00:22:04.967928 | TASK [Find the full path of the Python interpreter] 2025-10-09 00:22:05.183981 | controller | /usr/bin/python3.9 2025-10-09 00:22:05.503251 | controller | ok 2025-10-09 00:22:05.516396 | 2025-10-09 00:22:05.516536 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-09 00:22:06.892936 | controller | created virtual environment CPython3.9.18.final.0-64 in 811ms 2025-10-09 00:22:06.936686 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-10-09 00:22:06.936725 | 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-10-09 00:22:06.936736 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-10-09 00:22:06.936750 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-10-09 00:22:07.077113 | controller | changed 2025-10-09 00:22:07.091196 | 2025-10-09 00:22:07.091335 | TASK [Set selinux package] 2025-10-09 00:22:07.133148 | controller | ok 2025-10-09 00:22:07.146032 | 2025-10-09 00:22:07.146216 | TASK [Set selinux package (Fedora)] 2025-10-09 00:22:07.209386 | controller | ok 2025-10-09 00:22:07.223456 | 2025-10-09 00:22:07.223618 | TASK [Install selinux into virtualenv] 2025-10-09 00:22:20.327788 | controller | Collecting selinux-please-lie-to-me 2025-10-09 00:22:32.580398 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-10-09 00:22:32.931413 | controller | Collecting setuptools<50.0.0 2025-10-09 00:22:32.937492 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-10-09 00:22:33.037462 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.5 MB/s eta 0:00:00 2025-10-09 00:22:33.114353 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-10-09 00:22:33.114437 | controller | Attempting uninstall: setuptools 2025-10-09 00:22:33.115160 | controller | Found existing installation: setuptools 62.6.0 2025-10-09 00:22:33.185500 | controller | Uninstalling setuptools-62.6.0: 2025-10-09 00:22:33.194267 | controller | Successfully uninstalled setuptools-62.6.0 2025-10-09 00:22:33.533319 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-10-09 00:22:46.892361 | controller | 2025-10-09 00:22:46.974058 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-09 00:22:46.974159 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-09 00:22:47.294636 | controller | ok: Runtime: 0:00:39.107588 2025-10-09 00:22:47.311615 | 2025-10-09 00:22:47.311845 | TASK [Install pytest-forked into virtualenv] 2025-10-09 00:23:07.301692 | controller | Collecting pytest-forked 2025-10-09 00:23:19.584728 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-10-09 00:23:19.726590 | controller | Collecting pytest>=3.10 2025-10-09 00:23:19.733132 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-10-09 00:23:19.801042 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 5.9 MB/s eta 0:00:00 2025-10-09 00:23:19.863387 | controller | Collecting py 2025-10-09 00:23:19.869261 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-10-09 00:23:19.916033 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.2 MB/s eta 0:00:00 2025-10-09 00:23:19.993792 | controller | Collecting pygments>=2.7.2 2025-10-09 00:23:20.018817 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-10-09 00:23:20.072061 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 31.7 MB/s eta 0:00:00 2025-10-09 00:23:20.103782 | controller | Collecting iniconfig>=1 2025-10-09 00:23:20.109345 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-10-09 00:23:20.150862 | controller | Collecting pluggy<2,>=1.5 2025-10-09 00:23:20.154602 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-10-09 00:23:20.205422 | controller | Collecting tomli>=1 2025-10-09 00:23:20.210669 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-10-09 00:23:20.263271 | controller | Collecting packaging>=20 2025-10-09 00:23:20.268874 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-10-09 00:23:20.277580 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 10.4 MB/s eta 0:00:00 2025-10-09 00:23:20.314692 | controller | Collecting exceptiongroup>=1 2025-10-09 00:23:20.323313 | controller | Downloading exceptiongroup-1.3.0-py3-none-any.whl (16 kB) 2025-10-09 00:23:20.382865 | controller | Collecting typing-extensions>=4.6.0 2025-10-09 00:23:20.386445 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-10-09 00:23:20.394287 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 8.1 MB/s eta 0:00:00 2025-10-09 00:23:20.464265 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-10-09 00:23:21.517167 | controller | Successfully installed exceptiongroup-1.3.0 iniconfig-2.1.0 packaging-25.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-8.4.2 pytest-forked-1.6.0 tomli-2.3.0 typing-extensions-4.15.0 2025-10-09 00:23:21.521550 | controller | 2025-10-09 00:23:21.601872 | controller | [notice] A new release of pip available: 22.2.2 -> 25.2 2025-10-09 00:23:21.601930 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-10-09 00:23:21.885599 | controller | ok: Runtime: 0:00:34.069225 2025-10-09 00:23:21.892204 | 2025-10-09 00:23:21.892295 | TASK [Update pip] 2025-10-09 00:23:22.455435 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-10-09 00:23:31.954961 | controller | Collecting pip 2025-10-09 00:23:44.339160 | controller | Downloading pip-25.2-py3-none-any.whl (1.8 MB) 2025-10-09 00:23:44.393063 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 35.5 MB/s eta 0:00:00 2025-10-09 00:23:44.458717 | controller | Installing collected packages: pip 2025-10-09 00:23:44.458865 | controller | Attempting uninstall: pip 2025-10-09 00:23:44.459570 | controller | Found existing installation: pip 22.2.2 2025-10-09 00:23:44.631428 | controller | Uninstalling pip-22.2.2: 2025-10-09 00:23:44.648884 | controller | Successfully uninstalled pip-22.2.2 2025-10-09 00:23:45.448544 | controller | Successfully installed pip-25.2 2025-10-09 00:23:45.945161 | controller | ok: Runtime: 0:00:23.405648 2025-10-09 00:23:45.959515 | 2025-10-09 00:23:45.959652 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-09 00:23:46.186222 | controller | changed 2025-10-09 00:23:46.201711 | 2025-10-09 00:23:46.201897 | TASK [Install ansible into virtualenv] 2025-10-09 00:23:46.760428 | controller | Processing ./src/github.com/ansible/ansible 2025-10-09 00:23:46.761623 | controller | Preparing metadata (setup.py): started 2025-10-09 00:23:47.203513 | controller | Preparing metadata (setup.py): finished with status 'done' 2025-10-09 00:23:54.575534 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-10-09 00:24:05.799519 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-10-09 00:24:05.876849 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-10-09 00:24:05.922996 | 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) 2025-10-09 00:24:06.223577 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-10-09 00:24:06.229446 | controller | Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-10-09 00:24:06.243314 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-10-09 00:24:06.259496 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-10-09 00:24:06.268319 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-10-09 00:24:06.426165 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-10-09 00:24:06.442447 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-10-09 00:24:06.447969 | 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) 2025-10-09 00:24:06.531507 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-10-09 00:24:06.535847 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-10-09 00:24:06.625714 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-10-09 00:24:06.636536 | 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) 2025-10-09 00:24:06.657230 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-10-09 00:24:06.703281 | controller | Downloading cryptography-46.0.2-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-10-09 00:24:06.807787 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 43.9 MB/s 0:00:00 2025-10-09 00:24:06.814267 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-10-09 00:24:06.826379 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-10-09 00:24:06.840508 | controller | Downloading markupsafe-3.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2025-10-09 00:24:06.850080 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-10-09 00:24:06.864285 | controller | Downloading pyyaml-6.0.3-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (750 kB) 2025-10-09 00:24:06.880771 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 38.7 MB/s 0:00:00 2025-10-09 00:24:06.895310 | controller | Building wheels for collected packages: ansible-core 2025-10-09 00:24:06.895966 | controller | DEPRECATION: Building 'ansible-core' using the legacy setup.py bdist_wheel mechanism, which will be removed in a future version. pip 25.3 will enforce this behaviour change. A possible replacement is to use the standardized build interface by setting the `--use-pep517` option, (possibly combined with `--no-build-isolation`), or adding a `pyproject.toml` file to the source tree of 'ansible-core'. Discussion can be found at https://github.com/pypa/pip/issues/6334 2025-10-09 00:24:06.896179 | controller | Building wheel for ansible-core (setup.py): started 2025-10-09 00:24:08.078869 | controller | Building wheel for ansible-core (setup.py): finished with status 'done' 2025-10-09 00:24:08.083541 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961168 sha256=56dda00546766da2d72cb54359c06aed3347aa625820acb5620ad63bd993c5f6 2025-10-09 00:24:08.083922 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-2spleigp/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-10-09 00:24:08.093906 | controller | Successfully built ansible-core 2025-10-09 00:24:08.131631 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-10-09 00:24:09.548986 | controller | 2025-10-09 00:24:09.553131 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.2 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-10-09 00:24:09.775386 | controller | ok: Runtime: 0:00:23.197454 2025-10-09 00:24:09.792090 | 2025-10-09 00:24:09.792693 | TASK [Setup download-artifact-fork role] 2025-10-09 00:24:09.828050 | controller | ok 2025-10-09 00:24:09.875861 | 2025-10-09 00:24:09.876087 | TASK [download-artifact-fork : Parse inventory response] 2025-10-09 00:24:09.937269 | controller | ok 2025-10-09 00:24:09.953989 | 2025-10-09 00:24:09.954164 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-10-09 00:24:09.991949 | controller | skipping: Conditional result was False 2025-10-09 00:24:10.007843 | 2025-10-09 00:24:10.008013 | TASK [download-artifact-fork : Parse build response] 2025-10-09 00:24:10.045726 | controller | skipping: Conditional result was False 2025-10-09 00:24:10.059571 | 2025-10-09 00:24:10.059745 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-10-09 00:24:10.279998 | controller | changed 2025-10-09 00:24:10.294607 | 2025-10-09 00:24:10.294823 | LOOP [download-artifact-fork : Download archive by type] 2025-10-09 00:24:10.322271 | controller | skipping: Conditional result was False 2025-10-09 00:24:10.322637 | 2025-10-09 00:24:11.223806 | controller | changed: OK (20204 bytes) 2025-10-09 00:24:11.267244 | 2025-10-09 00:24:11.267463 | TASK [deploy-artifacts : define __collections] 2025-10-09 00:24:11.312746 | controller | ok 2025-10-09 00:24:11.328105 | 2025-10-09 00:24:11.328355 | LOOP [deploy-artifacts : Create list of collections] 2025-10-09 00:24:11.370728 | controller | skipping: Conditional result was False 2025-10-09 00:24:11.395397 | controller | ok: 2025-10-09 00:24:11.395550 | controller | { 2025-10-09 00:24:11.395603 | controller | "branch": "main", 2025-10-09 00:24:11.395654 | controller | "job": "build-ansible-collection", 2025-10-09 00:24:11.395773 | controller | "metadata": { 2025-10-09 00:24:11.395847 | controller | "type": "ansible_collection", 2025-10-09 00:24:11.395909 | controller | "version": "5.0.0" 2025-10-09 00:24:11.395951 | controller | }, 2025-10-09 00:24:11.395986 | controller | "name": "ansible.network", 2025-10-09 00:24:11.396021 | controller | "project": "ansible-collections/ansible.network", 2025-10-09 00:24:11.396055 | controller | "url": "https://38e4b53e710594e7e2c6-77212082f2de2f22f87ec6cf98595a55.ssl.cf1.rackcdn.com/ansible/3f3d45c87c0445bd97b1d42ffdff9dd1/artifacts/ansible-network-5.0.0.tar.gz" 2025-10-09 00:24:11.396103 | controller | } 2025-10-09 00:24:11.407457 | 2025-10-09 00:24:11.407641 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-10-09 00:24:12.100326 | controller | Starting galaxy collection install process 2025-10-09 00:24:12.100621 | controller | Process install dependency map 2025-10-09 00:25:55.082940 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-10-09 00:25:55.083836 | controller | unexpected error when getting available versions of collection 2025-10-09 00:25:55.083897 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-10-09 00:25:55.083914 | controller | ansible/netcommon/versions/' 2025-10-09 00:25:55.083937 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-10-09 00:25:55.084050 | controller | to see the full traceback, use -vvv 2025-10-09 00:25:55.565063 | controller | ERROR 2025-10-09 00:25:55.565330 | controller | { 2025-10-09 00:25:55.565365 | controller | "delta": "0:01:43.444437", 2025-10-09 00:25:55.565388 | controller | "end": "2025-10-09 00:25:55.158135", 2025-10-09 00:25:55.565412 | controller | "msg": "non-zero return code", 2025-10-09 00:25:55.565435 | controller | "rc": 250, 2025-10-09 00:25:55.565457 | controller | "start": "2025-10-09 00:24:11.713698" 2025-10-09 00:25:55.565475 | controller | } failure 2025-10-09 00:25:55.568160 | 2025-10-09 00:25:55.568230 | PLAY RECAP 2025-10-09 00:25:55.568289 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-10-09 00:25:55.568321 | 2025-10-09 00:25:55.712981 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-09 00:25:55.714271 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-09 00:25:56.349856 | 2025-10-09 00:25:56.350004 | PLAY [all] 2025-10-09 00:25:56.375722 | 2025-10-09 00:25:56.375846 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-09 00:25:56.822689 | controller | changed: non-zero return code 2025-10-09 00:25:56.835908 | 2025-10-09 00:25:56.836138 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-09 00:25:56.863131 | controller | skipping: Conditional result was False 2025-10-09 00:25:56.870097 | 2025-10-09 00:25:56.870187 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-09 00:25:56.895788 | 2025-10-09 00:25:56.895997 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-09 00:25:56.921200 | 2025-10-09 00:25:56.921397 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-09 00:25:56.936887 | controller | skipping: Conditional result was False 2025-10-09 00:25:56.947578 | 2025-10-09 00:25:56.947727 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-09 00:25:56.971898 | 2025-10-09 00:25:56.972091 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-09 00:25:56.987764 | controller | skipping: Conditional result was False 2025-10-09 00:25:56.996848 | 2025-10-09 00:25:56.996947 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-09 00:25:57.012006 | controller | skipping: Conditional result was False 2025-10-09 00:25:57.020779 | 2025-10-09 00:25:57.020874 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-09 00:25:57.035922 | controller | skipping: Conditional result was False 2025-10-09 00:25:57.068620 | 2025-10-09 00:25:57.068712 | PLAY RECAP 2025-10-09 00:25:57.068757 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-09 00:25:57.068777 | 2025-10-09 00:25:57.178488 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-09 00:25:57.180211 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-09 00:25:57.796536 | 2025-10-09 00:25:57.796711 | PLAY [all:!appliance*] 2025-10-09 00:25:57.819080 | 2025-10-09 00:25:57.819173 | TASK [unregister the node] 2025-10-09 00:25:58.351346 | controller | skipping: Conditional result was False 2025-10-09 00:25:58.360590 | 2025-10-09 00:25:58.360762 | TASK [include_role : fetch-output] 2025-10-09 00:25:58.415793 | controller | ok 2025-10-09 00:25:58.448953 | 2025-10-09 00:25:58.449124 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-09 00:25:58.516198 | controller | skipping: Conditional result was False 2025-10-09 00:25:58.525598 | 2025-10-09 00:25:58.525772 | TASK [fetch-output : Set log path for single node] 2025-10-09 00:25:58.572589 | controller | ok 2025-10-09 00:25:58.584760 | 2025-10-09 00:25:58.584935 | LOOP [fetch-output : Ensure local output dirs] 2025-10-09 00:25:59.084437 | controller -> localhost | ok: "/var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/logs" 2025-10-09 00:25:59.351887 | controller -> localhost | changed: "/var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/artifacts" 2025-10-09 00:25:59.627541 | controller -> localhost | changed: "/var/lib/zuul/builds/7ee9952ea6b64438b9541d31333ae1be/work/docs" 2025-10-09 00:25:59.645258 | 2025-10-09 00:25:59.645394 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-09 00:26:00.428524 | controller | changed: 2025-10-09 00:26:00.428944 | controller | .d..t...... ./ 2025-10-09 00:26:00.429019 | controller | cd+++++++++ controller/ 2025-10-09 00:26:00.429109 | controller | changed: All items complete 2025-10-09 00:26:00.429163 | 2025-10-09 00:26:01.086487 | controller | changed: .d..t...... ./ 2025-10-09 00:26:01.754188 | controller | changed: .d..t...... ./ 2025-10-09 00:26:01.784411 | 2025-10-09 00:26:01.784843 | TASK [include_role : fetch-output-openshift] 2025-10-09 00:26:01.812870 | controller | skipping: Conditional result was False 2025-10-09 00:26:01.829151 | 2025-10-09 00:26:01.829372 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-09 00:26:01.880084 | controller | skipping: Conditional result was False 2025-10-09 00:26:01.893801 | controller | skipping: Conditional result was False 2025-10-09 00:26:01.942429 | 2025-10-09 00:26:01.942571 | PLAY [localhost] 2025-10-09 00:26:01.966978 | 2025-10-09 00:26:01.967160 | TASK [Run Zuul manifest role] 2025-10-09 00:26:01.991106 | localhost | ok 2025-10-09 00:26:02.008853 | 2025-10-09 00:26:02.008997 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-09 00:26:02.420779 | localhost | changed 2025-10-09 00:26:02.430894 | 2025-10-09 00:26:02.431030 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-09 00:26:02.466033 | localhost | ok 2025-10-09 00:26:02.484376 | 2025-10-09 00:26:02.484517 | TASK [Set zuul-log-path fact] 2025-10-09 00:26:02.506913 | localhost | ok 2025-10-09 00:26:02.527598 | 2025-10-09 00:26:02.527719 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-09 00:26:02.567538 | localhost | ok 2025-10-09 00:26:02.576614 | 2025-10-09 00:26:02.576711 | LOOP [Run upload-logs-swift role] 2025-10-09 00:26:02.611312 | localhost | Output suppressed because no_log was given 2025-10-09 00:26:02.637536 | 2025-10-09 00:26:02.637621 | TASK [Set zuul-log-path fact] 2025-10-09 00:26:02.662337 | localhost | skipping: Conditional result was False 2025-10-09 00:26:02.669392 | 2025-10-09 00:26:02.669470 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-09 00:26:03.122001 | localhost -> localhost | ok: Runtime: 0:00:00.005538 2025-10-09 00:26:03.133192 | 2025-10-09 00:26:03.133454 | TASK [upload-logs-swift : Upload logs to swift]