2026-02-26 00:06:41.665774 | Job console starting... 2026-02-26 00:06:41.678006 | Updating repositories 2026-02-26 00:06:42.025688 | Preparing job workspace 2026-02-26 00:06:46.141380 | Running Ansible setup... 2026-02-26 00:06:51.067267 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-26 00:06:51.762382 | 2026-02-26 00:06:51.762513 | PLAY [localhost] 2026-02-26 00:06:51.771108 | 2026-02-26 00:06:51.771190 | TASK [Gathering Facts] 2026-02-26 00:06:52.700244 | localhost | ok 2026-02-26 00:06:52.720645 | 2026-02-26 00:06:52.720829 | TASK [Setup log path fact] 2026-02-26 00:06:52.737936 | localhost | ok 2026-02-26 00:06:52.750568 | 2026-02-26 00:06:52.750670 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-26 00:06:52.788570 | localhost | ok 2026-02-26 00:06:52.796565 | 2026-02-26 00:06:52.796641 | TASK [emit-job-header : Print job information] 2026-02-26 00:06:52.834864 | # Job Information 2026-02-26 00:06:52.835029 | Ansible Version: 2.15.12 2026-02-26 00:06:52.835063 | Job: ansible-test-sanity-docker-stable-2.12 2026-02-26 00:06:52.835085 | Pipeline: periodic 2026-02-26 00:06:52.835105 | Executor: ze03.softwarefactory-project.io 2026-02-26 00:06:52.835124 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-02-26 00:06:52.835145 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/dbe/ansible/dbe105ec86d243c794c9e5662ae4a0d3/ 2026-02-26 00:06:52.835165 | Event ID: 2adb30751d3546bcab5e20cd6f839642 2026-02-26 00:06:52.840206 | 2026-02-26 00:06:52.840278 | LOOP [emit-job-header : Print node information] 2026-02-26 00:06:52.951281 | localhost | ok: 2026-02-26 00:06:52.951830 | localhost | # Node Information 2026-02-26 00:06:52.951867 | localhost | Inventory Hostname: controller 2026-02-26 00:06:52.951891 | localhost | Hostname: np0005630838 2026-02-26 00:06:52.951916 | localhost | Username: zuul 2026-02-26 00:06:52.951942 | localhost | Distro: Fedora 37 2026-02-26 00:06:52.951964 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-02-26 00:06:52.951987 | localhost | Region: ca-ymq-1 2026-02-26 00:06:52.952012 | localhost | Label: ansible-fedora-37-1vcpu 2026-02-26 00:06:52.952035 | localhost | Product Name: OpenStack Nova 2026-02-26 00:06:52.952056 | localhost | Interface IP: 162.253.55.195 2026-02-26 00:06:52.966091 | 2026-02-26 00:06:52.966176 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-02-26 00:06:53.340232 | localhost -> localhost | changed 2026-02-26 00:06:53.356337 | 2026-02-26 00:06:53.356507 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-02-26 00:06:54.377183 | localhost -> localhost | changed 2026-02-26 00:06:54.397853 | 2026-02-26 00:06:54.397927 | PLAY [all:!appliance*] 2026-02-26 00:06:54.415213 | 2026-02-26 00:06:54.415404 | TASK [include_role : start-zuul-console] 2026-02-26 00:06:54.434222 | controller | ok 2026-02-26 00:06:54.449147 | 2026-02-26 00:06:54.449254 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-02-26 00:06:54.818467 | controller | ok 2026-02-26 00:06:54.837803 | 2026-02-26 00:06:54.838011 | TASK [use-our-mirror : Retrieve the IP address] 2026-02-26 00:06:56.150168 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-02-26 00:06:56.161004 | 2026-02-26 00:06:56.161176 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-02-26 00:06:56.704698 | controller | skipping: Conditional result was False 2026-02-26 00:06:56.711636 | 2026-02-26 00:06:56.711801 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-02-26 00:06:56.746117 | controller | skipping: Conditional result was False 2026-02-26 00:06:56.760896 | 2026-02-26 00:06:56.761218 | TASK [use-our-mirror : Create the podman configuration directory] 2026-02-26 00:06:56.799061 | controller | skipping: Conditional result was False 2026-02-26 00:06:56.814361 | 2026-02-26 00:06:56.814562 | TASK [use-our-mirror : Copy the podman configuration] 2026-02-26 00:06:56.842170 | controller | skipping: Conditional result was False 2026-02-26 00:06:56.856891 | 2026-02-26 00:06:56.857047 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-02-26 00:06:56.883398 | controller | skipping: Conditional result was False 2026-02-26 00:06:56.899280 | 2026-02-26 00:06:56.899452 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-02-26 00:06:56.926658 | controller | skipping: Conditional result was False 2026-02-26 00:06:56.952506 | 2026-02-26 00:06:56.952682 | TASK [Disable Fedora Modular] 2026-02-26 00:06:57.210358 | controller | changed 2026-02-26 00:06:57.216588 | 2026-02-26 00:06:57.216676 | TASK [Enable EPEL] 2026-02-26 00:06:57.230461 | controller | skipping: Conditional result was False 2026-02-26 00:06:57.237155 | 2026-02-26 00:06:57.237259 | TASK [Register the RHEL node] 2026-02-26 00:06:57.773433 | 2026-02-26 00:06:57.773595 | TASK [Show the subscription-manager status] 2026-02-26 00:06:58.363869 | controller | skipping: Conditional result was False 2026-02-26 00:06:58.370923 | 2026-02-26 00:06:58.371013 | TASK [Enable EPEL on RHEL] 2026-02-26 00:06:58.901920 | controller | skipping: Conditional result was False 2026-02-26 00:06:58.917077 | 2026-02-26 00:06:58.917257 | TASK [Install git and tox] 2026-02-26 00:08:17.904468 | controller | changed 2026-02-26 00:08:17.919179 | 2026-02-26 00:08:17.919370 | TASK [include_role : prepare-workspace] 2026-02-26 00:08:17.981018 | controller | ok 2026-02-26 00:08:18.015444 | 2026-02-26 00:08:18.015894 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-02-26 00:08:18.268376 | controller | ok 2026-02-26 00:08:18.277671 | 2026-02-26 00:08:18.277809 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-02-26 00:08:38.675271 | controller | Output suppressed because no_log was given 2026-02-26 00:08:38.697447 | 2026-02-26 00:08:38.697683 | TASK [include_role : prepare-workspace-openshift] 2026-02-26 00:08:38.728771 | controller | skipping: Conditional result was False 2026-02-26 00:08:38.815831 | 2026-02-26 00:08:38.815935 | PLAY [all:!appliance] 2026-02-26 00:08:38.895076 | 2026-02-26 00:08:38.895239 | TASK [Run add-build-sshkey role (RSA)] 2026-02-26 00:08:38.932842 | controller | ok 2026-02-26 00:08:38.965220 | 2026-02-26 00:08:38.965591 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-26 00:08:39.365015 | controller -> localhost | ok 2026-02-26 00:08:39.376494 | 2026-02-26 00:08:39.376708 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-26 00:08:39.413209 | controller | ok 2026-02-26 00:08:39.469699 | controller | included: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-26 00:08:39.476618 | 2026-02-26 00:08:39.476692 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-26 00:08:40.134492 | controller -> localhost | Generating public/private rsa key pair. 2026-02-26 00:08:40.134856 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/dbe105ec86d243c794c9e5662ae4a0d3_id_rsa. 2026-02-26 00:08:40.134891 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/dbe105ec86d243c794c9e5662ae4a0d3_id_rsa.pub. 2026-02-26 00:08:40.134913 | controller -> localhost | The key fingerprint is: 2026-02-26 00:08:40.134932 | controller -> localhost | SHA256:MHWeyB2K7GBrNvXpTuP3Cwg10xMoLG6tYUjDUFVMRds zuul-build-sshkey 2026-02-26 00:08:40.134951 | controller -> localhost | The key's randomart image is: 2026-02-26 00:08:40.134970 | controller -> localhost | +---[RSA 2048]----+ 2026-02-26 00:08:40.134988 | controller -> localhost | |.+...=oo+.+ | 2026-02-26 00:08:40.135007 | controller -> localhost | | + ..++.X + | 2026-02-26 00:08:40.135024 | controller -> localhost | | . +oo*.O E | 2026-02-26 00:08:40.135071 | controller -> localhost | | ..==.= + . | 2026-02-26 00:08:40.135092 | controller -> localhost | | o=oo S | 2026-02-26 00:08:40.135129 | controller -> localhost | | o.. o . | 2026-02-26 00:08:40.135164 | controller -> localhost | | = . | 2026-02-26 00:08:40.135183 | controller -> localhost | | + ... | 2026-02-26 00:08:40.135209 | controller -> localhost | | o. .o. | 2026-02-26 00:08:40.135230 | controller -> localhost | +----[SHA256]-----+ 2026-02-26 00:08:40.135297 | controller -> localhost | ok: Runtime: 0:00:00.159945 2026-02-26 00:08:40.155119 | 2026-02-26 00:08:40.155363 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-26 00:08:40.205883 | controller | ok 2026-02-26 00:08:40.233277 | controller | included: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-26 00:08:40.246999 | 2026-02-26 00:08:40.248008 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-26 00:08:40.274406 | controller | skipping: Conditional result was False 2026-02-26 00:08:40.286603 | 2026-02-26 00:08:40.286830 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-26 00:08:40.862655 | controller | changed 2026-02-26 00:08:40.878291 | 2026-02-26 00:08:40.878438 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-26 00:08:41.147857 | controller | ok 2026-02-26 00:08:41.169682 | 2026-02-26 00:08:41.170228 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-26 00:08:41.900670 | controller | changed 2026-02-26 00:08:41.921147 | 2026-02-26 00:08:41.921307 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-26 00:08:42.592264 | controller | changed 2026-02-26 00:08:42.607308 | 2026-02-26 00:08:42.607436 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-26 00:08:42.633770 | controller | skipping: Conditional result was False 2026-02-26 00:08:42.643551 | 2026-02-26 00:08:42.643665 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-26 00:08:43.030482 | controller -> localhost | changed 2026-02-26 00:08:43.050040 | 2026-02-26 00:08:43.050369 | TASK [add-build-sshkey : Add back temp key] 2026-02-26 00:08:43.557451 | controller -> localhost | Identity added: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/dbe105ec86d243c794c9e5662ae4a0d3_id_rsa (zuul-build-sshkey) 2026-02-26 00:08:43.557698 | controller -> localhost | ok: Runtime: 0:00:00.021956 2026-02-26 00:08:43.572968 | 2026-02-26 00:08:43.573764 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-26 00:08:44.009774 | controller | ok 2026-02-26 00:08:44.024751 | 2026-02-26 00:08:44.024908 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-26 00:08:44.072314 | controller | skipping: Conditional result was False 2026-02-26 00:08:44.102590 | 2026-02-26 00:08:44.102793 | TASK [Run add-build-sshkey role (ECDSA)] 2026-02-26 00:08:44.157399 | controller | ok 2026-02-26 00:08:44.192363 | 2026-02-26 00:08:44.192506 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-02-26 00:08:44.549530 | controller -> localhost | ok 2026-02-26 00:08:44.562151 | 2026-02-26 00:08:44.562276 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-02-26 00:08:44.605636 | controller | ok 2026-02-26 00:08:44.620814 | controller | included: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-02-26 00:08:44.629933 | 2026-02-26 00:08:44.630053 | TASK [add-build-sshkey : Create Temp SSH key] 2026-02-26 00:08:44.961902 | controller -> localhost | Generating public/private ecdsa key pair. 2026-02-26 00:08:44.962095 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/dbe105ec86d243c794c9e5662ae4a0d3_id_ecdsa. 2026-02-26 00:08:44.962124 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/dbe105ec86d243c794c9e5662ae4a0d3_id_ecdsa.pub. 2026-02-26 00:08:44.962156 | controller -> localhost | The key fingerprint is: 2026-02-26 00:08:44.962177 | controller -> localhost | SHA256:rRr9NNeryyBCO38f21Qa3V5aAHkUZGcWW3TW43adp8k zuul-build-sshkey 2026-02-26 00:08:44.962196 | controller -> localhost | The key's randomart image is: 2026-02-26 00:08:44.962215 | controller -> localhost | +---[ECDSA 521]---+ 2026-02-26 00:08:44.962233 | controller -> localhost | | .+=+@| 2026-02-26 00:08:44.962250 | controller -> localhost | | .o.*=| 2026-02-26 00:08:44.962268 | controller -> localhost | | .o.+| 2026-02-26 00:08:44.962285 | controller -> localhost | | . *=| 2026-02-26 00:08:44.962303 | controller -> localhost | | . S . .ooO| 2026-02-26 00:08:44.962319 | controller -> localhost | | . o . .EB.| 2026-02-26 00:08:44.962336 | controller -> localhost | | = + + o = .| 2026-02-26 00:08:44.962353 | controller -> localhost | | * +.= = . | 2026-02-26 00:08:44.962371 | controller -> localhost | | . ....*oo | 2026-02-26 00:08:44.962388 | controller -> localhost | +----[SHA256]-----+ 2026-02-26 00:08:44.962437 | controller -> localhost | ok: Runtime: 0:00:00.010658 2026-02-26 00:08:44.970336 | 2026-02-26 00:08:44.970448 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-02-26 00:08:45.002341 | controller | ok 2026-02-26 00:08:45.011036 | controller | included: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-02-26 00:08:45.022935 | 2026-02-26 00:08:45.023049 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-02-26 00:08:45.047427 | controller | skipping: Conditional result was False 2026-02-26 00:08:45.057022 | 2026-02-26 00:08:45.057152 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-02-26 00:13:48.225522 | controller | changed 2026-02-26 00:13:48.235018 | 2026-02-26 00:13:48.235173 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-02-26 00:13:48.454528 | controller | ok 2026-02-26 00:13:48.461323 | 2026-02-26 00:13:48.461480 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-02-26 00:13:49.046389 | controller | changed 2026-02-26 00:13:49.054012 | 2026-02-26 00:13:49.054142 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-02-26 00:13:49.642993 | controller | changed 2026-02-26 00:13:49.652737 | 2026-02-26 00:13:49.652885 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-02-26 00:13:49.679099 | controller | skipping: Conditional result was False 2026-02-26 00:13:49.687364 | 2026-02-26 00:13:49.687476 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-02-26 00:13:49.999170 | controller -> localhost | changed 2026-02-26 00:13:50.020929 | 2026-02-26 00:13:50.021101 | TASK [add-build-sshkey : Add back temp key] 2026-02-26 00:13:50.334047 | controller -> localhost | Identity added: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/dbe105ec86d243c794c9e5662ae4a0d3_id_ecdsa (zuul-build-sshkey) 2026-02-26 00:13:50.334304 | controller -> localhost | ok: Runtime: 0:00:00.013064 2026-02-26 00:13:50.341420 | 2026-02-26 00:13:50.341501 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-02-26 00:13:50.538065 | controller | ok 2026-02-26 00:13:50.545400 | 2026-02-26 00:13:50.545504 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-02-26 00:13:50.583894 | controller | skipping: Conditional result was False 2026-02-26 00:13:50.603558 | 2026-02-26 00:13:50.603707 | TASK [include_role : remove-zuul-sshkey] 2026-02-26 00:13:50.630367 | controller | skipping: Conditional result was False 2026-02-26 00:13:50.640213 | 2026-02-26 00:13:50.640405 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-02-26 00:13:50.870669 | controller | ok: "logs" 2026-02-26 00:13:50.871055 | controller | ok: All items complete 2026-02-26 00:13:50.871100 | 2026-02-26 00:13:51.058108 | controller | ok: "artifacts" 2026-02-26 00:13:51.243510 | controller | ok: "docs" 2026-02-26 00:13:51.265913 | 2026-02-26 00:13:51.266085 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-02-26 00:13:51.491495 | controller | changed: "logs" 2026-02-26 00:13:51.675443 | controller | changed: "artifacts" 2026-02-26 00:13:51.861244 | controller | changed: "docs" 2026-02-26 00:13:51.955500 | 2026-02-26 00:13:51.955649 | PLAY RECAP 2026-02-26 00:13:51.955699 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-02-26 00:13:51.955740 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-02-26 00:13:51.955764 | 2026-02-26 00:13:52.122700 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-02-26 00:13:52.123786 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-26 00:13:52.771326 | 2026-02-26 00:13:52.771493 | PLAY [all] 2026-02-26 00:13:52.797942 | 2026-02-26 00:13:52.798122 | TASK [Install binary dependencies] 2026-02-26 00:13:52.871870 | controller | ok 2026-02-26 00:13:52.901627 | 2026-02-26 00:13:52.901854 | TASK [bindep : Include find tasks] 2026-02-26 00:13:52.934286 | controller | ok 2026-02-26 00:13:52.944678 | controller | included: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-02-26 00:13:52.956682 | 2026-02-26 00:13:52.956894 | TASK [bindep : Look for bindep.txt] 2026-02-26 00:13:53.512084 | controller | ok 2026-02-26 00:13:53.521043 | 2026-02-26 00:13:53.521203 | TASK [bindep : Define bindep_file fact] 2026-02-26 00:13:53.548680 | controller | skipping: Conditional result was False 2026-02-26 00:13:53.558044 | 2026-02-26 00:13:53.558214 | TASK [bindep : Look for other-requirements.txt] 2026-02-26 00:13:53.754012 | controller | ok 2026-02-26 00:13:53.760446 | 2026-02-26 00:13:53.760569 | TASK [bindep : Define bindep_file fact] 2026-02-26 00:13:53.795637 | controller | skipping: Conditional result was False 2026-02-26 00:13:53.803926 | 2026-02-26 00:13:53.804072 | TASK [bindep : Look for bindep fallback file] 2026-02-26 00:13:53.839018 | controller | skipping: Conditional result was False 2026-02-26 00:13:53.847534 | 2026-02-26 00:13:53.847691 | TASK [bindep : Define bindep_file fact] 2026-02-26 00:13:53.872447 | controller | skipping: Conditional result was False 2026-02-26 00:13:53.879697 | 2026-02-26 00:13:53.879827 | TASK [bindep : Include bindep tasks] 2026-02-26 00:13:53.904752 | controller | skipping: Conditional result was False 2026-02-26 00:13:53.912200 | 2026-02-26 00:13:53.912342 | TASK [bindep : Include install tasks] 2026-02-26 00:13:53.937667 | controller | skipping: Conditional result was False 2026-02-26 00:13:53.946927 | 2026-02-26 00:13:53.947092 | LOOP [bindep : Include package tasks] 2026-02-26 00:13:54.029981 | 2026-02-26 00:13:54.030216 | TASK [Run test-setup role] 2026-02-26 00:13:54.056680 | controller | ok 2026-02-26 00:13:54.083177 | 2026-02-26 00:13:54.083341 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-02-26 00:13:54.290761 | controller | ok 2026-02-26 00:13:54.297607 | 2026-02-26 00:13:54.297741 | TASK [test-setup : Run tools/test-setup.sh] 2026-02-26 00:13:54.833214 | controller | skipping: Conditional result was False 2026-02-26 00:13:54.895400 | 2026-02-26 00:13:54.895540 | PLAY RECAP 2026-02-26 00:13:54.895597 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-26 00:13:54.895626 | 2026-02-26 00:13:55.085999 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-02-26 00:13:55.087076 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-26 00:13:55.774852 | 2026-02-26 00:13:55.775014 | PLAY [controller] 2026-02-26 00:13:55.803635 | 2026-02-26 00:13:55.803864 | TASK [Create the /root directory] 2026-02-26 00:13:56.402221 | controller | ok 2026-02-26 00:13:56.409053 | 2026-02-26 00:13:56.409195 | TASK [Install glibc-langpack-en] 2026-02-26 00:14:00.332029 | controller | ok: Nothing to do 2026-02-26 00:14:00.340917 | 2026-02-26 00:14:00.341054 | TASK [Ensure controller directory exists] 2026-02-26 00:14:00.560831 | controller | changed 2026-02-26 00:14:00.569441 | 2026-02-26 00:14:00.569571 | TASK [Install container runtime] 2026-02-26 00:14:00.626660 | controller | ok 2026-02-26 00:14:00.673440 | 2026-02-26 00:14:00.673605 | LOOP [ensure-podman : Find distribution installation] 2026-02-26 00:14:00.711963 | controller | ok: "/var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2026-02-26 00:14:00.736567 | controller | included: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2026-02-26 00:14:00.745365 | 2026-02-26 00:14:00.745498 | TASK [ensure-podman : Install podman (RedHat)] 2026-02-26 00:15:04.782888 | controller | changed 2026-02-26 00:15:04.795641 | 2026-02-26 00:15:04.795846 | TASK [ensure-podman : Fetch podman version] 2026-02-26 00:15:05.480401 | controller | Client: Podman Engine 2026-02-26 00:15:05.510143 | controller | Version: 4.6.2 2026-02-26 00:15:05.510189 | controller | API Version: 4.6.2 2026-02-26 00:15:05.510228 | controller | Go Version: go1.19.12 2026-02-26 00:15:05.510256 | controller | Built: Mon Aug 28 19:38:31 2023 2026-02-26 00:15:05.510270 | controller | OS/Arch: linux/amd64 2026-02-26 00:15:05.840766 | controller | ok: Runtime: 0:00:00.190734 2026-02-26 00:15:05.848047 | 2026-02-26 00:15:05.848194 | TASK [ensure-podman : Print podman version installed] 2026-02-26 00:15:05.900387 | Podman version: Client: Podman Engine 2026-02-26 00:15:05.900603 | Version: 4.6.2 2026-02-26 00:15:05.900635 | API Version: 4.6.2 2026-02-26 00:15:05.900656 | Go Version: go1.19.12 2026-02-26 00:15:05.900673 | Built: Mon Aug 28 19:38:31 2023 2026-02-26 00:15:05.900692 | OS/Arch: linux/amd64 2026-02-26 00:15:05.907220 | 2026-02-26 00:15:05.907301 | TASK [ensure-podman : Validate podman engine] 2026-02-26 00:15:06.484289 | controller | skipping: Conditional result was False 2026-02-26 00:15:06.508286 | 2026-02-26 00:15:06.509603 | TASK [ensure-podman : Set up docker compatability socket] 2026-02-26 00:15:06.553194 | controller | skipping: Conditional result was False 2026-02-26 00:15:06.608758 | 2026-02-26 00:15:06.608887 | TASK [Ensure python3.8 is present] 2026-02-26 00:15:06.650928 | controller | skipping: Conditional result was False 2026-02-26 00:15:06.660216 | 2026-02-26 00:15:06.660375 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-02-26 00:15:06.691702 | controller | ok 2026-02-26 00:15:06.726587 | 2026-02-26 00:15:06.726835 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-02-26 00:15:08.157802 | controller | ok: Nothing to do 2026-02-26 00:15:08.165886 | 2026-02-26 00:15:08.166037 | TASK [our-ensure-python : Also install python3-devel] 2026-02-26 00:15:16.345987 | controller | changed 2026-02-26 00:15:16.373297 | 2026-02-26 00:15:16.373478 | TASK [Run ensure-virtualenv role] 2026-02-26 00:15:16.399810 | controller | ok 2026-02-26 00:15:16.432653 | 2026-02-26 00:15:16.432834 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-02-26 00:15:16.712137 | controller | /usr/bin/virtualenv 2026-02-26 00:15:17.026938 | controller | ok: Runtime: 0:00:00.004908 2026-02-26 00:15:17.038814 | 2026-02-26 00:15:17.041439 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-02-26 00:15:17.064308 | controller | skipping: Conditional result was False 2026-02-26 00:15:17.064798 | controller | ok: All items complete 2026-02-26 00:15:17.064884 | 2026-02-26 00:15:17.098504 | 2026-02-26 00:15:17.098774 | TASK [Find the full path of the Python interpreter] 2026-02-26 00:15:17.340523 | controller | /usr/bin/python3.10 2026-02-26 00:15:17.652988 | controller | ok 2026-02-26 00:15:17.659751 | 2026-02-26 00:15:17.659940 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-02-26 00:15:18.565044 | controller | created virtual environment CPython3.10.13.final.0-64 in 473ms 2026-02-26 00:15:18.584824 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-02-26 00:15:18.585417 | 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-26 00:15:18.585611 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-02-26 00:15:18.585893 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-02-26 00:15:18.705571 | controller | changed 2026-02-26 00:15:18.713085 | 2026-02-26 00:15:18.714500 | TASK [Set selinux package] 2026-02-26 00:15:18.749817 | controller | ok 2026-02-26 00:15:18.759329 | 2026-02-26 00:15:18.759516 | TASK [Set selinux package (Fedora)] 2026-02-26 00:15:18.792549 | controller | ok 2026-02-26 00:15:18.801798 | 2026-02-26 00:15:18.801938 | TASK [Install selinux into virtualenv] 2026-02-26 00:15:20.012071 | controller | Collecting selinux-please-lie-to-me 2026-02-26 00:15:20.117909 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-02-26 00:15:20.457718 | controller | Collecting setuptools<50.0.0 2026-02-26 00:15:20.485186 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-02-26 00:15:20.592870 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 7.9 MB/s eta 0:00:00 2026-02-26 00:15:20.668414 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-02-26 00:15:20.668631 | controller | Attempting uninstall: setuptools 2026-02-26 00:15:20.669340 | controller | Found existing installation: setuptools 62.6.0 2026-02-26 00:15:20.739927 | controller | Uninstalling setuptools-62.6.0: 2026-02-26 00:15:20.748862 | controller | Successfully uninstalled setuptools-62.6.0 2026-02-26 00:15:21.072859 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-02-26 00:15:21.197552 | controller | 2026-02-26 00:15:21.277399 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-26 00:15:21.277441 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-26 00:15:21.343251 | controller | ok: Runtime: 0:00:02.217237 2026-02-26 00:15:21.350584 | 2026-02-26 00:15:21.350754 | TASK [Install pytest-forked into virtualenv] 2026-02-26 00:15:21.933265 | controller | Collecting pytest-forked 2026-02-26 00:15:22.034717 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-02-26 00:15:22.153094 | controller | Collecting pytest>=3.10 2026-02-26 00:15:22.178377 | controller | Downloading pytest-9.0.2-py3-none-any.whl (374 kB) 2026-02-26 00:15:22.260838 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 374.8/374.8 kB 4.9 MB/s eta 0:00:00 2026-02-26 00:15:22.293830 | controller | Collecting py 2026-02-26 00:15:22.319458 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-02-26 00:15:22.327193 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 15.9 MB/s eta 0:00:00 2026-02-26 00:15:22.361983 | controller | Collecting iniconfig>=1.0.1 2026-02-26 00:15:22.386940 | controller | Downloading iniconfig-2.3.0-py3-none-any.whl (7.5 kB) 2026-02-26 00:15:22.430615 | controller | Collecting packaging>=22 2026-02-26 00:15:22.455605 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-02-26 00:15:22.463967 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 12.4 MB/s eta 0:00:00 2026-02-26 00:15:22.508603 | controller | Collecting pygments>=2.7.2 2026-02-26 00:15:22.533594 | controller | Downloading pygments-2.19.2-py3-none-any.whl (1.2 MB) 2026-02-26 00:15:22.579272 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.2/1.2 MB 28.5 MB/s eta 0:00:00 2026-02-26 00:15:22.616621 | controller | Collecting pluggy<2,>=1.5 2026-02-26 00:15:22.641874 | controller | Downloading pluggy-1.6.0-py3-none-any.whl (20 kB) 2026-02-26 00:15:22.697689 | controller | Collecting tomli>=1 2026-02-26 00:15:22.722218 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-02-26 00:15:22.751820 | controller | Collecting exceptiongroup>=1 2026-02-26 00:15:22.776084 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-02-26 00:15:22.823419 | controller | Collecting typing-extensions>=4.6.0 2026-02-26 00:15:22.847804 | controller | Downloading typing_extensions-4.15.0-py3-none-any.whl (44 kB) 2026-02-26 00:15:22.853403 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 44.6/44.6 kB 10.8 MB/s eta 0:00:00 2026-02-26 00:15:22.917603 | controller | Installing collected packages: typing-extensions, tomli, pygments, py, pluggy, packaging, iniconfig, exceptiongroup, pytest, pytest-forked 2026-02-26 00:15:23.994304 | 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-26 00:15:23.997506 | controller | 2026-02-26 00:15:24.063184 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-02-26 00:15:24.063238 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-02-26 00:15:24.397628 | controller | ok: Runtime: 0:00:02.493726 2026-02-26 00:15:24.406489 | 2026-02-26 00:15:24.406716 | TASK [Update pip] 2026-02-26 00:15:24.971040 | controller | Requirement already satisfied: pip in ./venv/lib/python3.10/site-packages (22.2.2) 2026-02-26 00:15:25.100725 | controller | Collecting pip 2026-02-26 00:15:25.198935 | controller | Downloading pip-26.0.1-py3-none-any.whl (1.8 MB) 2026-02-26 00:15:25.334452 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 1.8/1.8 MB 13.4 MB/s eta 0:00:00 2026-02-26 00:15:25.391268 | controller | Installing collected packages: pip 2026-02-26 00:15:25.391486 | controller | Attempting uninstall: pip 2026-02-26 00:15:25.392096 | controller | Found existing installation: pip 22.2.2 2026-02-26 00:15:25.552763 | controller | Uninstalling pip-22.2.2: 2026-02-26 00:15:25.569602 | controller | Successfully uninstalled pip-22.2.2 2026-02-26 00:15:26.389599 | controller | Successfully installed pip-26.0.1 2026-02-26 00:15:26.559615 | controller | ok: Runtime: 0:00:01.773876 2026-02-26 00:15:26.567029 | 2026-02-26 00:15:26.567111 | TASK [Ensure pip temp directory exists in /var/tmp] 2026-02-26 00:15:26.794296 | controller | changed 2026-02-26 00:15:26.803332 | 2026-02-26 00:15:26.803472 | TASK [Install ansible into virtualenv] 2026-02-26 00:15:27.314899 | controller | Processing ./src/github.com/ansible/ansible 2026-02-26 00:15:27.316466 | controller | Installing build dependencies: started 2026-02-26 00:15:28.334568 | controller | Installing build dependencies: finished with status 'done' 2026-02-26 00:15:28.951702 | controller | Getting requirements to build wheel: started 2026-02-26 00:15:28.951755 | controller | Getting requirements to build wheel: finished with status 'done' 2026-02-26 00:15:29.425245 | controller | Preparing metadata (pyproject.toml): started 2026-02-26 00:15:29.425285 | controller | Preparing metadata (pyproject.toml): finished with status 'done' 2026-02-26 00:15:29.534699 | controller | Collecting jinja2 (from ansible-core==2.12.10.post0) 2026-02-26 00:15:29.656227 | controller | Downloading jinja2-3.1.6-py3-none-any.whl.metadata (2.9 kB) 2026-02-26 00:15:29.745603 | controller | Collecting PyYAML (from ansible-core==2.12.10.post0) 2026-02-26 00:15:29.768250 | 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-26 00:15:30.047760 | controller | Collecting cryptography (from ansible-core==2.12.10.post0) 2026-02-26 00:15:30.072234 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl.metadata (5.7 kB) 2026-02-26 00:15:30.090904 | controller | Requirement already satisfied: packaging in ./venv/lib/python3.10/site-packages (from ansible-core==2.12.10.post0) (26.0) 2026-02-26 00:15:30.107977 | controller | Collecting resolvelib<0.6.0,>=0.5.3 (from ansible-core==2.12.10.post0) 2026-02-26 00:15:30.131321 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl.metadata (3.7 kB) 2026-02-26 00:15:30.275098 | controller | Collecting cffi>=2.0.0 (from cryptography->ansible-core==2.12.10.post0) 2026-02-26 00:15:30.299193 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl.metadata (2.6 kB) 2026-02-26 00:15:30.310633 | 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-26 00:15:30.323468 | controller | Collecting pycparser (from cffi>=2.0.0->cryptography->ansible-core==2.12.10.post0) 2026-02-26 00:15:30.345477 | controller | Downloading pycparser-3.0-py3-none-any.whl.metadata (8.2 kB) 2026-02-26 00:15:30.430073 | controller | Collecting MarkupSafe>=2.0 (from jinja2->ansible-core==2.12.10.post0) 2026-02-26 00:15:30.455157 | 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-26 00:15:30.488087 | controller | Downloading resolvelib-0.5.4-py2.py3-none-any.whl (12 kB) 2026-02-26 00:15:30.522058 | controller | Downloading cryptography-46.0.5-cp38-abi3-manylinux_2_34_x86_64.whl (4.5 MB) 2026-02-26 00:15:30.670583 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 4.5/4.5 MB 43.9 MB/s 0:00:00 2026-02-26 00:15:30.693634 | controller | Downloading cffi-2.0.0-cp310-cp310-manylinux2014_x86_64.manylinux_2_17_x86_64.whl (216 kB) 2026-02-26 00:15:30.723427 | controller | Downloading jinja2-3.1.6-py3-none-any.whl (134 kB) 2026-02-26 00:15:30.754948 | 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-26 00:15:30.783283 | controller | Downloading pycparser-3.0-py3-none-any.whl (48 kB) 2026-02-26 00:15:30.812068 | 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-26 00:15:30.846250 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 770.3/770.3 kB 16.5 MB/s 0:00:00 2026-02-26 00:15:30.863374 | controller | Building wheels for collected packages: ansible-core 2026-02-26 00:15:30.866309 | controller | Building wheel for ansible-core (pyproject.toml): started 2026-02-26 00:15:32.500869 | controller | Building wheel for ansible-core (pyproject.toml): finished with status 'done' 2026-02-26 00:15:32.503172 | controller | Created wheel for ansible-core: filename=ansible_core-2.12.10.post0-py3-none-any.whl size=2117520 sha256=f03808c6faa6f22fe7b1474ca226d1416e766d6ec3b53de97660673191435f9d 2026-02-26 00:15:32.503389 | controller | Stored in directory: /var/tmp/ansible-test-pip/pip-ephem-wheel-cache-ary43t1t/wheels/68/27/b0/9b068d12b42b734c64dc438319298ad4c1c5027b13ccb3a660 2026-02-26 00:15:32.511711 | controller | Successfully built ansible-core 2026-02-26 00:15:32.543606 | controller | Installing collected packages: resolvelib, PyYAML, pycparser, MarkupSafe, jinja2, cffi, cryptography, ansible-core 2026-02-26 00:15:34.332086 | controller | 2026-02-26 00:15:34.334339 | 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-26 00:15:34.857238 | controller | ok: Runtime: 0:00:07.435297 2026-02-26 00:15:34.864473 | 2026-02-26 00:15:34.864585 | TASK [Setup download-artifact-fork role] 2026-02-26 00:15:34.899024 | controller | ok 2026-02-26 00:15:34.928957 | 2026-02-26 00:15:34.929099 | TASK [download-artifact-fork : Parse inventory response] 2026-02-26 00:15:34.976221 | controller | ok 2026-02-26 00:15:34.986769 | 2026-02-26 00:15:34.986935 | TASK [download-artifact-fork : Fetch info from Zuul API] 2026-02-26 00:15:35.029698 | controller | skipping: Conditional result was False 2026-02-26 00:15:35.036329 | 2026-02-26 00:15:35.036442 | TASK [download-artifact-fork : Parse build response] 2026-02-26 00:15:35.072054 | controller | skipping: Conditional result was False 2026-02-26 00:15:35.082163 | 2026-02-26 00:15:35.082328 | TASK [download-artifact-fork : Ensure artifacts directory exists] 2026-02-26 00:15:35.323006 | controller | changed 2026-02-26 00:15:35.335047 | 2026-02-26 00:15:35.335220 | LOOP [download-artifact-fork : Download archive by type] 2026-02-26 00:15:35.363044 | controller | skipping: Conditional result was False 2026-02-26 00:15:35.363390 | 2026-02-26 00:15:36.632507 | controller | changed: OK (20185 bytes) 2026-02-26 00:15:36.654974 | 2026-02-26 00:15:36.655109 | TASK [deploy-artifacts : define __collections] 2026-02-26 00:15:36.687095 | controller | ok 2026-02-26 00:15:36.692801 | 2026-02-26 00:15:36.692882 | LOOP [deploy-artifacts : Create list of collections] 2026-02-26 00:15:36.721416 | controller | skipping: Conditional result was False 2026-02-26 00:15:36.742248 | controller | ok: 2026-02-26 00:15:36.742440 | controller | { 2026-02-26 00:15:36.742496 | controller | "branch": "main", 2026-02-26 00:15:36.742521 | controller | "job": "build-ansible-collection", 2026-02-26 00:15:36.742544 | controller | "metadata": { 2026-02-26 00:15:36.742566 | controller | "type": "ansible_collection", 2026-02-26 00:15:36.743094 | controller | "version": "5.0.0" 2026-02-26 00:15:36.743130 | controller | }, 2026-02-26 00:15:36.743149 | controller | "name": "ansible.network", 2026-02-26 00:15:36.743165 | controller | "project": "ansible-collections/ansible.network", 2026-02-26 00:15:36.743181 | controller | "url": "https://118c11da91095f79916d-f61db552e254be53f052d53baea54a2b.ssl.cf1.rackcdn.com/ansible/9741c0f3dfa94d9b857d4614f51c009d/artifacts/ansible-network-5.0.0.tar.gz" 2026-02-26 00:15:36.743206 | controller | } 2026-02-26 00:15:36.763204 | 2026-02-26 00:15:36.763390 | TASK [deploy-artifacts : Install require-project collection using ansible-galaxy] 2026-02-26 00:15:37.360962 | controller | Starting galaxy collection install process 2026-02-26 00:15:37.361160 | controller | Process install dependency map 2026-02-26 00:20:40.481029 | controller | ERROR 2026-02-26 00:20:40.481176 | controller | { 2026-02-26 00:20:40.481207 | controller | "msg": "Data could not be sent to remote host \"162.253.55.195\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.195 port 22: No route to host\r\n", 2026-02-26 00:20:40.481234 | controller | "unreachable": true 2026-02-26 00:20:40.481254 | controller | } unreachable 2026-02-26 00:20:40.482926 | 2026-02-26 00:20:40.483177 | PLAY RECAP 2026-02-26 00:20:40.483234 | controller | ok: 24 changed: 13 unreachable: 1 failed: 0 skipped: 6 rescued: 0 ignored: 0 2026-02-26 00:20:40.483267 | 2026-02-26 00:20:40.637640 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-02-26 00:20:40.638919 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-26 00:20:41.369874 | 2026-02-26 00:20:41.370052 | PLAY [all] 2026-02-26 00:20:41.397410 | 2026-02-26 00:20:41.397591 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-02-26 00:20:51.157904 | controller | changed: non-zero return code 2026-02-26 00:20:51.165788 | 2026-02-26 00:20:51.165969 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-02-26 00:20:51.191573 | controller | skipping: Conditional result was False 2026-02-26 00:20:51.199615 | 2026-02-26 00:20:51.199771 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-02-26 00:20:51.233969 | 2026-02-26 00:20:51.234194 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-02-26 00:20:51.261277 | 2026-02-26 00:20:51.261603 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-02-26 00:20:51.278608 | controller | skipping: Conditional result was False 2026-02-26 00:20:51.286628 | 2026-02-26 00:20:51.286776 | LOOP [fetch-subunit-output : Generate subunit file] 2026-02-26 00:20:51.322143 | 2026-02-26 00:20:51.322378 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-02-26 00:20:51.338579 | controller | skipping: Conditional result was False 2026-02-26 00:20:51.347892 | 2026-02-26 00:20:51.348026 | TASK [fetch-subunit-output : Remove the temporary file] 2026-02-26 00:20:51.375373 | controller | skipping: Conditional result was False 2026-02-26 00:20:51.385660 | 2026-02-26 00:20:51.385790 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-02-26 00:20:51.401871 | controller | skipping: Conditional result was False 2026-02-26 00:20:51.442524 | 2026-02-26 00:20:51.442651 | PLAY RECAP 2026-02-26 00:20:51.442705 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-02-26 00:20:51.442771 | 2026-02-26 00:20:51.595868 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-02-26 00:20:51.596835 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-02-26 00:20:52.264886 | 2026-02-26 00:20:52.265065 | PLAY [all:!appliance*] 2026-02-26 00:20:52.294503 | 2026-02-26 00:20:52.294626 | TASK [unregister the node] 2026-02-26 00:20:52.831052 | controller | skipping: Conditional result was False 2026-02-26 00:20:52.837642 | 2026-02-26 00:20:52.837794 | TASK [include_role : fetch-output] 2026-02-26 00:20:52.869165 | controller | ok 2026-02-26 00:20:52.895272 | 2026-02-26 00:20:52.895418 | TASK [fetch-output : Set log path for multiple nodes] 2026-02-26 00:20:52.951182 | controller | skipping: Conditional result was False 2026-02-26 00:20:52.958345 | 2026-02-26 00:20:52.958492 | TASK [fetch-output : Set log path for single node] 2026-02-26 00:20:53.001218 | controller | ok 2026-02-26 00:20:53.008416 | 2026-02-26 00:20:53.008557 | LOOP [fetch-output : Ensure local output dirs] 2026-02-26 00:20:53.492632 | controller -> localhost | ok: "/var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/logs" 2026-02-26 00:20:53.756377 | controller -> localhost | changed: "/var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/artifacts" 2026-02-26 00:20:54.016076 | controller -> localhost | changed: "/var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/work/docs" 2026-02-26 00:20:54.027209 | 2026-02-26 00:20:54.027464 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-02-26 00:20:54.771320 | controller | changed: 2026-02-26 00:20:54.771739 | controller | .d..t...... ./ 2026-02-26 00:20:54.771793 | controller | cd+++++++++ controller/ 2026-02-26 00:20:54.771854 | controller | changed: All items complete 2026-02-26 00:20:54.771888 | 2026-02-26 00:20:55.226965 | controller | changed: .d..t...... ./ 2026-02-26 00:20:55.688022 | controller | changed: .d..t...... ./ 2026-02-26 00:20:55.706091 | 2026-02-26 00:20:55.706247 | TASK [include_role : fetch-output-openshift] 2026-02-26 00:20:55.721062 | controller | skipping: Conditional result was False 2026-02-26 00:20:55.729625 | 2026-02-26 00:20:55.729792 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-02-26 00:20:55.759621 | controller | skipping: Conditional result was False 2026-02-26 00:20:55.773351 | controller | skipping: Conditional result was False 2026-02-26 00:20:55.809591 | 2026-02-26 00:20:55.809711 | PLAY [localhost] 2026-02-26 00:20:55.822207 | 2026-02-26 00:20:55.822290 | TASK [Run Zuul manifest role] 2026-02-26 00:20:55.840799 | localhost | ok 2026-02-26 00:20:55.860159 | 2026-02-26 00:20:55.860283 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-02-26 00:20:56.267079 | localhost | changed 2026-02-26 00:20:56.273303 | 2026-02-26 00:20:56.273415 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-02-26 00:20:56.314788 | localhost | ok 2026-02-26 00:20:56.323289 | 2026-02-26 00:20:56.323418 | TASK [Set zuul-log-path fact] 2026-02-26 00:20:56.342618 | localhost | ok 2026-02-26 00:20:56.359947 | 2026-02-26 00:20:56.360081 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-02-26 00:20:56.389400 | localhost | ok 2026-02-26 00:20:56.398583 | 2026-02-26 00:20:56.398695 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-02-26 00:20:56.427440 | localhost | ok: 2026-02-26 00:20:56.427662 | localhost | { 2026-02-26 00:20:56.427695 | localhost | "auth": { 2026-02-26 00:20:56.427721 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-02-26 00:20:56.427773 | localhost | "username": "9LF91O0RK7JU" 2026-02-26 00:20:56.427799 | localhost | }, 2026-02-26 00:20:56.427824 | localhost | "profile": "rackspace", 2026-02-26 00:20:56.427845 | localhost | "region_name": "ORD" 2026-02-26 00:20:56.427865 | localhost | } 2026-02-26 00:20:56.427895 | 2026-02-26 00:20:56.438937 | 2026-02-26 00:20:56.439064 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-02-26 00:20:56.458603 | localhost | Output suppressed because no_log was given 2026-02-26 00:20:56.473089 | 2026-02-26 00:20:56.473202 | TASK [upload-logs-failover : Initialize control vars] 2026-02-26 00:20:56.514468 | localhost | ok 2026-02-26 00:20:56.521207 | 2026-02-26 00:20:56.521312 | LOOP [upload-logs-failover : Perform log upload] 2026-02-26 00:20:56.558069 | localhost | Output suppressed because no_log was given 2026-02-26 00:20:56.606373 | localhost | included: /var/lib/zuul/builds/dbe105ec86d243c794c9e5662ae4a0d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-02-26 00:20:56.613170 | 2026-02-26 00:20:56.613288 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-02-26 00:20:56.690029 | localhost | Output suppressed because no_log was given 2026-02-26 00:20:56.696224 | 2026-02-26 00:20:56.696322 | TASK [Upload logs to swift] 2026-02-26 00:20:56.715496 | localhost | ok 2026-02-26 00:20:56.744458 | 2026-02-26 00:20:56.744585 | TASK [Set zuul-log-path fact] 2026-02-26 00:20:56.779416 | localhost | skipping: Conditional result was False 2026-02-26 00:20:56.785220 | 2026-02-26 00:20:56.785293 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-02-26 00:20:57.178953 | localhost -> localhost | ok: Runtime: 0:00:00.005185 2026-02-26 00:20:57.186708 | 2026-02-26 00:20:57.186860 | TASK [upload-logs-swift : Upload logs to swift]