2025-12-10 00:06:48.429823 | Job console starting... 2025-12-10 00:06:48.440444 | Updating repositories 2025-12-10 00:06:48.608215 | Preparing job workspace 2025-12-10 00:06:52.351464 | Running Ansible setup... 2025-12-10 00:06:56.649063 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-10 00:06:57.273964 | 2025-12-10 00:06:57.274159 | PLAY [localhost] 2025-12-10 00:06:57.286476 | 2025-12-10 00:06:57.286609 | TASK [Gathering Facts] 2025-12-10 00:06:58.283645 | localhost | ok 2025-12-10 00:06:58.307889 | 2025-12-10 00:06:58.308064 | TASK [Setup log path fact] 2025-12-10 00:06:58.326843 | localhost | ok 2025-12-10 00:06:58.340924 | 2025-12-10 00:06:58.341006 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-10 00:06:58.377988 | localhost | ok 2025-12-10 00:06:58.394166 | 2025-12-10 00:06:58.394281 | TASK [emit-job-header : Print job information] 2025-12-10 00:06:58.454274 | # Job Information 2025-12-10 00:06:58.454438 | Ansible Version: 2.15.12 2025-12-10 00:06:58.454472 | Job: ansible-test-sanity-docker-stable-2.11 2025-12-10 00:06:58.454498 | Pipeline: periodic 2025-12-10 00:06:58.454519 | Executor: ze03.softwarefactory-project.io 2025-12-10 00:06:58.454538 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-12-10 00:06:58.454561 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/84a/ansible/84a9738d723441deb11d4b9e8e6730eb/ 2025-12-10 00:06:58.454582 | Event ID: 6e49960649da492f963de05b327e4f7a 2025-12-10 00:06:58.458498 | 2025-12-10 00:06:58.458616 | LOOP [emit-job-header : Print node information] 2025-12-10 00:06:58.567053 | localhost | ok: 2025-12-10 00:06:58.567227 | localhost | # Node Information 2025-12-10 00:06:58.567513 | localhost | Inventory Hostname: controller 2025-12-10 00:06:58.567558 | localhost | Hostname: np0005552590 2025-12-10 00:06:58.567617 | localhost | Username: zuul 2025-12-10 00:06:58.567649 | localhost | Distro: Fedora 37 2025-12-10 00:06:58.567671 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2025-12-10 00:06:58.567689 | localhost | Region: ca-ymq-1 2025-12-10 00:06:58.567706 | localhost | Label: ansible-fedora-37-1vcpu 2025-12-10 00:06:58.567722 | localhost | Product Name: OpenStack Nova 2025-12-10 00:06:58.567830 | localhost | Interface IP: 162.253.55.140 2025-12-10 00:06:58.576777 | 2025-12-10 00:06:58.576855 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-12-10 00:06:58.999194 | localhost -> localhost | changed 2025-12-10 00:06:59.022584 | 2025-12-10 00:06:59.023292 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-12-10 00:06:59.915869 | localhost -> localhost | changed 2025-12-10 00:06:59.936625 | 2025-12-10 00:06:59.936741 | PLAY [all:!appliance*] 2025-12-10 00:06:59.952695 | 2025-12-10 00:06:59.952794 | TASK [include_role : start-zuul-console] 2025-12-10 00:06:59.971491 | controller | ok 2025-12-10 00:06:59.989274 | 2025-12-10 00:06:59.989402 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-12-10 00:07:00.364357 | controller | ok 2025-12-10 00:07:00.386298 | 2025-12-10 00:07:00.386419 | TASK [use-our-mirror : Retrieve the IP address] 2025-12-10 00:07:01.164634 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-12-10 00:07:01.173598 | 2025-12-10 00:07:01.173754 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-12-10 00:07:01.716134 | controller | skipping: Conditional result was False 2025-12-10 00:07:01.730427 | 2025-12-10 00:07:01.730624 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-12-10 00:07:01.768033 | controller | skipping: Conditional result was False 2025-12-10 00:07:01.781330 | 2025-12-10 00:07:01.781528 | TASK [use-our-mirror : Create the podman configuration directory] 2025-12-10 00:07:01.808380 | controller | skipping: Conditional result was False 2025-12-10 00:07:01.823482 | 2025-12-10 00:07:01.823664 | TASK [use-our-mirror : Copy the podman configuration] 2025-12-10 00:07:01.850907 | controller | skipping: Conditional result was False 2025-12-10 00:07:01.864073 | 2025-12-10 00:07:01.864274 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-12-10 00:07:01.890813 | controller | skipping: Conditional result was False 2025-12-10 00:07:01.898689 | 2025-12-10 00:07:01.898849 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-12-10 00:07:01.922955 | controller | skipping: Conditional result was False 2025-12-10 00:07:01.935847 | 2025-12-10 00:07:01.935970 | TASK [Disable Fedora Modular] 2025-12-10 00:07:02.169174 | controller | changed 2025-12-10 00:07:02.176541 | 2025-12-10 00:07:02.176656 | TASK [Enable EPEL] 2025-12-10 00:07:02.224663 | controller | skipping: Conditional result was False 2025-12-10 00:07:02.233279 | 2025-12-10 00:07:02.233402 | TASK [Register the RHEL node] 2025-12-10 00:07:02.784692 | 2025-12-10 00:07:02.785016 | TASK [Show the subscription-manager status] 2025-12-10 00:07:03.414614 | controller | skipping: Conditional result was False 2025-12-10 00:07:03.424602 | 2025-12-10 00:07:03.424718 | TASK [Enable EPEL on RHEL] 2025-12-10 00:07:03.967634 | controller | skipping: Conditional result was False 2025-12-10 00:07:03.976651 | 2025-12-10 00:07:03.976794 | TASK [Install git and tox] 2025-12-10 00:08:29.445199 | controller | changed 2025-12-10 00:08:29.478012 | 2025-12-10 00:08:29.478156 | TASK [include_role : prepare-workspace] 2025-12-10 00:08:29.498386 | controller | ok 2025-12-10 00:08:29.519894 | 2025-12-10 00:08:29.519970 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-12-10 00:08:29.757352 | controller | ok 2025-12-10 00:08:29.773923 | 2025-12-10 00:08:29.774102 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-12-10 00:08:49.306776 | controller | Output suppressed because no_log was given 2025-12-10 00:08:49.368593 | 2025-12-10 00:08:49.368744 | TASK [include_role : prepare-workspace-openshift] 2025-12-10 00:08:49.388185 | controller | skipping: Conditional result was False 2025-12-10 00:08:49.416600 | 2025-12-10 00:08:49.416790 | PLAY [all:!appliance] 2025-12-10 00:08:49.436989 | 2025-12-10 00:08:49.437118 | TASK [Run add-build-sshkey role (RSA)] 2025-12-10 00:08:49.469853 | controller | ok 2025-12-10 00:08:49.493535 | 2025-12-10 00:08:49.493692 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-10 00:08:49.841364 | controller -> localhost | ok 2025-12-10 00:08:49.854463 | 2025-12-10 00:08:49.854801 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-10 00:08:49.889374 | controller | ok 2025-12-10 00:08:49.909324 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-10 00:08:49.919441 | 2025-12-10 00:08:49.919582 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-10 00:08:50.457327 | controller -> localhost | Generating public/private rsa key pair. 2025-12-10 00:08:50.457544 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/84a9738d723441deb11d4b9e8e6730eb_id_rsa. 2025-12-10 00:08:50.457582 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/84a9738d723441deb11d4b9e8e6730eb_id_rsa.pub. 2025-12-10 00:08:50.457614 | controller -> localhost | The key fingerprint is: 2025-12-10 00:08:50.457645 | controller -> localhost | SHA256:fDvmCEaoI+QnsUFLLzRmLmsrwMOLXwK4RrTiA6btMiQ zuul-build-sshkey 2025-12-10 00:08:50.457676 | controller -> localhost | The key's randomart image is: 2025-12-10 00:08:50.457707 | controller -> localhost | +---[RSA 2048]----+ 2025-12-10 00:08:50.457775 | controller -> localhost | | | 2025-12-10 00:08:50.457807 | controller -> localhost | | | 2025-12-10 00:08:50.457831 | controller -> localhost | | O | 2025-12-10 00:08:50.457856 | controller -> localhost | |X = . . | 2025-12-10 00:08:50.457880 | controller -> localhost | |O@ .. . S . | 2025-12-10 00:08:50.457914 | controller -> localhost | |EX=. . . . | 2025-12-10 00:08:50.457946 | controller -> localhost | |XOB.. o + | 2025-12-10 00:08:50.457974 | controller -> localhost | |B+=+ . . + . | 2025-12-10 00:08:50.458000 | controller -> localhost | |.=o . . | 2025-12-10 00:08:50.458029 | controller -> localhost | +----[SHA256]-----+ 2025-12-10 00:08:50.458089 | controller -> localhost | ok: Runtime: 0:00:00.115822 2025-12-10 00:08:50.466602 | 2025-12-10 00:08:50.466689 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-10 00:08:50.497614 | controller | ok 2025-12-10 00:08:50.509357 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-10 00:08:50.520615 | 2025-12-10 00:08:50.520736 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-10 00:08:50.545229 | controller | skipping: Conditional result was False 2025-12-10 00:08:50.552037 | 2025-12-10 00:08:50.552135 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-10 00:08:51.009395 | controller | changed 2025-12-10 00:08:51.023145 | 2025-12-10 00:08:51.023294 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-10 00:08:51.268412 | controller | ok 2025-12-10 00:08:51.281294 | 2025-12-10 00:08:51.281425 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-10 00:08:51.933959 | controller | changed 2025-12-10 00:08:51.949256 | 2025-12-10 00:08:51.949448 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-10 00:08:52.606860 | controller | changed 2025-12-10 00:08:52.615871 | 2025-12-10 00:08:52.615963 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-10 00:08:52.652900 | controller | skipping: Conditional result was False 2025-12-10 00:08:52.665875 | 2025-12-10 00:08:52.666018 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-10 00:08:53.071034 | controller -> localhost | changed 2025-12-10 00:08:53.097498 | 2025-12-10 00:08:53.097677 | TASK [add-build-sshkey : Add back temp key] 2025-12-10 00:08:53.411667 | controller -> localhost | Identity added: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/84a9738d723441deb11d4b9e8e6730eb_id_rsa (zuul-build-sshkey) 2025-12-10 00:08:53.412126 | controller -> localhost | ok: Runtime: 0:00:00.015718 2025-12-10 00:08:53.426684 | 2025-12-10 00:08:53.426810 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-10 00:08:53.772317 | controller | ok 2025-12-10 00:08:53.788059 | 2025-12-10 00:08:53.788192 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-10 00:08:53.814296 | controller | skipping: Conditional result was False 2025-12-10 00:08:53.840262 | 2025-12-10 00:08:53.840402 | TASK [Run add-build-sshkey role (ECDSA)] 2025-12-10 00:08:53.863427 | controller | ok 2025-12-10 00:08:53.885768 | 2025-12-10 00:08:53.885858 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-12-10 00:08:54.216921 | controller -> localhost | ok 2025-12-10 00:08:54.226214 | 2025-12-10 00:08:54.226365 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-12-10 00:08:54.270928 | controller | ok 2025-12-10 00:08:54.286564 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-12-10 00:08:54.295088 | 2025-12-10 00:08:54.295176 | TASK [add-build-sshkey : Create Temp SSH key] 2025-12-10 00:08:54.565717 | controller -> localhost | Generating public/private ecdsa key pair. 2025-12-10 00:08:54.565945 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/84a9738d723441deb11d4b9e8e6730eb_id_ecdsa. 2025-12-10 00:08:54.565976 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/84a9738d723441deb11d4b9e8e6730eb_id_ecdsa.pub. 2025-12-10 00:08:54.566005 | controller -> localhost | The key fingerprint is: 2025-12-10 00:08:54.566025 | controller -> localhost | SHA256:zya5tEpz821LUiGdO4i2zOU4HkKw/ifSq4Q3RIqNeXc zuul-build-sshkey 2025-12-10 00:08:54.566044 | controller -> localhost | The key's randomart image is: 2025-12-10 00:08:54.566063 | controller -> localhost | +---[ECDSA 521]---+ 2025-12-10 00:08:54.566083 | controller -> localhost | | | 2025-12-10 00:08:54.566102 | controller -> localhost | | . . | 2025-12-10 00:08:54.566141 | controller -> localhost | | o . + | 2025-12-10 00:08:54.566161 | controller -> localhost | | = o o . o o | 2025-12-10 00:08:54.566180 | controller -> localhost | |+ + + E S o + | 2025-12-10 00:08:54.566197 | controller -> localhost | | . = o + O . . | 2025-12-10 00:08:54.566215 | controller -> localhost | | . =.+ & * . | 2025-12-10 00:08:54.566233 | controller -> localhost | | o.+o*.X +. | 2025-12-10 00:08:54.566250 | controller -> localhost | | .o+=+ ..o. | 2025-12-10 00:08:54.566268 | controller -> localhost | +----[SHA256]-----+ 2025-12-10 00:08:54.566319 | controller -> localhost | ok: Runtime: 0:00:00.008639 2025-12-10 00:08:54.573431 | 2025-12-10 00:08:54.573514 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-12-10 00:08:54.614632 | controller | ok 2025-12-10 00:08:54.622537 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-12-10 00:08:54.632419 | 2025-12-10 00:08:54.632493 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-12-10 00:08:54.647096 | controller | skipping: Conditional result was False 2025-12-10 00:08:54.654382 | 2025-12-10 00:08:54.654453 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-12-10 00:08:54.930612 | controller | changed 2025-12-10 00:08:54.950948 | 2025-12-10 00:08:54.951198 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-12-10 00:08:55.186537 | controller | ok 2025-12-10 00:08:55.195003 | 2025-12-10 00:08:55.195126 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-12-10 00:08:55.799676 | controller | changed 2025-12-10 00:08:55.815899 | 2025-12-10 00:08:55.816324 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-12-10 00:08:56.443845 | controller | changed 2025-12-10 00:08:56.458563 | 2025-12-10 00:08:56.458783 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-12-10 00:08:56.486634 | controller | skipping: Conditional result was False 2025-12-10 00:08:56.545989 | 2025-12-10 00:08:56.546144 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-12-10 00:08:56.867207 | controller -> localhost | changed 2025-12-10 00:08:56.891778 | 2025-12-10 00:08:56.891959 | TASK [add-build-sshkey : Add back temp key] 2025-12-10 00:08:57.246041 | controller -> localhost | Identity added: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/84a9738d723441deb11d4b9e8e6730eb_id_ecdsa (zuul-build-sshkey) 2025-12-10 00:08:57.246311 | controller -> localhost | ok: Runtime: 0:00:00.007198 2025-12-10 00:08:57.255553 | 2025-12-10 00:08:57.255652 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-12-10 00:08:57.456302 | controller | ok 2025-12-10 00:08:57.474283 | 2025-12-10 00:08:57.474466 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-12-10 00:08:57.502490 | controller | skipping: Conditional result was False 2025-12-10 00:08:57.513995 | 2025-12-10 00:08:57.514090 | TASK [include_role : remove-zuul-sshkey] 2025-12-10 00:08:57.528458 | controller | skipping: Conditional result was False 2025-12-10 00:08:57.540692 | 2025-12-10 00:08:57.540824 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-12-10 00:08:57.770511 | controller | ok: "logs" 2025-12-10 00:08:57.771004 | controller | ok: All items complete 2025-12-10 00:08:57.771068 | 2025-12-10 00:08:57.971220 | controller | ok: "artifacts" 2025-12-10 00:08:58.160399 | controller | ok: "docs" 2025-12-10 00:08:58.178905 | 2025-12-10 00:08:58.179145 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-12-10 00:08:58.423431 | controller | changed: "logs" 2025-12-10 00:08:58.629629 | controller | changed: "artifacts" 2025-12-10 00:08:58.819090 | controller | changed: "docs" 2025-12-10 00:08:58.876972 | 2025-12-10 00:08:58.877120 | PLAY RECAP 2025-12-10 00:08:58.877291 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-12-10 00:08:58.877344 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-12-10 00:08:58.877370 | 2025-12-10 00:08:59.020935 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-12-10 00:08:59.021929 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-10 00:08:59.652117 | 2025-12-10 00:08:59.652227 | PLAY [all] 2025-12-10 00:08:59.682544 | 2025-12-10 00:08:59.682717 | TASK [Install binary dependencies] 2025-12-10 00:08:59.771451 | controller | ok 2025-12-10 00:08:59.805090 | 2025-12-10 00:08:59.805250 | TASK [bindep : Include find tasks] 2025-12-10 00:08:59.838820 | controller | ok 2025-12-10 00:08:59.849606 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-12-10 00:08:59.858499 | 2025-12-10 00:08:59.858584 | TASK [bindep : Look for bindep.txt] 2025-12-10 00:09:00.249857 | controller | ok 2025-12-10 00:09:00.257253 | 2025-12-10 00:09:00.257557 | TASK [bindep : Define bindep_file fact] 2025-12-10 00:09:00.271677 | controller | skipping: Conditional result was False 2025-12-10 00:09:00.279054 | 2025-12-10 00:09:00.279163 | TASK [bindep : Look for other-requirements.txt] 2025-12-10 00:09:00.482675 | controller | ok 2025-12-10 00:09:00.489222 | 2025-12-10 00:09:00.489346 | TASK [bindep : Define bindep_file fact] 2025-12-10 00:09:00.513853 | controller | skipping: Conditional result was False 2025-12-10 00:09:00.520333 | 2025-12-10 00:09:00.520408 | TASK [bindep : Look for bindep fallback file] 2025-12-10 00:09:00.544666 | controller | skipping: Conditional result was False 2025-12-10 00:09:00.555993 | 2025-12-10 00:09:00.556102 | TASK [bindep : Define bindep_file fact] 2025-12-10 00:09:00.580268 | controller | skipping: Conditional result was False 2025-12-10 00:09:00.586632 | 2025-12-10 00:09:00.586709 | TASK [bindep : Include bindep tasks] 2025-12-10 00:09:00.610682 | controller | skipping: Conditional result was False 2025-12-10 00:09:00.617586 | 2025-12-10 00:09:00.617672 | TASK [bindep : Include install tasks] 2025-12-10 00:09:00.641457 | controller | skipping: Conditional result was False 2025-12-10 00:09:00.647786 | 2025-12-10 00:09:00.647869 | LOOP [bindep : Include package tasks] 2025-12-10 00:09:00.713410 | 2025-12-10 00:09:00.713573 | TASK [Run test-setup role] 2025-12-10 00:09:00.733977 | controller | ok 2025-12-10 00:09:00.750775 | 2025-12-10 00:09:00.750867 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-12-10 00:09:00.958166 | controller | ok 2025-12-10 00:09:00.968719 | 2025-12-10 00:09:00.968841 | TASK [test-setup : Run tools/test-setup.sh] 2025-12-10 00:09:01.501196 | controller | skipping: Conditional result was False 2025-12-10 00:09:01.545806 | 2025-12-10 00:09:01.545911 | PLAY RECAP 2025-12-10 00:09:01.545952 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-10 00:09:01.545971 | 2025-12-10 00:09:01.655076 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-12-10 00:09:01.656306 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-10 00:09:02.241555 | 2025-12-10 00:09:02.241666 | PLAY [controller] 2025-12-10 00:09:02.261132 | 2025-12-10 00:09:02.261218 | TASK [Create the /root directory] 2025-12-10 00:09:02.698921 | controller | ok 2025-12-10 00:09:02.708648 | 2025-12-10 00:09:02.708814 | TASK [Install glibc-langpack-en] 2025-12-10 00:09:06.626320 | controller | ok: Nothing to do 2025-12-10 00:09:06.638578 | 2025-12-10 00:09:06.638826 | TASK [Ensure controller directory exists] 2025-12-10 00:09:06.860185 | controller | changed 2025-12-10 00:09:06.867406 | 2025-12-10 00:09:06.867513 | TASK [Install container runtime] 2025-12-10 00:09:06.919018 | controller | ok 2025-12-10 00:09:06.969710 | 2025-12-10 00:09:06.969865 | LOOP [ensure-docker : Gather variables for each operating system] 2025-12-10 00:09:07.015538 | controller | ok: "/var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2025-12-10 00:09:07.033494 | 2025-12-10 00:09:07.033620 | TASK [ensure-docker : Sanity check for distro selection] 2025-12-10 00:09:07.047916 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.055845 | 2025-12-10 00:09:07.055964 | TASK [ensure-docker : Sanity check for distro install] 2025-12-10 00:09:07.082259 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.091034 | 2025-12-10 00:09:07.091174 | TASK [ensure-docker : Sanity check for upstream install] 2025-12-10 00:09:07.117311 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.123927 | 2025-12-10 00:09:07.124050 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2025-12-10 00:09:07.148175 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.156362 | 2025-12-10 00:09:07.156481 | TASK [ensure-docker : Include tasks for docker install] 2025-12-10 00:09:07.187119 | controller | ok 2025-12-10 00:09:07.195985 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2025-12-10 00:09:07.206012 | 2025-12-10 00:09:07.206127 | TASK [ensure-docker : Not Implemented] 2025-12-10 00:09:07.221177 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.231088 | 2025-12-10 00:09:07.231176 | TASK [Set up docker mirrors] 2025-12-10 00:09:07.258565 | controller | ok 2025-12-10 00:09:07.288194 | 2025-12-10 00:09:07.288300 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2025-12-10 00:09:07.322860 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.330075 | 2025-12-10 00:09:07.330145 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2025-12-10 00:09:07.357063 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.366101 | 2025-12-10 00:09:07.366192 | TASK [use-docker-mirror : Set docker_mirror fact] 2025-12-10 00:09:07.400481 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.407355 | 2025-12-10 00:09:07.407445 | TASK [use-docker-mirror : Set up docker mirrors] 2025-12-10 00:09:07.432878 | controller | skipping: Conditional result was False 2025-12-10 00:09:07.446541 | 2025-12-10 00:09:07.446659 | TASK [Add all repositories] 2025-12-10 00:09:07.466446 | controller | ok 2025-12-10 00:09:07.492022 | 2025-12-10 00:09:07.492148 | LOOP [ensure-package-repositories : Install repositories] 2025-12-10 00:09:07.515143 | controller | ok: "/var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2025-12-10 00:09:07.515370 | controller | ok: All items complete 2025-12-10 00:09:07.515397 | 2025-12-10 00:09:07.564002 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2025-12-10 00:09:07.573207 | 2025-12-10 00:09:07.573305 | TASK [ensure-package-repositories : Create GPG key temp directory] 2025-12-10 00:09:07.879994 | controller | changed 2025-12-10 00:09:07.896182 | 2025-12-10 00:09:07.896374 | LOOP [ensure-package-repositories : Upload GPG keys] 2025-12-10 00:09:08.707659 | controller | changed: 2025-12-10 00:09:08.707973 | controller | { 2025-12-10 00:09:08.708038 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-12-10 00:09:08.708094 | controller | } 2025-12-10 00:09:08.721788 | 2025-12-10 00:09:08.721931 | LOOP [ensure-package-repositories : Import GPG keys] 2025-12-10 00:09:09.377854 | controller | changed: 2025-12-10 00:09:09.378157 | controller | { 2025-12-10 00:09:09.378220 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2025-12-10 00:09:09.378272 | controller | } 2025-12-10 00:09:09.378348 | controller | changed: All items complete 2025-12-10 00:09:09.378394 | 2025-12-10 00:09:09.394432 | 2025-12-10 00:09:09.394649 | TASK [ensure-package-repositories : Remove GPG temp directory] 2025-12-10 00:09:09.625796 | controller | changed 2025-12-10 00:09:09.636064 | 2025-12-10 00:09:09.636170 | LOOP [ensure-package-repositories : Add repositories] 2025-12-10 00:09:09.947607 | controller | changed: 2025-12-10 00:09:09.948663 | controller | { 2025-12-10 00:09:09.948717 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2025-12-10 00:09:09.948771 | controller | "description": "Docker CE Stable - $basearch", 2025-12-10 00:09:09.948797 | controller | "gpgcheck": true, 2025-12-10 00:09:09.948821 | controller | "name": "docker-ce-stable" 2025-12-10 00:09:09.948844 | controller | } 2025-12-10 00:09:09.981052 | 2025-12-10 00:09:09.981188 | TASK [ensure-docker : Install pre-reqs] 2025-12-10 00:09:12.765531 | controller | ok: Nothing to do 2025-12-10 00:09:12.772443 | 2025-12-10 00:09:12.772545 | TASK [ensure-docker : Remove packages] 2025-12-10 00:09:14.096303 | controller | ok: Nothing to do 2025-12-10 00:09:14.106269 | 2025-12-10 00:09:14.106405 | TASK [ensure-docker : Install upstream docker using package] 2025-12-10 00:10:00.528263 | controller | changed 2025-12-10 00:10:00.543236 | 2025-12-10 00:10:00.543402 | TASK [ensure-docker : Setup docker] 2025-12-10 00:10:00.579765 | controller | ok 2025-12-10 00:10:00.603171 | controller | included: /var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2025-12-10 00:10:00.629437 | 2025-12-10 00:10:00.629589 | TASK [ensure-docker : Ensure "docker" group exists] 2025-12-10 00:10:01.026082 | controller | ok 2025-12-10 00:10:01.036089 | 2025-12-10 00:10:01.036189 | TASK [ensure-docker : Add user to docker group] 2025-12-10 00:10:01.645289 | controller | changed 2025-12-10 00:10:01.651792 | 2025-12-10 00:10:01.651867 | TASK [Update docker daemon proxy configuration] 2025-12-10 00:10:01.675966 | controller | skipping: Conditional result was False 2025-12-10 00:10:01.683383 | 2025-12-10 00:10:01.683551 | TASK [Lower default MTU] 2025-12-10 00:10:01.697253 | controller | skipping: Conditional result was False 2025-12-10 00:10:01.706795 | 2025-12-10 00:10:01.706884 | TASK [ensure-docker : Get the status of the docket.socket unit] 2025-12-10 00:10:02.104270 | controller | inactive 2025-12-10 00:10:02.338069 | controller | ok: Runtime: 0:00:00.017243 2025-12-10 00:10:02.349922 | 2025-12-10 00:10:02.350071 | TASK [ensure-docker : Get the status of the docket unit] 2025-12-10 00:10:02.600841 | controller | inactive 2025-12-10 00:10:02.909835 | controller | ok: Runtime: 0:00:00.009576 2025-12-10 00:10:02.918920 | 2025-12-10 00:10:02.919020 | TASK [ensure-docker : Restart docker] 2025-12-10 00:10:02.960124 | Trigger a Docker restart 2025-12-10 00:10:02.966273 | 2025-12-10 00:10:02.966349 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2025-12-10 00:10:03.000322 | 2025-12-10 00:10:03.000462 | TASK [ensure-docker : Install docker-compose] 2025-12-10 00:10:03.016989 | controller | skipping: Conditional result was False 2025-12-10 00:10:03.030895 | 2025-12-10 00:10:03.031065 | TASK [ensure-docker : Flush handlers before role exit] 2025-12-10 00:10:03.053405 | 2025-12-10 00:10:03.053502 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2025-12-10 00:10:04.277821 | controller | changed 2025-12-10 00:10:04.292214 | 2025-12-10 00:10:04.292461 | TASK [ensure-docker : Assure docker service is running] 2025-12-10 00:10:06.271230 | controller | changed 2025-12-10 00:10:06.289222 | 2025-12-10 00:10:06.289367 | TASK [ensure-docker : Assure docker.socket service is running] 2025-12-10 00:10:07.234962 | controller | ok 2025-12-10 00:10:07.245463 | 2025-12-10 00:10:07.245553 | TASK [ensure-docker : Correct group ownership on docker sock] 2025-12-10 00:10:07.840982 | controller | ok 2025-12-10 00:10:07.856490 | 2025-12-10 00:10:07.856658 | TASK [ensure-docker : Validate ability to talk with docker] 2025-12-10 00:10:08.427800 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2025-12-10 00:10:08.909930 | controller | ok: Runtime: 0:00:00.042030 2025-12-10 00:10:08.922936 | 2025-12-10 00:10:08.923077 | TASK [ensure-docker : Get version details] 2025-12-10 00:10:09.286772 | controller | Client: Docker Engine - Community 2025-12-10 00:10:09.286826 | controller | Version: 24.0.7 2025-12-10 00:10:09.286882 | controller | API version: 1.43 2025-12-10 00:10:09.286890 | controller | Go version: go1.20.10 2025-12-10 00:10:09.286902 | controller | Git commit: afdd53b 2025-12-10 00:10:09.286914 | controller | Built: Thu Oct 26 09:09:39 2023 2025-12-10 00:10:09.286920 | controller | OS/Arch: linux/amd64 2025-12-10 00:10:09.286925 | controller | Context: default 2025-12-10 00:10:09.286931 | controller | 2025-12-10 00:10:09.286937 | controller | Server: Docker Engine - Community 2025-12-10 00:10:09.286941 | controller | Engine: 2025-12-10 00:10:09.286948 | controller | Version: 24.0.7 2025-12-10 00:10:09.286952 | controller | API version: 1.43 (minimum version 1.12) 2025-12-10 00:10:09.286956 | controller | Go version: go1.20.10 2025-12-10 00:10:09.286961 | controller | Git commit: 311b9ff 2025-12-10 00:10:09.286972 | controller | Built: Thu Oct 26 09:08:11 2023 2025-12-10 00:10:09.286977 | controller | OS/Arch: linux/amd64 2025-12-10 00:10:09.286981 | controller | Experimental: false 2025-12-10 00:10:09.286985 | controller | containerd: 2025-12-10 00:10:09.286989 | controller | Version: 1.6.25 2025-12-10 00:10:09.286993 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2025-12-10 00:10:09.286998 | controller | runc: 2025-12-10 00:10:09.287002 | controller | Version: 1.1.10 2025-12-10 00:10:09.287006 | controller | GitCommit: v1.1.10-0-g18a0cb0 2025-12-10 00:10:09.287012 | controller | docker-init: 2025-12-10 00:10:09.287017 | controller | Version: 0.19.0 2025-12-10 00:10:09.288081 | controller | GitCommit: de40ad0 2025-12-10 00:10:09.477185 | controller | ok: Runtime: 0:00:00.029526 2025-12-10 00:10:09.483465 | 2025-12-10 00:10:09.483535 | TASK [ensure-docker : Dump installed docker details] 2025-12-10 00:10:09.510431 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2025-12-10 00:10:09.258781', 'end': '2025-12-10 00:10:09.288307', 'delta': '0:00:00.029526', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-8a56-0ef7-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2025-12-10 00:10:09.516794 | 2025-12-10 00:10:09.516962 | TASK [Use buildset registry] 2025-12-10 00:10:09.533241 | controller | skipping: Conditional result was False 2025-12-10 00:10:09.554416 | 2025-12-10 00:10:09.554606 | TASK [Ensure python3.8 is present] 2025-12-10 00:10:09.585209 | controller | skipping: Conditional result was False 2025-12-10 00:10:09.594651 | 2025-12-10 00:10:09.594836 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-12-10 00:10:09.617787 | controller | ok 2025-12-10 00:10:09.642209 | 2025-12-10 00:10:09.642497 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-12-10 00:10:11.781607 | controller | ok: Nothing to do 2025-12-10 00:10:11.795124 | 2025-12-10 00:10:11.795283 | TASK [our-ensure-python : Also install python3-devel] 2025-12-10 00:10:23.306220 | controller | changed 2025-12-10 00:10:23.330573 | 2025-12-10 00:10:23.330767 | TASK [Run ensure-virtualenv role] 2025-12-10 00:10:23.357123 | controller | ok 2025-12-10 00:10:23.377969 | 2025-12-10 00:10:23.378078 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-12-10 00:10:23.588909 | controller | /usr/bin/virtualenv 2025-12-10 00:10:23.907644 | controller | ok: Runtime: 0:00:00.003520 2025-12-10 00:10:23.916425 | 2025-12-10 00:10:23.916514 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-12-10 00:10:23.941089 | controller | skipping: Conditional result was False 2025-12-10 00:10:23.941542 | controller | changed: All items complete 2025-12-10 00:10:23.941601 | 2025-12-10 00:10:23.959121 | 2025-12-10 00:10:23.959253 | TASK [Find the full path of the Python interpreter] 2025-12-10 00:10:24.186362 | controller | /usr/bin/python3.9 2025-12-10 00:10:24.501861 | controller | ok 2025-12-10 00:10:24.510453 | 2025-12-10 00:10:24.510528 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-12-10 00:10:26.158776 | controller | created virtual environment CPython3.9.18.final.0-64 in 856ms 2025-12-10 00:10:26.208525 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2025-12-10 00:10:26.208553 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2025-12-10 00:10:26.208562 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2025-12-10 00:10:26.208640 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2025-12-10 00:10:26.544551 | controller | changed 2025-12-10 00:10:26.556721 | 2025-12-10 00:10:26.556850 | TASK [Set selinux package] 2025-12-10 00:10:26.590194 | controller | ok 2025-12-10 00:10:26.595571 | 2025-12-10 00:10:26.595636 | TASK [Set selinux package (Fedora)] 2025-12-10 00:10:26.652893 | controller | ok 2025-12-10 00:10:26.663913 | 2025-12-10 00:10:26.664042 | TASK [Install selinux into virtualenv] 2025-12-10 00:10:40.114075 | controller | Collecting selinux-please-lie-to-me 2025-12-10 00:11:04.395789 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2025-12-10 00:11:04.826295 | controller | Collecting setuptools<50.0.0 2025-12-10 00:11:04.884630 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2025-12-10 00:11:04.920236 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 26.0 MB/s eta 0:00:00 2025-12-10 00:11:04.997840 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2025-12-10 00:11:04.997994 | controller | Attempting uninstall: setuptools 2025-12-10 00:11:04.998674 | controller | Found existing installation: setuptools 62.6.0 2025-12-10 00:11:05.078246 | controller | Uninstalling setuptools-62.6.0: 2025-12-10 00:11:05.087290 | controller | Successfully uninstalled setuptools-62.6.0 2025-12-10 00:11:05.438912 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2025-12-10 00:11:16.763014 | controller | 2025-12-10 00:11:16.844694 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-12-10 00:11:16.844739 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-12-10 00:11:17.239870 | controller | ok: Runtime: 0:00:49.598298 2025-12-10 00:11:17.253030 | 2025-12-10 00:11:17.253171 | TASK [Install pytest-forked into virtualenv] 2025-12-10 00:11:29.004063 | controller | Collecting pytest-forked 2025-12-10 00:11:41.332723 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2025-12-10 00:11:41.462042 | controller | Collecting pytest>=3.10 2025-12-10 00:11:41.564827 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2025-12-10 00:11:41.596337 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 13.2 MB/s eta 0:00:00 2025-12-10 00:11:41.636122 | controller | Collecting py 2025-12-10 00:11:41.680131 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2025-12-10 00:11:41.689622 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 14.1 MB/s eta 0:00:00 2025-12-10 00:11:41.749625 | controller | Collecting packaging>=20 2025-12-10 00:11:41.821723 | controller | Downloading packaging-25.0-py3-none-any.whl (66 kB) 2025-12-10 00:11:41.831874 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 66.5/66.5 kB 8.1 MB/s eta 0:00:00 2025-12-10 00:11:41.868143 | controller | Collecting pluggy<2,>=1.5 2025-12-10 00:11:41.941447 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2025-12-10 00:11:41.997235 | controller | Collecting pygments>=2.7.2 2025-12-10 00:11:42.080026 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2025-12-10 00:11:42.105242 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 55.6 MB/s eta 0:00:00 2025-12-10 00:11:42.163441 | controller | Collecting tomli>=1 2025-12-10 00:11:42.223744 | controller | Downloading tomli-2.3.0-py3-none-any.whl (14 kB) 2025-12-10 00:11:42.264894 | controller | Collecting exceptiongroup>=1 2025-12-10 00:11:42.336885 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2025-12-10 00:11:42.377311 | controller | Collecting iniconfig>=1 2025-12-10 00:11:42.482468 | controller | Downloading iniconfig-2.1.0-py3-none-any.whl (6.0 kB) 2025-12-10 00:11:42.541835 | controller | Collecting typing-extensions>=4.6.0 2025-12-10 00:11:42.622939 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2025-12-10 00:11:42.632432 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 7.3 MB/s eta 0:00:00 2025-12-10 00:11:42.697881 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2025-12-10 00:11:43.770599 | controller | Successfully installed exceptiongroup-1.3.1 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-12-10 00:11:43.774781 | controller | 2025-12-10 00:11:43.849934 | controller | [notice] A new release of pip available: 22.2.2 -> 25.3 2025-12-10 00:11:43.849965 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2025-12-10 00:11:44.331282 | controller | ok: Runtime: 0:00:26.352163 2025-12-10 00:11:44.344278 | 2025-12-10 00:11:44.344424 | TASK [Update pip] 2025-12-10 00:11:44.917312 | controller | Requirement already satisfied: pip in ./venv/lib/python3.9/site-packages (22.2.2) 2025-12-10 00:11:55.297870 | controller | Collecting pip 2025-12-10 00:12:07.502841 | controller | Downloading pip-25.3-py3-none-any.whl (1.8 MB) 2025-12-10 00:12:07.552675 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 40.2 MB/s eta 0:00:00 2025-12-10 00:12:07.611569 | controller | Installing collected packages: pip 2025-12-10 00:12:07.611711 | controller | Attempting uninstall: pip 2025-12-10 00:12:07.612309 | controller | Found existing installation: pip 22.2.2 2025-12-10 00:12:07.776750 | controller | Uninstalling pip-22.2.2: 2025-12-10 00:12:07.793339 | controller | Successfully uninstalled pip-22.2.2 2025-12-10 00:12:08.566199 | controller | Successfully installed pip-25.3 2025-12-10 00:12:08.959420 | controller | ok: Runtime: 0:00:24.015317 2025-12-10 00:12:08.975816 | 2025-12-10 00:12:08.975971 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-12-10 00:12:09.196200 | controller | changed 2025-12-10 00:12:09.203775 | 2025-12-10 00:12:09.203886 | TASK [Install ansible into virtualenv] 2025-12-10 00:12:09.694078 | controller | Processing ./src/github.com/ansible/ansible 2025-12-10 00:12:09.698097 | controller | Installing build dependencies: started 2025-12-10 00:12:32.380997 | controller | Installing build dependencies: finished with status 'done' 2025-12-10 00:12:32.383452 | controller | Getting requirements to build wheel: started 2025-12-10 00:12:32.966880 | controller | Getting requirements to build wheel: finished with status 'done' 2025-12-10 00:12:32.969323 | controller | Preparing metadata (pyproject.toml): started 2025-12-10 00:12:33.431728 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2025-12-10 00:12:44.393815 | controller | Collecting jinja2 (from ansible-core==2.11.12.post0) 2025-12-10 00:13:08.620433 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2025-12-10 00:13:08.735721 | controller | Collecting PyYAML (from ansible-core==2.11.12.post0) 2025-12-10 00:13:08.740438 | 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-12-10 00:13:09.033551 | controller | Collecting cryptography (from ansible-core==2.11.12.post0) 2025-12-10 00:13:09.038217 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2025-12-10 00:13:09.054720 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.9/site-packages (from ansible-core==2.11.12.post0) (25.0) 2025-12-10 00:13:09.115348 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.11.12.post0) 2025-12-10 00:13:09.142335 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2025-12-10 00:13:09.345970 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.11.12.post0) 2025-12-10 00:13:09.379809 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2025-12-10 00:13:09.387877 | 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-12-10 00:13:09.404699 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.11.12.post0) 2025-12-10 00:13:09.408547 | controller | Downloading pycparser-2.23-py3-none-any.whl.metadata (993 bytes) 2025-12-10 00:13:09.498666 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.11.12.post0) 2025-12-10 00:13:09.507863 | 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-12-10 00:13:09.525981 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2025-12-10 00:13:09.540278 | controller | Downloading cryptography-46.0.3-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2025-12-10 00:13:09.633051 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 53.1 MB/s 0:00:00 2025-12-10 00:13:09.639364 | controller | Downloading cffi-2.0.0-cp39-cp39-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2025-12-10 00:13:09.649882 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2025-12-10 00:13:09.661953 | 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-12-10 00:13:09.670738 | controller | Downloading pycparser-2.23-py3-none-any.whl (118 kB) 2025-12-10 00:13:09.690445 | 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-12-10 00:13:09.705380 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 750.8/750.8 kB 42.5 MB/s 0:00:00 2025-12-10 00:13:09.720629 | controller | Building wheels for collected packages: ansible-core 2025-12-10 00:13:09.721796 | controller | Building wheel for ansible-core (pyproject.toml): started 2025-12-10 00:13:11.133250 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2025-12-10 00:13:11.135002 | controller | Created wheel for ansible-core: filename=ansible_core-2.11.12.post0-py3-none-any.whl size=1961250 sha256=5ab93685743d1fea8745e0653736da5b1d26af2bab42ed5464d3cf213840759c 2025-12-10 00:13:11.135266 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-cikmzzni/wheels/0f/a0/93/ab85b739e1eb1e0467171316f13571c14efdf87e0d5d44a33e 2025-12-10 00:13:11.143834 | controller | Successfully built ansible-core 2025-12-10 00:13:11.179948 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2025-12-10 00:13:12.625999 | controller | 2025-12-10 00:13:12.629240 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.11.12.post0 cffi-2.0.0 cryptography-46.0.3 jinja2-3.1.6 pycparser-2.23 resolvelib-0.5.4 2025-12-10 00:13:12.786324 | controller | ok: Runtime: 0:01:03.322042 2025-12-10 00:13:12.802078 | 2025-12-10 00:13:12.802221 | TASK [Setup download-artifact-fork role] 2025-12-10 00:13:12.835235 | controller | ok 2025-12-10 00:13:12.870258 | 2025-12-10 00:13:12.870379 | TASK [download-artifact-fork : Parse inventory response] 2025-12-10 00:13:12.928595 | controller | ok 2025-12-10 00:13:12.943804 | 2025-12-10 00:13:12.943999 | TASK [download-artifact-fork : Fetch info from Zuul API] 2025-12-10 00:13:12.980646 | controller | skipping: Conditional result was False 2025-12-10 00:13:12.989810 | 2025-12-10 00:13:12.989914 | TASK [download-artifact-fork : Parse build response] 2025-12-10 00:13:13.026231 | controller | skipping: Conditional result was False 2025-12-10 00:13:13.036508 | 2025-12-10 00:13:13.036663 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2025-12-10 00:13:13.269522 | controller | changed 2025-12-10 00:13:13.282423 | 2025-12-10 00:13:13.282556 | LOOP [download-artifact-fork : Download archive by type] 2025-12-10 00:13:13.321237 | controller | skipping: Conditional result was False 2025-12-10 00:13:13.321833 | 2025-12-10 00:13:14.056670 | controller | changed: OK (20218 bytes) 2025-12-10 00:13:14.085327 | 2025-12-10 00:13:14.085480 | TASK [deploy-artifacts : define __collections] 2025-12-10 00:13:14.124643 | controller | ok 2025-12-10 00:13:14.136569 | 2025-12-10 00:13:14.136707 | LOOP [deploy-artifacts : Create list of collections] 2025-12-10 00:13:14.165132 | controller | skipping: Conditional result was False 2025-12-10 00:13:14.175523 | controller | ok: 2025-12-10 00:13:14.175624 | controller | { 2025-12-10 00:13:14.175658 | controller | "branch": "main", 2025-12-10 00:13:14.175684 | controller | "job": "build-ansible-collection", 2025-12-10 00:13:14.175707 | controller | "metadata": { 2025-12-10 00:13:14.175753 | controller | "type": "ansible_collection", 2025-12-10 00:13:14.175777 | controller | "version": "5.0.0" 2025-12-10 00:13:14.175799 | controller | }, 2025-12-10 00:13:14.175820 | controller | "name": "ansible.network", 2025-12-10 00:13:14.175840 | controller | "project": "ansible-collections/ansible.network", 2025-12-10 00:13:14.175861 | controller | "url": "https://e2f99daaef3302a905d2-660382170450398e2c2c9da1b86ad5d1.ssl.cf5.rackcdn.com/ansible/590f5fdf33334119808301a5f1fd5e91/artifacts/ansible-network-5.0.0.tar.gz" 2025-12-10 00:13:14.175890 | controller | } 2025-12-10 00:13:14.184539 | 2025-12-10 00:13:14.184667 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2025-12-10 00:13:14.785642 | controller | Starting galaxy collection install process 2025-12-10 00:13:14.785842 | controller | Process install dependency map 2025-12-10 00:14:08.441100 | controller | [WARNING]: Skipping Galaxy server https://galaxy.ansible.com/api/. Got an 2025-12-10 00:14:08.441490 | controller | unexpected error when getting available versions of collection 2025-12-10 00:14:08.441544 | controller | ansible.netcommon: '/api/v3/plugin/ansible/content/published/collections/index/ 2025-12-10 00:14:08.441557 | controller | ansible/netcommon/versions/' 2025-12-10 00:14:08.441573 | controller | ERROR! Unexpected Exception, this is probably a bug: '/api/v3/plugin/ansible/content/published/collections/index/ansible/netcommon/versions/' 2025-12-10 00:14:08.478167 | controller | to see the full traceback, use -vvv 2025-12-10 00:14:08.756092 | controller | ERROR 2025-12-10 00:14:08.756442 | controller | { 2025-12-10 00:14:08.756477 | controller | "delta": "0:00:54.076927", 2025-12-10 00:14:08.756497 | controller | "end": "2025-12-10 00:14:08.478758", 2025-12-10 00:14:08.756515 | controller | "msg": "non-zero return code", 2025-12-10 00:14:08.756532 | controller | "rc": 250, 2025-12-10 00:14:08.756548 | controller | "start": "2025-12-10 00:13:14.401831" 2025-12-10 00:14:08.756564 | controller | } failure 2025-12-10 00:14:08.759518 | 2025-12-10 00:14:08.759594 | PLAY RECAP 2025-12-10 00:14:08.759655 | controller | ok: 44 changed: 24 unreachable: 0 failed: 1 skipped: 17 rescued: 0 ignored: 0 2025-12-10 00:14:08.759688 | 2025-12-10 00:14:08.979902 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-12-10 00:14:08.980926 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-10 00:14:09.561508 | 2025-12-10 00:14:09.561623 | PLAY [all] 2025-12-10 00:14:09.584082 | 2025-12-10 00:14:09.584187 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-12-10 00:14:10.033533 | controller | changed: non-zero return code 2025-12-10 00:14:10.046193 | 2025-12-10 00:14:10.046356 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-12-10 00:14:10.075200 | controller | skipping: Conditional result was False 2025-12-10 00:14:10.095774 | 2025-12-10 00:14:10.096007 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-12-10 00:14:10.134857 | 2025-12-10 00:14:10.135060 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-12-10 00:14:10.169418 | 2025-12-10 00:14:10.169595 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-12-10 00:14:10.194917 | controller | skipping: Conditional result was False 2025-12-10 00:14:10.205254 | 2025-12-10 00:14:10.205357 | LOOP [fetch-subunit-output : Generate subunit file] 2025-12-10 00:14:10.239421 | 2025-12-10 00:14:10.239646 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-12-10 00:14:10.265002 | controller | skipping: Conditional result was False 2025-12-10 00:14:10.273397 | 2025-12-10 00:14:10.273491 | TASK [fetch-subunit-output : Remove the temporary file] 2025-12-10 00:14:10.288616 | controller | skipping: Conditional result was False 2025-12-10 00:14:10.297921 | 2025-12-10 00:14:10.298015 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-12-10 00:14:10.312795 | controller | skipping: Conditional result was False 2025-12-10 00:14:10.350190 | 2025-12-10 00:14:10.350298 | PLAY RECAP 2025-12-10 00:14:10.350360 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-12-10 00:14:10.350391 | 2025-12-10 00:14:10.477870 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-12-10 00:14:10.479832 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-12-10 00:14:11.102614 | 2025-12-10 00:14:11.102747 | PLAY [all:!appliance*] 2025-12-10 00:14:11.125035 | 2025-12-10 00:14:11.125157 | TASK [unregister the node] 2025-12-10 00:14:11.658033 | controller | skipping: Conditional result was False 2025-12-10 00:14:11.665591 | 2025-12-10 00:14:11.665685 | TASK [include_role : fetch-output] 2025-12-10 00:14:11.710660 | controller | ok 2025-12-10 00:14:11.745979 | 2025-12-10 00:14:11.746291 | TASK [fetch-output : Set log path for multiple nodes] 2025-12-10 00:14:11.804101 | controller | skipping: Conditional result was False 2025-12-10 00:14:11.815875 | 2025-12-10 00:14:11.816018 | TASK [fetch-output : Set log path for single node] 2025-12-10 00:14:11.859874 | controller | ok 2025-12-10 00:14:11.870328 | 2025-12-10 00:14:11.870438 | LOOP [fetch-output : Ensure local output dirs] 2025-12-10 00:14:12.290594 | controller -> localhost | ok: "/var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/logs" 2025-12-10 00:14:12.546088 | controller -> localhost | changed: "/var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/artifacts" 2025-12-10 00:14:12.822299 | controller -> localhost | changed: "/var/lib/zuul/builds/84a9738d723441deb11d4b9e8e6730eb/work/docs" 2025-12-10 00:14:12.840985 | 2025-12-10 00:14:12.841147 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-12-10 00:14:13.628573 | controller | changed: 2025-12-10 00:14:13.628946 | controller | .d..t...... ./ 2025-12-10 00:14:13.629008 | controller | cd+++++++++ controller/ 2025-12-10 00:14:13.629074 | controller | changed: All items complete 2025-12-10 00:14:13.629118 | 2025-12-10 00:14:14.176987 | controller | changed: .d..t...... ./ 2025-12-10 00:14:14.771141 | controller | changed: .d..t...... ./ 2025-12-10 00:14:14.801780 | 2025-12-10 00:14:14.802000 | TASK [include_role : fetch-output-openshift] 2025-12-10 00:14:14.820142 | controller | skipping: Conditional result was False 2025-12-10 00:14:14.837463 | 2025-12-10 00:14:14.837836 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-12-10 00:14:14.873045 | controller | skipping: Conditional result was False 2025-12-10 00:14:14.880197 | controller | skipping: Conditional result was False 2025-12-10 00:14:14.938885 | 2025-12-10 00:14:14.939090 | PLAY [localhost] 2025-12-10 00:14:14.960082 | 2025-12-10 00:14:14.960276 | TASK [Run Zuul manifest role] 2025-12-10 00:14:14.985155 | localhost | ok 2025-12-10 00:14:15.000347 | 2025-12-10 00:14:15.000434 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-12-10 00:14:15.418194 | localhost | changed 2025-12-10 00:14:15.422973 | 2025-12-10 00:14:15.423039 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-12-10 00:14:15.451479 | localhost | ok 2025-12-10 00:14:15.460119 | 2025-12-10 00:14:15.460201 | TASK [Set zuul-log-path fact] 2025-12-10 00:14:15.478401 | localhost | ok 2025-12-10 00:14:15.492173 | 2025-12-10 00:14:15.492249 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-12-10 00:14:15.530189 | localhost | ok 2025-12-10 00:14:15.537912 | 2025-12-10 00:14:15.537972 | LOOP [Run upload-logs-swift role] 2025-12-10 00:14:15.581812 | localhost | Output suppressed because no_log was given 2025-12-10 00:14:15.614288 | 2025-12-10 00:14:15.614401 | TASK [Set zuul-log-path fact] 2025-12-10 00:14:15.648778 | localhost | skipping: Conditional result was False 2025-12-10 00:14:15.658035 | 2025-12-10 00:14:15.658172 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-12-10 00:14:16.058379 | localhost -> localhost | ok: Runtime: 0:00:00.004742 2025-12-10 00:14:16.069631 | 2025-12-10 00:14:16.069886 | TASK [upload-logs-swift : Upload logs to swift]