2026-02-24 00:06:56.801511 | Job console starting... 2026-02-24 00:06:56.812660 | Updating repositories 2026-02-24 00:07:00.992642 | Preparing job workspace 2026-02-24 00:07:08.028375 | Running Ansible setup... 2026-02-24 00:07:15.239675 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-24 00:07:15.859823 | 2026-02-24 00:07:15.859962 | PLAY [localhost] 2026-02-24 00:07:15.868738 | 2026-02-24 00:07:15.868811 | TASK [Gathering Facts] 2026-02-24 00:07:16.832263 | localhost | ok 2026-02-24 00:07:16.847426 | 2026-02-24 00:07:16.847564 | TASK [Setup log path fact] 2026-02-24 00:07:16.865487 | localhost | ok 2026-02-24 00:07:16.891636 | 2026-02-24 00:07:16.891754 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-24 00:07:16.962317 | localhost | ok 2026-02-24 00:07:16.970228 | 2026-02-24 00:07:16.970332 | TASK [emit-job-header : Print job information] 2026-02-24 00:07:17.023671 | # Job Information 2026-02-24 00:07:17.023854 | Ansible Version: 2.15.12 2026-02-24 00:07:17.023900 | Job: ansible-test-sanity-docker-stable-2.12 2026-02-24 00:07:17.023933 | Pipeline: periodic 2026-02-24 00:07:17.023962 | Executor: ze01.softwarefactory-project.io 2026-02-24 00:07:17.023991 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-24 00:07:17.024060 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/6dd/ansible/6ddc65909cba4a20a81ca782aa47d19c/ 2026-02-24 00:07:17.024098 | Event ID: 023de3d20dba42aeae84d5f5ac7fcc8d 2026-02-24 00:07:17.029686 | 2026-02-24 00:07:17.029779 | LOOP [emit-job-header : Print node information] 2026-02-24 00:07:17.182183 | localhost | ok: 2026-02-24 00:07:17.182322 | localhost | # Node Information 2026-02-24 00:07:17.182349 | localhost | Inventory Hostname: controller 2026-02-24 00:07:17.182370 | localhost | Hostname: np0005627467 2026-02-24 00:07:17.182390 | localhost | Username: zuul 2026-02-24 00:07:17.182412 | localhost | Distro: Fedora 37 2026-02-24 00:07:17.182431 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-02-24 00:07:17.182449 | localhost | Region: ca-ymq-1 2026-02-24 00:07:17.182467 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-24 00:07:17.182485 | localhost | Product Name: OpenStack Nova 2026-02-24 00:07:17.182503 | localhost | Interface IP: 162.253.55.209 2026-02-24 00:07:17.194694 | 2026-02-24 00:07:17.194820 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-24 00:07:17.570864 | localhost -> localhost | changed 2026-02-24 00:07:17.576175 | 2026-02-24 00:07:17.576247 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-24 00:07:18.640478 | localhost -> localhost | changed 2026-02-24 00:07:18.698345 | 2026-02-24 00:07:18.698453 | PLAY [all:!appliance*] 2026-02-24 00:07:18.724308 | 2026-02-24 00:07:18.724445 | TASK [include_role : start-zuul-console] 2026-02-24 00:07:18.747605 | controller | ok 2026-02-24 00:07:18.765548 | 2026-02-24 00:07:18.765666 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-24 00:07:19.173757 | controller | ok 2026-02-24 00:07:19.197634 | 2026-02-24 00:07:19.197773 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-24 00:07:20.084123 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-24 00:07:20.100185 | 2026-02-24 00:07:20.100329 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-24 00:07:20.653966 | controller | skipping: Conditional result was False 2026-02-24 00:07:20.663325 | 2026-02-24 00:07:20.663456 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-24 00:07:20.701707 | controller | skipping: Conditional result was False 2026-02-24 00:07:20.710441 | 2026-02-24 00:07:20.710564 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-24 00:07:20.747579 | controller | skipping: Conditional result was False 2026-02-24 00:07:20.757767 | 2026-02-24 00:07:20.757905 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-24 00:07:20.782666 | controller | skipping: Conditional result was False 2026-02-24 00:07:20.789512 | 2026-02-24 00:07:20.789601 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-24 00:07:20.813885 | controller | skipping: Conditional result was False 2026-02-24 00:07:20.820699 | 2026-02-24 00:07:20.820777 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-24 00:07:20.844431 | controller | skipping: Conditional result was False 2026-02-24 00:07:20.856494 | 2026-02-24 00:07:20.856564 | TASK [Disable Fedora Modular] 2026-02-24 00:07:21.154533 | controller | changed 2026-02-24 00:07:21.168471 | 2026-02-24 00:07:21.168627 | TASK [Enable EPEL] 2026-02-24 00:07:21.197342 | controller | skipping: Conditional result was False 2026-02-24 00:07:21.211181 | 2026-02-24 00:07:21.211301 | TASK [Register the RHEL node] 2026-02-24 00:07:21.765634 | 2026-02-24 00:07:21.765818 | TASK [Show the subscription-manager status] 2026-02-24 00:07:22.326170 | controller | skipping: Conditional result was False 2026-02-24 00:07:22.339684 | 2026-02-24 00:07:22.339838 | TASK [Enable EPEL on RHEL] 2026-02-24 00:07:22.898048 | controller | skipping: Conditional result was False 2026-02-24 00:07:22.911894 | 2026-02-24 00:07:22.912132 | TASK [Install git and tox] 2026-02-24 00:08:55.441991 | controller | changed 2026-02-24 00:08:55.450745 | 2026-02-24 00:08:55.450845 | TASK [include_role : prepare-workspace] 2026-02-24 00:08:55.483649 | controller | ok 2026-02-24 00:08:55.512327 | 2026-02-24 00:08:55.512436 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-24 00:08:55.747879 | controller | ok 2026-02-24 00:08:55.756282 | 2026-02-24 00:08:55.756359 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-24 00:14:28.462957 | controller | Output suppressed because no_log was given 2026-02-24 00:14:28.517704 | 2026-02-24 00:14:28.517836 | TASK [include_role : prepare-workspace-openshift] 2026-02-24 00:14:28.544957 | controller | skipping: Conditional result was False 2026-02-24 00:14:28.570142 | 2026-02-24 00:14:28.570238 | PLAY [all:!appliance] 2026-02-24 00:14:28.587729 | 2026-02-24 00:14:28.587831 | TASK [Run add-build-sshkey role (RSA)] 2026-02-24 00:14:28.618344 | controller | ok 2026-02-24 00:14:28.635486 | 2026-02-24 00:14:28.635625 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-24 00:14:28.918260 | controller -> localhost | ok 2026-02-24 00:14:28.926792 | 2026-02-24 00:14:28.926896 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-24 00:14:28.947414 | controller | ok 2026-02-24 00:14:28.963925 | controller | included: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-24 00:14:28.970403 | 2026-02-24 00:14:28.970468 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-24 00:14:29.416207 | controller -> localhost | Generating public/private rsa key pair. 2026-02-24 00:14:29.416474 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/6ddc65909cba4a20a81ca782aa47d19c_id_rsa. 2026-02-24 00:14:29.416521 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/6ddc65909cba4a20a81ca782aa47d19c_id_rsa.pub. 2026-02-24 00:14:29.416560 | controller -> localhost | The key fingerprint is: 2026-02-24 00:14:29.416593 | controller -> localhost | SHA256:rTEwBJ0upURQDvtTOKZho0m7SgIGnfkyEz2t7QI+BEw zuul-build-sshkey 2026-02-24 00:14:29.416623 | controller -> localhost | The key's randomart image is: 2026-02-24 00:14:29.416658 | controller -> localhost | +---[RSA 2048]----+ 2026-02-24 00:14:29.416693 | controller -> localhost | | Eo++o.. | 2026-02-24 00:14:29.416728 | controller -> localhost | |o. B.++ | 2026-02-24 00:14:29.416763 | controller -> localhost | |ooX.B+= | 2026-02-24 00:14:29.416797 | controller -> localhost | |o=.Oo*.o . | 2026-02-24 00:14:29.416830 | controller -> localhost | |+oB =.. S . | 2026-02-24 00:14:29.416876 | controller -> localhost | |oo.= o + | 2026-02-24 00:14:29.416917 | controller -> localhost | |.oo . . . | 2026-02-24 00:14:29.416955 | controller -> localhost | |+ . . | 2026-02-24 00:14:29.416989 | controller -> localhost | |. | 2026-02-24 00:14:29.417059 | controller -> localhost | +----[SHA256]-----+ 2026-02-24 00:14:29.417147 | controller -> localhost | ok: Runtime: 0:00:00.076560 2026-02-24 00:14:29.431319 | 2026-02-24 00:14:29.431451 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-24 00:14:29.461959 | controller | ok 2026-02-24 00:14:29.472685 | controller | included: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-24 00:14:29.482116 | 2026-02-24 00:14:29.482228 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-24 00:14:29.516552 | controller | skipping: Conditional result was False 2026-02-24 00:14:29.523280 | 2026-02-24 00:14:29.523358 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-24 00:14:30.309684 | controller | changed 2026-02-24 00:14:30.316857 | 2026-02-24 00:14:30.316973 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-24 00:14:30.541460 | controller | ok 2026-02-24 00:14:30.547994 | 2026-02-24 00:14:30.548102 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-24 00:14:31.124460 | controller | changed 2026-02-24 00:14:31.133595 | 2026-02-24 00:14:31.133749 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-24 00:14:31.760683 | controller | changed 2026-02-24 00:14:31.766745 | 2026-02-24 00:14:31.766818 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-24 00:14:31.781830 | controller | skipping: Conditional result was False 2026-02-24 00:14:31.789480 | 2026-02-24 00:14:31.789577 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-24 00:14:32.178774 | controller -> localhost | changed 2026-02-24 00:14:32.191485 | 2026-02-24 00:14:32.191608 | TASK [add-build-sshkey : Add back temp key] 2026-02-24 00:14:32.493887 | controller -> localhost | Identity added: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/6ddc65909cba4a20a81ca782aa47d19c_id_rsa (zuul-build-sshkey) 2026-02-24 00:14:32.494237 | controller -> localhost | ok: Runtime: 0:00:00.007244 2026-02-24 00:14:32.505053 | 2026-02-24 00:14:32.505168 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-24 00:14:32.905772 | controller | ok 2026-02-24 00:14:32.912838 | 2026-02-24 00:14:32.912944 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-24 00:14:32.948274 | controller | skipping: Conditional result was False 2026-02-24 00:14:32.965157 | 2026-02-24 00:14:32.965291 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-24 00:14:32.989982 | controller | ok 2026-02-24 00:14:33.009996 | 2026-02-24 00:14:33.010126 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-24 00:14:33.259075 | controller -> localhost | ok 2026-02-24 00:14:33.266108 | 2026-02-24 00:14:33.266239 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-24 00:14:33.296561 | controller | ok 2026-02-24 00:14:33.309493 | controller | included: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-24 00:14:33.316043 | 2026-02-24 00:14:33.316149 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-24 00:14:33.574857 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-24 00:14:33.575449 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/6ddc65909cba4a20a81ca782aa47d19c_id_ecdsa. 2026-02-24 00:14:33.575486 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/6ddc65909cba4a20a81ca782aa47d19c_id_ecdsa.pub. 2026-02-24 00:14:33.575519 | controller -> localhost | The key fingerprint is: 2026-02-24 00:14:33.575541 | controller -> localhost | SHA256:NdxZVf+RwR7EFX5+Knq1uRxuGpyUYbghVQX1ndqkH5k zuul-build-sshkey 2026-02-24 00:14:33.575561 | controller -> localhost | The key's randomart image is: 2026-02-24 00:14:33.575580 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-24 00:14:33.575598 | controller -> localhost | | ..o+B=B| 2026-02-24 00:14:33.575617 | controller -> localhost | | o o o.=*| 2026-02-24 00:14:33.575635 | controller -> localhost | | . * = .**| 2026-02-24 00:14:33.575653 | controller -> localhost | | o = o=o*| 2026-02-24 00:14:33.575671 | controller -> localhost | | S . oo E+| 2026-02-24 00:14:33.575688 | controller -> localhost | | o .o.o| 2026-02-24 00:14:33.575707 | controller -> localhost | | =.o+ | 2026-02-24 00:14:33.575726 | controller -> localhost | | ..=+. | 2026-02-24 00:14:33.575744 | controller -> localhost | | ...o+. | 2026-02-24 00:14:33.575762 | controller -> localhost | +----[SHA256]-----+ 2026-02-24 00:14:33.575812 | controller -> localhost | ok: Runtime: 0:00:00.014529 2026-02-24 00:14:33.582456 | 2026-02-24 00:14:33.582529 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-24 00:14:33.612030 | controller | ok 2026-02-24 00:14:33.620135 | controller | included: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-24 00:14:33.631770 | 2026-02-24 00:14:33.631906 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-24 00:14:33.646703 | controller | skipping: Conditional result was False 2026-02-24 00:14:33.655408 | 2026-02-24 00:14:33.655540 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-24 00:14:33.909592 | controller | changed 2026-02-24 00:14:33.915607 | 2026-02-24 00:14:33.915677 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-24 00:14:34.154371 | controller | ok 2026-02-24 00:14:34.164218 | 2026-02-24 00:14:34.164325 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-24 00:14:34.798737 | controller | changed 2026-02-24 00:14:34.813521 | 2026-02-24 00:14:34.813708 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-24 00:14:35.434435 | controller | changed 2026-02-24 00:14:35.442357 | 2026-02-24 00:14:35.442495 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-24 00:14:35.478334 | controller | skipping: Conditional result was False 2026-02-24 00:14:35.487306 | 2026-02-24 00:14:35.487455 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-24 00:14:35.819883 | controller -> localhost | changed 2026-02-24 00:14:35.833446 | 2026-02-24 00:14:35.833541 | TASK [add-build-sshkey : Add back temp key] 2026-02-24 00:14:36.134187 | controller -> localhost | Identity added: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/6ddc65909cba4a20a81ca782aa47d19c_id_ecdsa (zuul-build-sshkey) 2026-02-24 00:14:36.134396 | controller -> localhost | ok: Runtime: 0:00:00.018636 2026-02-24 00:14:36.144364 | 2026-02-24 00:14:36.144513 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-24 00:14:36.359186 | controller | ok 2026-02-24 00:14:36.374821 | 2026-02-24 00:14:36.374996 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-24 00:14:36.422807 | controller | skipping: Conditional result was False 2026-02-24 00:14:36.441511 | 2026-02-24 00:14:36.441653 | TASK [include_role : remove-zuul-sshkey] 2026-02-24 00:14:36.466893 | controller | skipping: Conditional result was False 2026-02-24 00:14:36.476280 | 2026-02-24 00:14:36.476376 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-24 00:14:36.742689 | controller | ok: "logs" 2026-02-24 00:14:36.743050 | controller | ok: All items complete 2026-02-24 00:14:36.743091 | 2026-02-24 00:14:36.918506 | controller | ok: "artifacts" 2026-02-24 00:14:37.106440 | controller | ok: "docs" 2026-02-24 00:14:37.121114 | 2026-02-24 00:14:37.121229 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-24 00:14:37.344799 | controller | changed: "logs" 2026-02-24 00:14:37.539573 | controller | changed: "artifacts" 2026-02-24 00:14:37.739073 | controller | changed: "docs" 2026-02-24 00:14:37.782275 | 2026-02-24 00:14:37.782372 | PLAY RECAP 2026-02-24 00:14:37.782417 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-24 00:14:37.782444 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-24 00:14:37.782462 | 2026-02-24 00:14:37.947739 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-24 00:14:37.950626 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-24 00:14:38.606281 | 2026-02-24 00:14:38.606442 | PLAY [all] 2026-02-24 00:14:38.631571 | 2026-02-24 00:14:38.631708 | TASK [Install binary dependencies] 2026-02-24 00:14:38.716802 | controller | ok 2026-02-24 00:14:38.751700 | 2026-02-24 00:14:38.751884 | TASK [bindep : Include find tasks] 2026-02-24 00:14:38.786457 | controller | ok 2026-02-24 00:14:38.800527 | controller | included: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-24 00:14:38.810769 | 2026-02-24 00:14:38.810894 | TASK [bindep : Look for bindep.txt] 2026-02-24 00:14:39.369161 | controller | ok 2026-02-24 00:14:39.376857 | 2026-02-24 00:14:39.376960 | TASK [bindep : Define bindep_file fact] 2026-02-24 00:14:39.391747 | controller | skipping: Conditional result was False 2026-02-24 00:14:39.403455 | 2026-02-24 00:14:39.404059 | TASK [bindep : Look for other-requirements.txt] 2026-02-24 00:14:39.636178 | controller | ok 2026-02-24 00:14:39.643063 | 2026-02-24 00:14:39.643177 | TASK [bindep : Define bindep_file fact] 2026-02-24 00:14:39.687693 | controller | skipping: Conditional result was False 2026-02-24 00:14:39.694176 | 2026-02-24 00:14:39.694267 | TASK [bindep : Look for bindep fallback file] 2026-02-24 00:14:39.740396 | controller | skipping: Conditional result was False 2026-02-24 00:14:39.747326 | 2026-02-24 00:14:39.747418 | TASK [bindep : Define bindep_file fact] 2026-02-24 00:14:39.771036 | controller | skipping: Conditional result was False 2026-02-24 00:14:39.778495 | 2026-02-24 00:14:39.778593 | TASK [bindep : Include bindep tasks] 2026-02-24 00:14:39.817551 | controller | skipping: Conditional result was False 2026-02-24 00:14:39.824316 | 2026-02-24 00:14:39.824411 | TASK [bindep : Include install tasks] 2026-02-24 00:14:39.868670 | controller | skipping: Conditional result was False 2026-02-24 00:14:39.875968 | 2026-02-24 00:14:39.876149 | LOOP [bindep : Include package tasks] 2026-02-24 00:14:39.934533 | 2026-02-24 00:14:39.934709 | TASK [Run test-setup role] 2026-02-24 00:14:39.955400 | controller | ok 2026-02-24 00:14:39.979334 | 2026-02-24 00:14:39.979453 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-24 00:14:40.211125 | controller | ok 2026-02-24 00:14:40.227281 | 2026-02-24 00:14:40.227394 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-24 00:14:40.807090 | controller | skipping: Conditional result was False 2026-02-24 00:14:40.861564 | 2026-02-24 00:14:40.861664 | PLAY RECAP 2026-02-24 00:14:40.861710 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-24 00:14:40.861731 | 2026-02-24 00:14:40.999105 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-24 00:14:40.999977 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-24 00:14:41.664837 | 2026-02-24 00:14:41.665069 | PLAY [controller] 2026-02-24 00:14:41.691874 | 2026-02-24 00:14:41.692043 | TASK [Create the /root directory] 2026-02-24 00:14:42.259549 | controller | ok 2026-02-24 00:14:42.283648 | 2026-02-24 00:14:42.283791 | TASK [Install glibc-langpack-en] 2026-02-24 00:14:46.206306 | controller | ok: Nothing to do 2026-02-24 00:14:46.212338 | 2026-02-24 00:14:46.212449 | TASK [Ensure controller directory exists] 2026-02-24 00:14:46.438112 | controller | changed 2026-02-24 00:14:46.447202 | 2026-02-24 00:14:46.447323 | TASK [Install container runtime] 2026-02-24 00:14:46.514727 | controller | ok 2026-02-24 00:14:46.555291 | 2026-02-24 00:14:46.555446 | LOOP [ensure-podman : Find distribution installation] 2026-02-24 00:14:46.585944 | controller | ok: "/var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-02-24 00:14:46.600810 | controller | included: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-02-24 00:14:46.607838 | 2026-02-24 00:14:46.607911 | TASK [ensure-podman : Install podman (RedHat)] 2026-02-24 00:15:53.058600 | controller | changed 2026-02-24 00:15:53.065572 | 2026-02-24 00:15:53.065679 | TASK [ensure-podman : Fetch podman version] 2026-02-24 00:15:53.574369 | controller | Client: Podman Engine 2026-02-24 00:15:53.574523 | controller | Version: 4.6.2 2026-02-24 00:15:53.574571 | controller | API Version: 4.6.2 2026-02-24 00:15:53.574830 | controller | Go Version: go1.19.12 2026-02-24 00:15:53.574889 | controller | Built: Mon Aug 28 19:38:31 2023 2026-02-24 00:15:53.574933 | controller | OS/Arch: linux/amd64 2026-02-24 00:15:53.646826 | controller | ok: Runtime: 0:00:00.183463 2026-02-24 00:15:53.659783 | 2026-02-24 00:15:53.659912 | TASK [ensure-podman : Print podman version installed] 2026-02-24 00:15:53.709261 | Podman version: Client: Podman Engine 2026-02-24 00:15:53.709528 | Version: 4.6.2 2026-02-24 00:15:53.709571 | API Version: 4.6.2 2026-02-24 00:15:53.709601 | Go Version: go1.19.12 2026-02-24 00:15:53.709631 | Built: Mon Aug 28 19:38:31 2023 2026-02-24 00:15:53.709661 | OS/Arch: linux/amd64 2026-02-24 00:15:53.715616 | 2026-02-24 00:15:53.715683 | TASK [ensure-podman : Validate podman engine] 2026-02-24 00:15:54.256863 | controller | skipping: Conditional result was False 2026-02-24 00:15:54.263496 | 2026-02-24 00:15:54.263587 | TASK [ensure-podman : Set up docker compatability socket] 2026-02-24 00:15:54.288095 | controller | skipping: Conditional result was False 2026-02-24 00:15:54.300320 | 2026-02-24 00:15:54.300469 | TASK [Ensure python3.8 is present] 2026-02-24 00:15:54.314187 | controller | skipping: Conditional result was False 2026-02-24 00:15:54.321421 | 2026-02-24 00:15:54.321512 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-02-24 00:15:54.342806 | controller | ok 2026-02-24 00:15:54.361873 | 2026-02-24 00:15:54.361994 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-02-24 00:15:55.851797 | controller | ok: Nothing to do 2026-02-24 00:15:55.857929 | 2026-02-24 00:15:55.857999 | TASK [our-ensure-python : Also install python3-devel] 2026-02-24 00:16:04.348286 | controller | changed 2026-02-24 00:16:04.359635 | 2026-02-24 00:16:04.359729 | TASK [Run ensure-virtualenv role] 2026-02-24 00:16:04.379170 | controller | ok 2026-02-24 00:16:04.401583 | 2026-02-24 00:16:04.401705 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-02-24 00:16:34.689349 | [Zuul] Log Stream did not terminate 2026-02-24 00:16:34.689654 | controller | ok: Runtime: 0:00:00.008356 2026-02-24 00:16:34.697954 | 2026-02-24 00:16:34.698071 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-02-24 00:16:34.736605 | controller | skipping: Conditional result was False 2026-02-24 00:16:34.737182 | controller | ok: All items complete 2026-02-24 00:16:34.737255 | 2026-02-24 00:16:34.758727 | 2026-02-24 00:16:34.758881 | TASK [Find the full path of the Python interpreter] 2026-02-24 00:21:16.681808 | controller | ok 2026-02-24 00:21:16.689977 | 2026-02-24 00:21:16.690086 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-02-24 00:21:17.474653 | controller | created virtual environment CPython3.10.13.final.0-64 in 476ms 2026-02-24 00:21:17.488171 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-02-24 00:21:17.488237 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-02-24 00:21:17.488258 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-02-24 00:21:17.488286 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-02-24 00:21:17.736346 | controller | changed 2026-02-24 00:21:17.747163 | 2026-02-24 00:21:17.747277 | TASK [Set selinux package] 2026-02-24 00:21:17.781706 | controller | ok 2026-02-24 00:21:17.789913 | 2026-02-24 00:21:17.790003 | TASK [Set selinux package (Fedora)] 2026-02-24 00:21:17.833864 | controller | ok 2026-02-24 00:21:17.842728 | 2026-02-24 00:21:17.842822 | TASK [Install selinux into virtualenv] 2026-02-24 00:21:19.075212 | controller | Collecting selinux-please-lie-to-me 2026-02-24 00:21:19.119950 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-02-24 00:21:19.463238 | controller | Collecting setuptools<50.0.0 2026-02-24 00:21:19.468052 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-02-24 00:21:19.511420 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 20.2 MB/s eta 0:00:00 2026-02-24 00:21:19.586522 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-02-24 00:21:19.586713 | controller | Attempting uninstall: setuptools 2026-02-24 00:21:19.587393 | controller | Found existing installation: setuptools 62.6.0 2026-02-24 00:21:19.658419 | controller | Uninstalling setuptools-62.6.0: 2026-02-24 00:21:19.680831 | controller | Successfully uninstalled setuptools-62.6.0 2026-02-24 00:21:20.034934 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-02-24 00:21:20.161624 | controller | 2026-02-24 00:21:20.237148 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-24 00:21:20.237186 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-24 00:21:20.395029 | controller | ok: Runtime: 0:00:02.152964 2026-02-24 00:21:20.401507 | 2026-02-24 00:21:20.401643 | TASK [Install pytest-forked into virtualenv] 2026-02-24 00:21:21.176475 | controller | Collecting pytest-forked 2026-02-24 00:21:21.213464 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-02-24 00:21:21.263558 | controller | Collecting py 2026-02-24 00:21:21.272686 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-02-24 00:21:21.291147 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 5.6 MB/s eta 0:00:00 2026-02-24 00:21:21.451425 | controller | Collecting pytest>=3.10 2026-02-24 00:21:21.456314 | controller | Downloading pytest-9.0.2-py3-none-any.whl (374 kB) 2026-02-24 00:21:21.474667 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 24.8 MB/s eta 0:00:00 2026-02-24 00:21:21.539374 | controller | Collecting pluggy<2,>=1.5 2026-02-24 00:21:21.545594 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-24 00:21:21.568211 | controller | Collecting iniconfig>=1.0.1 2026-02-24 00:21:21.573892 | controller | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-02-24 00:21:21.634903 | controller | Collecting pygments>=2.7.2 2026-02-24 00:21:21.639852 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-02-24 00:21:21.663443 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 60.3 MB/s eta 0:00:00 2026-02-24 00:21:21.734572 | controller | Collecting packaging>=22 2026-02-24 00:21:21.743536 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-24 00:21:21.751499 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 11.6 MB/s eta 0:00:00 2026-02-24 00:21:21.788538 | controller | Collecting exceptiongroup>=1 2026-02-24 00:21:21.792079 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-02-24 00:21:21.872690 | controller | Collecting tomli>=1 2026-02-24 00:21:21.878481 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-02-24 00:21:21.948322 | controller | Collecting typing-extensions>=4.6.0 2026-02-24 00:21:21.953149 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-02-24 00:21:21.960144 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 8.0 MB/s eta 0:00:00 2026-02-24 00:21:22.060144 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-02-24 00:21:23.272495 | controller | Successfully installed exceptiongroup-1.3.1 iniconfig-2.3.0 packaging-26.0 pluggy-1.6.0 py-1.11.0 pygments-2.19.2 pytest-9.0.2 pytest-forked-1.6.0 tomli-2.4.0 typing-extensions-4.15.0 2026-02-24 00:21:23.275642 | controller | 2026-02-24 00:21:23.342885 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-24 00:21:23.342898 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-24 00:21:23.448079 | controller | ok: Runtime: 0:00:02.637808 2026-02-24 00:21:23.457153 | 2026-02-24 00:21:23.457293 | TASK [Update pip] 2026-02-24 00:21:23.984088 | controller | Requirement already satisfied: pip in ./venv/lib/python3.10/site-packages (22.2.2) 2026-02-24 00:21:24.113481 | controller | Collecting pip 2026-02-24 00:21:24.149703 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-02-24 00:21:24.218249 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 27.9 MB/s eta 0:00:00 2026-02-24 00:21:24.280046 | controller | Installing collected packages: pip 2026-02-24 00:21:24.280274 | controller | Attempting uninstall: pip 2026-02-24 00:21:24.280902 | controller | Found existing installation: pip 22.2.2 2026-02-24 00:21:24.442437 | controller | Uninstalling pip-22.2.2: 2026-02-24 00:21:24.459770 | controller | Successfully uninstalled pip-22.2.2 2026-02-24 00:21:25.294440 | controller | Successfully installed pip-26.0.1 2026-02-24 00:21:25.508327 | controller | ok: Runtime: 0:00:01.674204 2026-02-24 00:21:25.516202 | 2026-02-24 00:21:25.516343 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-02-24 00:21:25.727862 | controller | changed 2026-02-24 00:21:25.737114 | 2026-02-24 00:21:25.737274 | TASK [Install ansible into virtualenv] 2026-02-24 00:21:26.267607 | controller | Processing ./src/github.com/ansible/ansible 2026-02-24 00:21:26.269230 | controller | Installing build dependencies: started 2026-02-24 00:21:27.128545 | controller | Installing build dependencies: finished with status 'done' 2026-02-24 00:21:27.733803 | controller | Getting requirements to build wheel: started 2026-02-24 00:21:27.733863 | controller | Getting requirements to build wheel: finished with status 'done' 2026-02-24 00:21:28.230992 | controller | Preparing metadata (pyproject.toml): started 2026-02-24 00:21:28.231013 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-02-24 00:21:28.332322 | controller | Collecting jinja2 (from ansible-core==2.12.10.post0) 2026-02-24 00:21:28.387052 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-02-24 00:21:28.461381 | controller | Collecting PyYAML (from ansible-core==2.12.10.post0) 2026-02-24 00:21:28.464706 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.4 kB) 2026-02-24 00:21:28.739930 | controller | Collecting cryptography (from ansible-core==2.12.10.post0) 2026-02-24 00:21:28.744877 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-02-24 00:21:28.759053 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.10/site-packages (from ansible-core==2.12.10.post0) (26.0) 2026-02-24 00:21:28.775262 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.12.10.post0) 2026-02-24 00:21:28.780979 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-02-24 00:21:29.010452 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.12.10.post0) 2026-02-24 00:21:29.015032 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-02-24 00:21:29.022039 | controller | Requirement already satisfied: typing-extensions>=4.13.2 in ./venv/lib/python3.10/site-packages (from cryptography->ansible-core==2.12.10.post0) (4.15.0) 2026-02-24 00:21:29.036347 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.12.10.post0) 2026-02-24 00:21:29.040074 | controller | Downloading pycparser-3.0-py3-none-any.whl.metadata (8.2 kB) 2026-02-24 00:21:29.122053 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.12.10.post0) 2026-02-24 00:21:29.125567 | controller | Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl.metadata (2.7 kB) 2026-02-24 00:21:29.139744 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-02-24 00:21:29.149228 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-02-24 00:21:29.223974 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 69.4 MB/s 0:00:00 2026-02-24 00:21:29.228128 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-02-24 00:21:29.240028 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-02-24 00:21:29.249716 | controller | Downloading markupsafe-3.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (20 kB) 2026-02-24 00:21:29.258696 | controller | Downloading pycparser-3.0-py3-none-any.whl (48 kB) 2026-02-24 00:21:29.266102 | controller | Downloading pyyaml-6.0.3-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.manylinux_2_28_x86_64.whl (770 kB) 2026-02-24 00:21:29.281932 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 770.3/770.3 kB 51.8 MB/s 0:00:00 2026-02-24 00:21:29.292821 | controller | Building wheels for collected packages: ansible-core 2026-02-24 00:21:29.293654 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-02-24 00:21:30.956743 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-02-24 00:21:30.961540 | controller | Created wheel for ansible-core: filename=ansible_core-2.12.10.post0-py3-none-any.whl size=2117520 sha256=928f59ffdc1ae538f0ac60904fa047effee192357944239255ce906b98281dae 2026-02-24 00:21:30.961553 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-k20ul149/wheels/68/27/b0/9b068d12b42b734c64dc438319298ad4c1c5027b13ccb3a660 2026-02-24 00:21:30.961574 | controller | Successfully built ansible-core 2026-02-24 00:21:30.993299 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-02-24 00:21:32.725257 | controller | 2026-02-24 00:21:32.727463 | controller | Successfully installed MarkupSafe-3.0.3 PyYAML-6.0.3 ansible-core-2.12.10.post0 cffi-2.0.0 cryptography-46.0.5 jinja2-3.1.6 pycparser-3.0 resolvelib-0.5.4 2026-02-24 00:21:33.306580 | controller | ok: Runtime: 0:00:06.905211 2026-02-24 00:21:33.321179 | 2026-02-24 00:21:33.321352 | TASK [Setup download-artifact-fork role] 2026-02-24 00:21:33.351970 | controller | ok 2026-02-24 00:21:33.389490 | 2026-02-24 00:21:33.389657 | TASK [download-artifact-fork : Parse inventory response] 2026-02-24 00:21:33.444430 | controller | ok 2026-02-24 00:21:33.453710 | 2026-02-24 00:21:33.453843 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-02-24 00:21:33.489596 | controller | skipping: Conditional result was False 2026-02-24 00:21:33.499585 | 2026-02-24 00:21:33.499769 | TASK [download-artifact-fork : Parse build response] 2026-02-24 00:21:33.535754 | controller | skipping: Conditional result was False 2026-02-24 00:21:33.546790 | 2026-02-24 00:21:33.546929 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-02-24 00:21:33.912332 | controller | changed 2026-02-24 00:21:33.926809 | 2026-02-24 00:21:33.927133 | LOOP [download-artifact-fork : Download archive by type] 2026-02-24 00:21:33.967047 | controller | skipping: Conditional result was False 2026-02-24 00:21:33.967834 | 2026-02-24 00:21:35.274765 | controller | changed: OK (20200 bytes) 2026-02-24 00:21:35.306298 | 2026-02-24 00:21:35.306459 | TASK [deploy-artifacts : define __collections] 2026-02-24 00:21:35.330232 | controller | ok 2026-02-24 00:21:35.340284 | 2026-02-24 00:21:35.340434 | LOOP [deploy-artifacts : Create list of collections] 2026-02-24 00:21:35.369367 | controller | skipping: Conditional result was False 2026-02-24 00:21:35.385686 | controller | ok: 2026-02-24 00:21:35.385810 | controller | { 2026-02-24 00:21:35.385852 | controller | "branch": "main", 2026-02-24 00:21:35.385880 | controller | "job": "build-ansible-collection", 2026-02-24 00:21:35.385911 | controller | "metadata": { 2026-02-24 00:21:35.385944 | controller | "type": "ansible_collection", 2026-02-24 00:21:35.385976 | controller | "version": "5.0.0" 2026-02-24 00:21:35.386007 | controller | }, 2026-02-24 00:21:35.386070 | controller | "name": "ansible.network", 2026-02-24 00:21:35.386097 | controller | "project": "ansible-collections/ansible.network", 2026-02-24 00:21:35.386123 | controller | "url": "https://f30bcf576ae4b165da2a-1880631fa2540a8d60b3b9ea139018c7.ssl.cf2.rackcdn.com/ansible/88f0594a032e442cb572fdcc5e8066b4/artifacts/ansible-network-5.0.0.tar.gz" 2026-02-24 00:21:35.386264 | controller | } 2026-02-24 00:21:35.406544 | 2026-02-24 00:21:35.406694 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-02-24 00:21:36.019274 | controller | Starting galaxy collection install process 2026-02-24 00:21:36.019377 | controller | Process install dependency map 2026-02-24 00:21:58.856939 | controller | Starting collection install process 2026-02-24 00:21:58.857056 | controller | Installing 'ansible.network:5.0.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/network' 2026-02-24 00:21:58.865575 | controller | ansible.network:5.0.0 was installed successfully 2026-02-24 00:21:58.865706 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3664j54pfom6/tmp1ro6e5k0/ansible-utils-6.0.1-44i5qdmb 2026-02-24 00:21:59.698067 | controller | Installing 'ansible.utils:6.0.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils' 2026-02-24 00:21:59.895446 | controller | ansible.utils:6.0.1 was installed successfully 2026-02-24 00:21:59.895586 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/arista-eos-12.0.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3664j54pfom6/tmp1ro6e5k0/arista-eos-12.0.1-uh0civ_z 2026-02-24 00:22:00.630206 | controller | Installing 'arista.eos:12.0.1' to '/home/zuul/.ansible/collections/ansible_collections/arista/eos' 2026-02-24 00:22:01.198913 | controller | arista.eos:12.0.1 was installed successfully 2026-02-24 00:22:01.199069 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.4.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3664j54pfom6/tmp1ro6e5k0/ansible-netcommon-8.4.0-qi7tn5sc 2026-02-24 00:22:01.841730 | controller | Installing 'ansible.netcommon:8.4.0' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon' 2026-02-24 00:22:01.987229 | controller | ansible.netcommon:8.4.0 was installed successfully 2026-02-24 00:22:01.987352 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/junipernetworks-junos-11.0.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3664j54pfom6/tmp1ro6e5k0/junipernetworks-junos-11.0.0-cbktank0 2026-02-24 00:22:02.922003 | controller | Installing 'junipernetworks.junos:11.0.0' to '/home/zuul/.ansible/collections/ansible_collections/junipernetworks/junos' 2026-02-24 00:22:03.480839 | controller | junipernetworks.junos:11.0.0 was installed successfully 2026-02-24 00:22:03.480990 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-iosxr-12.1.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3664j54pfom6/tmp1ro6e5k0/cisco-iosxr-12.1.1-el08v7eq 2026-02-24 00:22:04.257287 | controller | Installing 'cisco.iosxr:12.1.1' to '/home/zuul/.ansible/collections/ansible_collections/cisco/iosxr' 2026-02-24 00:22:04.939661 | controller | cisco.iosxr:12.1.1 was installed successfully 2026-02-24 00:22:04.939725 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-nxos-11.1.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3664j54pfom6/tmp1ro6e5k0/cisco-nxos-11.1.3-r5urffcr 2026-02-24 00:22:05.869933 | controller | Installing 'cisco.nxos:11.1.3' to '/home/zuul/.ansible/collections/ansible_collections/cisco/nxos' 2026-02-24 00:22:07.406458 | controller | cisco.nxos:11.1.3 was installed successfully 2026-02-24 00:22:07.406606 | controller | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/cisco-ios-11.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3664j54pfom6/tmp1ro6e5k0/cisco-ios-11.3.0-r4ah7tde 2026-02-24 00:22:08.044460 | controller | Installing 'cisco.ios:11.3.0' to '/home/zuul/.ansible/collections/ansible_collections/cisco/ios' 2026-02-24 00:22:08.793790 | controller | cisco.ios:11.3.0 was installed successfully 2026-02-24 00:22:08.977784 | controller | ok: Runtime: 0:00:33.228638 2026-02-24 00:22:09.004545 | 2026-02-24 00:22:09.004738 | TASK [podman may need iptables kernel modules] 2026-02-24 00:22:09.586608 | controller | ok: Runtime: 0:00:00.013521 2026-02-24 00:22:09.635929 | 2026-02-24 00:22:09.636080 | PLAY RECAP 2026-02-24 00:22:09.636144 | controller | ok: 26 changed: 15 unreachable: 0 failed: 0 skipped: 6 rescued: 0 ignored: 0 2026-02-24 00:22:09.636176 | 2026-02-24 00:22:09.772584 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-24 00:22:09.773563 | RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master] 2026-02-24 00:22:10.413887 | 2026-02-24 00:22:10.414101 | PLAY [controller] 2026-02-24 00:22:10.436512 | 2026-02-24 00:22:10.436654 | TASK [Copy the galaxy.yml on the controller] 2026-02-24 00:22:11.120423 | controller | changed 2026-02-24 00:22:11.127441 | 2026-02-24 00:22:11.127565 | TASK [Load information from galaxy.yml] 2026-02-24 00:22:11.167859 | controller | ok 2026-02-24 00:22:11.173876 | 2026-02-24 00:22:11.173958 | LOOP [Enable FIPS mode] 2026-02-24 00:22:11.195305 | 2026-02-24 00:22:11.195477 | LOOP [ansible-test : Include branch specific variables.] 2026-02-24 00:22:11.235621 | controller | ok: "/var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/untrusted/project_2/github.com/ansible/ansible-zuul-jobs/roles/ansible-test/vars/default.yaml" 2026-02-24 00:22:11.235907 | 2026-02-24 00:22:11.248805 | 2026-02-24 00:22:11.248959 | TASK [ansible-test : Setup --skip-tags for test_options] 2026-02-24 00:22:11.299333 | controller | ok 2026-02-24 00:22:11.311755 | 2026-02-24 00:22:11.311913 | TASK [ansible-test : Enable --inventory for network-integration] 2026-02-24 00:22:11.338562 | controller | skipping: Conditional result was False 2026-02-24 00:22:11.347518 | 2026-02-24 00:22:11.347649 | TASK [ansible-test : Enable --retry-on-error] 2026-02-24 00:22:11.372824 | controller | skipping: Conditional result was False 2026-02-24 00:22:11.381619 | 2026-02-24 00:22:11.381723 | TASK [ansible-test : Enable --continue-on-error] 2026-02-24 00:22:11.414589 | controller | ok 2026-02-24 00:22:11.421212 | 2026-02-24 00:22:11.421304 | TASK [ansible-test : Enable --requirements] 2026-02-24 00:22:11.445926 | controller | skipping: Conditional result was False 2026-02-24 00:22:11.453128 | 2026-02-24 00:22:11.453211 | TASK [ansible-test : Adjust options for unit tests] 2026-02-24 00:22:11.477525 | controller | skipping: Conditional result was False 2026-02-24 00:22:11.490608 | 2026-02-24 00:22:11.490733 | TASK [ansible-test : Install requirements with unit tests] 2026-02-24 00:22:11.536708 | controller | ok 2026-02-24 00:22:11.545290 | 2026-02-24 00:22:11.545406 | TASK [ansible-test : Set --skip-test] 2026-02-24 00:22:11.592905 | controller | skipping: Conditional result was False 2026-02-24 00:22:11.603623 | 2026-02-24 00:22:11.603787 | TASK [ansible-test : Reduce verbosity] 2026-02-24 00:22:11.640352 | controller | ok 2026-02-24 00:22:11.649191 | 2026-02-24 00:22:11.649293 | TASK [ansible-test : Setup --python option] 2026-02-24 00:22:11.675911 | controller | skipping: Conditional result was False 2026-02-24 00:22:11.688334 | 2026-02-24 00:22:11.688505 | TASK [ansible-test : Setup --docker option] 2026-02-24 00:22:11.757845 | controller | ok 2026-02-24 00:22:11.763552 | 2026-02-24 00:22:11.763618 | TASK [ansible-test : check if MANIFEST.json exists] 2026-02-24 00:22:11.966618 | controller | ok 2026-02-24 00:22:11.972751 | 2026-02-24 00:22:11.972829 | TASK [ansible-test : Copy the MANIFEST.json on the controller] 2026-02-24 00:22:11.998165 | controller | skipping: Conditional result was False 2026-02-24 00:22:12.005777 | 2026-02-24 00:22:12.005927 | TASK [ansible-test : Load information from MANIFEST.json] 2026-02-24 00:22:12.031555 | controller | skipping: Conditional result was False 2026-02-24 00:22:12.039039 | 2026-02-24 00:22:12.039153 | TASK [ansible-test : Setup location of project for integration tests] 2026-02-24 00:22:12.053597 | controller | skipping: Conditional result was False 2026-02-24 00:22:12.062562 | 2026-02-24 00:22:12.062679 | TASK [ansible-test : Copy the galaxy.yml on the controller] 2026-02-24 00:22:12.425380 | controller | ok 2026-02-24 00:22:12.439650 | 2026-02-24 00:22:12.439910 | TASK [ansible-test : Load information from galaxy.yml] 2026-02-24 00:22:12.503513 | controller | ok 2026-02-24 00:22:12.518428 | 2026-02-24 00:22:12.518675 | TASK [ansible-test : Setup location of project for integration tests] 2026-02-24 00:22:12.580752 | controller | ok 2026-02-24 00:22:12.590250 | 2026-02-24 00:22:12.590476 | TASK [ansible-test : Setup minimum test requirements] 2026-02-24 00:22:12.635756 | controller | ok 2026-02-24 00:22:12.642508 | 2026-02-24 00:22:12.642658 | TASK [ansible-test : Ensure pip temp directory exists in /var/tmp] 2026-02-24 00:22:13.136994 | controller | ok 2026-02-24 00:22:13.146749 | 2026-02-24 00:22:13.147006 | TASK [ansible-test : Install python requirements] 2026-02-24 00:22:13.907120 | controller | Collecting yamllint (from -r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) 2026-02-24 00:22:13.978690 | controller | Downloading yamllint-1.38.0-py3-none-any.whl.metadata (4.2 kB) 2026-02-24 00:22:14.001138 | controller | Collecting pathspec>=1.0.0 (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) 2026-02-24 00:22:14.004540 | controller | Downloading pathspec-1.0.4-py3-none-any.whl.metadata (13 kB) 2026-02-24 00:22:14.011215 | controller | Requirement already satisfied: pyyaml in ./venv/lib64/python3.10/site-packages (from yamllint->-r /home/zuul/.ansible/collections/ansible_collections/ansible/network/test-requirements.txt (line 1)) (6.0.3) 2026-02-24 00:22:14.016292 | controller | Downloading yamllint-1.38.0-py3-none-any.whl (68 kB) 2026-02-24 00:22:14.040629 | controller | Downloading pathspec-1.0.4-py3-none-any.whl (55 kB) 2026-02-24 00:22:14.080725 | controller | Installing collected packages: pathspec, yamllint 2026-02-24 00:22:14.205393 | controller | 2026-02-24 00:22:14.207828 | controller | Successfully installed pathspec-1.0.4 yamllint-1.38.0 2026-02-24 00:22:14.687761 | controller | ok: Runtime: 0:00:00.769878 2026-02-24 00:22:14.701899 | 2026-02-24 00:22:14.702077 | TASK [ansible-test : Copy the integration_config.yml file from the controller] 2026-02-24 00:22:14.980146 | controller | cp: cannot stat '/tmp/integration_config.yml': No such file or directory 2026-02-24 00:22:15.256188 | controller | ERROR 2026-02-24 00:22:15.256401 | controller | { 2026-02-24 00:22:15.256431 | controller | "delta": "0:00:00.004135", 2026-02-24 00:22:15.256450 | controller | "end": "2026-02-24 00:22:14.980286", 2026-02-24 00:22:15.256467 | controller | "msg": "non-zero return code", 2026-02-24 00:22:15.256483 | controller | "rc": 1, 2026-02-24 00:22:15.256500 | controller | "start": "2026-02-24 00:22:14.976151" 2026-02-24 00:22:15.256515 | controller | } 2026-02-24 00:22:15.256536 | controller | ERROR: Ignoring Errors 2026-02-24 00:22:15.265486 | 2026-02-24 00:22:15.265644 | TASK [ansible-test : Copy potential cloud provider configuration for ansible-test in the collection] 2026-02-24 00:22:15.818138 | controller | skipping: Conditional result was False 2026-02-24 00:22:15.832968 | 2026-02-24 00:22:15.833170 | TASK [ansible-test : Enable persistent connection logging] 2026-02-24 00:22:16.225078 | controller | changed: section and option added 2026-02-24 00:22:16.232700 | 2026-02-24 00:22:16.232829 | TASK [ansible-test : Set persistent ssh_type value] 2026-02-24 00:22:16.447415 | controller | changed: option added 2026-02-24 00:22:16.455187 | 2026-02-24 00:22:16.455299 | TASK [ansible-test : Set ansible log path] 2026-02-24 00:22:16.681106 | controller | changed: section and option added 2026-02-24 00:22:16.694956 | 2026-02-24 00:22:16.695238 | TASK [ansible-test : Identify the changed targets] 2026-02-24 00:22:17.252133 | controller | skipping: Conditional result was False 2026-02-24 00:22:17.269205 | 2026-02-24 00:22:17.269440 | TASK [ansible-test : set_fact] 2026-02-24 00:22:17.339253 | controller | skipping: Conditional result was False 2026-02-24 00:22:17.349246 | 2026-02-24 00:22:17.349396 | TASK [ansible-test : Set the targets] 2026-02-24 00:22:17.392126 | controller | ok 2026-02-24 00:22:17.410247 | 2026-02-24 00:22:17.410476 | TASK [ansible-test : set_fact] 2026-02-24 00:22:17.437920 | controller | skipping: Conditional result was False 2026-02-24 00:22:17.448722 | 2026-02-24 00:22:17.448897 | TASK [ansible-test : List the targets] 2026-02-24 00:22:17.995626 | controller | skipping: Conditional result was False 2026-02-24 00:22:18.002565 | 2026-02-24 00:22:18.002632 | TASK [ansible-test : set_fact] 2026-02-24 00:22:18.027662 | controller | skipping: Conditional result was False 2026-02-24 00:22:18.034954 | 2026-02-24 00:22:18.035056 | TASK [ansible-test : set_fact] 2026-02-24 00:22:18.061535 | controller | skipping: Conditional result was False 2026-02-24 00:22:18.067824 | 2026-02-24 00:22:18.067893 | LOOP [ansible-test : set_fact] 2026-02-24 00:22:18.145918 | 2026-02-24 00:22:18.146259 | TASK [ansible-test : set_fact] 2026-02-24 00:22:18.162615 | controller | skipping: Conditional result was False 2026-02-24 00:22:18.177501 | 2026-02-24 00:22:18.177651 | TASK [ansible-test : debug] 2026-02-24 00:22:18.194887 | controller | skipping: Conditional result was False 2026-02-24 00:22:18.211767 | 2026-02-24 00:22:18.211989 | TASK [ansible-test : debug] 2026-02-24 00:22:18.248886 | About to run: ansible-test sanity --requirements --docker -v 2026-02-24 00:22:18.256768 | 2026-02-24 00:22:18.256856 | TASK [ansible-test : Run the test suite] 2026-02-24 00:22:18.724144 | controller | Run command: podman -v 2026-02-24 00:22:18.762741 | controller | Detected "podman" container runtime version: podman version 4.6.2 2026-02-24 00:22:18.763248 | controller | Run command: ssh-keygen -m PEM -q -t rsa -N '' -f /home/zuul/.ansible/test/id_rsa 2026-02-24 00:22:19.119037 | controller | Run command: podman system connection list --format=json 2026-02-24 00:22:19.151778 | controller | Run command: podman image inspect quay.io/ansible/ansible-test-utility-container:2.0.0 2026-02-24 00:22:19.206528 | controller | Stream command: podman pull quay.io/ansible/ansible-test-utility-container:2.0.0 2026-02-24 00:22:19.255983 | controller | Trying to pull quay.io/ansible/ansible-test-utility-container:2.0.0... 2026-02-24 00:22:19.760845 | controller | Getting image source signatures 2026-02-24 00:22:19.760986 | controller | Copying blob sha256:b0331c2be39b3d217a77f9bd21157e5250d7d5a5bca1b4a6fc33d23f3a62f079 2026-02-24 00:22:19.761033 | controller | Copying blob sha256:d1f06b6a9dd086195da047818fa2e586a9a07dbdd5bb5510047ea59c02d9e12b 2026-02-24 00:22:19.761144 | controller | Copying blob sha256:c158987b05517b6f2c5913f3acef1f2182a32345a304fe357e3ace5fadcad715 2026-02-24 00:22:20.315965 | controller | Copying config sha256:846c88fea76d38a1e53f09457d565cf8b2d380b234a88675c733e5bbdf6f26c7 2026-02-24 00:22:20.326434 | controller | Writing manifest to image destination 2026-02-24 00:22:20.344146 | controller | 846c88fea76d38a1e53f09457d565cf8b2d380b234a88675c733e5bbdf6f26c7 2026-02-24 00:22:20.350443 | controller | Run command: podman image inspect quay.io/ansible/ansible-test-utility-container:2.0.0 2026-02-24 00:22:20.416154 | controller | Run command: podman run --volume /sys/fs/cgroup:/probe:ro --name ansible-test-probe-kVQGdR71 --rm quay.io/ansible/ansible-test-utility-container:2.0.0 sh -c 'audit-status && cat /proc/sys/fs/nr_open && ulimit -Hn && (cat /proc/1/loginuid; echo) && echo "-" && cat /proc/1/cgroup && echo "-" && cat /proc/1/mountinfo' 2026-02-24 00:22:20.701782 | controller | Container host audit status: ECONNREFUSED (-111) 2026-02-24 00:22:20.753845 | controller | Container host max open files: 524288 2026-02-24 00:22:20.753882 | controller | Container loginuid: 0 2026-02-24 00:22:20.753889 | controller | Starting new "pypi-test-container-kVQGdR71" container. 2026-02-24 00:22:20.753895 | controller | Run command: podman image inspect quay.io/ansible/pypi-test-container:1.0.0 2026-02-24 00:22:20.753909 | controller | Stream command: podman pull quay.io/ansible/pypi-test-container:1.0.0 2026-02-24 00:22:20.813809 | controller | Trying to pull quay.io/ansible/pypi-test-container:1.0.0... 2026-02-24 00:22:21.354441 | controller | Getting image source signatures 2026-02-24 00:22:21.354713 | controller | Copying blob sha256:ad15fa9da3982f21803d3d9895abdc1c788d7b5cc56a66f28a6edfa859e5644e 2026-02-24 00:22:21.354764 | controller | Copying blob sha256:ff496a88c8ed9b745dab2f00bfbd9013c6d1db198442a6a8683998a29a85458a 2026-02-24 00:22:21.354798 | controller | Copying blob sha256:04a5f4cda3eea2313a61a2f72208342a57ea36a9326dff54f4f26ed47d145c7c 2026-02-24 00:22:21.693306 | controller | Copying blob sha256:2e5170e1f09947ed33fdbbc906f171de8f72c173e5fa6371c64eb3207a0fe138 2026-02-24 00:22:21.693322 | controller | Copying blob sha256:0ce83f459fe7e0bf459d0c222ef3b2ca4d9911f6b0f9aae02c2120561b54ca18 2026-02-24 00:22:21.693327 | controller | Copying blob sha256:7641eb41b08ce6cc657efb1e6493fe81f2e8d846eabcd12a931b8910f8d1d610 2026-02-24 00:22:21.693334 | controller | Copying blob sha256:087d913524244a58dd7306b75a77cb7eac041371d726b7265b9dfc40c720761b 2026-02-24 00:22:21.775710 | controller | Copying blob sha256:8b92efd6a1009fce5c35f4a9c5d573ff85a44a3a8d45c74f8052f5cacf5c9954 2026-02-24 00:22:26.951411 | controller | Copying config sha256:bd2983c8b11505d16a3a36b016b1ad82cfec1057c7358d47febc8106d49a6e70 2026-02-24 00:22:26.962922 | controller | Writing manifest to image destination 2026-02-24 00:22:26.985609 | controller | bd2983c8b11505d16a3a36b016b1ad82cfec1057c7358d47febc8106d49a6e70 2026-02-24 00:22:26.994390 | controller | Run command: podman image inspect quay.io/ansible/pypi-test-container:1.0.0 2026-02-24 00:22:27.095898 | controller | Run command: podman network inspect podman 2026-02-24 00:22:27.196733 | controller | Run command: podman run -dt -p 3141 --ulimit nofile=10240 --name pypi-test-container-kVQGdR71 --network podman quay.io/ansible/pypi-test-container:1.0.0 2026-02-24 00:22:27.661669 | controller | Adding "pypi-test-container-kVQGdR71" to container database. 2026-02-24 00:22:27.661784 | controller | Run command: podman container inspect pypi-test-container-kVQGdR71 2026-02-24 00:22:27.814809 | controller | Run command: podman info --format '{{ json . }}' 2026-02-24 00:22:28.066735 | controller | Run command: podman version --format '{{ json . }}' 2026-02-24 00:22:28.101735 | controller | Container runtime: podman client=4.6.2 server=4.6.2 cgroup=v2 2026-02-24 00:22:28.102128 | controller | Assuming Docker is available on localhost. 2026-02-24 00:22:28.102136 | controller | Starting new "ansible-test-controller-kVQGdR71" container. 2026-02-24 00:22:28.102307 | controller | Run command: podman image inspect quay.io/ansible/default-test-container:4.2.1 2026-02-24 00:22:28.146650 | controller | Stream command: podman pull quay.io/ansible/default-test-container:4.2.1 2026-02-24 00:22:28.187084 | controller | Trying to pull quay.io/ansible/default-test-container:4.2.1... 2026-02-24 00:22:28.651273 | controller | Getting image source signatures 2026-02-24 00:22:28.652039 | controller | Copying blob sha256:28f2f958146ab8490310300b9befaedb3b3007c341a8c978ebc9e1887b51c3b8 2026-02-24 00:22:28.652232 | controller | Copying blob sha256:e80c964ece6a3edf0db1cfc72ae0e6f0699fb776bbfcc92b708fbb945b0b9547 2026-02-24 00:22:28.652269 | controller | Copying blob sha256:f22ccc0b8772d8e1bcb40f137b373686bc27427a70c0e41dd22b38016e09e7e0 2026-02-24 00:22:28.652289 | controller | Copying blob sha256:3cf8fb62ba5ffb221a2edb2208741346eb4d2d99a174138e4afbb69ce1fd9966 2026-02-24 00:22:28.652299 | controller | Copying blob sha256:f1f7256ecf9240d36a07d749ab30ced65f4ab586d7e836fb4acda283ead6321a 2026-02-24 00:22:28.652311 | controller | Copying blob sha256:d670905c9835f6cd256586ee35dc7c5627c355fa036230f5257723f5596a5408 2026-02-24 00:22:28.921489 | controller | Copying blob sha256:1dd83cc8efef406b274309fdf1e986fd874585fd5f68c184d012792486b38ac7 2026-02-24 00:22:29.053967 | controller | Copying blob sha256:33f47da76efd373dc00d01d2030442cb8a3796bc197d4cb4154f1d2393514049 2026-02-24 00:22:29.070071 | controller | Copying blob sha256:e95fea48e359df9f30aa55b34a8b72f43e61b1369b8fe398ef098b63aeeee410 2026-02-24 00:22:29.108388 | controller | Copying blob sha256:2f14d98739b556759c698b41ff9509ea00952f2eafe6604fcd06e8846c4ae9e0 2026-02-24 00:22:29.122793 | controller | Copying blob sha256:63fedd77f90cb69b508153da344beffa25f9f4ae32dc65250fda6022d7c53cff 2026-02-24 00:22:29.461241 | controller | Copying blob sha256:04c38eab1781d5cb24ec1754517fd5a9b704b9d8e9e2e052f46723cff8ba0d84 2026-02-24 00:22:29.536995 | controller | Copying blob sha256:4839094d7900badb1c3cea7b39705a6a51ef882a84bd60c3495e177037764eaa 2026-02-24 00:22:29.925777 | controller | Copying blob sha256:3a9de26d26ade017e9e12733bcbcdc285987719c5b9ac2ef8f00f3aba5266b0d 2026-02-24 00:22:30.648484 | controller | Copying blob sha256:4ed69b35842ae3222aa6aa64e8a2564e5961dabc854afb1e8c3fa358604bee57 2026-02-24 00:22:36.565366 | controller | Copying blob sha256:b4d7fc5ccf288a0d4f69e7ecb8d5a8c895d29e835f251b7b6bcb718a8fb53b47 2026-02-24 00:22:36.576533 | controller | Copying blob sha256:bf4990542af60cd381dda9f0b58c549ce26b4f9ef51200adb0084259a6f6d075 2026-02-24 00:22:36.582907 | controller | Copying blob sha256:b1875203865296aab332081bb65e5cb96d3083d5d57960a7f568528c75de384b 2026-02-24 00:22:36.699652 | controller | Copying blob sha256:d9f23fa8a9005f3dafca20fd0e15f3e0c17b59c9c7734de8dbd646e3c0fbe18c 2026-02-24 00:22:36.810380 | controller | Copying blob sha256:1445efde9a42398979cbad4cae3d7e549966b051a49d365f1a5bfee13c8c2a64 2026-02-24 00:22:36.847202 | controller | Copying blob sha256:a8f84c8ff4d521f3814e9ba7855ad946cf5d30e3f657dabc72459540bbd94007 2026-02-24 00:22:36.986565 | controller | Copying blob sha256:55aab14d4cd3051b6545c0a2dc15bec2c844b0fced90d339b60cbf9d121bf721 2026-02-24 00:23:06.351751 | controller | Copying config sha256:5b2cdba6ae5df40251666851e37df8883912c29e2964643216be3195d8756371 2026-02-24 00:23:06.369601 | controller | Writing manifest to image destination 2026-02-24 00:23:06.491772 | controller | 5b2cdba6ae5df40251666851e37df8883912c29e2964643216be3195d8756371 2026-02-24 00:23:06.499171 | controller | Run command: podman image inspect quay.io/ansible/default-test-container:4.2.1 2026-02-24 00:23:06.568542 | controller | Run command: podman run --tmpfs /tmp:exec --tmpfs /run:exec --tmpfs /run/lock --cap-add SYS_CHROOT --systemd always --cgroupns private -dt --ulimit nofile=10240 --name ansible-test-controller-kVQGdR71 --network podman quay.io/ansible/default-test-container:4.2.1 2026-02-24 00:23:06.804993 | controller | Adding "ansible-test-controller-kVQGdR71" to container database. 2026-02-24 00:23:06.881428 | controller | Run command: podman container inspect ansible-test-controller-kVQGdR71 2026-02-24 00:23:06.881470 | controller | Stream command with data: podman exec -i ansible-test-controller-kVQGdR71 /bin/sh 2026-02-24 00:23:07.892566 | controller | Scanning collection root: /home/zuul/.ansible/collections/ansible_collections 2026-02-24 00:23:07.900483 | controller | Including collection: ansible.netcommon (278 files) 2026-02-24 00:23:07.908164 | controller | Including collection: ansible.network (23 files) 2026-02-24 00:23:07.908198 | controller | Including collection: ansible.utils (441 files) 2026-02-24 00:23:07.933001 | controller | Including collection: arista.eos (1031 files) 2026-02-24 00:23:07.961749 | controller | Including collection: cisco.ios (1215 files) 2026-02-24 00:23:07.987256 | controller | Including collection: cisco.iosxr (1044 files) 2026-02-24 00:23:08.032635 | controller | Including collection: cisco.nxos (1687 files) 2026-02-24 00:23:08.057352 | controller | Including collection: junipernetworks.junos (963 files) 2026-02-24 00:23:08.081072 | controller | Creating a payload archive containing 7494 files... 2026-02-24 00:23:09.707748 | controller | Created a 6220154 byte payload archive containing 7494 files in 1 seconds. 2026-02-24 00:23:09.708828 | controller | Run command with stdin: podman exec -i ansible-test-controller-kVQGdR71 tar oxzf - -C /root 2026-02-24 00:23:10.525820 | controller | Creating container database. 2026-02-24 00:23:10.529906 | controller | Assuming Podman is available on localhost. 2026-02-24 00:23:10.529942 | controller | Detected Podman host IP: 127.0.0.1 2026-02-24 00:23:10.530732 | controller | Stream command: podman exec ansible-test-controller-kVQGdR71 /usr/bin/env ANSIBLE_TEST_CONTENT_ROOT=/root/ansible_collections/ansible/network LC_ALL=en_US.UTF-8 /usr/bin/python3.9 /root/ansible/bin/ansible-test sanity --containers '{"control": {"__pypi_proxy__": {"pypi-test-container-kVQGdR71": {"host_ip": "10.88.0.2", "names": ["pypi-test-container-kVQGdR71"], "ports": [3141]}}}, "managed": {"__pypi_proxy__": {"pypi-test-container-kVQGdR71": {"host_ip": "10.88.0.2", "names": ["pypi-test-container-kVQGdR71"], "ports": [3141]}}}}' --requirements -v --truncate 0 --color no --host-path tests/output/.tmp/host-g539igf2 --metadata tests/output/.tmp/metadata-e0rm2lus.json 2026-02-24 00:27:10.064369 | [Zuul] Log Stream did not terminate 2026-02-24 00:27:10.064687 | controller | ERROR 2026-02-24 00:27:10.064848 | controller | { 2026-02-24 00:27:10.064902 | controller | "msg": "Data could not be sent to remote host \"162.253.55.209\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.209 port 22: No route to host\r\n", 2026-02-24 00:27:10.064947 | controller | "unreachable": true 2026-02-24 00:27:10.064986 | controller | } unreachable 2026-02-24 00:27:10.067276 | 2026-02-24 00:27:10.067372 | PLAY RECAP 2026-02-24 00:27:10.067449 | controller | ok: 21 changed: 6 unreachable: 1 failed: 0 skipped: 20 rescued: 0 ignored: 1 2026-02-24 00:27:10.067493 | 2026-02-24 00:27:10.199160 | RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/run.yaml@master] 2026-02-24 00:27:10.201504 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-24 00:27:10.777927 | 2026-02-24 00:27:10.778061 | PLAY [all] 2026-02-24 00:27:10.798930 | 2026-02-24 00:27:10.799042 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-24 00:28:14.382168 | controller | ERROR 2026-02-24 00:28:14.382769 | controller | { 2026-02-24 00:28:14.382890 | controller | "msg": "Failed to connect to the host via ssh: ssh: connect to host 162.253.55.209 port 22: Connection timed out", 2026-02-24 00:28:14.383001 | controller | "unreachable": true 2026-02-24 00:28:14.383208 | controller | } unreachable 2026-02-24 00:28:14.385921 | 2026-02-24 00:28:14.386058 | PLAY RECAP 2026-02-24 00:28:14.386139 | controller | ok: 0 changed: 0 unreachable: 1 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-24 00:28:14.386178 | 2026-02-24 00:28:14.575901 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-24 00:28:14.577147 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-24 00:28:15.237241 | 2026-02-24 00:28:15.237412 | PLAY [all:!appliance*] 2026-02-24 00:28:15.261957 | 2026-02-24 00:28:15.262215 | TASK [unregister the node] 2026-02-24 00:28:15.800823 | controller | skipping: Conditional result was False 2026-02-24 00:28:15.814378 | 2026-02-24 00:28:15.814591 | TASK [include_role : fetch-output] 2026-02-24 00:28:15.872285 | controller | ok 2026-02-24 00:28:15.896137 | 2026-02-24 00:28:15.896325 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-24 00:28:15.974757 | controller | skipping: Conditional result was False 2026-02-24 00:28:15.990578 | 2026-02-24 00:28:15.990872 | TASK [fetch-output : Set log path for single node] 2026-02-24 00:28:16.038971 | controller | ok 2026-02-24 00:28:16.048072 | 2026-02-24 00:28:16.048236 | LOOP [fetch-output : Ensure local output dirs] 2026-02-24 00:28:16.504900 | controller -> localhost | ok: "/var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/logs" 2026-02-24 00:28:16.798320 | controller -> localhost | changed: "/var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/artifacts" 2026-02-24 00:28:17.089799 | controller -> localhost | changed: "/var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/work/docs" 2026-02-24 00:28:17.124146 | 2026-02-24 00:28:17.124481 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-24 00:28:17.879610 | controller | changed: 2026-02-24 00:28:17.879821 | controller | .d..t...... ./ 2026-02-24 00:28:17.879850 | controller | cd+++++++++ controller/ 2026-02-24 00:28:17.879883 | controller | changed: All items complete 2026-02-24 00:28:17.879904 | 2026-02-24 00:28:18.419642 | controller | changed: .d..t...... ./ 2026-02-24 00:28:18.974679 | controller | changed: .d..t...... ./ 2026-02-24 00:28:19.008057 | 2026-02-24 00:28:19.008294 | TASK [include_role : fetch-output-openshift] 2026-02-24 00:28:19.025948 | controller | skipping: Conditional result was False 2026-02-24 00:28:19.036460 | 2026-02-24 00:28:19.036577 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-24 00:28:19.067458 | controller | skipping: Conditional result was False 2026-02-24 00:28:19.077697 | controller | skipping: Conditional result was False 2026-02-24 00:28:19.124908 | 2026-02-24 00:28:19.125091 | PLAY [localhost] 2026-02-24 00:28:19.145965 | 2026-02-24 00:28:19.146320 | TASK [Run Zuul manifest role] 2026-02-24 00:28:19.168228 | localhost | ok 2026-02-24 00:28:19.192309 | 2026-02-24 00:28:19.192504 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-24 00:28:19.607626 | localhost | changed 2026-02-24 00:28:19.630702 | 2026-02-24 00:28:19.630843 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-24 00:28:19.663858 | localhost | ok 2026-02-24 00:28:19.677161 | 2026-02-24 00:28:19.677279 | TASK [Set zuul-log-path fact] 2026-02-24 00:28:19.696910 | localhost | ok 2026-02-24 00:28:19.710554 | 2026-02-24 00:28:19.710628 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-24 00:28:19.752010 | localhost | ok 2026-02-24 00:28:19.761181 | 2026-02-24 00:28:19.761315 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-02-24 00:28:19.801999 | localhost | ok: 2026-02-24 00:28:19.802337 | localhost | { 2026-02-24 00:28:19.802395 | localhost | "auth": { 2026-02-24 00:28:19.802446 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-02-24 00:28:19.802486 | localhost | "username": "9LF91O0RK7JU" 2026-02-24 00:28:19.802692 | localhost | }, 2026-02-24 00:28:19.802905 | localhost | "profile": "rackspace", 2026-02-24 00:28:19.802951 | localhost | "region_name": "ORD" 2026-02-24 00:28:19.802998 | localhost | } 2026-02-24 00:28:19.803105 | 2026-02-24 00:28:19.814756 | 2026-02-24 00:28:19.814910 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-02-24 00:28:19.835336 | localhost | Output suppressed because no_log was given 2026-02-24 00:28:19.850434 | 2026-02-24 00:28:19.850590 | TASK [upload-logs-failover : Initialize control vars] 2026-02-24 00:28:19.893066 | localhost | ok 2026-02-24 00:28:19.899907 | 2026-02-24 00:28:19.899997 | LOOP [upload-logs-failover : Perform log upload] 2026-02-24 00:28:19.959648 | localhost | Output suppressed because no_log was given 2026-02-24 00:28:20.021444 | localhost | included: /var/lib/zuul/builds/6ddc65909cba4a20a81ca782aa47d19c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-02-24 00:28:20.028468 | 2026-02-24 00:28:20.028543 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-02-24 00:28:20.138691 | localhost | Output suppressed because no_log was given 2026-02-24 00:28:20.146418 | 2026-02-24 00:28:20.146522 | TASK [Upload logs to swift] 2026-02-24 00:28:20.175809 | localhost | ok 2026-02-24 00:28:20.204088 | 2026-02-24 00:28:20.204178 | TASK [Set zuul-log-path fact] 2026-02-24 00:28:20.229183 | localhost | skipping: Conditional result was False 2026-02-24 00:28:20.235079 | 2026-02-24 00:28:20.235269 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-24 00:28:20.657480 | localhost -> localhost | ok: Runtime: 0:00:00.005267 2026-02-24 00:28:20.663599 | 2026-02-24 00:28:20.663699 | TASK [upload-logs-swift : Upload logs to swift]