2025-10-27 00:12:27.338229 | Job console starting... 2025-10-27 00:12:27.355885 | Updating repositories 2025-10-27 00:12:27.474292 | Preparing job workspace 2025-10-27 00:12:31.443095 | Running Ansible setup... 2025-10-27 00:12:37.852432 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-27 00:12:38.501441 | 2025-10-27 00:12:38.501569 | PLAY [localhost] 2025-10-27 00:12:38.510927 | 2025-10-27 00:12:38.511041 | TASK [Gathering Facts] 2025-10-27 00:12:39.465198 | localhost | ok 2025-10-27 00:12:39.479747 | 2025-10-27 00:12:39.479837 | TASK [Setup log path fact] 2025-10-27 00:12:39.497479 | localhost | ok 2025-10-27 00:12:39.510950 | 2025-10-27 00:12:39.511027 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-27 00:12:39.538696 | localhost | ok 2025-10-27 00:12:39.546925 | 2025-10-27 00:12:39.547012 | TASK [emit-job-header : Print job information] 2025-10-27 00:12:39.575012 | # Job Information 2025-10-27 00:12:39.575159 | Ansible Version: 2.15.12 2025-10-27 00:12:39.575203 | Job: ansible-test-sanity-docker-milestone 2025-10-27 00:12:39.575235 | Pipeline: periodic 2025-10-27 00:12:39.575265 | Executor: ze03.softwarefactory-project.io 2025-10-27 00:12:39.575292 | Triggered by: https://github.com/ansible-collections/ansible.network 2025-10-27 00:12:39.575320 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/93b/ansible/93b05d5669dd46e6b4ddd88d070a00d8/ 2025-10-27 00:12:39.575348 | Event ID: 750469794ad743fdb26497abfe8a4902 2025-10-27 00:12:39.579434 | 2025-10-27 00:12:39.579503 | LOOP [emit-job-header : Print node information] 2025-10-27 00:12:39.679456 | localhost | ok: 2025-10-27 00:12:39.679820 | localhost | # Node Information 2025-10-27 00:12:39.679883 | localhost | Inventory Hostname: controller 2025-10-27 00:12:39.679928 | localhost | Hostname: ip-172-16-132-79 2025-10-27 00:12:39.679970 | localhost | Username: zuul-worker 2025-10-27 00:12:39.680014 | localhost | Distro: Fedora 37 2025-10-27 00:12:39.680055 | localhost | Provider: ansible-us-east-2 2025-10-27 00:12:39.680093 | localhost | Region: us-east-2 2025-10-27 00:12:39.680131 | localhost | Label: ansible-fedora-37-1vcpu 2025-10-27 00:12:39.680169 | localhost | Product Name: t3.small 2025-10-27 00:12:39.680211 | localhost | Interface IP: 3.145.25.216 2025-10-27 00:12:39.708223 | 2025-10-27 00:12:39.708609 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2025-10-27 00:12:40.154969 | localhost -> localhost | changed 2025-10-27 00:12:40.161398 | 2025-10-27 00:12:40.161476 | TASK [log-inventory : Copy ansible inventory to logs dir] 2025-10-27 00:12:41.040148 | localhost -> localhost | changed 2025-10-27 00:12:41.079228 | 2025-10-27 00:12:41.079334 | PLAY [all:!appliance*] 2025-10-27 00:12:41.102432 | 2025-10-27 00:12:41.102540 | TASK [include_role : start-zuul-console] 2025-10-27 00:12:41.124622 | controller | ok 2025-10-27 00:12:41.144464 | 2025-10-27 00:12:41.144592 | TASK [start-zuul-console : Start zuul_console daemon.] 2025-10-27 00:12:41.835558 | controller | ok 2025-10-27 00:12:41.856879 | 2025-10-27 00:12:41.856989 | TASK [use-our-mirror : Retrieve the IP address] 2025-10-27 00:12:43.217896 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found 2025-10-27 00:12:43.227887 | 2025-10-27 00:12:43.227981 | TASK [use-our-mirror : Ensure the registry service is running on the mirror] 2025-10-27 00:12:43.378538 | controller | skipping: Conditional result was False 2025-10-27 00:12:43.395512 | 2025-10-27 00:12:43.395676 | TASK [use-our-mirror : Write the IP in /etc/hosts] 2025-10-27 00:12:43.413516 | controller | skipping: Conditional result was False 2025-10-27 00:12:43.424039 | 2025-10-27 00:12:43.424256 | TASK [use-our-mirror : Create the podman configuration directory] 2025-10-27 00:12:43.451160 | controller | skipping: Conditional result was False 2025-10-27 00:12:43.459879 | 2025-10-27 00:12:43.460468 | TASK [use-our-mirror : Copy the podman configuration] 2025-10-27 00:12:43.487187 | controller | skipping: Conditional result was False 2025-10-27 00:12:43.498742 | 2025-10-27 00:12:43.498921 | TASK [use-our-mirror : Copy the Yum configuration (main)] 2025-10-27 00:12:43.525515 | controller | skipping: Conditional result was False 2025-10-27 00:12:43.534611 | 2025-10-27 00:12:43.534708 | TASK [use-our-mirror : Copy the Yum configuration (update)] 2025-10-27 00:12:43.560366 | controller | skipping: Conditional result was False 2025-10-27 00:12:43.576172 | 2025-10-27 00:12:43.576285 | TASK [Disable Fedora Modular] 2025-10-27 00:12:44.326011 | controller | changed 2025-10-27 00:12:44.334633 | 2025-10-27 00:12:44.334770 | TASK [Enable EPEL] 2025-10-27 00:12:44.355683 | controller | skipping: Conditional result was False 2025-10-27 00:12:44.370046 | 2025-10-27 00:12:44.370197 | TASK [Register the RHEL node] 2025-10-27 00:12:44.539422 | 2025-10-27 00:12:44.539689 | TASK [Show the subscription-manager status] 2025-10-27 00:12:44.702788 | controller | skipping: Conditional result was False 2025-10-27 00:12:44.758373 | 2025-10-27 00:12:44.758507 | TASK [Enable EPEL on RHEL] 2025-10-27 00:12:44.894591 | controller | skipping: Conditional result was False 2025-10-27 00:12:44.901355 | 2025-10-27 00:12:44.901442 | TASK [Install git and tox] 2025-10-27 00:14:20.810425 | controller | changed 2025-10-27 00:14:20.823875 | 2025-10-27 00:14:20.824043 | TASK [include_role : prepare-workspace] 2025-10-27 00:14:20.851551 | controller | ok 2025-10-27 00:14:20.888917 | 2025-10-27 00:14:20.889089 | TASK [prepare-workspace : Start zuul_console daemon.] 2025-10-27 00:14:21.436303 | controller | ok 2025-10-27 00:14:21.442884 | 2025-10-27 00:14:21.442965 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2025-10-27 00:14:34.383482 | controller | Output suppressed because no_log was given 2025-10-27 00:14:34.399411 | 2025-10-27 00:14:34.399569 | TASK [include_role : prepare-workspace-openshift] 2025-10-27 00:14:34.419795 | controller | skipping: Conditional result was False 2025-10-27 00:14:34.450129 | 2025-10-27 00:14:34.450223 | PLAY [all:!appliance] 2025-10-27 00:14:34.471189 | 2025-10-27 00:14:34.471332 | TASK [Run add-build-sshkey role (RSA)] 2025-10-27 00:14:34.533663 | controller | ok 2025-10-27 00:14:34.553214 | 2025-10-27 00:14:34.553364 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-27 00:14:34.803572 | controller -> localhost | ok 2025-10-27 00:14:34.812762 | 2025-10-27 00:14:34.812874 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-27 00:14:34.842561 | controller | ok 2025-10-27 00:14:34.856835 | controller | included: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-27 00:14:34.865210 | 2025-10-27 00:14:34.865333 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-27 00:14:35.333375 | controller -> localhost | Generating public/private rsa key pair. 2025-10-27 00:14:35.333623 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/93b05d5669dd46e6b4ddd88d070a00d8_id_rsa. 2025-10-27 00:14:35.333659 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/93b05d5669dd46e6b4ddd88d070a00d8_id_rsa.pub. 2025-10-27 00:14:35.333684 | controller -> localhost | The key fingerprint is: 2025-10-27 00:14:35.333708 | controller -> localhost | SHA256:yWFKE4Mrdyk2vWl0RxDJn8igSabs61/VS/LZXFrtHf0 zuul-build-sshkey 2025-10-27 00:14:35.333752 | controller -> localhost | The key's randomart image is: 2025-10-27 00:14:35.333779 | controller -> localhost | +---[RSA 2048]----+ 2025-10-27 00:14:35.333803 | controller -> localhost | | .o.o+ | 2025-10-27 00:14:35.333826 | controller -> localhost | | + .oo . | 2025-10-27 00:14:35.333849 | controller -> localhost | | . + =o+o+ . | 2025-10-27 00:14:35.333872 | controller -> localhost | | + O.==+++ ..| 2025-10-27 00:14:35.333894 | controller -> localhost | | . + =.+S.o o.o| 2025-10-27 00:14:35.333930 | controller -> localhost | | . +. + = + .+| 2025-10-27 00:14:35.333957 | controller -> localhost | | . .. + + E| 2025-10-27 00:14:35.333983 | controller -> localhost | | . . | 2025-10-27 00:14:35.334007 | controller -> localhost | | .... | 2025-10-27 00:14:35.334033 | controller -> localhost | +----[SHA256]-----+ 2025-10-27 00:14:35.334093 | controller -> localhost | ok: Runtime: 0:00:00.086621 2025-10-27 00:14:35.341429 | 2025-10-27 00:14:35.341511 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-27 00:14:35.363623 | controller | ok 2025-10-27 00:14:35.373759 | controller | included: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-27 00:14:35.383417 | 2025-10-27 00:14:35.383502 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-27 00:14:35.409424 | controller | skipping: Conditional result was False 2025-10-27 00:14:35.420704 | 2025-10-27 00:14:35.420838 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-27 00:14:36.037832 | controller | changed 2025-10-27 00:14:36.053824 | 2025-10-27 00:14:36.053999 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-27 00:14:36.415892 | controller | ok 2025-10-27 00:14:36.424367 | 2025-10-27 00:14:36.424485 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-27 00:14:37.758966 | controller | changed 2025-10-27 00:14:37.768140 | 2025-10-27 00:14:37.768301 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-27 00:14:39.097628 | controller | changed 2025-10-27 00:14:39.111097 | 2025-10-27 00:14:39.111258 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-27 00:14:39.147982 | controller | skipping: Conditional result was False 2025-10-27 00:14:39.162488 | 2025-10-27 00:14:39.162666 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-27 00:14:39.537530 | controller -> localhost | changed 2025-10-27 00:14:39.562841 | 2025-10-27 00:14:39.563033 | TASK [add-build-sshkey : Add back temp key] 2025-10-27 00:14:39.885415 | controller -> localhost | Identity added: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/93b05d5669dd46e6b4ddd88d070a00d8_id_rsa (zuul-build-sshkey) 2025-10-27 00:14:39.885644 | controller -> localhost | ok: Runtime: 0:00:00.007213 2025-10-27 00:14:39.892150 | 2025-10-27 00:14:39.892233 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-27 00:14:40.421126 | controller | ok 2025-10-27 00:14:40.433410 | 2025-10-27 00:14:40.433592 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-27 00:14:40.461545 | controller | skipping: Conditional result was False 2025-10-27 00:14:40.494114 | 2025-10-27 00:14:40.494302 | TASK [Run add-build-sshkey role (ECDSA)] 2025-10-27 00:14:40.528960 | controller | ok 2025-10-27 00:14:40.551916 | 2025-10-27 00:14:40.552057 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2025-10-27 00:14:40.842128 | controller -> localhost | ok 2025-10-27 00:14:40.850415 | 2025-10-27 00:14:40.850511 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2025-10-27 00:14:40.874312 | controller | ok 2025-10-27 00:14:40.889226 | controller | included: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2025-10-27 00:14:40.898280 | 2025-10-27 00:14:40.898392 | TASK [add-build-sshkey : Create Temp SSH key] 2025-10-27 00:14:41.210004 | controller -> localhost | Generating public/private ecdsa key pair. 2025-10-27 00:14:41.210357 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/93b05d5669dd46e6b4ddd88d070a00d8_id_ecdsa. 2025-10-27 00:14:41.210425 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/93b05d5669dd46e6b4ddd88d070a00d8_id_ecdsa.pub. 2025-10-27 00:14:41.210499 | controller -> localhost | The key fingerprint is: 2025-10-27 00:14:41.210547 | controller -> localhost | SHA256:GxcyvRXsu5ae3IVvzNtHapUxu4jYrWG1163b8YON91Y zuul-build-sshkey 2025-10-27 00:14:41.210589 | controller -> localhost | The key's randomart image is: 2025-10-27 00:14:41.210629 | controller -> localhost | +---[ECDSA 521]---+ 2025-10-27 00:14:41.210668 | controller -> localhost | | .. | 2025-10-27 00:14:41.210707 | controller -> localhost | | . .. | 2025-10-27 00:14:41.210790 | controller -> localhost | | o o.. | 2025-10-27 00:14:41.210832 | controller -> localhost | | o +. o | 2025-10-27 00:14:41.210870 | controller -> localhost | | S o o =| 2025-10-27 00:14:41.210938 | controller -> localhost | | + o .+E| 2025-10-27 00:14:41.211002 | controller -> localhost | | . ooo+*O*| 2025-10-27 00:14:41.211065 | controller -> localhost | | ..+=B+XX| 2025-10-27 00:14:41.211111 | controller -> localhost | | +=.==@| 2025-10-27 00:14:41.211151 | controller -> localhost | +----[SHA256]-----+ 2025-10-27 00:14:41.211249 | controller -> localhost | ok: Runtime: 0:00:00.014568 2025-10-27 00:14:41.227089 | 2025-10-27 00:14:41.227245 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2025-10-27 00:14:41.268793 | controller | ok 2025-10-27 00:14:41.285975 | controller | included: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2025-10-27 00:14:41.298314 | 2025-10-27 00:14:41.298401 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2025-10-27 00:14:41.323380 | controller | skipping: Conditional result was False 2025-10-27 00:14:41.331989 | 2025-10-27 00:14:41.332079 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2025-10-27 00:14:41.802321 | controller | changed 2025-10-27 00:14:41.810564 | 2025-10-27 00:14:41.810651 | TASK [add-build-sshkey : Make sure user has a .ssh] 2025-10-27 00:14:42.278801 | controller | ok 2025-10-27 00:14:42.294183 | 2025-10-27 00:14:42.294366 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2025-10-27 00:14:43.645367 | controller | changed 2025-10-27 00:14:43.653041 | 2025-10-27 00:14:43.653158 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2025-10-27 00:14:45.015393 | controller | changed 2025-10-27 00:14:45.028218 | 2025-10-27 00:14:45.028367 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2025-10-27 00:14:45.065943 | controller | skipping: Conditional result was False 2025-10-27 00:14:45.080158 | 2025-10-27 00:14:45.080362 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2025-10-27 00:14:45.343091 | controller -> localhost | changed 2025-10-27 00:14:45.368550 | 2025-10-27 00:14:45.368758 | TASK [add-build-sshkey : Add back temp key] 2025-10-27 00:14:45.726098 | controller -> localhost | Identity added: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/93b05d5669dd46e6b4ddd88d070a00d8_id_ecdsa (zuul-build-sshkey) 2025-10-27 00:14:45.726440 | controller -> localhost | ok: Runtime: 0:00:00.016034 2025-10-27 00:14:45.743016 | 2025-10-27 00:14:45.743189 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2025-10-27 00:14:46.136364 | controller | ok 2025-10-27 00:14:46.142978 | 2025-10-27 00:14:46.143056 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2025-10-27 00:14:46.177694 | controller | skipping: Conditional result was False 2025-10-27 00:14:46.201283 | 2025-10-27 00:14:46.201721 | TASK [include_role : remove-zuul-sshkey] 2025-10-27 00:14:46.228282 | controller | skipping: Conditional result was False 2025-10-27 00:14:46.236176 | 2025-10-27 00:14:46.236263 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2025-10-27 00:14:46.647063 | controller | ok: "logs" 2025-10-27 00:14:46.647463 | controller | ok: All items complete 2025-10-27 00:14:46.647507 | 2025-10-27 00:14:46.932565 | controller | ok: "artifacts" 2025-10-27 00:14:47.214140 | controller | ok: "docs" 2025-10-27 00:14:47.228471 | 2025-10-27 00:14:47.228582 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2025-10-27 00:14:47.558637 | controller | changed: "logs" 2025-10-27 00:14:47.854351 | controller | changed: "artifacts" 2025-10-27 00:14:48.154392 | controller | changed: "docs" 2025-10-27 00:14:48.217627 | 2025-10-27 00:14:48.217825 | PLAY RECAP 2025-10-27 00:14:48.217907 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0 2025-10-27 00:14:48.217951 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2025-10-27 00:14:48.217979 | 2025-10-27 00:14:48.338804 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master] 2025-10-27 00:14:48.340667 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-27 00:14:48.982152 | 2025-10-27 00:14:48.982279 | PLAY [all] 2025-10-27 00:14:49.007010 | 2025-10-27 00:14:49.007145 | TASK [Install binary dependencies] 2025-10-27 00:14:49.060216 | controller | ok 2025-10-27 00:14:49.085816 | 2025-10-27 00:14:49.085950 | TASK [bindep : Include find tasks] 2025-10-27 00:14:49.117277 | controller | ok 2025-10-27 00:14:49.127692 | controller | included: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml 2025-10-27 00:14:49.136353 | 2025-10-27 00:14:49.136497 | TASK [bindep : Look for bindep.txt] 2025-10-27 00:14:49.674081 | controller | ok 2025-10-27 00:14:49.688257 | 2025-10-27 00:14:49.688632 | TASK [bindep : Define bindep_file fact] 2025-10-27 00:14:49.715603 | controller | skipping: Conditional result was False 2025-10-27 00:14:49.724373 | 2025-10-27 00:14:49.724518 | TASK [bindep : Look for other-requirements.txt] 2025-10-27 00:14:50.038941 | controller | ok 2025-10-27 00:14:50.048381 | 2025-10-27 00:14:50.048505 | TASK [bindep : Define bindep_file fact] 2025-10-27 00:14:50.073514 | controller | skipping: Conditional result was False 2025-10-27 00:14:50.081606 | 2025-10-27 00:14:50.081758 | TASK [bindep : Look for bindep fallback file] 2025-10-27 00:14:50.106995 | controller | skipping: Conditional result was False 2025-10-27 00:14:50.115558 | 2025-10-27 00:14:50.115679 | TASK [bindep : Define bindep_file fact] 2025-10-27 00:14:50.140901 | controller | skipping: Conditional result was False 2025-10-27 00:14:50.149853 | 2025-10-27 00:14:50.149978 | TASK [bindep : Include bindep tasks] 2025-10-27 00:14:50.185343 | controller | skipping: Conditional result was False 2025-10-27 00:14:50.196985 | 2025-10-27 00:14:50.197112 | TASK [bindep : Include install tasks] 2025-10-27 00:14:50.223146 | controller | skipping: Conditional result was False 2025-10-27 00:14:50.232488 | 2025-10-27 00:14:50.232614 | LOOP [bindep : Include package tasks] 2025-10-27 00:14:50.294008 | 2025-10-27 00:14:50.294212 | TASK [Run test-setup role] 2025-10-27 00:14:50.319270 | controller | ok 2025-10-27 00:14:50.352826 | 2025-10-27 00:14:50.352972 | TASK [test-setup : Check if project's tools/test-setup.sh exists] 2025-10-27 00:14:50.667267 | controller | ok 2025-10-27 00:14:50.673107 | 2025-10-27 00:14:50.673182 | TASK [test-setup : Run tools/test-setup.sh] 2025-10-27 00:14:50.809588 | controller | skipping: Conditional result was False 2025-10-27 00:14:50.860577 | 2025-10-27 00:14:50.860705 | PLAY RECAP 2025-10-27 00:14:50.860790 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-27 00:14:50.860822 | 2025-10-27 00:14:50.965024 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master] 2025-10-27 00:14:50.965845 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-27 00:14:51.551199 | 2025-10-27 00:14:51.551331 | PLAY [controller] 2025-10-27 00:14:51.570812 | 2025-10-27 00:14:51.570906 | TASK [Create the /root directory] 2025-10-27 00:14:52.345992 | controller | ok 2025-10-27 00:14:52.359619 | 2025-10-27 00:14:52.359818 | TASK [Install glibc-langpack-en] 2025-10-27 00:17:49.123815 | controller | ok: Nothing to do 2025-10-27 00:17:49.135717 | 2025-10-27 00:17:49.135928 | TASK [Ensure controller directory exists] 2025-10-27 00:17:49.542997 | controller | changed 2025-10-27 00:17:49.554960 | 2025-10-27 00:17:49.555227 | TASK [Install container runtime] 2025-10-27 00:17:49.634529 | controller | ok 2025-10-27 00:17:49.696464 | 2025-10-27 00:17:49.696630 | LOOP [ensure-podman : Find distribution installation] 2025-10-27 00:17:49.746902 | controller | ok: "/var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml" 2025-10-27 00:17:49.761456 | controller | included: /var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/ensure-podman/tasks/RedHat.yaml 2025-10-27 00:17:49.767651 | 2025-10-27 00:17:49.767715 | TASK [ensure-podman : Install podman (RedHat)] 2025-10-27 00:19:10.848906 | controller | changed 2025-10-27 00:19:10.863779 | 2025-10-27 00:19:10.863947 | TASK [ensure-podman : Fetch podman version] 2025-10-27 00:19:10.938446 | [controller] Waiting on logger 2025-10-27 00:19:12.233814 | [controller] Waiting on logger 2025-10-27 00:19:13.517924 | [controller] Waiting on logger 2025-10-27 00:19:14.797174 | [controller] Waiting on logger 2025-10-27 00:19:16.086064 | [controller] Waiting on logger 2025-10-27 00:19:17.368165 | [controller] Waiting on logger 2025-10-27 00:19:18.645567 | [controller] Waiting on logger 2025-10-27 00:19:19.926865 | [controller] Waiting on logger 2025-10-27 00:19:21.215258 | [controller] Waiting on logger 2025-10-27 00:19:22.501234 | [controller] Waiting on logger 2025-10-27 00:19:23.779800 | [controller] Waiting on logger 2025-10-27 00:19:25.106555 | [controller] Waiting on logger 2025-10-27 00:19:26.395668 | [controller] Waiting on logger 2025-10-27 00:19:27.676166 | [controller] Waiting on logger 2025-10-27 00:19:28.965165 | [controller] Waiting on logger 2025-10-27 00:19:30.244604 | [controller] Waiting on logger 2025-10-27 00:19:31.537524 | [controller] Waiting on logger 2025-10-27 00:19:32.818231 | [controller] Waiting on logger 2025-10-27 00:19:34.107851 | [controller] Waiting on logger 2025-10-27 00:19:35.391207 | [controller] Waiting on logger 2025-10-27 00:19:36.676602 | [controller] Waiting on logger 2025-10-27 00:19:37.955773 | [controller] Waiting on logger 2025-10-27 00:19:39.245195 | [controller] Waiting on logger 2025-10-27 00:19:40.534715 | [controller] Waiting on logger 2025-10-27 00:19:41.690762 | [Zuul] Log Stream did not terminate 2025-10-27 00:19:41.691116 | controller | ok: Runtime: 0:00:00.222018 2025-10-27 00:19:41.704156 | 2025-10-27 00:19:41.704299 | TASK [ensure-podman : Print podman version installed] 2025-10-27 00:19:41.745485 | Podman version: Client: Podman Engine 2025-10-27 00:19:41.745932 | Version: 4.6.2 2025-10-27 00:19:41.746001 | API Version: 4.6.2 2025-10-27 00:19:41.746045 | Go Version: go1.19.12 2025-10-27 00:19:41.746084 | Built: Mon Aug 28 19:38:31 2023 2025-10-27 00:19:41.746130 | OS/Arch: linux/amd64 2025-10-27 00:19:41.759295 | 2025-10-27 00:19:41.759491 | TASK [ensure-podman : Validate podman engine] 2025-10-27 00:19:41.815862 | [controller] Waiting on logger 2025-10-27 00:19:41.820380 | [controller] Waiting on logger 2025-10-27 00:19:43.096340 | [controller] Waiting on logger 2025-10-27 00:19:43.098331 | [controller] Waiting on logger 2025-10-27 00:19:44.379505 | [controller] Waiting on logger 2025-10-27 00:19:44.379676 | [controller] Waiting on logger 2025-10-27 00:19:45.654709 | [controller] Waiting on logger 2025-10-27 00:19:45.668663 | [controller] Waiting on logger 2025-10-27 00:19:46.940196 | [controller] Waiting on logger 2025-10-27 00:19:46.951176 | [controller] Waiting on logger 2025-10-27 00:19:48.221436 | [controller] Waiting on logger 2025-10-27 00:19:48.241044 | [controller] Waiting on logger 2025-10-27 00:19:49.505495 | [controller] Waiting on logger 2025-10-27 00:19:49.524471 | [controller] Waiting on logger 2025-10-27 00:19:50.792576 | [controller] Waiting on logger 2025-10-27 00:19:50.806147 | [controller] Waiting on logger 2025-10-27 00:19:52.080689 | [controller] Waiting on logger 2025-10-27 00:19:52.086016 | [controller] Waiting on logger 2025-10-27 00:19:53.358503 | [controller] Waiting on logger 2025-10-27 00:19:53.366648 | [controller] Waiting on logger 2025-10-27 00:19:54.643383 | [controller] Waiting on logger 2025-10-27 00:19:55.673356 | [controller] Waiting on logger 2025-10-27 00:19:55.946955 | [controller] Waiting on logger 2025-10-27 00:19:56.961156 | [controller] Waiting on logger 2025-10-27 00:19:57.227029 | [controller] Waiting on logger 2025-10-27 00:19:58.245972 | [controller] Waiting on logger 2025-10-27 00:19:58.510637 | [controller] Waiting on logger 2025-10-27 00:19:59.529977 | [controller] Waiting on logger 2025-10-27 00:19:59.795348 | [controller] Waiting on logger 2025-10-27 00:20:00.816197 | [controller] Waiting on logger 2025-10-27 00:20:01.090133 | [controller] Waiting on logger 2025-10-27 00:20:02.108609 | [controller] Waiting on logger 2025-10-27 00:20:02.365764 | [controller] Waiting on logger 2025-10-27 00:20:03.649334 | [controller] Waiting on logger 2025-10-27 00:20:04.404642 | [controller] Waiting on logger 2025-10-27 00:20:04.932380 | [controller] Waiting on logger 2025-10-27 00:20:05.684388 | [controller] Waiting on logger 2025-10-27 00:20:06.217073 | [controller] Waiting on logger 2025-10-27 00:20:06.972186 | [controller] Waiting on logger 2025-10-27 00:20:07.505214 | [controller] Waiting on logger 2025-10-27 00:20:08.252871 | [controller] Waiting on logger 2025-10-27 00:20:08.785947 | [controller] Waiting on logger 2025-10-27 00:20:09.525333 | [controller] Waiting on logger 2025-10-27 00:20:10.077327 | [controller] Waiting on logger 2025-10-27 00:20:10.795471 | [controller] Waiting on logger 2025-10-27 00:20:11.355095 | [controller] Waiting on logger 2025-10-27 00:20:11.810609 | [Zuul] Log Stream did not terminate 2025-10-27 00:20:11.811063 | controller | skipping: Conditional result was False 2025-10-27 00:20:11.825664 | 2025-10-27 00:20:11.825865 | TASK [ensure-podman : Set up docker compatability socket] 2025-10-27 00:20:11.850263 | controller | skipping: Conditional result was False 2025-10-27 00:20:11.861814 | 2025-10-27 00:20:11.861896 | TASK [Ensure python3.8 is present] 2025-10-27 00:20:11.886014 | controller | skipping: Conditional result was False 2025-10-27 00:20:11.892826 | 2025-10-27 00:20:11.892892 | TASK [Run our-ensure-python role (Fedora only for now)] 2025-10-27 00:20:11.922912 | controller | ok 2025-10-27 00:20:11.940638 | 2025-10-27 00:20:11.940714 | TASK [our-ensure-python : Install the right Python version (rpm)] 2025-10-27 00:20:12.093186 | [controller] Waiting on logger 2025-10-27 00:20:12.644040 | [controller] Waiting on logger 2025-10-27 00:20:13.367234 | [controller] Waiting on logger 2025-10-27 00:20:13.925625 | [controller] Waiting on logger 2025-10-27 00:20:14.655416 | [controller] Waiting on logger 2025-10-27 00:20:14.977062 | controller | ok: Nothing to do 2025-10-27 00:20:14.998006 | 2025-10-27 00:20:14.998171 | TASK [our-ensure-python : Also install python3-devel] 2025-10-27 00:20:15.255944 | [controller] Waiting on logger 2025-10-27 00:20:15.937895 | [controller] Waiting on logger 2025-10-27 00:20:16.533812 | [controller] Waiting on logger 2025-10-27 00:20:17.214587 | [controller] Waiting on logger 2025-10-27 00:20:17.816464 | [controller] Waiting on logger 2025-10-27 00:20:18.494794 | [controller] Waiting on logger 2025-10-27 00:20:19.094487 | [controller] Waiting on logger 2025-10-27 00:20:19.783419 | [controller] Waiting on logger 2025-10-27 00:20:21.062552 | [controller] Waiting on logger 2025-10-27 00:20:21.443661 | [controller] Waiting on logger 2025-10-27 00:20:22.332897 | [controller] Waiting on logger 2025-10-27 00:20:22.729219 | [controller] Waiting on logger 2025-10-27 00:20:23.603596 | [controller] Waiting on logger 2025-10-27 00:20:24.018863 | [controller] Waiting on logger 2025-10-27 00:20:24.890551 | [controller] Waiting on logger 2025-10-27 00:20:25.301722 | [controller] Waiting on logger 2025-10-27 00:20:26.011059 | controller | changed 2025-10-27 00:20:26.033910 | 2025-10-27 00:20:26.034083 | TASK [Run ensure-virtualenv role] 2025-10-27 00:20:26.059011 | controller | ok 2025-10-27 00:20:26.088253 | 2025-10-27 00:20:26.088398 | TASK [ensure-virtualenv : Check if virtualenv is installed] 2025-10-27 00:20:26.142863 | [controller] Waiting on logger 2025-10-27 00:20:26.182233 | [controller] Waiting on logger 2025-10-27 00:20:26.590520 | [controller] Waiting on logger 2025-10-27 00:20:27.423825 | [controller] Waiting on logger 2025-10-27 00:20:27.466551 | [controller] Waiting on logger 2025-10-27 00:20:27.874872 | [controller] Waiting on logger 2025-10-27 00:20:28.709475 | [controller] Waiting on logger 2025-10-27 00:20:28.754296 | [controller] Waiting on logger 2025-10-27 00:20:29.158366 | [controller] Waiting on logger 2025-10-27 00:20:30.000342 | [controller] Waiting on logger 2025-10-27 00:20:30.040396 | [controller] Waiting on logger 2025-10-27 00:20:30.441477 | [controller] Waiting on logger 2025-10-27 00:20:31.285837 | [controller] Waiting on logger 2025-10-27 00:20:31.324070 | [controller] Waiting on logger 2025-10-27 00:20:31.721451 | [controller] Waiting on logger 2025-10-27 00:20:32.564414 | [controller] Waiting on logger 2025-10-27 00:20:32.607048 | [controller] Waiting on logger 2025-10-27 00:20:32.991974 | [controller] Waiting on logger 2025-10-27 00:20:33.843950 | [controller] Waiting on logger 2025-10-27 00:20:33.889016 | [controller] Waiting on logger 2025-10-27 00:20:34.273297 | [controller] Waiting on logger 2025-10-27 00:20:35.118571 | [controller] Waiting on logger 2025-10-27 00:20:35.162408 | [controller] Waiting on logger 2025-10-27 00:20:35.551197 | [controller] Waiting on logger 2025-10-27 00:20:36.405173 | [controller] Waiting on logger 2025-10-27 00:20:36.437785 | [controller] Waiting on logger 2025-10-27 00:20:36.828345 | [controller] Waiting on logger 2025-10-27 00:20:37.688761 | [controller] Waiting on logger 2025-10-27 00:20:37.723296 | [controller] Waiting on logger 2025-10-27 00:20:38.105931 | [controller] Waiting on logger 2025-10-27 00:20:38.977329 | [controller] Waiting on logger 2025-10-27 00:20:39.003452 | [controller] Waiting on logger 2025-10-27 00:20:39.381740 | [controller] Waiting on logger 2025-10-27 00:20:40.246136 | [controller] Waiting on logger 2025-10-27 00:20:40.282228 | [controller] Waiting on logger 2025-10-27 00:20:40.671917 | [controller] Waiting on logger 2025-10-27 00:20:41.533912 | [controller] Waiting on logger 2025-10-27 00:20:41.565277 | [controller] Waiting on logger 2025-10-27 00:20:41.950699 | [controller] Waiting on logger 2025-10-27 00:20:42.823176 | [controller] Waiting on logger 2025-10-27 00:20:42.841506 | [controller] Waiting on logger 2025-10-27 00:20:43.229797 | [controller] Waiting on logger 2025-10-27 00:20:44.096401 | [controller] Waiting on logger 2025-10-27 00:20:44.125849 | [controller] Waiting on logger 2025-10-27 00:20:44.510427 | [controller] Waiting on logger 2025-10-27 00:20:45.390062 | [controller] Waiting on logger 2025-10-27 00:20:45.409934 | [controller] Waiting on logger 2025-10-27 00:20:45.795144 | [controller] Waiting on logger 2025-10-27 00:20:46.673907 | [controller] Waiting on logger 2025-10-27 00:20:46.695237 | [controller] Waiting on logger 2025-10-27 00:20:47.081015 | [controller] Waiting on logger 2025-10-27 00:20:47.954246 | [controller] Waiting on logger 2025-10-27 00:20:49.021642 | [controller] Waiting on logger 2025-10-27 00:20:49.237139 | [controller] Waiting on logger 2025-10-27 00:20:49.403691 | [controller] Waiting on logger 2025-10-27 00:20:50.307302 | [controller] Waiting on logger 2025-10-27 00:20:50.523837 | [controller] Waiting on logger 2025-10-27 00:20:50.689466 | [controller] Waiting on logger 2025-10-27 00:20:51.594012 | [controller] Waiting on logger 2025-10-27 00:20:51.812718 | [controller] Waiting on logger 2025-10-27 00:20:51.978883 | [controller] Waiting on logger 2025-10-27 00:20:52.876418 | [controller] Waiting on logger 2025-10-27 00:20:53.101314 | [controller] Waiting on logger 2025-10-27 00:20:53.257988 | [controller] Waiting on logger 2025-10-27 00:20:54.165103 | [controller] Waiting on logger 2025-10-27 00:20:54.376801 | [controller] Waiting on logger 2025-10-27 00:20:54.533278 | [controller] Waiting on logger 2025-10-27 00:20:55.442446 | [controller] Waiting on logger 2025-10-27 00:20:55.664238 | [controller] Waiting on logger 2025-10-27 00:20:55.814854 | [controller] Waiting on logger 2025-10-27 00:20:56.516755 | [Zuul] Log Stream did not terminate 2025-10-27 00:20:56.517036 | controller | ok: Runtime: 0:00:00.004611 2025-10-27 00:20:56.525208 | 2025-10-27 00:20:56.525359 | LOOP [ensure-virtualenv : Install virtualenv package] 2025-10-27 00:20:56.550024 | controller | skipping: Conditional result was False 2025-10-27 00:20:56.550450 | controller | ok: All items complete 2025-10-27 00:20:56.550506 | 2025-10-27 00:20:56.574785 | 2025-10-27 00:20:56.575012 | TASK [Find the full path of the Python interpreter] 2025-10-27 00:20:56.644225 | [controller] Waiting on logger 2025-10-27 00:20:56.733932 | [controller] Waiting on logger 2025-10-27 00:20:56.946661 | [controller] Waiting on logger 2025-10-27 00:20:57.099367 | [controller] Waiting on logger 2025-10-27 00:20:57.928521 | [controller] Waiting on logger 2025-10-27 00:20:58.222158 | [controller] Waiting on logger 2025-10-27 00:20:58.557039 | [controller] Waiting on logger 2025-10-27 00:20:59.213939 | [controller] Waiting on logger 2025-10-27 00:20:59.388971 | [controller] Waiting on logger 2025-10-27 00:20:59.507925 | [controller] Waiting on logger 2025-10-27 00:20:59.849220 | [controller] Waiting on logger 2025-10-27 00:21:00.495962 | [controller] Waiting on logger 2025-10-27 00:21:00.670683 | [controller] Waiting on logger 2025-10-27 00:21:00.796234 | [controller] Waiting on logger 2025-10-27 00:21:01.137397 | [controller] Waiting on logger 2025-10-27 00:21:01.790204 | [controller] Waiting on logger 2025-10-27 00:21:01.966857 | [controller] Waiting on logger 2025-10-27 00:21:02.073422 | [controller] Waiting on logger 2025-10-27 00:21:02.418501 | [controller] Waiting on logger 2025-10-27 00:21:03.061999 | [controller] Waiting on logger 2025-10-27 00:21:03.246268 | [controller] Waiting on logger 2025-10-27 00:21:03.353543 | [controller] Waiting on logger 2025-10-27 00:21:03.697487 | [controller] Waiting on logger 2025-10-27 00:21:04.346178 | [controller] Waiting on logger 2025-10-27 00:21:04.529546 | [controller] Waiting on logger 2025-10-27 00:21:04.644780 | [controller] Waiting on logger 2025-10-27 00:21:04.983384 | [controller] Waiting on logger 2025-10-27 00:21:05.635953 | [controller] Waiting on logger 2025-10-27 00:21:05.822586 | [controller] Waiting on logger 2025-10-27 00:21:05.932420 | [controller] Waiting on logger 2025-10-27 00:21:06.264143 | [controller] Waiting on logger 2025-10-27 00:21:06.928792 | [controller] Waiting on logger 2025-10-27 00:21:07.104087 | [controller] Waiting on logger 2025-10-27 00:21:07.220636 | [controller] Waiting on logger 2025-10-27 00:21:07.547016 | [controller] Waiting on logger 2025-10-27 00:21:08.204584 | [controller] Waiting on logger 2025-10-27 00:21:08.381677 | [controller] Waiting on logger 2025-10-27 00:21:08.501556 | [controller] Waiting on logger 2025-10-27 00:21:08.820261 | [controller] Waiting on logger 2025-10-27 00:21:09.488468 | [controller] Waiting on logger 2025-10-27 00:21:09.666180 | [controller] Waiting on logger 2025-10-27 00:21:09.784548 | [controller] Waiting on logger 2025-10-27 00:21:10.106766 | [controller] Waiting on logger 2025-10-27 00:21:10.779174 | [controller] Waiting on logger 2025-10-27 00:21:10.952359 | [controller] Waiting on logger 2025-10-27 00:21:11.070205 | [controller] Waiting on logger 2025-10-27 00:21:11.397888 | [controller] Waiting on logger 2025-10-27 00:21:12.057891 | [controller] Waiting on logger 2025-10-27 00:21:12.234508 | [controller] Waiting on logger 2025-10-27 00:21:12.352110 | [controller] Waiting on logger 2025-10-27 00:21:12.676398 | [controller] Waiting on logger 2025-10-27 00:21:13.343502 | [controller] Waiting on logger 2025-10-27 00:21:13.519243 | [controller] Waiting on logger 2025-10-27 00:21:13.634537 | [controller] Waiting on logger 2025-10-27 00:21:13.956680 | [controller] Waiting on logger 2025-10-27 00:21:14.632795 | [controller] Waiting on logger 2025-10-27 00:21:14.799277 | [controller] Waiting on logger 2025-10-27 00:21:14.915559 | [controller] Waiting on logger 2025-10-27 00:21:15.234633 | [controller] Waiting on logger 2025-10-27 00:21:15.913021 | [controller] Waiting on logger 2025-10-27 00:21:16.084192 | [controller] Waiting on logger 2025-10-27 00:21:16.191879 | [controller] Waiting on logger 2025-10-27 00:21:16.514505 | [controller] Waiting on logger 2025-10-27 00:21:17.193450 | [controller] Waiting on logger 2025-10-27 00:21:17.372828 | [controller] Waiting on logger 2025-10-27 00:21:17.469699 | [controller] Waiting on logger 2025-10-27 00:21:17.792970 | [controller] Waiting on logger 2025-10-27 00:21:18.468190 | [controller] Waiting on logger 2025-10-27 00:21:18.653267 | [controller] Waiting on logger 2025-10-27 00:21:18.757788 | [controller] Waiting on logger 2025-10-27 00:21:19.075802 | [controller] Waiting on logger 2025-10-27 00:21:19.760449 | [controller] Waiting on logger 2025-10-27 00:21:19.932604 | [controller] Waiting on logger 2025-10-27 00:21:20.040519 | [controller] Waiting on logger 2025-10-27 00:21:20.353542 | [controller] Waiting on logger 2025-10-27 00:21:21.029673 | [controller] Waiting on logger 2025-10-27 00:21:21.232389 | [controller] Waiting on logger 2025-10-27 00:21:21.327656 | [controller] Waiting on logger 2025-10-27 00:21:21.636112 | [controller] Waiting on logger 2025-10-27 00:21:22.320122 | [controller] Waiting on logger 2025-10-27 00:21:22.505283 | [controller] Waiting on logger 2025-10-27 00:21:22.606479 | [controller] Waiting on logger 2025-10-27 00:21:22.929376 | [controller] Waiting on logger 2025-10-27 00:21:23.790050 | [controller] Waiting on logger 2025-10-27 00:21:23.886861 | [controller] Waiting on logger 2025-10-27 00:21:24.205094 | [controller] Waiting on logger 2025-10-27 00:21:24.605435 | [controller] Waiting on logger 2025-10-27 00:21:25.066884 | [controller] Waiting on logger 2025-10-27 00:21:25.162432 | [controller] Waiting on logger 2025-10-27 00:21:25.489746 | [controller] Waiting on logger 2025-10-27 00:21:25.883353 | [controller] Waiting on logger 2025-10-27 00:21:26.356560 | [controller] Waiting on logger 2025-10-27 00:21:26.451842 | [controller] Waiting on logger 2025-10-27 00:21:26.773540 | [controller] Waiting on logger 2025-10-27 00:21:27.020603 | [Zuul] Log Stream did not terminate 2025-10-27 00:21:27.020897 | controller | ok 2025-10-27 00:21:27.032140 | 2025-10-27 00:21:27.032275 | TASK [Create virtualenv for ansible-test with a specific interpreter] 2025-10-27 00:21:27.104708 | [controller] Waiting on logger 2025-10-27 00:21:27.170271 | [controller] Waiting on logger 2025-10-27 00:21:27.636721 | [controller] Waiting on logger 2025-10-27 00:21:27.742981 | [controller] Waiting on logger 2025-10-27 00:21:28.050341 | [controller] Waiting on logger 2025-10-27 00:21:28.879324 | [controller] Waiting on logger 2025-10-27 00:21:28.973351 | [controller] Waiting on logger 2025-10-27 00:21:29.026825 | [controller] Waiting on logger 2025-10-27 00:21:29.260513 | [controller] Waiting on logger 2025-10-27 00:21:29.334393 | [controller] Waiting on logger 2025-10-27 00:21:30.156547 | [controller] Waiting on logger 2025-10-27 00:21:30.265098 | [controller] Waiting on logger 2025-10-27 00:21:30.298715 | [controller] Waiting on logger 2025-10-27 00:21:30.544346 | [controller] Waiting on logger 2025-10-27 00:21:30.621168 | [controller] Waiting on logger 2025-10-27 00:21:31.442776 | [controller] Waiting on logger 2025-10-27 00:21:31.543630 | [controller] Waiting on logger 2025-10-27 00:21:31.579657 | [controller] Waiting on logger 2025-10-27 00:21:31.833254 | [controller] Waiting on logger 2025-10-27 00:21:31.901370 | [controller] Waiting on logger 2025-10-27 00:21:32.727089 | [controller] Waiting on logger 2025-10-27 00:21:32.826323 | [controller] Waiting on logger 2025-10-27 00:21:32.859811 | [controller] Waiting on logger 2025-10-27 00:21:33.107197 | [controller] Waiting on logger 2025-10-27 00:21:33.182022 | [controller] Waiting on logger 2025-10-27 00:21:34.010792 | [controller] Waiting on logger 2025-10-27 00:21:34.111450 | [controller] Waiting on logger 2025-10-27 00:21:34.143049 | [controller] Waiting on logger 2025-10-27 00:21:34.393803 | [controller] Waiting on logger 2025-10-27 00:21:34.469783 | [controller] Waiting on logger 2025-10-27 00:21:35.284453 | [controller] Waiting on logger 2025-10-27 00:21:35.389323 | [controller] Waiting on logger 2025-10-27 00:21:35.431350 | [controller] Waiting on logger 2025-10-27 00:21:35.677326 | [controller] Waiting on logger 2025-10-27 00:21:36.569438 | [controller] Waiting on logger 2025-10-27 00:21:36.673711 | [controller] Waiting on logger 2025-10-27 00:21:36.708557 | [controller] Waiting on logger 2025-10-27 00:21:36.763281 | [controller] Waiting on logger 2025-10-27 00:21:36.954550 | [controller] Waiting on logger 2025-10-27 00:21:37.849853 | [controller] Waiting on logger 2025-10-27 00:21:37.965003 | [controller] Waiting on logger 2025-10-27 00:21:37.991517 | [controller] Waiting on logger 2025-10-27 00:21:38.041271 | [controller] Waiting on logger 2025-10-27 00:21:38.239171 | [controller] Waiting on logger 2025-10-27 00:21:39.133546 | [controller] Waiting on logger 2025-10-27 00:21:39.255934 | [controller] Waiting on logger 2025-10-27 00:21:39.256306 | [controller] Waiting on logger 2025-10-27 00:21:39.320948 | [controller] Waiting on logger 2025-10-27 00:21:39.512710 | [controller] Waiting on logger 2025-10-27 00:21:40.418855 | [controller] Waiting on logger 2025-10-27 00:21:40.534462 | [controller] Waiting on logger 2025-10-27 00:21:40.539055 | [controller] Waiting on logger 2025-10-27 00:21:40.599345 | [controller] Waiting on logger 2025-10-27 00:21:40.793048 | [controller] Waiting on logger 2025-10-27 00:21:41.698992 | [controller] Waiting on logger 2025-10-27 00:21:41.815665 | [controller] Waiting on logger 2025-10-27 00:21:41.815999 | [controller] Waiting on logger 2025-10-27 00:21:41.879316 | [controller] Waiting on logger 2025-10-27 00:21:42.076230 | [controller] Waiting on logger 2025-10-27 00:21:42.988999 | [controller] Waiting on logger 2025-10-27 00:21:43.090615 | [controller] Waiting on logger 2025-10-27 00:21:43.096647 | [controller] Waiting on logger 2025-10-27 00:21:43.158354 | [controller] Waiting on logger 2025-10-27 00:21:43.367777 | [controller] Waiting on logger 2025-10-27 00:21:44.273556 | [controller] Waiting on logger 2025-10-27 00:21:44.374557 | [controller] Waiting on logger 2025-10-27 00:21:44.376781 | [controller] Waiting on logger 2025-10-27 00:21:44.448915 | [controller] Waiting on logger 2025-10-27 00:21:44.657615 | [controller] Waiting on logger 2025-10-27 00:21:45.550893 | [controller] Waiting on logger 2025-10-27 00:21:45.658955 | [controller] Waiting on logger 2025-10-27 00:21:45.659235 | [controller] Waiting on logger 2025-10-27 00:21:45.736111 | [controller] Waiting on logger 2025-10-27 00:21:45.935566 | [controller] Waiting on logger 2025-10-27 00:21:46.819653 | [controller] Waiting on logger 2025-10-27 00:21:46.931874 | [controller] Waiting on logger 2025-10-27 00:21:46.939544 | [controller] Waiting on logger 2025-10-27 00:21:47.028973 | [controller] Waiting on logger 2025-10-27 00:21:47.211511 | [controller] Waiting on logger 2025-10-27 00:21:48.087553 | [controller] Waiting on logger 2025-10-27 00:21:48.215213 | [controller] Waiting on logger 2025-10-27 00:21:48.218926 | [controller] Waiting on logger 2025-10-27 00:21:48.316544 | [controller] Waiting on logger 2025-10-27 00:21:48.495796 | [controller] Waiting on logger 2025-10-27 00:21:49.364177 | [controller] Waiting on logger 2025-10-27 00:21:49.495788 | [controller] Waiting on logger 2025-10-27 00:21:49.512062 | [controller] Waiting on logger 2025-10-27 00:21:49.585286 | [controller] Waiting on logger 2025-10-27 00:21:49.786940 | [controller] Waiting on logger 2025-10-27 00:21:50.642761 | [controller] Waiting on logger 2025-10-27 00:21:50.777615 | [controller] Waiting on logger 2025-10-27 00:21:50.796096 | [controller] Waiting on logger 2025-10-27 00:21:50.868146 | [controller] Waiting on logger 2025-10-27 00:21:51.060036 | [controller] Waiting on logger 2025-10-27 00:21:51.928855 | [controller] Waiting on logger 2025-10-27 00:21:52.058884 | [controller] Waiting on logger 2025-10-27 00:21:52.068251 | [controller] Waiting on logger 2025-10-27 00:21:52.155921 | [controller] Waiting on logger 2025-10-27 00:21:52.350089 | [controller] Waiting on logger 2025-10-27 00:21:53.209927 | [controller] Waiting on logger 2025-10-27 00:21:53.340814 | [controller] Waiting on logger 2025-10-27 00:21:53.362354 | [controller] Waiting on logger 2025-10-27 00:21:53.441303 | [controller] Waiting on logger 2025-10-27 00:21:53.632880 | [controller] Waiting on logger 2025-10-27 00:21:54.486570 | [controller] Waiting on logger 2025-10-27 00:21:54.615655 | [controller] Waiting on logger 2025-10-27 00:21:54.644963 | [controller] Waiting on logger 2025-10-27 00:21:54.730187 | [controller] Waiting on logger 2025-10-27 00:21:54.916830 | [controller] Waiting on logger 2025-10-27 00:21:55.772053 | [controller] Waiting on logger 2025-10-27 00:21:55.904250 | [controller] Waiting on logger 2025-10-27 00:21:55.934257 | [controller] Waiting on logger 2025-10-27 00:21:56.195808 | [controller] Waiting on logger 2025-10-27 00:21:57.055653 | [controller] Waiting on logger 2025-10-27 00:21:57.058292 | [controller] Waiting on logger 2025-10-27 00:21:57.189572 | [controller] Waiting on logger 2025-10-27 00:21:57.208873 | [controller] Waiting on logger 2025-10-27 00:21:57.473866 | [controller] Waiting on logger 2025-10-27 00:21:58.138033 | [Zuul] Log Stream did not terminate 2025-10-27 00:21:58.138224 | controller | changed 2025-10-27 00:21:58.144573 | 2025-10-27 00:21:58.144657 | TASK [Set selinux package] 2025-10-27 00:21:58.174631 | controller | ok 2025-10-27 00:21:58.180136 | 2025-10-27 00:21:58.180220 | TASK [Set selinux package (Fedora)] 2025-10-27 00:21:58.233328 | controller | ok 2025-10-27 00:21:58.241144 | 2025-10-27 00:21:58.241282 | TASK [Install selinux into virtualenv] 2025-10-27 00:21:58.305079 | [controller] Waiting on logger 2025-10-27 00:21:58.337875 | [controller] Waiting on logger 2025-10-27 00:21:58.339774 | [controller] Waiting on logger 2025-10-27 00:21:58.466715 | [controller] Waiting on logger 2025-10-27 00:21:58.492077 | [controller] Waiting on logger 2025-10-27 00:21:58.766411 | [controller] Waiting on logger 2025-10-27 00:21:59.711188 | [controller] Waiting on logger 2025-10-27 00:21:59.742751 | [controller] Waiting on logger 2025-10-27 00:21:59.777124 | [controller] Waiting on logger 2025-10-27 00:22:00.097861 | [controller] Waiting on logger 2025-10-27 00:22:00.100298 | [controller] Waiting on logger 2025-10-27 00:22:00.515871 | [controller] Waiting on logger 2025-10-27 00:22:00.987292 | [controller] Waiting on logger 2025-10-27 00:22:01.016027 | [controller] Waiting on logger 2025-10-27 00:22:01.066079 | [controller] Waiting on logger 2025-10-27 00:22:01.376596 | [controller] Waiting on logger 2025-10-27 00:22:01.377128 | [controller] Waiting on logger 2025-10-27 00:22:01.788487 | [controller] Waiting on logger 2025-10-27 00:22:02.269885 | [controller] Waiting on logger 2025-10-27 00:22:02.300118 | [controller] Waiting on logger 2025-10-27 00:22:02.340193 | [controller] Waiting on logger 2025-10-27 00:22:02.647822 | [controller] Waiting on logger 2025-10-27 00:22:02.652053 | [controller] Waiting on logger 2025-10-27 00:22:03.070718 | [controller] Waiting on logger 2025-10-27 00:22:03.552633 | [controller] Waiting on logger 2025-10-27 00:22:03.578838 | [controller] Waiting on logger 2025-10-27 00:22:03.622080 | [controller] Waiting on logger 2025-10-27 00:22:03.924808 | [controller] Waiting on logger 2025-10-27 00:22:03.930280 | [controller] Waiting on logger 2025-10-27 00:22:04.353803 | [controller] Waiting on logger 2025-10-27 00:22:04.830861 | [controller] Waiting on logger 2025-10-27 00:22:04.864351 | [controller] Waiting on logger 2025-10-27 00:22:04.894201 | [controller] Waiting on logger 2025-10-27 00:22:05.206174 | [controller] Waiting on logger 2025-10-27 00:22:05.213383 | [controller] Waiting on logger 2025-10-27 00:22:05.630545 | [controller] Waiting on logger 2025-10-27 00:22:06.114225 | [controller] Waiting on logger 2025-10-27 00:22:06.149059 | [controller] Waiting on logger 2025-10-27 00:22:06.181545 | [controller] Waiting on logger 2025-10-27 00:22:06.480902 | [controller] Waiting on logger 2025-10-27 00:22:06.486125 | [controller] Waiting on logger 2025-10-27 00:22:06.906823 | [controller] Waiting on logger 2025-10-27 00:22:07.392460 | [controller] Waiting on logger 2025-10-27 00:22:07.427361 | [controller] Waiting on logger 2025-10-27 00:22:07.459380 | [controller] Waiting on logger 2025-10-27 00:22:07.765057 | [controller] Waiting on logger 2025-10-27 00:22:07.766628 | [controller] Waiting on logger 2025-10-27 00:22:08.192446 | [controller] Waiting on logger 2025-10-27 00:22:08.687077 | [controller] Waiting on logger 2025-10-27 00:22:08.708164 | [controller] Waiting on logger 2025-10-27 00:22:08.749263 | [controller] Waiting on logger 2025-10-27 00:22:09.051192 | [controller] Waiting on logger 2025-10-27 00:22:09.057653 | [controller] Waiting on logger 2025-10-27 00:22:09.469545 | [controller] Waiting on logger 2025-10-27 00:22:09.962054 | [controller] Waiting on logger 2025-10-27 00:22:09.990146 | [controller] Waiting on logger 2025-10-27 00:22:10.028553 | [controller] Waiting on logger 2025-10-27 00:22:10.330104 | [controller] Waiting on logger 2025-10-27 00:22:10.333864 | [controller] Waiting on logger 2025-10-27 00:22:10.750246 | [controller] Waiting on logger 2025-10-27 00:22:11.236259 | [controller] Waiting on logger 2025-10-27 00:22:11.320589 | [controller] Waiting on logger 2025-10-27 00:22:11.603783 | [controller] Waiting on logger 2025-10-27 00:22:11.614152 | [controller] Waiting on logger 2025-10-27 00:22:12.031342 | [controller] Waiting on logger 2025-10-27 00:22:12.281451 | [controller] Waiting on logger 2025-10-27 00:22:12.522064 | [controller] Waiting on logger 2025-10-27 00:22:12.606855 | [controller] Waiting on logger 2025-10-27 00:22:12.885821 | [controller] Waiting on logger 2025-10-27 00:22:12.900012 | [controller] Waiting on logger 2025-10-27 00:22:13.313976 | [controller] Waiting on logger 2025-10-27 00:22:13.563895 | [controller] Waiting on logger 2025-10-27 00:22:13.798196 | [controller] Waiting on logger 2025-10-27 00:22:13.900710 | [controller] Waiting on logger 2025-10-27 00:22:14.172998 | [controller] Waiting on logger 2025-10-27 00:22:14.184196 | [controller] Waiting on logger 2025-10-27 00:22:14.591624 | [controller] Waiting on logger 2025-10-27 00:22:14.842877 | [controller] Waiting on logger 2025-10-27 00:22:15.092637 | [controller] Waiting on logger 2025-10-27 00:22:15.181672 | [controller] Waiting on logger 2025-10-27 00:22:15.456718 | [controller] Waiting on logger 2025-10-27 00:22:15.476089 | [controller] Waiting on logger 2025-10-27 00:22:15.876189 | [controller] Waiting on logger 2025-10-27 00:22:16.111166 | [controller] Waiting on logger 2025-10-27 00:22:16.375326 | [controller] Waiting on logger 2025-10-27 00:22:16.463986 | [controller] Waiting on logger 2025-10-27 00:22:16.741875 | [controller] Waiting on logger 2025-10-27 00:22:16.754801 | [controller] Waiting on logger 2025-10-27 00:22:17.158470 | [controller] Waiting on logger 2025-10-27 00:22:17.389023 | [controller] Waiting on logger 2025-10-27 00:22:17.661478 | [controller] Waiting on logger 2025-10-27 00:22:17.742708 | [controller] Waiting on logger 2025-10-27 00:22:18.025432 | [controller] Waiting on logger 2025-10-27 00:22:18.038775 | [controller] Waiting on logger 2025-10-27 00:22:18.431949 | [controller] Waiting on logger 2025-10-27 00:22:18.673892 | [controller] Waiting on logger 2025-10-27 00:22:18.938141 | [controller] Waiting on logger 2025-10-27 00:22:19.031909 | [controller] Waiting on logger 2025-10-27 00:22:19.306657 | [controller] Waiting on logger 2025-10-27 00:22:19.318180 | [controller] Waiting on logger 2025-10-27 00:22:19.704120 | [controller] Waiting on logger 2025-10-27 00:22:19.951855 | [controller] Waiting on logger 2025-10-27 00:22:20.210772 | [controller] Waiting on logger 2025-10-27 00:22:20.311452 | [controller] Waiting on logger 2025-10-27 00:22:20.589374 | [controller] Waiting on logger 2025-10-27 00:22:20.591757 | [controller] Waiting on logger 2025-10-27 00:22:20.995387 | [controller] Waiting on logger 2025-10-27 00:22:21.217923 | [controller] Waiting on logger 2025-10-27 00:22:21.494179 | [controller] Waiting on logger 2025-10-27 00:22:21.598388 | [controller] Waiting on logger 2025-10-27 00:22:21.859320 | [controller] Waiting on logger 2025-10-27 00:22:21.881953 | [controller] Waiting on logger 2025-10-27 00:22:22.270851 | [controller] Waiting on logger 2025-10-27 00:22:22.495668 | [controller] Waiting on logger 2025-10-27 00:22:22.768595 | [controller] Waiting on logger 2025-10-27 00:22:22.890868 | [controller] Waiting on logger 2025-10-27 00:22:23.138196 | [controller] Waiting on logger 2025-10-27 00:22:23.160108 | [controller] Waiting on logger 2025-10-27 00:22:23.548816 | [controller] Waiting on logger 2025-10-27 00:22:23.789458 | [controller] Waiting on logger 2025-10-27 00:22:24.055359 | [controller] Waiting on logger 2025-10-27 00:22:24.176421 | [controller] Waiting on logger 2025-10-27 00:22:24.412504 | [controller] Waiting on logger 2025-10-27 00:22:24.442484 | [controller] Waiting on logger 2025-10-27 00:22:24.834042 | [controller] Waiting on logger 2025-10-27 00:22:25.064810 | [controller] Waiting on logger 2025-10-27 00:22:25.343379 | [controller] Waiting on logger 2025-10-27 00:22:25.462661 | [controller] Waiting on logger 2025-10-27 00:22:25.702200 | [controller] Waiting on logger 2025-10-27 00:22:25.722576 | [controller] Waiting on logger 2025-10-27 00:22:26.117199 | [controller] Waiting on logger 2025-10-27 00:22:26.346206 | [controller] Waiting on logger 2025-10-27 00:22:26.625878 | [controller] Waiting on logger 2025-10-27 00:22:26.730186 | [controller] Waiting on logger 2025-10-27 00:22:26.987524 | [controller] Waiting on logger 2025-10-27 00:22:27.001809 | [controller] Waiting on logger 2025-10-27 00:22:27.387719 | [controller] Waiting on logger 2025-10-27 00:22:27.625399 | [controller] Waiting on logger 2025-10-27 00:22:27.903875 | [controller] Waiting on logger 2025-10-27 00:22:28.012487 | [controller] Waiting on logger 2025-10-27 00:22:28.277431 | [controller] Waiting on logger 2025-10-27 00:22:28.289532 | [controller] Waiting on logger 2025-10-27 00:22:28.675239 | [controller] Waiting on logger 2025-10-27 00:22:28.901311 | [controller] Waiting on logger 2025-10-27 00:22:29.175841 | [controller] Waiting on logger 2025-10-27 00:22:29.285711 | [controller] Waiting on logger 2025-10-27 00:22:29.554798 | [controller] Waiting on logger 2025-10-27 00:22:29.570943 | [controller] Waiting on logger 2025-10-27 00:22:30.139262 | [controller] Waiting on logger 2025-10-27 00:22:30.192253 | [controller] Waiting on logger 2025-10-27 00:22:30.454419 | [controller] Waiting on logger 2025-10-27 00:22:30.571391 | [controller] Waiting on logger 2025-10-27 00:22:31.064197 | [controller] Waiting on logger 2025-10-27 00:22:31.431114 | [controller] Waiting on logger 2025-10-27 00:22:31.482984 | [controller] Waiting on logger 2025-10-27 00:22:31.645343 | [Zuul] Log Stream did not terminate 2025-10-27 00:22:31.645665 | controller | ok: Runtime: 0:00:02.909577 2025-10-27 00:22:31.660135 | 2025-10-27 00:22:31.660341 | TASK [Install pytest-forked into virtualenv] 2025-10-27 00:22:31.739137 | [controller] Waiting on logger 2025-10-27 00:22:31.747668 | [controller] Waiting on logger 2025-10-27 00:22:31.857879 | [controller] Waiting on logger 2025-10-27 00:22:31.871693 | [controller] Waiting on logger 2025-10-27 00:22:32.348570 | [controller] Waiting on logger 2025-10-27 00:22:32.714792 | [controller] Waiting on logger 2025-10-27 00:22:32.757879 | [controller] Waiting on logger 2025-10-27 00:22:33.009048 | [controller] Waiting on logger 2025-10-27 00:22:33.024838 | [controller] Waiting on logger 2025-10-27 00:22:33.136630 | [controller] Waiting on logger 2025-10-27 00:22:33.155828 | [controller] Waiting on logger 2025-10-27 00:22:33.635055 | [controller] Waiting on logger 2025-10-27 00:22:34.000611 | [controller] Waiting on logger 2025-10-27 00:22:34.041686 | [controller] Waiting on logger 2025-10-27 00:22:34.293457 | [controller] Waiting on logger 2025-10-27 00:22:34.310696 | [controller] Waiting on logger 2025-10-27 00:22:34.421074 | [controller] Waiting on logger 2025-10-27 00:22:34.440722 | [controller] Waiting on logger 2025-10-27 00:22:34.910996 | [controller] Waiting on logger 2025-10-27 00:22:35.291195 | [controller] Waiting on logger 2025-10-27 00:22:35.325336 | [controller] Waiting on logger 2025-10-27 00:22:35.576159 | [controller] Waiting on logger 2025-10-27 00:22:35.579165 | [controller] Waiting on logger 2025-10-27 00:22:35.704894 | [controller] Waiting on logger 2025-10-27 00:22:35.722441 | [controller] Waiting on logger 2025-10-27 00:22:36.188078 | [controller] Waiting on logger 2025-10-27 00:22:36.567984 | [controller] Waiting on logger 2025-10-27 00:22:36.604758 | [controller] Waiting on logger 2025-10-27 00:22:36.860389 | [controller] Waiting on logger 2025-10-27 00:22:36.863480 | [controller] Waiting on logger 2025-10-27 00:22:36.984853 | [controller] Waiting on logger 2025-10-27 00:22:37.010339 | [controller] Waiting on logger 2025-10-27 00:22:37.470872 | [controller] Waiting on logger 2025-10-27 00:22:37.855757 | [controller] Waiting on logger 2025-10-27 00:22:37.884051 | [controller] Waiting on logger 2025-10-27 00:22:38.140593 | [controller] Waiting on logger 2025-10-27 00:22:38.148526 | [controller] Waiting on logger 2025-10-27 00:22:38.263750 | [controller] Waiting on logger 2025-10-27 00:22:38.301173 | [controller] Waiting on logger 2025-10-27 00:22:38.744065 | [controller] Waiting on logger 2025-10-27 00:22:39.143842 | [controller] Waiting on logger 2025-10-27 00:22:39.164165 | [controller] Waiting on logger 2025-10-27 00:22:39.427907 | [controller] Waiting on logger 2025-10-27 00:22:39.557435 | [controller] Waiting on logger 2025-10-27 00:22:39.580132 | [controller] Waiting on logger 2025-10-27 00:22:40.017673 | [controller] Waiting on logger 2025-10-27 00:22:40.422229 | [controller] Waiting on logger 2025-10-27 00:22:40.446850 | [controller] Waiting on logger 2025-10-27 00:22:40.454820 | [controller] Waiting on logger 2025-10-27 00:22:40.706391 | [controller] Waiting on logger 2025-10-27 00:22:40.829227 | [controller] Waiting on logger 2025-10-27 00:22:40.863801 | [controller] Waiting on logger 2025-10-27 00:22:41.302513 | [controller] Waiting on logger 2025-10-27 00:22:41.706550 | [controller] Waiting on logger 2025-10-27 00:22:41.731177 | [controller] Waiting on logger 2025-10-27 00:22:41.740887 | [controller] Waiting on logger 2025-10-27 00:22:41.990610 | [controller] Waiting on logger 2025-10-27 00:22:42.139810 | [controller] Waiting on logger 2025-10-27 00:22:42.578477 | [controller] Waiting on logger 2025-10-27 00:22:42.985403 | [controller] Waiting on logger 2025-10-27 00:22:43.014208 | [controller] Waiting on logger 2025-10-27 00:22:43.017581 | [controller] Waiting on logger 2025-10-27 00:22:43.138837 | [controller] Waiting on logger 2025-10-27 00:22:43.264865 | [controller] Waiting on logger 2025-10-27 00:22:43.410753 | [controller] Waiting on logger 2025-10-27 00:22:43.868940 | [controller] Waiting on logger 2025-10-27 00:22:44.267328 | [controller] Waiting on logger 2025-10-27 00:22:44.294064 | [controller] Waiting on logger 2025-10-27 00:22:44.301700 | [controller] Waiting on logger 2025-10-27 00:22:44.420969 | [controller] Waiting on logger 2025-10-27 00:22:44.540395 | [controller] Waiting on logger 2025-10-27 00:22:44.689656 | [controller] Waiting on logger 2025-10-27 00:22:45.150210 | [controller] Waiting on logger 2025-10-27 00:22:45.549143 | [controller] Waiting on logger 2025-10-27 00:22:45.576770 | [controller] Waiting on logger 2025-10-27 00:22:45.582114 | [controller] Waiting on logger 2025-10-27 00:22:45.706510 | [controller] Waiting on logger 2025-10-27 00:22:45.831707 | [controller] Waiting on logger 2025-10-27 00:22:45.963533 | [controller] Waiting on logger 2025-10-27 00:22:46.425994 | [controller] Waiting on logger 2025-10-27 00:22:46.839812 | [controller] Waiting on logger 2025-10-27 00:22:46.855685 | [controller] Waiting on logger 2025-10-27 00:22:46.857242 | [controller] Waiting on logger 2025-10-27 00:22:46.983394 | [controller] Waiting on logger 2025-10-27 00:22:47.106444 | [controller] Waiting on logger 2025-10-27 00:22:47.232126 | [controller] Waiting on logger 2025-10-27 00:22:47.716059 | [controller] Waiting on logger 2025-10-27 00:22:48.119297 | [controller] Waiting on logger 2025-10-27 00:22:48.134021 | [controller] Waiting on logger 2025-10-27 00:22:48.137773 | [controller] Waiting on logger 2025-10-27 00:22:48.273804 | [controller] Waiting on logger 2025-10-27 00:22:48.385051 | [controller] Waiting on logger 2025-10-27 00:22:48.516653 | [controller] Waiting on logger 2025-10-27 00:22:48.994862 | [controller] Waiting on logger 2025-10-27 00:22:49.402346 | [controller] Waiting on logger 2025-10-27 00:22:49.415985 | [controller] Waiting on logger 2025-10-27 00:22:49.416486 | [controller] Waiting on logger 2025-10-27 00:22:49.557975 | [controller] Waiting on logger 2025-10-27 00:22:49.674948 | [controller] Waiting on logger 2025-10-27 00:22:49.798895 | [controller] Waiting on logger 2025-10-27 00:22:50.280293 | [controller] Waiting on logger 2025-10-27 00:22:50.686991 | [controller] Waiting on logger 2025-10-27 00:22:50.690612 | [controller] Waiting on logger 2025-10-27 00:22:50.695032 | [controller] Waiting on logger 2025-10-27 00:22:50.830468 | [controller] Waiting on logger 2025-10-27 00:22:50.951862 | [controller] Waiting on logger 2025-10-27 00:22:51.086870 | [controller] Waiting on logger 2025-10-27 00:22:51.571017 | [controller] Waiting on logger 2025-10-27 00:22:51.971250 | [controller] Waiting on logger 2025-10-27 00:22:51.971838 | [controller] Waiting on logger 2025-10-27 00:22:51.973086 | [controller] Waiting on logger 2025-10-27 00:22:52.109811 | [controller] Waiting on logger 2025-10-27 00:22:52.230824 | [controller] Waiting on logger 2025-10-27 00:22:52.357965 | [controller] Waiting on logger 2025-10-27 00:22:52.857638 | [controller] Waiting on logger 2025-10-27 00:22:53.254843 | [controller] Waiting on logger 2025-10-27 00:22:53.256434 | [controller] Waiting on logger 2025-10-27 00:22:53.261703 | [controller] Waiting on logger 2025-10-27 00:22:53.399333 | [controller] Waiting on logger 2025-10-27 00:22:53.511549 | [controller] Waiting on logger 2025-10-27 00:22:53.644990 | [controller] Waiting on logger 2025-10-27 00:22:54.136609 | [controller] Waiting on logger 2025-10-27 00:22:54.526073 | [controller] Waiting on logger 2025-10-27 00:22:54.538826 | [controller] Waiting on logger 2025-10-27 00:22:54.553593 | [controller] Waiting on logger 2025-10-27 00:22:54.783804 | [controller] Waiting on logger 2025-10-27 00:22:54.919674 | [controller] Waiting on logger 2025-10-27 00:22:55.416522 | [controller] Waiting on logger 2025-10-27 00:22:55.733857 | [controller] Waiting on logger 2025-10-27 00:22:55.815408 | [controller] Waiting on logger 2025-10-27 00:22:55.826278 | [controller] Waiting on logger 2025-10-27 00:22:55.833329 | [controller] Waiting on logger 2025-10-27 00:22:56.063968 | [controller] Waiting on logger 2025-10-27 00:22:56.196881 | [controller] Waiting on logger 2025-10-27 00:22:56.700838 | [controller] Waiting on logger 2025-10-27 00:22:57.013870 | [controller] Waiting on logger 2025-10-27 00:22:57.096930 | [controller] Waiting on logger 2025-10-27 00:22:57.102694 | [controller] Waiting on logger 2025-10-27 00:22:57.113942 | [controller] Waiting on logger 2025-10-27 00:22:57.344107 | [controller] Waiting on logger 2025-10-27 00:22:57.472705 | [controller] Waiting on logger 2025-10-27 00:22:57.985034 | [controller] Waiting on logger 2025-10-27 00:22:58.298632 | [controller] Waiting on logger 2025-10-27 00:22:58.375986 | [controller] Waiting on logger 2025-10-27 00:22:58.382422 | [controller] Waiting on logger 2025-10-27 00:22:58.390204 | [controller] Waiting on logger 2025-10-27 00:22:58.615075 | [controller] Waiting on logger 2025-10-27 00:22:58.747946 | [controller] Waiting on logger 2025-10-27 00:22:59.262949 | [controller] Waiting on logger 2025-10-27 00:22:59.579258 | [controller] Waiting on logger 2025-10-27 00:22:59.655766 | [controller] Waiting on logger 2025-10-27 00:22:59.656271 | [controller] Waiting on logger 2025-10-27 00:22:59.674346 | [controller] Waiting on logger 2025-10-27 00:22:59.899509 | [controller] Waiting on logger 2025-10-27 00:23:00.033548 | [controller] Waiting on logger 2025-10-27 00:23:00.551858 | [controller] Waiting on logger 2025-10-27 00:23:00.863884 | [controller] Waiting on logger 2025-10-27 00:23:00.927487 | [controller] Waiting on logger 2025-10-27 00:23:00.934466 | [controller] Waiting on logger 2025-10-27 00:23:01.179599 | [controller] Waiting on logger 2025-10-27 00:23:01.531987 | [controller] Waiting on logger 2025-10-27 00:23:01.538755 | [controller] Waiting on logger 2025-10-27 00:23:02.177604 | [controller] Waiting on logger 2025-10-27 00:23:02.179146 | [controller] Waiting on logger 2025-10-27 00:23:02.218956 | [controller] Waiting on logger 2025-10-27 00:23:02.307398 | [controller] Waiting on logger 2025-10-27 00:23:02.470955 | [controller] Waiting on logger 2025-10-27 00:23:02.818032 | [controller] Waiting on logger 2025-10-27 00:23:02.819472 | [controller] Waiting on logger 2025-10-27 00:23:03.455716 | [controller] Waiting on logger 2025-10-27 00:23:03.468362 | [controller] Waiting on logger 2025-10-27 00:23:03.509923 | [controller] Waiting on logger 2025-10-27 00:23:03.594051 | [controller] Waiting on logger 2025-10-27 00:23:03.763562 | [controller] Waiting on logger 2025-10-27 00:23:04.104373 | [controller] Waiting on logger 2025-10-27 00:23:04.105328 | [controller] Waiting on logger 2025-10-27 00:23:04.736647 | [controller] Waiting on logger 2025-10-27 00:23:04.746719 | [controller] Waiting on logger 2025-10-27 00:23:04.789519 | [controller] Waiting on logger 2025-10-27 00:23:04.864559 | [controller] Waiting on logger 2025-10-27 00:23:04.907938 | [Zuul] Log Stream did not terminate 2025-10-27 00:23:04.908203 | controller | ok: Runtime: 0:00:02.754238 2025-10-27 00:23:04.915777 | 2025-10-27 00:23:04.915874 | TASK [Update pip] 2025-10-27 00:23:04.999940 | [controller] Waiting on logger 2025-10-27 00:23:05.090289 | [controller] Waiting on logger 2025-10-27 00:23:05.386399 | [controller] Waiting on logger 2025-10-27 00:23:05.389638 | [controller] Waiting on logger 2025-10-27 00:23:06.016374 | [controller] Waiting on logger 2025-10-27 00:23:06.028669 | [controller] Waiting on logger 2025-10-27 00:23:06.072168 | [controller] Waiting on logger 2025-10-27 00:23:06.144810 | [controller] Waiting on logger 2025-10-27 00:23:06.280713 | [controller] Waiting on logger 2025-10-27 00:23:06.373815 | [controller] Waiting on logger 2025-10-27 00:23:06.657813 | [controller] Waiting on logger 2025-10-27 00:23:06.673336 | [controller] Waiting on logger 2025-10-27 00:23:07.295965 | [controller] Waiting on logger 2025-10-27 00:23:07.306882 | [controller] Waiting on logger 2025-10-27 00:23:07.354810 | [controller] Waiting on logger 2025-10-27 00:23:07.427191 | [controller] Waiting on logger 2025-10-27 00:23:07.552807 | [controller] Waiting on logger 2025-10-27 00:23:07.648183 | [controller] Waiting on logger 2025-10-27 00:23:07.931660 | [controller] Waiting on logger 2025-10-27 00:23:07.968079 | [controller] Waiting on logger 2025-10-27 00:23:08.574067 | [controller] Waiting on logger 2025-10-27 00:23:08.591766 | [controller] Waiting on logger 2025-10-27 00:23:08.635563 | [controller] Waiting on logger 2025-10-27 00:23:08.709283 | [controller] Waiting on logger 2025-10-27 00:23:08.834040 | [controller] Waiting on logger 2025-10-27 00:23:08.927533 | [controller] Waiting on logger 2025-10-27 00:23:09.217968 | [controller] Waiting on logger 2025-10-27 00:23:09.251924 | [controller] Waiting on logger 2025-10-27 00:23:09.852200 | [controller] Waiting on logger 2025-10-27 00:23:09.871406 | [controller] Waiting on logger 2025-10-27 00:23:09.912919 | [controller] Waiting on logger 2025-10-27 00:23:09.974469 | [controller] Waiting on logger 2025-10-27 00:23:10.117542 | [controller] Waiting on logger 2025-10-27 00:23:10.211786 | [controller] Waiting on logger 2025-10-27 00:23:10.506895 | [controller] Waiting on logger 2025-10-27 00:23:10.550552 | [controller] Waiting on logger 2025-10-27 00:23:11.130578 | [controller] Waiting on logger 2025-10-27 00:23:11.156517 | [controller] Waiting on logger 2025-10-27 00:23:11.191403 | [controller] Waiting on logger 2025-10-27 00:23:11.256893 | [controller] Waiting on logger 2025-10-27 00:23:11.394663 | [controller] Waiting on logger 2025-10-27 00:23:11.501583 | [controller] Waiting on logger 2025-10-27 00:23:11.798799 | [controller] Waiting on logger 2025-10-27 00:23:11.825333 | [controller] Waiting on logger 2025-10-27 00:23:12.407321 | [controller] Waiting on logger 2025-10-27 00:23:12.439925 | [controller] Waiting on logger 2025-10-27 00:23:12.470858 | [controller] Waiting on logger 2025-10-27 00:23:12.542544 | [controller] Waiting on logger 2025-10-27 00:23:12.669392 | [controller] Waiting on logger 2025-10-27 00:23:12.772692 | [controller] Waiting on logger 2025-10-27 00:23:13.076929 | [controller] Waiting on logger 2025-10-27 00:23:13.099606 | [controller] Waiting on logger 2025-10-27 00:23:13.684940 | [controller] Waiting on logger 2025-10-27 00:23:13.714437 | [controller] Waiting on logger 2025-10-27 00:23:13.763670 | [controller] Waiting on logger 2025-10-27 00:23:13.825715 | [controller] Waiting on logger 2025-10-27 00:23:13.956540 | [controller] Waiting on logger 2025-10-27 00:23:14.044716 | [controller] Waiting on logger 2025-10-27 00:23:14.351182 | [controller] Waiting on logger 2025-10-27 00:23:14.388715 | [controller] Waiting on logger 2025-10-27 00:23:14.970898 | [controller] Waiting on logger 2025-10-27 00:23:14.999431 | [controller] Waiting on logger 2025-10-27 00:23:15.046213 | [controller] Waiting on logger 2025-10-27 00:23:15.103930 | [controller] Waiting on logger 2025-10-27 00:23:15.241067 | [controller] Waiting on logger 2025-10-27 00:23:15.324575 | [controller] Waiting on logger 2025-10-27 00:23:15.636832 | [controller] Waiting on logger 2025-10-27 00:23:15.674622 | [controller] Waiting on logger 2025-10-27 00:23:16.262816 | [controller] Waiting on logger 2025-10-27 00:23:16.280958 | [controller] Waiting on logger 2025-10-27 00:23:16.331797 | [controller] Waiting on logger 2025-10-27 00:23:16.389668 | [controller] Waiting on logger 2025-10-27 00:23:16.531130 | [controller] Waiting on logger 2025-10-27 00:23:16.614473 | [controller] Waiting on logger 2025-10-27 00:23:16.926965 | [controller] Waiting on logger 2025-10-27 00:23:16.958858 | [controller] Waiting on logger 2025-10-27 00:23:17.539829 | [controller] Waiting on logger 2025-10-27 00:23:17.568212 | [controller] Waiting on logger 2025-10-27 00:23:17.615133 | [controller] Waiting on logger 2025-10-27 00:23:17.666508 | [controller] Waiting on logger 2025-10-27 00:23:17.820354 | [controller] Waiting on logger 2025-10-27 00:23:17.884492 | [controller] Waiting on logger 2025-10-27 00:23:18.217428 | [controller] Waiting on logger 2025-10-27 00:23:18.240496 | [controller] Waiting on logger 2025-10-27 00:23:18.821191 | [controller] Waiting on logger 2025-10-27 00:23:18.837703 | [controller] Waiting on logger 2025-10-27 00:23:18.897785 | [controller] Waiting on logger 2025-10-27 00:23:18.956900 | [controller] Waiting on logger 2025-10-27 00:23:19.099154 | [controller] Waiting on logger 2025-10-27 00:23:19.166419 | [controller] Waiting on logger 2025-10-27 00:23:19.499802 | [controller] Waiting on logger 2025-10-27 00:23:19.529992 | [controller] Waiting on logger 2025-10-27 00:23:20.113741 | [controller] Waiting on logger 2025-10-27 00:23:20.120309 | [controller] Waiting on logger 2025-10-27 00:23:20.173080 | [controller] Waiting on logger 2025-10-27 00:23:20.238235 | [controller] Waiting on logger 2025-10-27 00:23:20.384257 | [controller] Waiting on logger 2025-10-27 00:23:20.442716 | [controller] Waiting on logger 2025-10-27 00:23:20.776338 | [controller] Waiting on logger 2025-10-27 00:23:20.811362 | [controller] Waiting on logger 2025-10-27 00:23:21.385197 | [controller] Waiting on logger 2025-10-27 00:23:21.405684 | [controller] Waiting on logger 2025-10-27 00:23:21.449715 | [controller] Waiting on logger 2025-10-27 00:23:21.523022 | [controller] Waiting on logger 2025-10-27 00:23:21.666255 | [controller] Waiting on logger 2025-10-27 00:23:21.713784 | [controller] Waiting on logger 2025-10-27 00:23:22.059022 | [controller] Waiting on logger 2025-10-27 00:23:22.103539 | [controller] Waiting on logger 2025-10-27 00:23:22.670442 | [controller] Waiting on logger 2025-10-27 00:23:22.681620 | [controller] Waiting on logger 2025-10-27 00:23:22.740143 | [controller] Waiting on logger 2025-10-27 00:23:22.800347 | [controller] Waiting on logger 2025-10-27 00:23:22.940750 | [controller] Waiting on logger 2025-10-27 00:23:22.999066 | [controller] Waiting on logger 2025-10-27 00:23:23.336982 | [controller] Waiting on logger 2025-10-27 00:23:23.382319 | [controller] Waiting on logger 2025-10-27 00:23:23.942913 | [controller] Waiting on logger 2025-10-27 00:23:23.963089 | [controller] Waiting on logger 2025-10-27 00:23:24.027313 | [controller] Waiting on logger 2025-10-27 00:23:24.078420 | [controller] Waiting on logger 2025-10-27 00:23:24.228264 | [controller] Waiting on logger 2025-10-27 00:23:24.276128 | [controller] Waiting on logger 2025-10-27 00:23:24.622600 | [controller] Waiting on logger 2025-10-27 00:23:24.661640 | [controller] Waiting on logger 2025-10-27 00:23:25.234539 | [controller] Waiting on logger 2025-10-27 00:23:25.242627 | [controller] Waiting on logger 2025-10-27 00:23:25.315977 | [controller] Waiting on logger 2025-10-27 00:23:25.359450 | [controller] Waiting on logger 2025-10-27 00:23:25.497345 | [controller] Waiting on logger 2025-10-27 00:23:25.551819 | [controller] Waiting on logger 2025-10-27 00:23:25.908382 | [controller] Waiting on logger 2025-10-27 00:23:25.932014 | [controller] Waiting on logger 2025-10-27 00:23:26.508490 | [controller] Waiting on logger 2025-10-27 00:23:26.523666 | [controller] Waiting on logger 2025-10-27 00:23:26.584078 | [controller] Waiting on logger 2025-10-27 00:23:26.643285 | [controller] Waiting on logger 2025-10-27 00:23:26.778200 | [controller] Waiting on logger 2025-10-27 00:23:26.834437 | [controller] Waiting on logger 2025-10-27 00:23:27.196407 | [controller] Waiting on logger 2025-10-27 00:23:27.213265 | [controller] Waiting on logger 2025-10-27 00:23:27.780852 | [controller] Waiting on logger 2025-10-27 00:23:27.803897 | [controller] Waiting on logger 2025-10-27 00:23:27.870834 | [controller] Waiting on logger 2025-10-27 00:23:27.910560 | [controller] Waiting on logger 2025-10-27 00:23:28.058204 | [controller] Waiting on logger 2025-10-27 00:23:28.124487 | [controller] Waiting on logger 2025-10-27 00:23:28.483768 | [controller] Waiting on logger 2025-10-27 00:23:28.494907 | [controller] Waiting on logger 2025-10-27 00:23:29.060646 | [controller] Waiting on logger 2025-10-27 00:23:29.077411 | [controller] Waiting on logger 2025-10-27 00:23:29.164666 | [controller] Waiting on logger 2025-10-27 00:23:29.189983 | [controller] Waiting on logger 2025-10-27 00:23:29.346924 | [controller] Waiting on logger 2025-10-27 00:23:29.413810 | [controller] Waiting on logger 2025-10-27 00:23:29.764398 | [controller] Waiting on logger 2025-10-27 00:23:29.773389 | [controller] Waiting on logger 2025-10-27 00:23:30.338230 | [controller] Waiting on logger 2025-10-27 00:23:30.366053 | [controller] Waiting on logger 2025-10-27 00:23:30.449373 | [controller] Waiting on logger 2025-10-27 00:23:30.457851 | [controller] Waiting on logger 2025-10-27 00:23:30.631050 | [controller] Waiting on logger 2025-10-27 00:23:30.702758 | [controller] Waiting on logger 2025-10-27 00:23:31.048105 | [controller] Waiting on logger 2025-10-27 00:23:31.055665 | [controller] Waiting on logger 2025-10-27 00:23:31.618939 | [controller] Waiting on logger 2025-10-27 00:23:31.733294 | [controller] Waiting on logger 2025-10-27 00:23:31.735290 | [controller] Waiting on logger 2025-10-27 00:23:31.918578 | [controller] Waiting on logger 2025-10-27 00:23:31.985181 | [controller] Waiting on logger 2025-10-27 00:23:32.330036 | [controller] Waiting on logger 2025-10-27 00:23:32.662791 | [controller] Waiting on logger 2025-10-27 00:23:32.743783 | [controller] Waiting on logger 2025-10-27 00:23:32.900142 | [controller] Waiting on logger 2025-10-27 00:23:33.024819 | [controller] Waiting on logger 2025-10-27 00:23:33.203872 | [controller] Waiting on logger 2025-10-27 00:23:33.258891 | [controller] Waiting on logger 2025-10-27 00:23:33.271347 | [controller] Waiting on logger 2025-10-27 00:23:33.618533 | [controller] Waiting on logger 2025-10-27 00:23:33.945495 | [controller] Waiting on logger 2025-10-27 00:23:34.028674 | [controller] Waiting on logger 2025-10-27 00:23:34.182765 | [controller] Waiting on logger 2025-10-27 00:23:34.309909 | [controller] Waiting on logger 2025-10-27 00:23:34.495757 | [controller] Waiting on logger 2025-10-27 00:23:34.541772 | [controller] Waiting on logger 2025-10-27 00:23:34.550401 | [controller] Waiting on logger 2025-10-27 00:23:34.902353 | [controller] Waiting on logger 2025-10-27 00:23:35.221514 | [controller] Waiting on logger 2025-10-27 00:23:35.303123 | [controller] Waiting on logger 2025-10-27 00:23:35.464753 | [controller] Waiting on logger 2025-10-27 00:23:35.590180 | [controller] Waiting on logger 2025-10-27 00:23:35.789097 | [controller] Waiting on logger 2025-10-27 00:23:35.826298 | [controller] Waiting on logger 2025-10-27 00:23:35.830077 | [controller] Waiting on logger 2025-10-27 00:23:36.181044 | [controller] Waiting on logger 2025-10-27 00:23:36.498930 | [controller] Waiting on logger 2025-10-27 00:23:36.582858 | [controller] Waiting on logger 2025-10-27 00:23:36.757584 | [controller] Waiting on logger 2025-10-27 00:23:36.869831 | [controller] Waiting on logger 2025-10-27 00:23:37.074419 | [controller] Waiting on logger 2025-10-27 00:23:37.113933 | [controller] Waiting on logger 2025-10-27 00:23:37.119073 | [controller] Waiting on logger 2025-10-27 00:23:37.467390 | [controller] Waiting on logger 2025-10-27 00:23:37.696505 | [Zuul] Log Stream did not terminate 2025-10-27 00:23:37.696862 | controller | ok: Runtime: 0:00:02.290493 2025-10-27 00:23:37.708930 | 2025-10-27 00:23:37.709150 | TASK [Ensure pip temp directory exists in /var/tmp] 2025-10-27 00:23:37.780468 | [controller] Waiting on logger 2025-10-27 00:23:37.864227 | [controller] Waiting on logger 2025-10-27 00:23:38.037863 | [controller] Waiting on logger 2025-10-27 00:23:38.142672 | controller | changed 2025-10-27 00:23:38.151001 | 2025-10-27 00:23:38.151205 | TASK [Install ansible into virtualenv] 2025-10-27 00:23:38.157856 | [controller] Waiting on logger 2025-10-27 00:23:38.217097 | [controller] Waiting on logger 2025-10-27 00:23:38.358186 | [controller] Waiting on logger 2025-10-27 00:23:38.394393 | [controller] Waiting on logger 2025-10-27 00:23:38.399774 | [controller] Waiting on logger 2025-10-27 00:23:38.748870 | [controller] Waiting on logger 2025-10-27 00:23:39.075016 | [controller] Waiting on logger 2025-10-27 00:23:39.144550 | [controller] Waiting on logger 2025-10-27 00:23:39.324038 | [controller] Waiting on logger 2025-10-27 00:23:39.441125 | [controller] Waiting on logger 2025-10-27 00:23:39.506503 | [controller] Waiting on logger 2025-10-27 00:23:39.647110 | [controller] Waiting on logger 2025-10-27 00:23:39.672872 | [controller] Waiting on logger 2025-10-27 00:23:39.682343 | [controller] Waiting on logger 2025-10-27 00:23:40.032530 | [controller] Waiting on logger 2025-10-27 00:23:40.356215 | [controller] Waiting on logger 2025-10-27 00:23:40.430570 | [controller] Waiting on logger 2025-10-27 00:23:40.609055 | [controller] Waiting on logger 2025-10-27 00:23:40.715873 | [controller] Waiting on logger 2025-10-27 00:23:40.790356 | [controller] Waiting on logger 2025-10-27 00:23:40.930173 | [controller] Waiting on logger 2025-10-27 00:23:40.958785 | [controller] Waiting on logger 2025-10-27 00:23:40.963817 | [controller] Waiting on logger 2025-10-27 00:23:41.308494 | [controller] Waiting on logger 2025-10-27 00:23:41.642598 | [controller] Waiting on logger 2025-10-27 00:23:41.704065 | [controller] Waiting on logger 2025-10-27 00:23:41.893684 | [controller] Waiting on logger 2025-10-27 00:23:41.996036 | [controller] Waiting on logger 2025-10-27 00:23:42.072545 | [controller] Waiting on logger 2025-10-27 00:23:42.211908 | [controller] Waiting on logger 2025-10-27 00:23:42.237139 | [controller] Waiting on logger 2025-10-27 00:23:42.240794 | [controller] Waiting on logger 2025-10-27 00:23:42.587939 | [controller] Waiting on logger 2025-10-27 00:23:42.923525 | [controller] Waiting on logger 2025-10-27 00:23:42.988470 | [controller] Waiting on logger 2025-10-27 00:23:43.176917 | [controller] Waiting on logger 2025-10-27 00:23:43.285369 | [controller] Waiting on logger 2025-10-27 00:23:43.351888 | [controller] Waiting on logger 2025-10-27 00:23:43.479538 | [controller] Waiting on logger 2025-10-27 00:23:43.524720 | [controller] Waiting on logger 2025-10-27 00:23:43.527812 | [controller] Waiting on logger 2025-10-27 00:23:43.865877 | [controller] Waiting on logger 2025-10-27 00:23:44.209919 | [controller] Waiting on logger 2025-10-27 00:23:44.273019 | [controller] Waiting on logger 2025-10-27 00:23:44.463415 | [controller] Waiting on logger 2025-10-27 00:23:44.568216 | [controller] Waiting on logger 2025-10-27 00:23:44.762050 | [controller] Waiting on logger 2025-10-27 00:23:44.800167 | [controller] Waiting on logger 2025-10-27 00:23:44.808552 | [controller] Waiting on logger 2025-10-27 00:23:45.153136 | [controller] Waiting on logger 2025-10-27 00:23:45.506933 | [controller] Waiting on logger 2025-10-27 00:23:45.545682 | [controller] Waiting on logger 2025-10-27 00:23:45.661096 | [controller] Waiting on logger 2025-10-27 00:23:45.751250 | [controller] Waiting on logger 2025-10-27 00:23:45.847169 | [controller] Waiting on logger 2025-10-27 00:23:46.049898 | [controller] Waiting on logger 2025-10-27 00:23:46.079165 | [controller] Waiting on logger 2025-10-27 00:23:46.093818 | [controller] Waiting on logger 2025-10-27 00:23:46.447321 | [controller] Waiting on logger 2025-10-27 00:23:46.790118 | [controller] Waiting on logger 2025-10-27 00:23:46.829949 | [controller] Waiting on logger 2025-10-27 00:23:46.946304 | [controller] Waiting on logger 2025-10-27 00:23:47.032588 | [controller] Waiting on logger 2025-10-27 00:23:47.134421 | [controller] Waiting on logger 2025-10-27 00:23:47.328027 | [controller] Waiting on logger 2025-10-27 00:23:47.356007 | [controller] Waiting on logger 2025-10-27 00:23:47.375750 | [controller] Waiting on logger 2025-10-27 00:23:47.734477 | [controller] Waiting on logger 2025-10-27 00:23:48.072960 | [controller] Waiting on logger 2025-10-27 00:23:48.118460 | [controller] Waiting on logger 2025-10-27 00:23:48.220315 | [controller] Waiting on logger 2025-10-27 00:23:48.302963 | [controller] Waiting on logger 2025-10-27 00:23:48.427526 | [controller] Waiting on logger 2025-10-27 00:23:48.605597 | [controller] Waiting on logger 2025-10-27 00:23:48.627636 | [controller] Waiting on logger 2025-10-27 00:23:48.654921 | [controller] Waiting on logger 2025-10-27 00:23:49.018046 | [controller] Waiting on logger 2025-10-27 00:23:49.365873 | [controller] Waiting on logger 2025-10-27 00:23:49.407413 | [controller] Waiting on logger 2025-10-27 00:23:49.514974 | [controller] Waiting on logger 2025-10-27 00:23:49.584314 | [controller] Waiting on logger 2025-10-27 00:23:49.701871 | [controller] Waiting on logger 2025-10-27 00:23:49.884870 | [controller] Waiting on logger 2025-10-27 00:23:49.904216 | [controller] Waiting on logger 2025-10-27 00:23:49.930948 | [controller] Waiting on logger 2025-10-27 00:23:50.297230 | [controller] Waiting on logger 2025-10-27 00:23:50.651005 | [controller] Waiting on logger 2025-10-27 00:23:50.684270 | [controller] Waiting on logger 2025-10-27 00:23:50.799114 | [controller] Waiting on logger 2025-10-27 00:23:50.871958 | [controller] Waiting on logger 2025-10-27 00:23:50.983711 | [controller] Waiting on logger 2025-10-27 00:23:51.172529 | [controller] Waiting on logger 2025-10-27 00:23:51.174602 | [controller] Waiting on logger 2025-10-27 00:23:51.206449 | [controller] Waiting on logger 2025-10-27 00:23:51.573487 | [controller] Waiting on logger 2025-10-27 00:23:51.938414 | [controller] Waiting on logger 2025-10-27 00:23:51.973137 | [controller] Waiting on logger 2025-10-27 00:23:52.078311 | [controller] Waiting on logger 2025-10-27 00:23:52.155649 | [controller] Waiting on logger 2025-10-27 00:23:52.259890 | [controller] Waiting on logger 2025-10-27 00:23:52.456684 | [controller] Waiting on logger 2025-10-27 00:23:52.456967 | [controller] Waiting on logger 2025-10-27 00:23:52.480628 | [controller] Waiting on logger 2025-10-27 00:23:52.854466 | [controller] Waiting on logger 2025-10-27 00:23:53.219090 | [controller] Waiting on logger 2025-10-27 00:23:53.253569 | [controller] Waiting on logger 2025-10-27 00:23:53.366917 | [controller] Waiting on logger 2025-10-27 00:23:53.447607 | [controller] Waiting on logger 2025-10-27 00:23:53.533639 | [controller] Waiting on logger 2025-10-27 00:23:53.729502 | [controller] Waiting on logger 2025-10-27 00:23:53.745798 | [controller] Waiting on logger 2025-10-27 00:23:53.766465 | [controller] Waiting on logger 2025-10-27 00:23:54.129659 | [controller] Waiting on logger 2025-10-27 00:23:54.503163 | [controller] Waiting on logger 2025-10-27 00:23:54.531468 | [controller] Waiting on logger 2025-10-27 00:23:54.646853 | [controller] Waiting on logger 2025-10-27 00:23:54.727303 | [controller] Waiting on logger 2025-10-27 00:23:54.809363 | [controller] Waiting on logger 2025-10-27 00:23:55.005114 | [controller] Waiting on logger 2025-10-27 00:23:55.030379 | [controller] Waiting on logger 2025-10-27 00:23:55.050131 | [controller] Waiting on logger 2025-10-27 00:23:55.414044 | [controller] Waiting on logger 2025-10-27 00:23:55.781640 | [controller] Waiting on logger 2025-10-27 00:23:55.810408 | [controller] Waiting on logger 2025-10-27 00:23:55.927008 | [controller] Waiting on logger 2025-10-27 00:23:55.997675 | [controller] Waiting on logger 2025-10-27 00:23:56.084810 | [controller] Waiting on logger 2025-10-27 00:23:56.294919 | [controller] Waiting on logger 2025-10-27 00:23:56.312032 | [controller] Waiting on logger 2025-10-27 00:23:56.326883 | [controller] Waiting on logger 2025-10-27 00:23:56.679422 | [controller] Waiting on logger 2025-10-27 00:23:57.063213 | [controller] Waiting on logger 2025-10-27 00:23:57.101385 | [controller] Waiting on logger 2025-10-27 00:23:57.198036 | [controller] Waiting on logger 2025-10-27 00:23:57.284251 | [controller] Waiting on logger 2025-10-27 00:23:57.365567 | [controller] Waiting on logger 2025-10-27 00:23:57.572784 | [controller] Waiting on logger 2025-10-27 00:23:57.596961 | [controller] Waiting on logger 2025-10-27 00:23:57.600150 | [controller] Waiting on logger 2025-10-27 00:23:57.958710 | [controller] Waiting on logger 2025-10-27 00:23:58.338272 | [controller] Waiting on logger 2025-10-27 00:23:58.389139 | [controller] Waiting on logger 2025-10-27 00:23:58.480208 | [controller] Waiting on logger 2025-10-27 00:23:58.567964 | [controller] Waiting on logger 2025-10-27 00:23:58.641639 | [controller] Waiting on logger 2025-10-27 00:23:58.861340 | [controller] Waiting on logger 2025-10-27 00:23:58.873335 | [controller] Waiting on logger 2025-10-27 00:23:58.887309 | [controller] Waiting on logger 2025-10-27 00:23:59.239679 | [controller] Waiting on logger 2025-10-27 00:23:59.623975 | [controller] Waiting on logger 2025-10-27 00:23:59.663705 | [controller] Waiting on logger 2025-10-27 00:23:59.764075 | [controller] Waiting on logger 2025-10-27 00:23:59.857849 | [controller] Waiting on logger 2025-10-27 00:23:59.928354 | [controller] Waiting on logger 2025-10-27 00:24:00.143012 | [controller] Waiting on logger 2025-10-27 00:24:00.145872 | [controller] Waiting on logger 2025-10-27 00:24:00.181362 | [controller] Waiting on logger 2025-10-27 00:24:00.520571 | [controller] Waiting on logger 2025-10-27 00:24:00.904888 | [controller] Waiting on logger 2025-10-27 00:24:00.954325 | [controller] Waiting on logger 2025-10-27 00:24:01.047974 | [controller] Waiting on logger 2025-10-27 00:24:01.141804 | [controller] Waiting on logger 2025-10-27 00:24:01.217410 | [controller] Waiting on logger 2025-10-27 00:24:01.409691 | [controller] Waiting on logger 2025-10-27 00:24:01.424840 | [controller] Waiting on logger 2025-10-27 00:24:01.457380 | [controller] Waiting on logger 2025-10-27 00:24:01.796302 | [controller] Waiting on logger 2025-10-27 00:24:02.191926 | [controller] Waiting on logger 2025-10-27 00:24:02.225197 | [controller] Waiting on logger 2025-10-27 00:24:02.324423 | [controller] Waiting on logger 2025-10-27 00:24:02.423309 | [controller] Waiting on logger 2025-10-27 00:24:02.498878 | [controller] Waiting on logger 2025-10-27 00:24:02.692394 | [controller] Waiting on logger 2025-10-27 00:24:02.704376 | [controller] Waiting on logger 2025-10-27 00:24:02.741578 | [controller] Waiting on logger 2025-10-27 00:24:03.074247 | [controller] Waiting on logger 2025-10-27 00:24:03.610319 | [controller] Waiting on logger 2025-10-27 00:24:03.770195 | [controller] Waiting on logger 2025-10-27 00:24:03.965837 | [controller] Waiting on logger 2025-10-27 00:24:03.995448 | [controller] Waiting on logger 2025-10-27 00:24:03.998925 | [controller] Waiting on logger 2025-10-27 00:24:04.245980 | [controller] Waiting on logger 2025-10-27 00:24:04.248597 | [controller] Waiting on logger 2025-10-27 00:24:04.250168 | [controller] Waiting on logger 2025-10-27 00:24:04.355271 | [controller] Waiting on logger 2025-10-27 00:24:04.883300 | [controller] Waiting on logger 2025-10-27 00:24:05.054066 | [controller] Waiting on logger 2025-10-27 00:24:05.255586 | [controller] Waiting on logger 2025-10-27 00:24:05.271873 | [controller] Waiting on logger 2025-10-27 00:24:05.276488 | [controller] Waiting on logger 2025-10-27 00:24:05.527877 | [controller] Waiting on logger 2025-10-27 00:24:05.530114 | [controller] Waiting on logger 2025-10-27 00:24:05.539620 | [controller] Waiting on logger 2025-10-27 00:24:05.652938 | [controller] Waiting on logger 2025-10-27 00:24:06.161004 | [controller] Waiting on logger 2025-10-27 00:24:06.338844 | [controller] Waiting on logger 2025-10-27 00:24:06.532034 | [controller] Waiting on logger 2025-10-27 00:24:06.546875 | [controller] Waiting on logger 2025-10-27 00:24:06.554862 | [controller] Waiting on logger 2025-10-27 00:24:06.807615 | [controller] Waiting on logger 2025-10-27 00:24:06.811873 | [controller] Waiting on logger 2025-10-27 00:24:06.818585 | [controller] Waiting on logger 2025-10-27 00:24:06.927669 | [controller] Waiting on logger 2025-10-27 00:24:07.441006 | [controller] Waiting on logger 2025-10-27 00:24:07.615505 | [controller] Waiting on logger 2025-10-27 00:24:07.816408 | [controller] Waiting on logger 2025-10-27 00:24:07.824186 | [controller] Waiting on logger 2025-10-27 00:24:07.839405 | [controller] Waiting on logger 2025-10-27 00:24:08.089668 | [controller] Waiting on logger 2025-10-27 00:24:08.096417 | [controller] Waiting on logger 2025-10-27 00:24:08.103897 | [controller] Waiting on logger 2025-10-27 00:24:08.204278 | [controller] Waiting on logger 2025-10-27 00:24:08.725212 | [controller] Waiting on logger 2025-10-27 00:24:08.889806 | [controller] Waiting on logger 2025-10-27 00:24:09.096664 | [controller] Waiting on logger 2025-10-27 00:24:09.101976 | [controller] Waiting on logger 2025-10-27 00:24:09.114542 | [controller] Waiting on logger 2025-10-27 00:24:09.374591 | [controller] Waiting on logger 2025-10-27 00:24:09.377166 | [controller] Waiting on logger 2025-10-27 00:24:09.386393 | [controller] Waiting on logger 2025-10-27 00:24:09.486479 | [controller] Waiting on logger 2025-10-27 00:24:10.001197 | [controller] Waiting on logger 2025-10-27 00:24:10.172519 | [controller] Waiting on logger 2025-10-27 00:24:10.381227 | [controller] Waiting on logger 2025-10-27 00:24:10.400787 | [controller] Waiting on logger 2025-10-27 00:24:10.651935 | [controller] Waiting on logger 2025-10-27 00:24:10.652167 | [controller] Waiting on logger 2025-10-27 00:24:10.672250 | [controller] Waiting on logger 2025-10-27 00:24:11.263844 | [controller] Waiting on logger 2025-10-27 00:24:11.383280 | [controller] Waiting on logger 2025-10-27 00:24:11.451692 | [controller] Waiting on logger 2025-10-27 00:24:11.654098 | [controller] Waiting on logger 2025-10-27 00:24:11.677787 | [controller] Waiting on logger 2025-10-27 00:24:11.770474 | [Zuul] Log Stream did not terminate 2025-10-27 00:24:11.770842 | controller | ERROR 2025-10-27 00:24:11.771033 | controller | { 2025-10-27 00:24:11.771089 | controller | "delta": "0:00:03.193501", 2025-10-27 00:24:11.771129 | controller | "end": "2025-10-27 00:23:41.720858", 2025-10-27 00:24:11.771178 | controller | "msg": "non-zero return code", 2025-10-27 00:24:11.771216 | controller | "rc": 1, 2025-10-27 00:24:11.771253 | controller | "start": "2025-10-27 00:23:38.527357" 2025-10-27 00:24:11.771287 | controller | } failure 2025-10-27 00:24:11.775130 | 2025-10-27 00:24:11.775267 | PLAY RECAP 2025-10-27 00:24:11.775376 | controller | ok: 18 changed: 10 unreachable: 0 failed: 1 skipped: 4 rescued: 0 ignored: 0 2025-10-27 00:24:11.775431 | 2025-10-27 00:24:11.775665 | [controller] Waiting on logger 2025-10-27 00:24:11.923514 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master] 2025-10-27 00:24:11.925770 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-27 00:24:12.524511 | 2025-10-27 00:24:12.524685 | PLAY [all] 2025-10-27 00:24:12.546175 | 2025-10-27 00:24:12.546280 | TASK [fetch-subunit-output : Find stestr or testr executable] 2025-10-27 00:24:13.940225 | controller | changed: non-zero return code 2025-10-27 00:24:13.948621 | 2025-10-27 00:24:13.948781 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2025-10-27 00:24:13.975193 | controller | skipping: Conditional result was False 2025-10-27 00:24:13.985019 | 2025-10-27 00:24:13.985155 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2025-10-27 00:24:14.032042 | 2025-10-27 00:24:14.032294 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2025-10-27 00:24:14.064207 | 2025-10-27 00:24:14.064377 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2025-10-27 00:24:14.087821 | controller | skipping: Conditional result was False 2025-10-27 00:24:14.097142 | 2025-10-27 00:24:14.097272 | LOOP [fetch-subunit-output : Generate subunit file] 2025-10-27 00:24:14.127157 | 2025-10-27 00:24:14.127314 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2025-10-27 00:24:14.150435 | controller | skipping: Conditional result was False 2025-10-27 00:24:14.158340 | 2025-10-27 00:24:14.158436 | TASK [fetch-subunit-output : Remove the temporary file] 2025-10-27 00:24:14.172207 | controller | skipping: Conditional result was False 2025-10-27 00:24:14.179167 | 2025-10-27 00:24:14.179262 | TASK [fetch-subunit-output : Process and fetch subunit results] 2025-10-27 00:24:14.209323 | controller | skipping: Conditional result was False 2025-10-27 00:24:14.236387 | 2025-10-27 00:24:14.236487 | PLAY RECAP 2025-10-27 00:24:14.236533 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0 2025-10-27 00:24:14.236557 | 2025-10-27 00:24:14.340625 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master] 2025-10-27 00:24:14.342302 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master] 2025-10-27 00:24:15.019011 | 2025-10-27 00:24:15.019196 | PLAY [all:!appliance*] 2025-10-27 00:24:15.040387 | 2025-10-27 00:24:15.040524 | TASK [unregister the node] 2025-10-27 00:24:15.094261 | [controller] Waiting on logger 2025-10-27 00:24:16.388161 | [controller] Waiting on logger 2025-10-27 00:24:17.666321 | [controller] Waiting on logger 2025-10-27 00:24:18.952793 | [controller] Waiting on logger 2025-10-27 00:24:20.242005 | [controller] Waiting on logger 2025-10-27 00:24:21.526414 | [controller] Waiting on logger 2025-10-27 00:24:22.810112 | [controller] Waiting on logger 2025-10-27 00:24:24.098005 | [controller] Waiting on logger 2025-10-27 00:24:25.377627 | [controller] Waiting on logger 2025-10-27 00:24:26.657154 | [controller] Waiting on logger 2025-10-27 00:24:27.942654 | [controller] Waiting on logger 2025-10-27 00:24:29.229829 | [controller] Waiting on logger 2025-10-27 00:24:30.506169 | [controller] Waiting on logger 2025-10-27 00:24:31.791797 | [controller] Waiting on logger 2025-10-27 00:24:33.073918 | [controller] Waiting on logger 2025-10-27 00:24:35.393656 | [controller] Waiting on logger 2025-10-27 00:24:36.676147 | [controller] Waiting on logger 2025-10-27 00:24:37.963149 | [controller] Waiting on logger 2025-10-27 00:24:39.245428 | [controller] Waiting on logger 2025-10-27 00:24:40.529162 | [controller] Waiting on logger 2025-10-27 00:24:41.802372 | [controller] Waiting on logger 2025-10-27 00:24:43.083428 | [controller] Waiting on logger 2025-10-27 00:24:44.364082 | [controller] Waiting on logger 2025-10-27 00:24:45.086488 | [Zuul] Log Stream did not terminate 2025-10-27 00:24:45.086903 | controller | skipping: Conditional result was False 2025-10-27 00:24:45.100126 | 2025-10-27 00:24:45.100338 | TASK [include_role : fetch-output] 2025-10-27 00:24:45.159656 | controller | ok 2025-10-27 00:24:45.200774 | 2025-10-27 00:24:45.200948 | TASK [fetch-output : Set log path for multiple nodes] 2025-10-27 00:24:45.267672 | controller | skipping: Conditional result was False 2025-10-27 00:24:45.276208 | 2025-10-27 00:24:45.276396 | TASK [fetch-output : Set log path for single node] 2025-10-27 00:24:45.320235 | controller | ok 2025-10-27 00:24:45.328348 | 2025-10-27 00:24:45.328487 | LOOP [fetch-output : Ensure local output dirs] 2025-10-27 00:24:45.664086 | [controller] Waiting on logger 2025-10-27 00:24:45.771617 | controller -> localhost | ok: "/var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/logs" 2025-10-27 00:24:45.997634 | controller -> localhost | changed: "/var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/artifacts" 2025-10-27 00:24:46.231714 | controller -> localhost | changed: "/var/lib/zuul/builds/93b05d5669dd46e6b4ddd88d070a00d8/work/docs" 2025-10-27 00:24:46.248586 | 2025-10-27 00:24:46.248773 | LOOP [fetch-output : Collect logs, artifacts and docs] 2025-10-27 00:24:46.941316 | [controller] Waiting on logger 2025-10-27 00:24:47.692670 | controller | changed: 2025-10-27 00:24:47.692900 | controller | .d..t...... ./ 2025-10-27 00:24:47.692970 | controller | cd+++++++++ controller/ 2025-10-27 00:24:47.693075 | controller | changed: All items complete 2025-10-27 00:24:47.693131 | 2025-10-27 00:24:48.228024 | [controller] Waiting on logger 2025-10-27 00:24:48.770530 | controller | changed: .d..t...... ./ 2025-10-27 00:24:49.515473 | [controller] Waiting on logger 2025-10-27 00:24:49.895765 | controller | changed: .d..t...... ./ 2025-10-27 00:24:49.929858 | 2025-10-27 00:24:49.930095 | TASK [include_role : fetch-output-openshift] 2025-10-27 00:24:49.956457 | controller | skipping: Conditional result was False 2025-10-27 00:24:49.966276 | 2025-10-27 00:24:49.966408 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2025-10-27 00:24:50.015785 | controller | skipping: Conditional result was False 2025-10-27 00:24:50.029385 | controller | skipping: Conditional result was False 2025-10-27 00:24:50.072165 | 2025-10-27 00:24:50.072285 | PLAY [localhost] 2025-10-27 00:24:50.091063 | 2025-10-27 00:24:50.091229 | TASK [Run Zuul manifest role] 2025-10-27 00:24:50.111471 | localhost | ok 2025-10-27 00:24:50.126347 | 2025-10-27 00:24:50.126444 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2025-10-27 00:24:50.562321 | localhost | changed 2025-10-27 00:24:50.567174 | 2025-10-27 00:24:50.567262 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2025-10-27 00:24:50.601514 | localhost | ok 2025-10-27 00:24:50.617832 | 2025-10-27 00:24:50.617983 | TASK [Set zuul-log-path fact] 2025-10-27 00:24:50.641199 | localhost | ok 2025-10-27 00:24:50.664609 | 2025-10-27 00:24:50.664806 | TASK [set-zuul-log-path-fact : Set log path for a build] 2025-10-27 00:24:50.697606 | localhost | ok 2025-10-27 00:24:50.712255 | 2025-10-27 00:24:50.712375 | LOOP [Run upload-logs-swift role] 2025-10-27 00:24:50.738271 | localhost | Output suppressed because no_log was given 2025-10-27 00:24:50.781096 | 2025-10-27 00:24:50.781222 | TASK [Set zuul-log-path fact] 2025-10-27 00:24:50.802776 | [controller] Waiting on logger 2025-10-27 00:24:50.815849 | localhost | skipping: Conditional result was False 2025-10-27 00:24:50.821547 | 2025-10-27 00:24:50.821624 | TASK [upload-logs-swift : Ensure logs are readable before uploading] 2025-10-27 00:24:51.284028 | localhost -> localhost | ok: Runtime: 0:00:00.005876 2025-10-27 00:24:51.291026 | 2025-10-27 00:24:51.291119 | TASK [upload-logs-swift : Upload logs to swift] 2025-10-27 00:24:52.101255 | [controller] Waiting on logger