2026-03-09 00:06:57.352113 | Job console starting... 2026-03-09 00:06:57.363261 | Updating repositories 2026-03-09 00:06:57.879488 | Preparing job workspace 2026-03-09 00:07:10.596636 | Running Ansible setup... 2026-03-09 00:07:16.383442 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-09 00:07:17.018750 | 2026-03-09 00:07:17.018870 | PLAY [localhost] 2026-03-09 00:07:17.027310 | 2026-03-09 00:07:17.027381 | TASK [Gathering Facts] 2026-03-09 00:07:18.148299 | localhost | ok 2026-03-09 00:07:18.167905 | 2026-03-09 00:07:18.168119 | TASK [Setup log path fact] 2026-03-09 00:07:18.188821 | localhost | ok 2026-03-09 00:07:18.208210 | 2026-03-09 00:07:18.208340 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 00:07:18.229973 | localhost | ok 2026-03-09 00:07:18.240859 | 2026-03-09 00:07:18.241179 | TASK [emit-job-header : Print job information] 2026-03-09 00:07:18.280381 | # Job Information 2026-03-09 00:07:18.280559 | Ansible Version: 2.15.12 2026-03-09 00:07:18.280602 | Job: ansible-test-sanity-docker-stable-2.10 2026-03-09 00:07:18.280630 | Pipeline: periodic 2026-03-09 00:07:18.280657 | Executor: ze01.softwarefactory-project.io 2026-03-09 00:07:18.280682 | Triggered by: https://github.com/ansible-collections/ansible.network 2026-03-09 00:07:18.280711 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/e00/ansible/e00fc02d3c5d4107a7f7938107e7b529/ 2026-03-09 00:07:18.280737 | Event ID: 6ed282d1e81147dcab9935779dfad26a 2026-03-09 00:07:18.286102 | 2026-03-09 00:07:18.286190 | LOOP [emit-job-header : Print node information] 2026-03-09 00:07:18.399537 | localhost | ok: 2026-03-09 00:07:18.399709 | localhost | # Node Information 2026-03-09 00:07:18.399738 | localhost | Inventory Hostname: controller 2026-03-09 00:07:18.399758 | localhost | Hostname: np0005641918 2026-03-09 00:07:18.399776 | localhost | Username: zuul 2026-03-09 00:07:18.399799 | localhost | Distro: Fedora 37 2026-03-09 00:07:18.399817 | localhost | Provider: ansible-vexxhost-ca-ymq-1 2026-03-09 00:07:18.399834 | localhost | Region: ca-ymq-1 2026-03-09 00:07:18.399850 | localhost | Label: ansible-fedora-37-1vcpu 2026-03-09 00:07:18.399866 | localhost | Product Name: OpenStack Nova 2026-03-09 00:07:18.399883 | localhost | Interface IP: 162.253.55.195 2026-03-09 00:07:18.410069 | 2026-03-09 00:07:18.410181 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-09 00:07:18.913097 | localhost -> localhost | changed 2026-03-09 00:07:18.918459 | 2026-03-09 00:07:18.918545 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-09 00:07:19.925514 | localhost -> localhost | changed 2026-03-09 00:07:19.957241 | 2026-03-09 00:07:19.957393 | PLAY [all:!appliance*] 2026-03-09 00:07:19.993918 | 2026-03-09 00:07:19.994002 | TASK [include_role : start-zuul-console] 2026-03-09 00:07:20.014236 | controller | ok 2026-03-09 00:07:20.027956 | 2026-03-09 00:07:20.028063 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-03-09 00:07:20.467686 | controller | ok 2026-03-09 00:07:20.508988 | 2026-03-09 00:07:20.509118 | TASK [use-our-mirror : Retrieve the IP address] 2026-03-09 00:07:21.462523 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2026-03-09 00:07:21.476984 | 2026-03-09 00:07:21.477160 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2026-03-09 00:07:22.026532 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.040382 | 2026-03-09 00:07:22.044558 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2026-03-09 00:07:22.078793 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.091523 | 2026-03-09 00:07:22.091640 | TASK [use-our-mirror : Create the podman configuration directory] 2026-03-09 00:07:22.127631 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.141248 | 2026-03-09 00:07:22.141398 | TASK [use-our-mirror : Copy the podman configuration] 2026-03-09 00:07:22.168274 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.176487 | 2026-03-09 00:07:22.176579 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2026-03-09 00:07:22.212533 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.223141 | 2026-03-09 00:07:22.223264 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2026-03-09 00:07:22.248558 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.266773 | 2026-03-09 00:07:22.266867 | TASK [Disable Fedora Modular] 2026-03-09 00:07:22.511810 | controller | changed 2026-03-09 00:07:22.528774 | 2026-03-09 00:07:22.528969 | TASK [Enable EPEL] 2026-03-09 00:07:22.558603 | controller | skipping: Conditional result was False 2026-03-09 00:07:22.568436 | 2026-03-09 00:07:22.568568 | TASK [Register the RHEL node] 2026-03-09 00:07:23.130304 | 2026-03-09 00:07:23.130464 | TASK [Show the subscription-manager status] 2026-03-09 00:07:23.717317 | controller | skipping: Conditional result was False 2026-03-09 00:07:23.737556 | 2026-03-09 00:07:23.737736 | TASK [Enable EPEL on RHEL] 2026-03-09 00:07:24.293791 | controller | skipping: Conditional result was False 2026-03-09 00:07:24.300279 | 2026-03-09 00:07:24.300376 | TASK [Install git and tox] 2026-03-09 00:08:56.587822 | controller | changed 2026-03-09 00:08:56.603327 | 2026-03-09 00:08:56.603494 | TASK [include_role : prepare-workspace] 2026-03-09 00:08:56.645005 | controller | ok 2026-03-09 00:08:56.684031 | 2026-03-09 00:08:56.684175 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-09 00:08:56.954410 | controller | ok 2026-03-09 00:08:56.969864 | 2026-03-09 00:08:56.970005 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-09 00:14:26.296347 | controller | Output suppressed because no_log was given 2026-03-09 00:14:26.322725 | 2026-03-09 00:14:26.322853 | TASK [include_role : prepare-workspace-openshift] 2026-03-09 00:14:26.351353 | controller | skipping: Conditional result was False 2026-03-09 00:14:26.373281 | 2026-03-09 00:14:26.373335 | PLAY [all:!appliance] 2026-03-09 00:14:26.389953 | 2026-03-09 00:14:26.390033 | TASK [Run add-build-sshkey role (RSA)] 2026-03-09 00:14:26.422975 | controller | ok 2026-03-09 00:14:26.445967 | 2026-03-09 00:14:26.446153 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 00:14:26.725390 | controller -> localhost | ok 2026-03-09 00:14:26.732402 | 2026-03-09 00:14:26.732489 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 00:14:26.773381 | controller | ok 2026-03-09 00:14:26.788229 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 00:14:26.794193 | 2026-03-09 00:14:26.794255 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 00:14:27.361652 | controller -> localhost | Generating public/private rsa key pair. 2026-03-09 00:14:27.361901 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/e00fc02d3c5d4107a7f7938107e7b529_id_rsa. 2026-03-09 00:14:27.361938 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/e00fc02d3c5d4107a7f7938107e7b529_id_rsa.pub. 2026-03-09 00:14:27.361961 | controller -> localhost | The key fingerprint is: 2026-03-09 00:14:27.361983 | controller -> localhost | SHA256:EX30QpdR1oEB0KdmFcoAOcFqdnYy0/gNJW2BtVr9hjI zuul-build-sshkey 2026-03-09 00:14:27.362030 | controller -> localhost | The key's randomart image is: 2026-03-09 00:14:27.362063 | controller -> localhost | +---[RSA 2048]----+ 2026-03-09 00:14:27.362092 | controller -> localhost | | .o=+*==o*==| 2026-03-09 00:14:27.362121 | controller -> localhost | | + ++*=*...| 2026-03-09 00:14:27.362149 | controller -> localhost | | . = +*=.. | 2026-03-09 00:14:27.362177 | controller -> localhost | | + B =o+ .o | 2026-03-09 00:14:27.362200 | controller -> localhost | | o o S.=E . o | 2026-03-09 00:14:27.362248 | controller -> localhost | | . .o . | 2026-03-09 00:14:27.362274 | controller -> localhost | | | 2026-03-09 00:14:27.362293 | controller -> localhost | | | 2026-03-09 00:14:27.362310 | controller -> localhost | | | 2026-03-09 00:14:27.362331 | controller -> localhost | +----[SHA256]-----+ 2026-03-09 00:14:27.362397 | controller -> localhost | ok: Runtime: 0:00:00.124765 2026-03-09 00:14:27.370655 | 2026-03-09 00:14:27.370788 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 00:14:27.392157 | controller | ok 2026-03-09 00:14:27.403204 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 00:14:27.414177 | 2026-03-09 00:14:27.414327 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 00:14:27.440250 | controller | skipping: Conditional result was False 2026-03-09 00:14:27.447397 | 2026-03-09 00:14:27.447531 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 00:14:28.085742 | controller | changed 2026-03-09 00:14:28.095955 | 2026-03-09 00:14:28.096151 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 00:14:28.323527 | controller | ok 2026-03-09 00:14:28.330403 | 2026-03-09 00:14:28.330501 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 00:14:28.947666 | controller | changed 2026-03-09 00:14:28.961583 | 2026-03-09 00:14:28.961746 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 00:14:29.625088 | controller | changed 2026-03-09 00:14:29.639602 | 2026-03-09 00:14:29.639854 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 00:14:29.667293 | controller | skipping: Conditional result was False 2026-03-09 00:14:29.684113 | 2026-03-09 00:14:29.684287 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 00:14:30.100411 | controller -> localhost | changed 2026-03-09 00:14:30.116338 | 2026-03-09 00:14:30.116554 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 00:14:30.431536 | controller -> localhost | Identity added: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/e00fc02d3c5d4107a7f7938107e7b529_id_rsa (zuul-build-sshkey) 2026-03-09 00:14:30.431884 | controller -> localhost | ok: Runtime: 0:00:00.008383 2026-03-09 00:14:30.444407 | 2026-03-09 00:14:30.444603 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 00:14:30.809340 | controller | ok 2026-03-09 00:14:30.817804 | 2026-03-09 00:14:30.817961 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 00:14:30.854911 | controller | skipping: Conditional result was False 2026-03-09 00:14:30.872230 | 2026-03-09 00:14:30.872377 | TASK [Run add-build-sshkey role (ECDSA)] 2026-03-09 00:14:30.904234 | controller | ok 2026-03-09 00:14:30.922577 | 2026-03-09 00:14:30.922703 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 00:14:31.173643 | controller -> localhost | ok 2026-03-09 00:14:31.180802 | 2026-03-09 00:14:31.180920 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 00:14:31.201676 | controller | ok 2026-03-09 00:14:31.212880 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 00:14:31.220839 | 2026-03-09 00:14:31.220964 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 00:14:31.491077 | controller -> localhost | Generating public/private ecdsa key pair. 2026-03-09 00:14:31.491248 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/e00fc02d3c5d4107a7f7938107e7b529_id_ecdsa. 2026-03-09 00:14:31.491277 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/e00fc02d3c5d4107a7f7938107e7b529_id_ecdsa.pub. 2026-03-09 00:14:31.491308 | controller -> localhost | The key fingerprint is: 2026-03-09 00:14:31.491330 | controller -> localhost | SHA256:wHKcx6iGGcLGec7Afp6opFjQFRlVEHdGc3wohL2INiI zuul-build-sshkey 2026-03-09 00:14:31.491349 | controller -> localhost | The key's randomart image is: 2026-03-09 00:14:31.491368 | controller -> localhost | +---[ECDSA 521]---+ 2026-03-09 00:14:31.491387 | controller -> localhost | | o+++o=*... | 2026-03-09 00:14:31.491413 | controller -> localhost | |+ . .+ =.oooo . | 2026-03-09 00:14:31.491433 | controller -> localhost | |.B..o B.o. o . | 2026-03-09 00:14:31.491451 | controller -> localhost | |ooE=.++o. . | 2026-03-09 00:14:31.491469 | controller -> localhost | |..+=oo .S | 2026-03-09 00:14:31.491488 | controller -> localhost | | .+.. | 2026-03-09 00:14:31.491505 | controller -> localhost | | o.o | 2026-03-09 00:14:31.491522 | controller -> localhost | |=. | 2026-03-09 00:14:31.491538 | controller -> localhost | |+ | 2026-03-09 00:14:31.491555 | controller -> localhost | +----[SHA256]-----+ 2026-03-09 00:14:31.491596 | controller -> localhost | ok: Runtime: 0:00:00.007474 2026-03-09 00:14:31.498718 | 2026-03-09 00:14:31.498791 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 00:14:31.526993 | controller | ok 2026-03-09 00:14:31.538795 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 00:14:31.554296 | 2026-03-09 00:14:31.554433 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 00:14:31.569720 | controller | skipping: Conditional result was False 2026-03-09 00:14:31.577348 | 2026-03-09 00:14:31.577434 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 00:14:31.835946 | controller | changed 2026-03-09 00:14:31.843576 | 2026-03-09 00:14:31.843757 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 00:14:32.083643 | controller | ok 2026-03-09 00:14:32.092294 | 2026-03-09 00:14:32.092485 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 00:14:32.727464 | controller | changed 2026-03-09 00:14:32.807764 | 2026-03-09 00:14:32.809010 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 00:14:33.459808 | controller | changed 2026-03-09 00:14:33.466008 | 2026-03-09 00:14:33.466095 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 00:14:33.491129 | controller | skipping: Conditional result was False 2026-03-09 00:14:33.502108 | 2026-03-09 00:14:33.502215 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 00:14:33.766737 | controller -> localhost | changed 2026-03-09 00:14:33.795323 | 2026-03-09 00:14:33.795569 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 00:14:34.101874 | controller -> localhost | Identity added: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/e00fc02d3c5d4107a7f7938107e7b529_id_ecdsa (zuul-build-sshkey) 2026-03-09 00:14:34.102309 | controller -> localhost | ok: Runtime: 0:00:00.007839 2026-03-09 00:14:34.118818 | 2026-03-09 00:14:34.119081 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 00:14:34.334914 | controller | ok 2026-03-09 00:14:34.348152 | 2026-03-09 00:14:34.348316 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 00:14:34.386218 | controller | skipping: Conditional result was False 2026-03-09 00:14:34.417107 | 2026-03-09 00:14:34.417291 | TASK [include_role : remove-zuul-sshkey] 2026-03-09 00:14:34.433716 | controller | skipping: Conditional result was False 2026-03-09 00:14:34.445832 | 2026-03-09 00:14:34.445998 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-09 00:14:34.690303 | controller | ok: "logs" 2026-03-09 00:14:34.690713 | controller | ok: All items complete 2026-03-09 00:14:34.690773 | 2026-03-09 00:14:34.895465 | controller | ok: "artifacts" 2026-03-09 00:14:35.092087 | controller | ok: "docs" 2026-03-09 00:14:35.103974 | 2026-03-09 00:14:35.104135 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-09 00:14:35.360408 | controller | changed: "logs" 2026-03-09 00:14:35.558264 | controller | changed: "artifacts" 2026-03-09 00:14:35.756787 | controller | changed: "docs" 2026-03-09 00:14:35.789402 | 2026-03-09 00:14:35.789512 | PLAY RECAP 2026-03-09 00:14:35.789557 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2026-03-09 00:14:35.789582 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 00:14:35.789599 | 2026-03-09 00:14:35.912759 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2026-03-09 00:14:35.913746 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-09 00:14:36.500997 | 2026-03-09 00:14:36.501158 | PLAY [all] 2026-03-09 00:14:36.526366 | 2026-03-09 00:14:36.526518 | TASK [Install binary dependencies] 2026-03-09 00:14:36.589524 | controller | ok 2026-03-09 00:14:36.612048 | 2026-03-09 00:14:36.612194 | TASK [bindep : Include find tasks] 2026-03-09 00:14:36.653375 | controller | ok 2026-03-09 00:14:36.662128 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2026-03-09 00:14:36.669083 | 2026-03-09 00:14:36.669160 | TASK [bindep : Look for bindep.txt] 2026-03-09 00:14:37.240087 | controller | ok 2026-03-09 00:14:37.254153 | 2026-03-09 00:14:37.254340 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:14:37.282237 | controller | skipping: Conditional result was False 2026-03-09 00:14:37.297429 | 2026-03-09 00:14:37.297609 | TASK [bindep : Look for other-requirements.txt] 2026-03-09 00:14:37.514832 | controller | ok 2026-03-09 00:14:37.520696 | 2026-03-09 00:14:37.520778 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:14:37.544400 | controller | skipping: Conditional result was False 2026-03-09 00:14:37.558282 | 2026-03-09 00:14:37.558467 | TASK [bindep : Look for bindep fallback file] 2026-03-09 00:14:37.596662 | controller | skipping: Conditional result was False 2026-03-09 00:14:37.607475 | 2026-03-09 00:14:37.607604 | TASK [bindep : Define bindep_file fact] 2026-03-09 00:14:37.633083 | controller | skipping: Conditional result was False 2026-03-09 00:14:37.643245 | 2026-03-09 00:14:37.643347 | TASK [bindep : Include bindep tasks] 2026-03-09 00:14:37.667831 | controller | skipping: Conditional result was False 2026-03-09 00:14:37.677376 | 2026-03-09 00:14:37.677482 | TASK [bindep : Include install tasks] 2026-03-09 00:14:37.702266 | controller | skipping: Conditional result was False 2026-03-09 00:14:37.709484 | 2026-03-09 00:14:37.709597 | LOOP [bindep : Include package tasks] 2026-03-09 00:14:37.765120 | 2026-03-09 00:14:37.765273 | TASK [Run test-setup role] 2026-03-09 00:14:37.786125 | controller | ok 2026-03-09 00:14:37.805231 | 2026-03-09 00:14:37.805562 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2026-03-09 00:14:38.015399 | controller | ok 2026-03-09 00:14:38.028983 | 2026-03-09 00:14:38.029238 | TASK [test-setup : Run tools/test-setup.sh] 2026-03-09 00:14:38.580119 | controller | skipping: Conditional result was False 2026-03-09 00:14:38.616670 | 2026-03-09 00:14:38.616779 | PLAY RECAP 2026-03-09 00:14:38.616834 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-09 00:14:38.616863 | 2026-03-09 00:14:38.724357 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2026-03-09 00:14:38.725219 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-09 00:14:39.372296 | 2026-03-09 00:14:39.372434 | PLAY [controller] 2026-03-09 00:14:39.394243 | 2026-03-09 00:14:39.394380 | TASK [Create the /root directory] 2026-03-09 00:14:39.990516 | controller | ok 2026-03-09 00:14:40.007010 | 2026-03-09 00:14:40.007240 | TASK [Install glibc-langpack-en] 2026-03-09 00:14:43.965639 | controller | ok: Nothing to do 2026-03-09 00:14:43.972897 | 2026-03-09 00:14:43.972990 | TASK [Ensure controller directory exists] 2026-03-09 00:14:44.217536 | controller | changed 2026-03-09 00:14:44.226109 | 2026-03-09 00:14:44.226201 | TASK [Install container runtime] 2026-03-09 00:14:44.298890 | controller | ok 2026-03-09 00:14:44.356919 | 2026-03-09 00:14:44.357628 | LOOP [ensure-docker : Gather variables for each operating system] 2026-03-09 00:14:44.399736 | controller | ok: "/var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/vars/fedora.yaml" 2026-03-09 00:14:44.412567 | 2026-03-09 00:14:44.412702 | TASK [ensure-docker : Sanity check for distro selection] 2026-03-09 00:14:44.427633 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.437298 | 2026-03-09 00:14:44.437445 | TASK [ensure-docker : Sanity check for distro install] 2026-03-09 00:14:44.463902 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.471482 | 2026-03-09 00:14:44.471585 | TASK [ensure-docker : Sanity check for upstream install] 2026-03-09 00:14:44.496155 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.503423 | 2026-03-09 00:14:44.503533 | TASK [ensure-docker : Sanity check for upstream install mirrors] 2026-03-09 00:14:44.538165 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.548260 | 2026-03-09 00:14:44.548491 | TASK [ensure-docker : Include tasks for docker install] 2026-03-09 00:14:44.568045 | controller | ok 2026-03-09 00:14:44.577879 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-upstream.yaml 2026-03-09 00:14:44.586853 | 2026-03-09 00:14:44.586939 | TASK [ensure-docker : Not Implemented] 2026-03-09 00:14:44.600526 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.608420 | 2026-03-09 00:14:44.608514 | TASK [Set up docker mirrors] 2026-03-09 00:14:44.629951 | controller | ok 2026-03-09 00:14:44.654100 | 2026-03-09 00:14:44.654226 | TASK [use-docker-mirror : Set docker_mirror fact from mirror_info] 2026-03-09 00:14:44.680054 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.687369 | 2026-03-09 00:14:44.687491 | TASK [use-docker-mirror : Set docker_mirror fact from zuul_site_mirror_info] 2026-03-09 00:14:44.722418 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.732954 | 2026-03-09 00:14:44.733078 | TASK [use-docker-mirror : Set docker_mirror fact] 2026-03-09 00:14:44.757687 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.769385 | 2026-03-09 00:14:44.769513 | TASK [use-docker-mirror : Set up docker mirrors] 2026-03-09 00:14:44.795396 | controller | skipping: Conditional result was False 2026-03-09 00:14:44.809251 | 2026-03-09 00:14:44.809374 | TASK [Add all repositories] 2026-03-09 00:14:44.829402 | controller | ok 2026-03-09 00:14:44.856040 | 2026-03-09 00:14:44.856158 | LOOP [ensure-package-repositories : Install repositories] 2026-03-09 00:14:44.879242 | controller | ok: "/var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml" 2026-03-09 00:14:44.879645 | controller | ok: All items complete 2026-03-09 00:14:44.879699 | 2026-03-09 00:14:44.938154 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-package-repositories/tasks/RedHat.yaml 2026-03-09 00:14:44.945580 | 2026-03-09 00:14:44.945652 | TASK [ensure-package-repositories : Create GPG key temp directory] 2026-03-09 00:14:45.274452 | controller | changed 2026-03-09 00:14:45.291390 | 2026-03-09 00:14:45.291575 | LOOP [ensure-package-repositories : Upload GPG keys] 2026-03-09 00:14:46.193591 | controller | changed: 2026-03-09 00:14:46.193819 | controller | { 2026-03-09 00:14:46.193857 | 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-09 00:14:46.193881 | controller | } 2026-03-09 00:14:46.215336 | 2026-03-09 00:14:46.215608 | LOOP [ensure-package-repositories : Import GPG keys] 2026-03-09 00:14:46.898717 | controller | changed: 2026-03-09 00:14:46.898964 | controller | { 2026-03-09 00:14:46.899006 | 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-09 00:14:46.899065 | controller | } 2026-03-09 00:14:46.899107 | controller | changed: All items complete 2026-03-09 00:14:46.899136 | 2026-03-09 00:14:46.910254 | 2026-03-09 00:14:46.910396 | TASK [ensure-package-repositories : Remove GPG temp directory] 2026-03-09 00:14:47.132943 | controller | changed 2026-03-09 00:14:47.144562 | 2026-03-09 00:14:47.144694 | LOOP [ensure-package-repositories : Add repositories] 2026-03-09 00:14:47.518242 | controller | changed: 2026-03-09 00:14:47.518511 | controller | { 2026-03-09 00:14:47.518566 | controller | "baseurl": "https://download.docker.com/linux/fedora/$releasever/$basearch/stable", 2026-03-09 00:14:47.518607 | controller | "description": "Docker CE Stable - $basearch", 2026-03-09 00:14:47.518644 | controller | "gpgcheck": true, 2026-03-09 00:14:47.518680 | controller | "name": "docker-ce-stable" 2026-03-09 00:14:47.518715 | controller | } 2026-03-09 00:14:47.541958 | 2026-03-09 00:14:47.542235 | TASK [ensure-docker : Install pre-reqs] 2026-03-09 00:14:49.999674 | controller | ok: Nothing to do 2026-03-09 00:14:50.011464 | 2026-03-09 00:14:50.011662 | TASK [ensure-docker : Remove packages] 2026-03-09 00:14:51.637449 | controller | ok: Nothing to do 2026-03-09 00:14:51.655615 | 2026-03-09 00:14:51.655851 | TASK [ensure-docker : Install upstream docker using package] 2026-03-09 00:15:38.288153 | controller | changed 2026-03-09 00:15:38.294234 | 2026-03-09 00:15:38.294307 | TASK [ensure-docker : Setup docker] 2026-03-09 00:15:38.313371 | controller | ok 2026-03-09 00:15:38.326530 | controller | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-docker/tasks/docker-setup.yaml 2026-03-09 00:15:38.339370 | 2026-03-09 00:15:38.339467 | TASK [ensure-docker : Ensure "docker" group exists] 2026-03-09 00:15:38.738298 | controller | ok 2026-03-09 00:15:38.745126 | 2026-03-09 00:15:38.745293 | TASK [ensure-docker : Add user to docker group] 2026-03-09 00:15:39.377860 | controller | changed 2026-03-09 00:15:39.394446 | 2026-03-09 00:15:39.394615 | TASK [Update docker daemon proxy configuration] 2026-03-09 00:15:39.420956 | controller | skipping: Conditional result was False 2026-03-09 00:15:39.432122 | 2026-03-09 00:15:39.432282 | TASK [Lower default MTU] 2026-03-09 00:15:39.457663 | controller | skipping: Conditional result was False 2026-03-09 00:15:39.468887 | 2026-03-09 00:15:39.469049 | TASK [ensure-docker : Get the status of the docket.socket unit] 2026-03-09 00:15:39.865044 | controller | inactive 2026-03-09 00:15:40.048762 | controller | ok: Runtime: 0:00:00.013228 2026-03-09 00:15:40.058141 | 2026-03-09 00:15:40.058269 | TASK [ensure-docker : Get the status of the docket unit] 2026-03-09 00:15:40.307722 | controller | inactive 2026-03-09 00:15:40.607788 | controller | ok: Runtime: 0:00:00.010144 2026-03-09 00:15:40.624480 | 2026-03-09 00:15:40.624773 | TASK [ensure-docker : Restart docker] 2026-03-09 00:15:40.683891 | Trigger a Docker restart 2026-03-09 00:15:40.693855 | 2026-03-09 00:15:40.694001 | TASK [ensure-docker : Reset ssh connection to pick up docker group] 2026-03-09 00:15:40.725827 | 2026-03-09 00:15:40.725957 | TASK [ensure-docker : Install docker-compose] 2026-03-09 00:15:40.760653 | controller | skipping: Conditional result was False 2026-03-09 00:15:40.769004 | 2026-03-09 00:15:40.769163 | TASK [ensure-docker : Flush handlers before role exit] 2026-03-09 00:15:40.781523 | 2026-03-09 00:15:40.781682 | TASK [ensure-docker : Stop docker.socket to avoid any conflict] 2026-03-09 00:15:42.134196 | controller | changed 2026-03-09 00:15:42.145439 | 2026-03-09 00:15:42.145539 | TASK [ensure-docker : Assure docker service is running] 2026-03-09 00:15:44.281601 | controller | changed 2026-03-09 00:15:44.292454 | 2026-03-09 00:15:44.292557 | TASK [ensure-docker : Assure docker.socket service is running] 2026-03-09 00:15:45.262324 | controller | ok 2026-03-09 00:15:45.272008 | 2026-03-09 00:15:45.272115 | TASK [ensure-docker : Correct group ownership on docker sock] 2026-03-09 00:15:45.611191 | controller | ok 2026-03-09 00:15:45.621629 | 2026-03-09 00:15:45.621775 | TASK [ensure-docker : Validate ability to talk with docker] 2026-03-09 00:15:46.196454 | controller | CONTAINER ID IMAGE COMMAND CREATED STATUS PORTS NAMES 2026-03-09 00:15:46.678619 | controller | ok: Runtime: 0:00:00.047846 2026-03-09 00:15:46.687205 | 2026-03-09 00:15:46.687300 | TASK [ensure-docker : Get version details] 2026-03-09 00:15:47.314409 | controller | Client: Docker Engine - Community 2026-03-09 00:15:47.315537 | controller | Version: 24.0.7 2026-03-09 00:15:47.315550 | controller | API version: 1.43 2026-03-09 00:15:47.315561 | controller | Go version: go1.20.10 2026-03-09 00:15:47.315577 | controller | Git commit: afdd53b 2026-03-09 00:15:47.315672 | controller | Built: Thu Oct 26 09:09:39 2023 2026-03-09 00:15:47.315682 | controller | OS/Arch: linux/amd64 2026-03-09 00:15:47.315688 | controller | Context: default 2026-03-09 00:15:47.315694 | controller | 2026-03-09 00:15:47.315699 | controller | Server: Docker Engine - Community 2026-03-09 00:15:47.315703 | controller | Engine: 2026-03-09 00:15:47.315708 | controller | Version: 24.0.7 2026-03-09 00:15:47.315712 | controller | API version: 1.43 (minimum version 1.12) 2026-03-09 00:15:47.315716 | controller | Go version: go1.20.10 2026-03-09 00:15:47.315720 | controller | Git commit: 311b9ff 2026-03-09 00:15:47.315725 | controller | Built: Thu Oct 26 09:08:11 2023 2026-03-09 00:15:47.315729 | controller | OS/Arch: linux/amd64 2026-03-09 00:15:47.315733 | controller | Experimental: false 2026-03-09 00:15:47.315737 | controller | containerd: 2026-03-09 00:15:47.315742 | controller | Version: 1.6.25 2026-03-09 00:15:47.315746 | controller | GitCommit: d8f198a4ed8892c764191ef7b3b06d8a2eeb5c7f 2026-03-09 00:15:47.315750 | controller | runc: 2026-03-09 00:15:47.315754 | controller | Version: 1.1.10 2026-03-09 00:15:47.315759 | controller | GitCommit: v1.1.10-0-g18a0cb0 2026-03-09 00:15:47.315765 | controller | docker-init: 2026-03-09 00:15:47.315770 | controller | Version: 0.19.0 2026-03-09 00:15:47.315774 | controller | GitCommit: de40ad0 2026-03-09 00:15:47.725857 | controller | ok: Runtime: 0:00:00.050121 2026-03-09 00:15:47.732453 | 2026-03-09 00:15:47.732539 | TASK [ensure-docker : Dump installed docker details] 2026-03-09 00:15:47.763357 | {'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-09 00:15:47.265910', 'end': '2026-03-09 00:15:47.316031', 'delta': '0:00:00.050121', 'msg': '', 'zuul_log_id': 'fa163ec2-ffbe-41ed-1cc4-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-09 00:15:47.771085 | 2026-03-09 00:15:47.771192 | TASK [Use buildset registry] 2026-03-09 00:15:47.786421 | controller | skipping: Conditional result was False 2026-03-09 00:15:47.798247 | 2026-03-09 00:15:47.798363 | TASK [Ensure python3.8 is present] 2026-03-09 00:15:47.812387 | controller | skipping: Conditional result was False 2026-03-09 00:15:47.819452 | 2026-03-09 00:15:47.819559 | TASK [Run our-ensure-python role (Fedora only for now)] 2026-03-09 00:15:47.851321 | controller | ok 2026-03-09 00:15:47.874877 | 2026-03-09 00:15:47.875115 | TASK [our-ensure-python : Install the right Python version (rpm)] 2026-03-09 00:15:50.119880 | controller | ok: Nothing to do 2026-03-09 00:15:50.125532 | 2026-03-09 00:15:50.125593 | TASK [our-ensure-python : Also install python3-devel] 2026-03-09 00:16:01.442728 | controller | changed 2026-03-09 00:16:01.460643 | 2026-03-09 00:16:01.460799 | TASK [Run ensure-virtualenv role] 2026-03-09 00:16:01.482178 | controller | ok 2026-03-09 00:16:01.505131 | 2026-03-09 00:16:01.505250 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2026-03-09 00:16:01.715142 | controller | /usr/bin/virtualenv 2026-03-09 00:16:02.038774 | controller | ok: Runtime: 0:00:00.003199 2026-03-09 00:16:02.047174 | 2026-03-09 00:16:02.047291 | LOOP [ensure-virtualenv : Install virtualenv package] 2026-03-09 00:16:02.071734 | controller | skipping: Conditional result was False 2026-03-09 00:16:02.072048 | controller | changed: All items complete 2026-03-09 00:16:02.072082 | 2026-03-09 00:16:02.091734 | 2026-03-09 00:16:02.091883 | TASK [Find the full path of the Python interpreter] 2026-03-09 00:16:02.307686 | controller | /usr/bin/python3.8 2026-03-09 00:16:02.638062 | controller | ok 2026-03-09 00:16:02.643859 | 2026-03-09 00:16:02.643950 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2026-03-09 00:16:04.221229 | controller | created virtual environment CPython3.8.18.final.0-64 in 747ms 2026-03-09 00:16:04.255209 | controller | creator CPython3Posix(dest=/home/zuul/venv, clear=False, no_vcs_ignore=False, global=False) 2026-03-09 00:16:04.255225 | 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-09 00:16:04.255241 | controller | added seed packages: pip==22.2.2, setuptools==62.6.0, wheel==0.37.1 2026-03-09 00:16:04.255253 | controller | activators BashActivator,CShellActivator,FishActivator,NushellActivator,PowerShellActivator,PythonActivator 2026-03-09 00:16:04.698140 | controller | changed 2026-03-09 00:16:04.712525 | 2026-03-09 00:16:04.712698 | TASK [Set selinux package] 2026-03-09 00:16:04.748956 | controller | ok 2026-03-09 00:16:04.757486 | 2026-03-09 00:16:04.757587 | TASK [Set selinux package (Fedora)] 2026-03-09 00:16:04.798825 | controller | ok 2026-03-09 00:16:04.804331 | 2026-03-09 00:16:04.804392 | TASK [Install selinux into virtualenv] 2026-03-09 00:20:55.525610 | [Zuul] Log Stream did not terminate 2026-03-09 00:20:55.526071 | controller | ERROR 2026-03-09 00:20:55.526260 | controller | { 2026-03-09 00:20:55.526330 | 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-03-09 00:20:55.526402 | controller | "unreachable": true 2026-03-09 00:20:55.526443 | controller | } unreachable 2026-03-09 00:20:55.530309 | 2026-03-09 00:20:55.530414 | PLAY RECAP 2026-03-09 00:20:55.530510 | controller | ok: 34 changed: 17 unreachable: 1 failed: 0 skipped: 15 rescued: 0 ignored: 0 2026-03-09 00:20:55.530609 | 2026-03-09 00:20:55.686605 | PRE-RUN END RESULT_UNREACHABLE: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2026-03-09 00:20:55.688660 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-09 00:20:56.314984 | 2026-03-09 00:20:56.315108 | PLAY [all] 2026-03-09 00:20:56.336657 | 2026-03-09 00:20:56.336743 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-03-09 00:21:11.972459 | controller | changed: non-zero return code 2026-03-09 00:21:11.979250 | 2026-03-09 00:21:11.979347 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-03-09 00:21:12.003643 | controller | skipping: Conditional result was False 2026-03-09 00:21:12.011511 | 2026-03-09 00:21:12.011682 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-03-09 00:21:12.051007 | 2026-03-09 00:21:12.051350 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-03-09 00:21:12.079674 | 2026-03-09 00:21:12.079946 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-03-09 00:21:12.107860 | controller | skipping: Conditional result was False 2026-03-09 00:21:12.121652 | 2026-03-09 00:21:12.121822 | LOOP [fetch-subunit-output : Generate subunit file] 2026-03-09 00:21:12.153331 | 2026-03-09 00:21:12.153491 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-03-09 00:21:12.166949 | controller | skipping: Conditional result was False 2026-03-09 00:21:12.173100 | 2026-03-09 00:21:12.173172 | TASK [fetch-subunit-output : Remove the temporary file] 2026-03-09 00:21:12.186208 | controller | skipping: Conditional result was False 2026-03-09 00:21:12.192168 | 2026-03-09 00:21:12.192234 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-03-09 00:21:12.205347 | controller | skipping: Conditional result was False 2026-03-09 00:21:12.232256 | 2026-03-09 00:21:12.232333 | PLAY RECAP 2026-03-09 00:21:12.232374 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2026-03-09 00:21:12.232394 | 2026-03-09 00:21:12.331856 | POST-RUN END RESULT_UNREACHABLE: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2026-03-09 00:21:12.332866 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2026-03-09 00:21:12.899579 | 2026-03-09 00:21:12.899688 | PLAY [all:!appliance*] 2026-03-09 00:21:12.928332 | 2026-03-09 00:21:12.928445 | TASK [unregister the node] 2026-03-09 00:21:13.460087 | controller | skipping: Conditional result was False 2026-03-09 00:21:13.466309 | 2026-03-09 00:21:13.466417 | TASK [include_role : fetch-output] 2026-03-09 00:21:13.497849 | controller | ok 2026-03-09 00:21:13.526248 | 2026-03-09 00:21:13.526366 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-09 00:21:13.585101 | controller | skipping: Conditional result was False 2026-03-09 00:21:13.592075 | 2026-03-09 00:21:13.592185 | TASK [fetch-output : Set log path for single node] 2026-03-09 00:21:13.632304 | controller | ok 2026-03-09 00:21:13.637853 | 2026-03-09 00:21:13.637934 | LOOP [fetch-output : Ensure local output dirs] 2026-03-09 00:21:14.038926 | controller -> localhost | ok: "/var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/logs" 2026-03-09 00:21:14.302416 | controller -> localhost | changed: "/var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/artifacts" 2026-03-09 00:21:14.526293 | controller -> localhost | changed: "/var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/work/docs" 2026-03-09 00:21:14.543119 | 2026-03-09 00:21:14.543263 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-09 00:21:15.244471 | controller | changed: 2026-03-09 00:21:15.244713 | controller | .d..t...... ./ 2026-03-09 00:21:15.244760 | controller | cd+++++++++ controller/ 2026-03-09 00:21:15.244813 | controller | changed: All items complete 2026-03-09 00:21:15.244849 | 2026-03-09 00:21:15.734145 | controller | changed: .d..t...... ./ 2026-03-09 00:21:16.213727 | controller | changed: .d..t...... ./ 2026-03-09 00:21:16.255961 | 2026-03-09 00:21:16.256152 | TASK [include_role : fetch-output-openshift] 2026-03-09 00:21:16.277987 | controller | skipping: Conditional result was False 2026-03-09 00:21:16.285573 | 2026-03-09 00:21:16.285702 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-09 00:21:16.327923 | controller | skipping: Conditional result was False 2026-03-09 00:21:16.328896 | controller | skipping: Conditional result was False 2026-03-09 00:21:16.372838 | 2026-03-09 00:21:16.372954 | PLAY [localhost] 2026-03-09 00:21:16.390538 | 2026-03-09 00:21:16.390708 | TASK [Run Zuul manifest role] 2026-03-09 00:21:16.411175 | localhost | ok 2026-03-09 00:21:16.425697 | 2026-03-09 00:21:16.425809 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-09 00:21:16.845115 | localhost | changed 2026-03-09 00:21:16.849807 | 2026-03-09 00:21:16.849890 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-09 00:21:16.912898 | localhost | ok 2026-03-09 00:21:16.921495 | 2026-03-09 00:21:16.921625 | TASK [Set zuul-log-path fact] 2026-03-09 00:21:16.950323 | localhost | ok 2026-03-09 00:21:16.963682 | 2026-03-09 00:21:16.963795 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 00:21:17.000746 | localhost | ok 2026-03-09 00:21:17.017989 | 2026-03-09 00:21:17.018184 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)] 2026-03-09 00:21:17.078107 | localhost | ok: 2026-03-09 00:21:17.078384 | localhost | { 2026-03-09 00:21:17.078420 | localhost | "auth": { 2026-03-09 00:21:17.078448 | localhost | "password": "IonWCwcuOFib&&<}wp^:[2G/", 2026-03-09 00:21:17.078471 | localhost | "username": "9LF91O0RK7JU" 2026-03-09 00:21:17.078494 | localhost | }, 2026-03-09 00:21:17.078517 | localhost | "profile": "rackspace", 2026-03-09 00:21:17.078539 | localhost | "project_name": "1251317", 2026-03-09 00:21:17.078561 | localhost | "region_name": "DFW" 2026-03-09 00:21:17.078583 | localhost | } 2026-03-09 00:21:17.078618 | 2026-03-09 00:21:17.090137 | 2026-03-09 00:21:17.090260 | TASK [Run upload-logs-failover role (S3 then Swift)] 2026-03-09 00:21:17.110242 | localhost | Output suppressed because no_log was given 2026-03-09 00:21:17.128258 | 2026-03-09 00:21:17.128378 | TASK [upload-logs-failover : Initialize control vars] 2026-03-09 00:21:17.160282 | localhost | ok 2026-03-09 00:21:17.166530 | 2026-03-09 00:21:17.166634 | LOOP [upload-logs-failover : Perform log upload] 2026-03-09 00:21:17.203522 | localhost | Output suppressed because no_log was given 2026-03-09 00:21:17.252930 | localhost | included: /var/lib/zuul/builds/e00fc02d3c5d4107a7f7938107e7b529/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml 2026-03-09 00:21:17.259416 | 2026-03-09 00:21:17.259503 | LOOP [upload-logs-failover : Unpack vars for swift upload] 2026-03-09 00:21:17.344688 | localhost | Output suppressed because no_log was given 2026-03-09 00:21:17.351542 | 2026-03-09 00:21:17.351767 | TASK [Upload logs to swift] 2026-03-09 00:21:17.392574 | localhost | ok 2026-03-09 00:21:17.426189 | 2026-03-09 00:21:17.426292 | TASK [Set zuul-log-path fact] 2026-03-09 00:21:17.461839 | localhost | skipping: Conditional result was False 2026-03-09 00:21:17.468997 | 2026-03-09 00:21:17.469117 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2026-03-09 00:21:17.879657 | localhost -> localhost | ok: Runtime: 0:00:00.006245 2026-03-09 00:21:17.885410 | 2026-03-09 00:21:17.885486 | TASK [upload-logs-swift : Upload logs to swift]