2026-03-12 00:07:02.172637 | Job console starting... 2026-03-12 00:07:02.183485 | Updating repositories 2026-03-12 00:07:02.306152 | Preparing job workspace 2026-03-12 00:07:06.461320 | Running Ansible setup... 2026-03-12 00:07:11.523274 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-12 00:07:12.310060 | 2026-03-12 00:07:12.310196 | PLAY [localhost] 2026-03-12 00:07:12.329606 | 2026-03-12 00:07:12.329757 | TASK [Gathering Facts] 2026-03-12 00:07:13.495247 | localhost | ok 2026-03-12 00:07:13.513607 | 2026-03-12 00:07:13.513758 | TASK [Setup log path fact] 2026-03-12 00:07:13.543116 | localhost | ok 2026-03-12 00:07:13.560471 | 2026-03-12 00:07:13.560991 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-12 00:07:13.611134 | localhost | ok 2026-03-12 00:07:13.619748 | 2026-03-12 00:07:13.619883 | TASK [emit-job-header : Print job information] 2026-03-12 00:07:13.700715 | # Job Information 2026-03-12 00:07:13.700875 | Ansible Version: 2.15.12 2026-03-12 00:07:13.700907 | Job: ansible-test-sanity-docker-stable-2.11 2026-03-12 00:07:13.700929 | Pipeline: periodic 2026-03-12 00:07:13.700948 | Executor: ze03.softwarefactory-project.io 2026-03-12 00:07:13.700967 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-12 00:07:13.700988 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/bf6/ansible/bf6634952f924ce0bd5851aedc7b9457/ 2026-03-12 00:07:13.701008 | Event ID: 1521915404ba4f519e0765e96d83261c 2026-03-12 00:07:13.706188 | 2026-03-12 00:07:13.706284 | LOOP [emit-job-header : Print node information] 2026-03-12 00:07:13.884126 | localhost | ok: 2026-03-12 00:07:13.884450 | localhost | # Node Information 2026-03-12 00:07:13.884485 | localhost | Inventory Hostname: controller 2026-03-12 00:07:13.884507 | localhost | Hostname: np0005644942 2026-03-12 00:07:13.884528 | localhost | Username: zuul 2026-03-12 00:07:13.884551 | localhost | Distro: Fedora 37 2026-03-12 00:07:13.884571 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-12 00:07:13.884590 | localhost | Region: ca-ymq-1 2026-03-12 00:07:13.884608 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-12 00:07:13.884626 | localhost | Product Name: OpenStack Nova 2026-03-12 00:07:13.884644 | localhost | Interface IP: 162.253.55.49 2026-03-12 00:07:13.903686 | 2026-03-12 00:07:13.903842 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-12 00:07:14.321336 | localhost -> localhost | changed 2026-03-12 00:07:14.328234 | 2026-03-12 00:07:14.328361 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-12 00:07:15.320424 | localhost -> localhost | changed 2026-03-12 00:07:15.354324 | 2026-03-12 00:07:15.354514 | PLAY [all:!appliance*] 2026-03-12 00:07:15.374866 | 2026-03-12 00:07:15.374998 | TASK [include_role : start-zuul-console] 2026-03-12 00:07:15.395993 | controller | ok 2026-03-12 00:07:15.411865 | 2026-03-12 00:07:15.411998 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-12 00:07:15.800507 | controller | ok 2026-03-12 00:07:15.817066 | 2026-03-12 00:07:15.817313 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-12 00:07:16.662238 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-12 00:07:16.669154 | 2026-03-12 00:07:16.669233 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-12 00:07:17.205185 | controller | skipping: Conditional result was False 2026-03-12 00:07:17.212378 | 2026-03-12 00:07:17.212510 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-12 00:07:17.238230 | controller | skipping: Conditional result was False 2026-03-12 00:07:17.245059 | 2026-03-12 00:07:17.245137 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-12 00:07:17.269522 | controller | skipping: Conditional result was False 2026-03-12 00:07:17.276706 | 2026-03-12 00:07:17.276829 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-12 00:07:17.292367 | controller | skipping: Conditional result was False 2026-03-12 00:07:17.306697 | 2026-03-12 00:07:17.306892 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-12 00:07:17.322958 | controller | skipping: Conditional result was False 2026-03-12 00:07:17.334563 | 2026-03-12 00:07:17.334700 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-12 00:07:17.350223 | controller | skipping: Conditional result was False 2026-03-12 00:07:17.370235 | 2026-03-12 00:07:17.370408 | TASK [Disable Fedora Modular] 2026-03-12 00:07:17.621639 | controller | changed 2026-03-12 00:07:17.630632 | 2026-03-12 00:07:17.630766 | TASK [Enable EPEL] 2026-03-12 00:07:17.656148 | controller | skipping: Conditional result was False 2026-03-12 00:07:17.662511 | 2026-03-12 00:07:17.662590 | TASK [Register the RHEL node] 2026-03-12 00:07:18.207999 | 2026-03-12 00:07:18.208191 | TASK [Show the subscription-manager status] 2026-03-12 00:07:18.772770 | controller | skipping: Conditional result was False 2026-03-12 00:07:18.786995 | 2026-03-12 00:07:18.787165 | TASK [Enable EPEL on RHEL] 2026-03-12 00:07:19.347592 | controller | skipping: Conditional result was False 2026-03-12 00:07:19.354716 | 2026-03-12 00:07:19.354883 | TASK [Install git and tox] 2026-03-12 00:08:45.753521 | controller | changed 2026-03-12 00:08:45.760499 | 2026-03-12 00:08:45.760583 | TASK [include_role : prepare-workspace] 2026-03-12 00:08:45.790492 | controller | ok 2026-03-12 00:08:45.811090 | 2026-03-12 00:08:45.811204 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-12 00:08:46.043715 | controller | ok 2026-03-12 00:08:46.059998 | 2026-03-12 00:08:46.060177 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-12 00:14:20.509648 | controller | Output suppressed because no_log was given 2026-03-12 00:14:20.519959 | 2026-03-12 00:14:20.520024 | TASK [include_role : prepare-workspace-openshift] 2026-03-12 00:14:20.543800 | controller | skipping: Conditional result was False 2026-03-12 00:14:20.566112 | 2026-03-12 00:14:20.566185 | PLAY [all:!appliance] 2026-03-12 00:14:20.581554 | 2026-03-12 00:14:20.581617 | TASK [Run add-build-sshkey role (RSA)] 2026-03-12 00:14:20.610709 | controller | ok 2026-03-12 00:14:20.625636 | 2026-03-12 00:14:20.625707 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-12 00:14:20.842227 | controller -> localhost | ok 2026-03-12 00:14:20.853426 | 2026-03-12 00:14:20.853549 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-12 00:14:20.873959 | controller | ok 2026-03-12 00:14:20.892646 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-12 00:14:20.900900 | 2026-03-12 00:14:20.901055 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-12 00:14:21.363800 | controller -> localhost | Generating public/private rsa key pair. 2026-03-12 00:14:21.364012 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/bf6634952f924ce0bd5851aedc7b9457_id_rsa. 2026-03-12 00:14:21.364047 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/bf6634952f924ce0bd5851aedc7b9457_id_rsa.pub. 2026-03-12 00:14:21.364075 | controller -> localhost | The key fingerprint is: 2026-03-12 00:14:21.364101 | controller -> localhost | SHA256:mSz34LBaM3H+qPA8tCVAtry/S7/s7JoGDTMbKP8egdU zuul-build-sshkey 2026-03-12 00:14:21.364126 | controller -> localhost | The key's randomart image is: 2026-03-12 00:14:21.364150 | controller -> localhost | +---[RSA 2048]----+ 2026-03-12 00:14:21.364174 | controller -> localhost | | | 2026-03-12 00:14:21.364199 | controller -> localhost | | o. | 2026-03-12 00:14:21.364223 | controller -> localhost | | =..E | 2026-03-12 00:14:21.364246 | controller -> localhost | |. .oO . o | 2026-03-12 00:14:21.364269 | controller -> localhost | | o. .X+ S | 2026-03-12 00:14:21.364303 | controller -> localhost | | . +.+X.o | 2026-03-12 00:14:21.364328 | controller -> localhost | | .o+*+o . | 2026-03-12 00:14:21.364354 | controller -> localhost | | .O=B o | 2026-03-12 00:14:21.364377 | controller -> localhost | | .o.OOO.. | 2026-03-12 00:14:21.364402 | controller -> localhost | +----[SHA256]-----+ 2026-03-12 00:14:21.364459 | controller -> localhost | ok: Runtime: 0:00:00.072356 2026-03-12 00:14:21.372970 | 2026-03-12 00:14:21.373051 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-12 00:14:21.402229 | controller | ok 2026-03-12 00:14:21.412165 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-12 00:14:21.422638 | 2026-03-12 00:14:21.422748 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-12 00:14:21.436814 | controller | skipping: Conditional result was False 2026-03-12 00:14:21.443763 | 2026-03-12 00:14:21.443850 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-12 00:14:22.066059 | controller | changed 2026-03-12 00:14:22.072221 | 2026-03-12 00:14:22.072308 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-12 00:14:22.295812 | controller | ok 2026-03-12 00:14:22.315854 | 2026-03-12 00:14:22.316106 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-12 00:14:22.969350 | controller | changed 2026-03-12 00:14:22.991500 | 2026-03-12 00:14:22.991688 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-12 00:14:23.576293 | controller | changed 2026-03-12 00:14:23.582909 | 2026-03-12 00:14:23.582994 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-12 00:14:23.607274 | controller | skipping: Conditional result was False 2026-03-12 00:14:23.614039 | 2026-03-12 00:14:23.614166 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-12 00:14:23.995162 | controller -> localhost | changed 2026-03-12 00:14:24.015469 | 2026-03-12 00:14:24.015632 | TASK [add-build-sshkey : Add back temp key] 2026-03-12 00:14:24.278008 | controller -> localhost | Identity added: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/bf6634952f924ce0bd5851aedc7b9457_id_rsa (zuul-build-sshkey) 2026-03-12 00:14:24.278319 | controller -> localhost | ok: Runtime: 0:00:00.007239 2026-03-12 00:14:24.288062 | 2026-03-12 00:14:24.288198 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-12 00:14:24.655826 | controller | ok 2026-03-12 00:14:24.662820 | 2026-03-12 00:14:24.662936 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-12 00:14:24.708480 | controller | skipping: Conditional result was False 2026-03-12 00:14:24.723213 | 2026-03-12 00:14:24.723320 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-12 00:14:24.747130 | controller | ok 2026-03-12 00:14:24.771175 | 2026-03-12 00:14:24.771333 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-12 00:14:25.041037 | controller -> localhost | ok 2026-03-12 00:14:25.049707 | 2026-03-12 00:14:25.049849 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-12 00:14:25.082227 | controller | ok 2026-03-12 00:14:25.093328 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-12 00:14:25.100121 | 2026-03-12 00:14:25.100224 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-12 00:14:25.390419 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-12 00:14:25.390608 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/bf6634952f924ce0bd5851aedc7b9457_id_ecdsa. 2026-03-12 00:14:25.390637 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/bf6634952f924ce0bd5851aedc7b9457_id_ecdsa.pub. 2026-03-12 00:14:25.390668 | controller -> localhost | The key fingerprint is: 2026-03-12 00:14:25.390690 | controller -> localhost | SHA256:sB3vmUUvK9XmNbTA4eNHiAdOtSwtNlcdepf3gVgVcY0 zuul-build-sshkey 2026-03-12 00:14:25.390709 | controller -> localhost | The key's randomart image is: 2026-03-12 00:14:25.390746 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-12 00:14:25.390770 | controller -> localhost | | o.+.BB| 2026-03-12 00:14:25.390789 | controller -> localhost | | o X E =| 2026-03-12 00:14:25.390807 | controller -> localhost | | . . O.# *o| 2026-03-12 00:14:25.390824 | controller -> localhost | | + o..Bo* *| 2026-03-12 00:14:25.390842 | controller -> localhost | | . S . +.++o| 2026-03-12 00:14:25.390859 | controller -> localhost | | . = =...| 2026-03-12 00:14:25.390875 | controller -> localhost | | = . . | 2026-03-12 00:14:25.390892 | controller -> localhost | | . | 2026-03-12 00:14:25.390908 | controller -> localhost | | | 2026-03-12 00:14:25.390923 | controller -> localhost | +----[SHA256]-----+ 2026-03-12 00:14:25.390970 | controller -> localhost | ok: Runtime: 0:00:00.014971 2026-03-12 00:14:25.398573 | 2026-03-12 00:14:25.398670 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-12 00:14:25.429108 | controller | ok 2026-03-12 00:14:25.436958 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-12 00:14:25.447884 | 2026-03-12 00:14:25.448006 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-12 00:14:25.463687 | controller | skipping: Conditional result was False 2026-03-12 00:14:25.471610 | 2026-03-12 00:14:25.471764 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-12 00:14:25.738538 | controller | changed 2026-03-12 00:14:25.745312 | 2026-03-12 00:14:25.745433 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-12 00:14:25.966505 | controller | ok 2026-03-12 00:14:25.977362 | 2026-03-12 00:14:25.977524 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-12 00:14:26.574465 | controller | changed 2026-03-12 00:14:26.584758 | 2026-03-12 00:14:26.584914 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-12 00:14:27.187216 | controller | changed 2026-03-12 00:14:27.200709 | 2026-03-12 00:14:27.200905 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-12 00:14:27.237244 | controller | skipping: Conditional result was False 2026-03-12 00:14:27.281694 | 2026-03-12 00:14:27.281829 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-12 00:14:27.530890 | controller -> localhost | changed 2026-03-12 00:14:27.549835 | 2026-03-12 00:14:27.549980 | TASK [add-build-sshkey : Add back temp key] 2026-03-12 00:14:27.861775 | controller -> localhost | Identity added: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/bf6634952f924ce0bd5851aedc7b9457_id_ecdsa (zuul-build-sshkey) 2026-03-12 00:14:27.862021 | controller -> localhost | ok: Runtime: 0:00:00.007719 2026-03-12 00:14:27.871145 | 2026-03-12 00:14:27.871244 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-12 00:14:28.103251 | controller | ok 2026-03-12 00:14:28.136542 | 2026-03-12 00:14:28.136680 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-12 00:14:28.162828 | controller | skipping: Conditional result was False 2026-03-12 00:14:28.183375 | 2026-03-12 00:14:28.183521 | TASK [include_role : remove-zuul-sshkey] 2026-03-12 00:14:28.209115 | controller | skipping: Conditional result was False 2026-03-12 00:14:28.216121 | 2026-03-12 00:14:28.216233 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-12 00:14:28.477619 | controller | ok: "logs" 2026-03-12 00:14:28.478116 | controller | ok: All items complete 2026-03-12 00:14:28.478212 | 2026-03-12 00:14:28.682497 | controller | ok: "artifacts" 2026-03-12 00:14:28.876722 | controller | ok: "docs" 2026-03-12 00:14:28.892275 | 2026-03-12 00:14:28.892388 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-12 00:14:29.135867 | controller | changed: "logs" 2026-03-12 00:14:29.352767 | controller | changed: "artifacts" 2026-03-12 00:14:29.559439 | controller | changed: "docs" 2026-03-12 00:14:29.613307 | 2026-03-12 00:14:29.613472 | PLAY RECAP 2026-03-12 00:14:29.613525 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-12 00:14:29.613553 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-12 00:14:29.613571 | 2026-03-12 00:14:29.852703 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-12 00:14:29.853599 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-12 00:14:30.458577 | 2026-03-12 00:14:30.458696 | PLAY [all] 2026-03-12 00:14:30.482508 | 2026-03-12 00:14:30.482646 | TASK [Install binary dependencies] 2026-03-12 00:14:30.554567 | controller | ok 2026-03-12 00:14:30.575606 | 2026-03-12 00:14:30.575779 | TASK [bindep : Include find tasks] 2026-03-12 00:14:30.622134 | controller | ok 2026-03-12 00:14:30.633781 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-12 00:14:30.643528 | 2026-03-12 00:14:30.643686 | TASK [bindep : Look for bindep.txt] 2026-03-12 00:14:31.209904 | controller | ok 2026-03-12 00:14:31.218030 | 2026-03-12 00:14:31.218136 | TASK [bindep : Define bindep_file fact] 2026-03-12 00:14:31.233740 | controller | skipping: Conditional result was False 2026-03-12 00:14:31.242533 | 2026-03-12 00:14:31.242669 | TASK [bindep : Look for other-requirements.txt] 2026-03-12 00:14:31.448337 | controller | ok 2026-03-12 00:14:31.454378 | 2026-03-12 00:14:31.454456 | TASK [bindep : Define bindep_file fact] 2026-03-12 00:14:31.478976 | controller | skipping: Conditional result was False 2026-03-12 00:14:31.485472 | 2026-03-12 00:14:31.485585 | TASK [bindep : Look for bindep fallback file] 2026-03-12 00:14:31.510355 | controller | skipping: Conditional result was False 2026-03-12 00:14:31.518256 | 2026-03-12 00:14:31.518376 | TASK [bindep : Define bindep_file fact] 2026-03-12 00:14:31.542826 | controller | skipping: Conditional result was False 2026-03-12 00:14:31.549758 | 2026-03-12 00:14:31.549869 | TASK [bindep : Include bindep tasks] 2026-03-12 00:14:31.574370 | controller | skipping: Conditional result was False 2026-03-12 00:14:31.580976 | 2026-03-12 00:14:31.581067 | TASK [bindep : Include install tasks] 2026-03-12 00:14:31.605009 | controller | skipping: Conditional result was False 2026-03-12 00:14:31.612657 | 2026-03-12 00:14:31.612814 | LOOP [bindep : Include package tasks] 2026-03-12 00:14:31.714492 | 2026-03-12 00:14:31.714708 | TASK [Run test-setup role] 2026-03-12 00:14:31.740323 | controller | ok 2026-03-12 00:14:31.767115 | 2026-03-12 00:14:31.767564 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-12 00:14:31.984569 | controller | ok 2026-03-12 00:14:31.991135 | 2026-03-12 00:14:31.991264 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-12 00:14:32.530189 | controller | skipping: Conditional result was False 2026-03-12 00:14:32.587540 | 2026-03-12 00:14:32.587655 | PLAY RECAP 2026-03-12 00:14:32.587707 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-12 00:14:32.587762 | 2026-03-12 00:14:32.718836 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-12 00:14:32.719781 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-12 00:14:33.296114 | 2026-03-12 00:14:33.296239 | PLAY [controller] 2026-03-12 00:14:33.320285 | 2026-03-12 00:14:33.320436 | TASK [Create the /root directory] 2026-03-12 00:14:33.886019 | controller | ok 2026-03-12 00:14:33.892982 | 2026-03-12 00:14:33.893056 | TASK [Install glibc-langpack-en] 2026-03-12 00:14:37.973002 | controller | ok: Nothing to do 2026-03-12 00:14:37.987626 | 2026-03-12 00:14:37.987841 | TASK [Ensure controller directory exists] 2026-03-12 00:14:38.206973 | controller | changed 2026-03-12 00:14:38.215106 | 2026-03-12 00:14:38.215232 | TASK [Install container runtime] 2026-03-12 00:14:38.285936 | controller | ok 2026-03-12 00:14:38.328632 | 2026-03-12 00:14:38.328860 | LOOP [ensure-docker : Gather variables for each operating system] 2026-03-12 00:14:38.362240 | controller | ok: "/var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-03-12 00:14:38.383048 | 2026-03-12 00:14:38.383191 | TASK [ensure-docker : Sanity check for distro selection] 2026-03-12 00:14:38.398019 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.406834 | 2026-03-12 00:14:38.406938 | TASK [ensure-docker : Sanity check for distro install] 2026-03-12 00:14:38.421439 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.432402 | 2026-03-12 00:14:38.432543 | TASK [ensure-docker : Sanity check for upstream install] 2026-03-12 00:14:38.448836 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.461648 | 2026-03-12 00:14:38.461822 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-03-12 00:14:38.487259 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.497052 | 2026-03-12 00:14:38.497194 | TASK [ensure-docker : Include tasks for docker install] 2026-03-12 00:14:38.521825 | controller | ok 2026-03-12 00:14:38.537193 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-03-12 00:14:38.549681 | 2026-03-12 00:14:38.549772 | TASK [ensure-docker : Not Implemented] 2026-03-12 00:14:38.565935 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.573256 | 2026-03-12 00:14:38.573378 | TASK [Set up docker mirrors] 2026-03-12 00:14:38.598778 | controller | ok 2026-03-12 00:14:38.628285 | 2026-03-12 00:14:38.628447 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-03-12 00:14:38.653954 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.662864 | 2026-03-12 00:14:38.663007 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-03-12 00:14:38.688471 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.694720 | 2026-03-12 00:14:38.694827 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-03-12 00:14:38.718838 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.728089 | 2026-03-12 00:14:38.728208 | TASK [use-docker-mirror : Set up docker mirrors] 2026-03-12 00:14:38.751952 | controller | skipping: Conditional result was False 2026-03-12 00:14:38.765964 | 2026-03-12 00:14:38.766085 | TASK [Add all repositories] 2026-03-12 00:14:38.785941 | controller | ok 2026-03-12 00:14:38.811544 | 2026-03-12 00:14:38.811685 | LOOP [ensure-package-repositories : Install repositories] 2026-03-12 00:14:38.834754 | controller | ok: "/var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-03-12 00:14:38.834925 | controller | ok: All items complete 2026-03-12 00:14:38.834951 | 2026-03-12 00:14:38.879829 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-03-12 00:14:38.886843 | 2026-03-12 00:14:38.886971 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-03-12 00:14:39.203146 | controller | changed 2026-03-12 00:14:39.211347 | 2026-03-12 00:14:39.211468 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-03-12 00:14:40.087128 | controller | changed: 2026-03-12 00:14:40.087835 | controller | { 2026-03-12 00:14:40.087934 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-03-12 00:14:40.087996 | controller | } 2026-03-12 00:14:40.104776 | 2026-03-12 00:14:40.104901 | LOOP [ensure-package-repositories : Import GPG keys] 2026-03-12 00:14:40.731618 | controller | changed: 2026-03-12 00:14:40.731800 | controller | { 2026-03-12 00:14:40.731845 | controller | "data": "-----BEGIN PGP PUBLIC KEY BLOCK-----\n\nmQINBFit5IEBEADDt86QpYKz5flnCsOyZ/fk3WwBKxfDjwHf/GIflo+4GWAXS7wJ\n1PSzPsvSDATV10J44i5WQzh99q+lZvFCVRFiNhRmlmcXG+rk1QmDh3fsCCj9Q/yP\nw8jn3Hx0zDtz8PIB/18ReftYJzUo34COLiHn8WiY20uGCF2pjdPgfxE+K454c4G7\ngKFqVUFYgPug2CS0quaBB5b0rpFUdzTeI5RCStd27nHCpuSDCvRYAfdv+4Y1yiVh\nKKdoe3Smj+RnXeVMgDxtH9FJibZ3DK7WnMN2yeob6VqXox+FvKYJCCLkbQgQmE50\nuVK0uN71A1mQDcTRKQ2q3fFGlMTqJbbzr3LwnCBE6hV0a36t+DABtZTmz5O69xdJ\nWGdBeePCnWVqtDb/BdEYz7hPKskcZBarygCCe2Xi7sZieoFZuq6ltPoCsdfEdfbO\n+VBVKJnExqNZCcFUTEnbH4CldWROOzMS8BGUlkGpa59Sl1t0QcmWlw1EbkeMQNrN\nspdR8lobcdNS9bpAJQqSHRZh3cAM9mA3Yq/bssUS/P2quRXLjJ9mIv3dky9C3udM\n+q2unvnbNpPtIUly76FJ3s8g8sHeOnmYcKqNGqHq2Q3kMdA2eIbI0MqfOIo2+Xk0\nrNt3ctq3g+cQiorcN3rdHPsTRSAcp+NCz1QF9TwXYtH1XV24A6QMO0+CZwARAQAB\ntCtEb2NrZXIgUmVsZWFzZSAoQ0UgcnBtKSA8ZG9ja2VyQGRvY2tlci5jb20+iQI3\nBBMBCgAhBQJYrep4AhsvBQsJCAcDBRUKCQgLBRYCAwEAAh4BAheAAAoJEMUv62ti\nHp816C0P/iP+1uhSa6Qq3TIc5sIFE5JHxOO6y0R97cUdAmCbEqBiJHUPNQDQaaRG\nVYBm0K013Q1gcJeUJvS32gthmIvhkstw7KTodwOM8Kl11CCqZ07NPFef1b2SaJ7l\nTYpyUsT9+e343ph+O4C1oUQw6flaAJe+8ATCmI/4KxfhIjD2a/Q1voR5tUIxfexC\n/LZTx05gyf2mAgEWlRm/cGTStNfqDN1uoKMlV+WFuB1j2oTUuO1/dr8mL+FgZAM3\nntWFo9gQCllNV9ahYOON2gkoZoNuPUnHsf4Bj6BQJnIXbAhMk9H2sZzwUi9bgObZ\nXO8+OrP4D4B9kCAKqqaQqA+O46LzO2vhN74lm/Fy6PumHuviqDBdN+HgtRPMUuao\nxnuVJSvBu9sPdgT/pR1N9u/KnfAnnLtR6g+fx4mWz+ts/riB/KRHzXd+44jGKZra\nIhTMfniguMJNsyEOO0AN8Tqcl0eRBxcOArcri7xu8HFvvl+e+ILymu4buusbYEVL\nGBkYP5YMmScfKn+jnDVN4mWoN1Bq2yMhMGx6PA3hOvzPNsUoYy2BwDxNZyflzuAi\ng59mgJm2NXtzNbSRJbMamKpQ69mzLWGdFNsRd4aH7PT7uPAURaf7B5BVp3UyjERW\n5alSGnBqsZmvlRnVH5BDUhYsWZMPRQS9rRr4iGW0l+TH+O2VJ8aQ\n=0Zqq\n-----END PGP PUBLIC KEY BLOCK-----\n" 2026-03-12 00:14:40.731878 | controller | } 2026-03-12 00:14:40.731913 | controller | changed: All items complete 2026-03-12 00:14:40.731939 | 2026-03-12 00:14:40.760942 | 2026-03-12 00:14:40.761124 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-03-12 00:14:40.981553 | controller | changed 2026-03-12 00:14:40.990629 | 2026-03-12 00:14:40.990718 | LOOP [ensure-package-repositories : Add repositories] 2026-03-12 00:14:41.355393 | controller | changed: 2026-03-12 00:14:41.355553 | controller | { 2026-03-12 00:14:41.355584 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-03-12 00:14:41.355610 | controller | "description": "Docker CE Stable - $basearch", 2026-03-12 00:14:41.355630 | controller | "gpgcheck": true, 2026-03-12 00:14:41.355650 | controller | "name": "docker-ce-stable" 2026-03-12 00:14:41.355670 | controller | } 2026-03-12 00:14:41.379235 | 2026-03-12 00:14:41.379343 | TASK [ensure-docker : Install pre-reqs] 2026-03-12 00:14:43.881866 | controller | ok: Nothing to do 2026-03-12 00:14:43.888521 | 2026-03-12 00:14:43.888650 | TASK [ensure-docker : Remove packages] 2026-03-12 00:14:45.188538 | controller | ok: Nothing to do 2026-03-12 00:14:45.196606 | 2026-03-12 00:14:45.196699 | TASK [ensure-docker : Install upstream docker using package] 2026-03-12 00:15:32.444764 | controller | changed 2026-03-12 00:15:32.463981 | 2026-03-12 00:15:32.464188 | TASK [ensure-docker : Setup docker] 2026-03-12 00:15:32.492822 | controller | ok 2026-03-12 00:15:32.511465 | controller | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-03-12 00:15:32.531779 | 2026-03-12 00:15:32.531974 | TASK [ensure-docker : Ensure "docker" group exists] 2026-03-12 00:15:32.948970 | controller | ok 2026-03-12 00:15:32.968650 | 2026-03-12 00:15:32.968866 | TASK [ensure-docker : Add user to docker group] 2026-03-12 00:15:33.509047 | controller | changed 2026-03-12 00:15:33.519415 | 2026-03-12 00:15:33.519684 | TASK [Update docker daemon proxy configuration] 2026-03-12 00:15:33.545195 | controller | skipping: Conditional result was False 2026-03-12 00:15:33.557240 | 2026-03-12 00:15:33.557393 | TASK [Lower default MTU] 2026-03-12 00:15:33.576252 | controller | skipping: Conditional result was False 2026-03-12 00:15:33.585414 | 2026-03-12 00:15:33.586055 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-03-12 00:15:33.986255 | controller | inactive 2026-03-12 00:15:34.122855 | controller | ok: Runtime: 0:00:00.016229 2026-03-12 00:15:34.131722 | 2026-03-12 00:15:34.131829 | TASK [ensure-docker : Get the status of the docket unit] 2026-03-12 00:15:34.410550 | controller | inactive 2026-03-12 00:15:34.710707 | controller | ok: Runtime: 0:00:00.009424 2026-03-12 00:15:34.717858 | 2026-03-12 00:15:34.717965 | TASK [ensure-docker : Restart docker] 2026-03-12 00:15:34.768870 | Trigger a Docker restart 2026-03-12 00:15:34.776500 | 2026-03-12 00:15:34.776607 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-03-12 00:15:34.817504 | 2026-03-12 00:15:34.817629 | TASK [ensure-docker : Install docker-compose] 2026-03-12 00:15:34.845279 | controller | skipping: Conditional result was False 2026-03-12 00:15:34.853526 | 2026-03-12 00:15:34.853637 | TASK [ensure-docker : Flush handlers before role exit] 2026-03-12 00:15:34.868161 | 2026-03-12 00:15:34.868375 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-03-12 00:15:36.140489 | controller | changed 2026-03-12 00:15:36.150158 | 2026-03-12 00:15:36.150244 | TASK [ensure-docker : Assure docker service is running] 2026-03-12 00:15:38.133940 | controller | changed 2026-03-12 00:15:38.141615 | 2026-03-12 00:15:38.141713 | TASK [ensure-docker : Assure docker.socket service is running] 2026-03-12 00:15:39.123641 | controller | ok 2026-03-12 00:15:39.135535 | 2026-03-12 00:15:39.135675 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-03-12 00:15:39.653189 | controller | ok 2026-03-12 00:15:39.664591 | 2026-03-12 00:15:39.664681 | TASK [ensure-docker : Validate ability to talk with docker] 2026-03-12 00:15:40.135033 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-03-12 00:15:40.231765 | controller | ok: Runtime: 0:00:00.055410 2026-03-12 00:15:40.246354 | 2026-03-12 00:15:40.246505 | TASK [ensure-docker : Get version details] 2026-03-12 00:15:40.843685 | controller | Client: Docker Engine - Community 2026-03-12 00:15:40.843850 | controller | Version: 24.0.7 2026-03-12 00:15:40.844006 | controller | API version: 1.43 2026-03-12 00:15:40.844149 | controller | Go version: go1.20.10 2026-03-12 00:15:40.844273 | controller | Git commit: afdd53b 2026-03-12 00:15:40.844429 | controller | Built: Thu Oct 26 09:09:39 2023 2026-03-12 00:15:40.844664 | controller | OS/Arch: linux/amd64 2026-03-12 00:15:40.844783 | controller | Context: default 2026-03-12 00:15:40.844829 | controller | 2026-03-12 00:15:40.844910 | controller | Server: Docker Engine - Community 2026-03-12 00:15:40.844957 | controller | Engine: 2026-03-12 00:15:40.845087 | controller | Version: 24.0.7 2026-03-12 00:15:40.845170 | controller | API version: 1.43 (minimum version 1.12) 2026-03-12 00:15:40.845253 | controller | Go version: go1.20.10 2026-03-12 00:15:40.845335 | controller | Git commit: 311b9ff 2026-03-12 00:15:40.845460 | controller | Built: Thu Oct 26 09:08:11 2023 2026-03-12 00:15:40.845688 | controller | OS/Arch: linux/amd64 2026-03-12 00:15:40.845801 | controller | Experimental: false 2026-03-12 00:15:40.845873 | controller | containerd: 2026-03-12 00:15:40.846105 | controller | Version: 1.6.25 2026-03-12 00:15:40.846234 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-03-12 00:15:40.846341 | controller | runc: 2026-03-12 00:15:40.846536 | controller | Version: 1.1.10 2026-03-12 00:15:40.846717 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-03-12 00:15:40.846816 | controller | docker-init: 2026-03-12 00:15:40.847009 | controller | Version: 0.19.0 2026-03-12 00:15:40.847133 | controller | GitCommit: de40ad0 2026-03-12 00:15:41.297575 | controller | ok: Runtime: 0:00:00.056038 2026-03-12 00:15:41.305254 | 2026-03-12 00:15:41.305337 | TASK [ensure-docker : Dump installed docker details] 2026-03-12 00:15:41.336810 | {'changed': True, 'stdout': 'Client: Docker Engine - Community\n Version: 24.0.7\n API version: 1.43\n Go version: go1.20.10\n Git commit: afdd53b\n Built: Thu Oct 26 09:09:39 2023\n OS/Arch: linux/amd64\n Context: default\n\nServer: Docker Engine - Community\n Engine:\n Version: 24.0.7\n API version: 1.43 (minimum version 1.12)\n Go version: go1.20.10\n Git commit: 311b9ff\n Built: Thu Oct 26 09:08:11 2023\n OS/Arch: linux/amd64\n Experimental: false\n containerd:\n Version: 1.6.25\n GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f\n runc:\n Version: 1.1.10\n GitCommit: v1.1.10-0-g18a0cb0\n docker-init:\n Version: 0.19.0\n GitCommit: de40ad0', 'stderr': '', 'rc': 0, 'cmd': ['docker', 'version'], 'start': '2026-03-12 00:15:40.792603', 'end': '2026-03-12 00:15:40.848641', 'delta': '0:00:00.056038', 'msg': '', 'zuul_log_id': 'fa163ef9-e89a-5703-0b59-000000000061-1-controller', 'stdout_lines': ['Client: Docker Engine - Community', ' Version: 24.0.7', ' API version: 1.43', ' Go version: go1.20.10', ' Git commit: afdd53b', ' Built: Thu Oct 26 09:09:39 2023', ' OS/Arch: linux/amd64', ' Context: default', '', 'Server: Docker Engine - Community', ' Engine:', ' Version: 24.0.7', ' API version: 1.43 (minimum version 1.12)', ' Go version: go1.20.10', ' Git commit: 311b9ff', ' Built: Thu Oct 26 09:08:11 2023', ' OS/Arch: linux/amd64', ' Experimental: false', ' containerd:', ' Version: 1.6.25', ' GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f', ' runc:', ' Version: 1.1.10', ' GitCommit: v1.1.10-0-g18a0cb0', ' docker-init:', ' Version: 0.19.0', ' GitCommit: de40ad0'], 'stderr_lines': [], 'failed': False} 2026-03-12 00:15:41.343212 | 2026-03-12 00:15:41.343287 | TASK [Use buildset registry] 2026-03-12 00:15:41.370041 | controller | skipping: Conditional result was False 2026-03-12 00:15:41.382074 | 2026-03-12 00:15:41.382148 | TASK [Ensure python3.8 is present] 2026-03-12 00:15:41.400616 | controller | skipping: Conditional result was False 2026-03-12 00:15:41.408545 | 2026-03-12 00:15:41.408623 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-12 00:15:41.429334 | controller | ok 2026-03-12 00:15:41.450240 | 2026-03-12 00:15:41.450497 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-12 00:15:43.640104 | controller | ok: Nothing to do 2026-03-12 00:15:43.646904 | 2026-03-12 00:15:43.647029 | TASK [our-ensure-python : Also install python3-devel] 2026-03-12 00:15:54.674642 | controller | changed 2026-03-12 00:15:54.704866 | 2026-03-12 00:15:54.705032 | TASK [Run ensure-virtualenv role] 2026-03-12 00:15:54.733404 | controller | ok 2026-03-12 00:15:54.775578 | 2026-03-12 00:15:54.775704 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-03-12 00:15:55.019644 | controller | /usr/bin/virtualenv 2026-03-12 00:15:55.333175 | controller | ok: Runtime: 0:00:00.003419 2026-03-12 00:15:55.349360 | 2026-03-12 00:15:55.349509 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-03-12 00:15:55.373894 | controller | skipping: Conditional result was False 2026-03-12 00:15:55.374175 | controller | changed: All items complete 2026-03-12 00:15:55.374207 | 2026-03-12 00:15:55.393481 | 2026-03-12 00:15:55.393598 | TASK [Find the full path of the Python interpreter] 2026-03-12 00:15:55.612686 | controller | /usr/bin/python3.9 2026-03-12 00:15:55.930863 | controller | ok 2026-03-12 00:15:55.939791 | 2026-03-12 00:15:55.939906 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-03-12 00:15:57.441843 | controller | created virtual environment CPython3.9.18.final.0-64 in 843ms 2026-03-12 00:15:57.481496 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-03-12 00:15:57.481540 | controller | seeder FromAppData(extra_search_dir=/usr/share/python-wheels,download=False, pip=bundle, setuptools=bundle, wheel=bundle, via=copy, app_data_dir=/home/zuul/.local/share/virtualenv) 2026-03-12 00:15:57.481552 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-03-12 00:15:57.481584 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-03-12 00:15:57.984275 | controller | changed 2026-03-12 00:15:57.991262 | 2026-03-12 00:15:57.991377 | TASK [Set selinux package] 2026-03-12 00:15:58.011923 | controller | ok 2026-03-12 00:15:58.018247 | 2026-03-12 00:15:58.018370 | TASK [Set selinux package (Fedora)] 2026-03-12 00:15:58.049434 | controller | ok 2026-03-12 00:15:58.057501 | 2026-03-12 00:15:58.057596 | TASK [Install selinux into virtualenv] 2026-03-12 00:16:01.419941 | controller | Collecting selinux-please-lie-to-me 2026-03-12 00:16:01.522806 | controller | Downloading selinux_please_lie_to_me-1.0.1-py2.py3-none-any.whl (3.8 kB) 2026-03-12 00:16:02.399818 | controller | Collecting setuptools<50.0.0 2026-03-12 00:16:02.429759 | controller | Downloading setuptools-49.6.0-py3-none-any.whl (803 kB) 2026-03-12 00:16:02.527146 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 803.3/803.3 kB 8.6 MB/s eta 0:00:00 2026-03-12 00:16:02.600476 | controller | Installing collected packages: setuptools, selinux-please-lie-to-me 2026-03-12 00:16:02.600713 | controller | Attempting uninstall: setuptools 2026-03-12 00:16:02.601397 | controller | Found existing installation: setuptools 62.6.0 2026-03-12 00:16:02.797269 | controller | Uninstalling setuptools-62.6.0: 2026-03-12 00:16:02.816723 | controller | Successfully uninstalled setuptools-62.6.0 2026-03-12 00:16:03.797199 | controller | Successfully installed selinux-please-lie-to-me-1.0.1 setuptools-49.6.0 2026-03-12 00:16:04.132235 | controller | 2026-03-12 00:16:04.349971 | controller | [notice] A new release of pip available: 22.2.2 -> 26.0.1 2026-03-12 00:16:04.350010 | controller | [notice] To update, run: /home/zuul/venv/bin/python -m pip install --upgrade pip 2026-03-12 00:16:04.595812 | controller | ok: Runtime: 0:00:05.762566 2026-03-12 00:16:04.606047 | 2026-03-12 00:16:04.606172 | TASK [Install pytest-forked into virtualenv] 2026-03-12 00:16:05.714781 | controller | Collecting pytest-forked 2026-03-12 00:16:05.851790 | controller | Downloading pytest_forked-1.6.0-py3-none-any.whl (4.9 kB) 2026-03-12 00:16:05.897345 | controller | Collecting py 2026-03-12 00:16:05.927733 | controller | Downloading py-1.11.0-py2.py3-none-any.whl (98 kB) 2026-03-12 00:16:05.974421 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 98.7/98.7 kB 2.2 MB/s eta 0:00:00 2026-03-12 00:16:06.099822 | controller | Collecting pytest>=3.10 2026-03-12 00:16:06.125723 | controller | Downloading pytest-8.4.2-py3-none-any.whl (365 kB) 2026-03-12 00:16:06.164219 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 365.8/365.8 kB 10.1 MB/s eta 0:00:00 2026-03-12 00:16:06.349977 | controller | Collecting exceptiongroup>=1 2026-03-12 00:16:06.377891 | controller | Downloading exceptiongroup-1.3.1-py3-none-any.whl (16 kB) 2026-03-12 00:16:06.447476 | controller | Collecting tomli>=1 2026-03-12 00:16:06.472772 | controller | Downloading tomli-2.4.0-py3-none-any.whl (14 kB) 2026-03-12 00:16:06.591348 | controller | Collecting packaging>=20 2026-03-12 00:16:06.617634 | controller | Downloading packaging-26.0-py3-none-any.whl (74 kB) 2026-03-12 00:16:06.628848 | controller | ━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━━ 74.4/74.4 kB 7.4 MB/s eta 0:00:00 2026-03-12 00:21:05.423435 | [Zuul] Log Stream did not terminate 2026-03-12 00:21:05.423616 | controller | ERROR 2026-03-12 00:21:05.423696 | controller | { 2026-03-12 00:21:05.423738 | controller | "msg": "Data could not be sent to remote host \"162.253.55.49\". Make sure this host can be reached over ssh: ssh: connect to host 162.253.55.49 port 22: No route to host\r\n", 2026-03-12 00:21:05.423766 | controller | "unreachable": true 2026-03-12 00:21:05.423786 | controller | } unreachable 2026-03-12 00:21:05.425548 | 2026-03-12 00:21:05.425601 | PLAY RECAP 2026-03-12 00:21:05.425642 | controller | ok: 35 changed: 18 unreachable: 1 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-03-12 00:21:05.425664 | 2026-03-12 00:21:05.567757 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-12 00:21:05.568841 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-12 00:21:06.217521 | 2026-03-12 00:21:06.217675 | PLAY [all] 2026-03-12 00:21:06.242338 | 2026-03-12 00:21:06.242492 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-12 00:21:08.014107 | controller | changed: non-zero return code 2026-03-12 00:21:08.022196 | 2026-03-12 00:21:08.022396 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-12 00:21:08.038752 | controller | skipping: Conditional result was False 2026-03-12 00:21:08.049534 | 2026-03-12 00:21:08.049716 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-12 00:21:08.085368 | 2026-03-12 00:21:08.085556 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-12 00:21:08.117544 | 2026-03-12 00:21:08.117759 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-12 00:21:08.133507 | controller | skipping: Conditional result was False 2026-03-12 00:21:08.143122 | 2026-03-12 00:21:08.143290 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-12 00:21:08.181314 | 2026-03-12 00:21:08.181529 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-12 00:21:08.208263 | controller | skipping: Conditional result was False 2026-03-12 00:21:08.218697 | 2026-03-12 00:21:08.218909 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-12 00:21:08.246001 | controller | skipping: Conditional result was False 2026-03-12 00:21:08.253984 | 2026-03-12 00:21:08.254129 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-12 00:21:08.279050 | controller | skipping: Conditional result was False 2026-03-12 00:21:08.323055 | 2026-03-12 00:21:08.323171 | PLAY RECAP 2026-03-12 00:21:08.323216 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-12 00:21:08.323237 | 2026-03-12 00:21:08.463433 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-12 00:21:08.464373 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-12 00:21:09.117611 | 2026-03-12 00:21:09.117818 | PLAY [all:!appliance*] 2026-03-12 00:21:09.140528 | 2026-03-12 00:21:09.140621 | TASK [unregister the node] 2026-03-12 00:21:09.670948 | controller | skipping: Conditional result was False 2026-03-12 00:21:09.685067 | 2026-03-12 00:21:09.685310 | TASK [include_role : fetch-output] 2026-03-12 00:21:09.727419 | controller | ok 2026-03-12 00:21:09.766438 | 2026-03-12 00:21:09.766566 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-12 00:21:09.821623 | controller | skipping: Conditional result was False 2026-03-12 00:21:09.828483 | 2026-03-12 00:21:09.828597 | TASK [fetch-output : Set log path for single node] 2026-03-12 00:21:09.869107 | controller | ok 2026-03-12 00:21:09.874957 | 2026-03-12 00:21:09.875029 | LOOP [fetch-output : Ensure local output dirs] 2026-03-12 00:21:10.331945 | controller -> localhost | ok: "/var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/logs" 2026-03-12 00:21:10.569885 | controller -> localhost | changed: "/var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/artifacts" 2026-03-12 00:21:10.815227 | controller -> localhost | changed: "/var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/work/docs" 2026-03-12 00:21:10.829575 | 2026-03-12 00:21:10.829783 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-12 00:21:11.486267 | controller | changed: 2026-03-12 00:21:11.486456 | controller | .d..t...... ./ 2026-03-12 00:21:11.486486 | controller | cd+++++++++ controller/ 2026-03-12 00:21:11.486532 | controller | changed: All items complete 2026-03-12 00:21:11.486582 | 2026-03-12 00:21:11.961930 | controller | changed: .d..t...... ./ 2026-03-12 00:21:12.452919 | controller | changed: .d..t...... ./ 2026-03-12 00:21:12.481976 | 2026-03-12 00:21:12.482155 | TASK [include_role : fetch-output-openshift] 2026-03-12 00:21:12.497946 | controller | skipping: Conditional result was False 2026-03-12 00:21:12.508617 | 2026-03-12 00:21:12.508775 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-12 00:21:12.537890 | controller | skipping: Conditional result was False 2026-03-12 00:21:12.545922 | controller | skipping: Conditional result was False 2026-03-12 00:21:12.595313 | 2026-03-12 00:21:12.595494 | PLAY [localhost] 2026-03-12 00:21:12.613193 | 2026-03-12 00:21:12.613294 | TASK [Run Zuul manifest role] 2026-03-12 00:21:12.631982 | localhost | ok 2026-03-12 00:21:12.682230 | 2026-03-12 00:21:12.682371 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-12 00:21:13.139485 | localhost | changed 2026-03-12 00:21:13.145028 | 2026-03-12 00:21:13.145158 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-12 00:21:13.184776 | localhost | ok 2026-03-12 00:21:13.193094 | 2026-03-12 00:21:13.193160 | TASK [Set zuul-log-path fact] 2026-03-12 00:21:13.213206 | localhost | ok 2026-03-12 00:21:13.228610 | 2026-03-12 00:21:13.228762 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-12 00:21:13.264666 | localhost | ok 2026-03-12 00:21:13.279287 | 2026-03-12 00:21:13.279478 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-12 00:21:13.322876 | localhost | ok: 2026-03-12 00:21:13.323192 | localhost | { 2026-03-12 00:21:13.323250 | localhost | "auth": { 2026-03-12 00:21:13.323300 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-12 00:21:13.323364 | localhost | "username": "9LF91O0RK7JU" 2026-03-12 00:21:13.323406 | localhost | }, 2026-03-12 00:21:13.323444 | localhost | "profile": "rackspace", 2026-03-12 00:21:13.323481 | localhost | "region_name": "IAD" 2026-03-12 00:21:13.323518 | localhost | } 2026-03-12 00:21:13.323571 | 2026-03-12 00:21:13.332640 | 2026-03-12 00:21:13.332823 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-12 00:21:13.355681 | localhost | Output suppressed because no_log was given 2026-03-12 00:21:13.387229 | 2026-03-12 00:21:13.387371 | TASK [upload-logs-failover : Initialize control vars] 2026-03-12 00:21:13.421950 | localhost | ok 2026-03-12 00:21:13.433174 | 2026-03-12 00:21:13.434259 | LOOP [upload-logs-failover : Perform log upload] 2026-03-12 00:21:13.491363 | localhost | Output suppressed because no_log was given 2026-03-12 00:21:13.578468 | localhost | included: /var/lib/zuul/builds/bf6634952f924ce0bd5851aedc7b9457/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-12 00:21:13.595849 | 2026-03-12 00:21:13.596060 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-12 00:21:13.726339 | localhost | Output suppressed because no_log was given 2026-03-12 00:21:13.735589 | 2026-03-12 00:21:13.735781 | TASK [Upload logs to swift] 2026-03-12 00:21:13.757914 | localhost | ok 2026-03-12 00:21:13.833258 | 2026-03-12 00:21:13.833414 | TASK [Set zuul-log-path fact] 2026-03-12 00:21:13.880874 | localhost | skipping: Conditional result was False 2026-03-12 00:21:13.888575 | 2026-03-12 00:21:13.888708 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-12 00:21:14.335398 | localhost -> localhost | ok: Runtime: 0:00:00.005630 2026-03-12 00:21:14.362964 | 2026-03-12 00:21:14.363175 | TASK [upload-logs-swift : Upload logs to swift]