2026-02-27 00:23:40.485913 | Job console starting...
2026-02-27 00:23:40.498267 | Updating repositories
2026-02-27 00:23:40.620883 | Preparing job workspace
2026-02-27 00:23:44.331372 | Running Ansible setup...
2026-02-27 00:23:50.558045 | PRE-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-27 00:23:51.240157 | 
2026-02-27 00:23:51.240359 | PLAY [localhost]
2026-02-27 00:23:51.250210 | 
2026-02-27 00:23:51.250296 | TASK [Gathering Facts]
2026-02-27 00:23:52.460362 | localhost | ok
2026-02-27 00:23:52.475763 | 
2026-02-27 00:23:52.475908 | TASK [Setup log path fact]
2026-02-27 00:23:52.496792 | localhost | ok
2026-02-27 00:23:52.509900 | 
2026-02-27 00:23:52.509983 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 00:23:52.538074 | localhost | ok
2026-02-27 00:23:52.546271 | 
2026-02-27 00:23:52.546337 | TASK [emit-job-header : Print job information]
2026-02-27 00:23:52.596079 | # Job Information
2026-02-27 00:23:52.596238 | Ansible Version: 2.15.12
2026-02-27 00:23:52.596270 | Job: ansible-test-sanity-docker-stable-2.11
2026-02-27 00:23:52.596290 | Pipeline: periodic
2026-02-27 00:23:52.596309 | Executor: ze01.softwarefactory-project.io
2026-02-27 00:23:52.596327 | Triggered by: https://github.com/ansible-collections/ansible.network
2026-02-27 00:23:52.596347 | Log URL (when completed): https://ansible.softwarefactory-project.io/logs/de1/ansible/de108514d2a64444987e2e859f82c351/
2026-02-27 00:23:52.596366 | Event ID: c66414a510e648d9aec75995eff050a1
2026-02-27 00:23:52.600678 | 
2026-02-27 00:23:52.600759 | LOOP [emit-job-header : Print node information]
2026-02-27 00:23:52.717196 | localhost | ok:
2026-02-27 00:23:52.717610 | localhost | # Node Information
2026-02-27 00:23:52.717677 | localhost | Inventory Hostname: controller
2026-02-27 00:23:52.717753 | localhost | Hostname: np0005632252
2026-02-27 00:23:52.717797 | localhost | Username: zuul
2026-02-27 00:23:52.717845 | localhost | Distro: Fedora 37
2026-02-27 00:23:52.717884 | localhost | Provider: ansible-vexxhost-ca-ymq-1
2026-02-27 00:23:52.717922 | localhost | Region: ca-ymq-1
2026-02-27 00:23:52.717958 | localhost | Label: ansible-fedora-37-1vcpu
2026-02-27 00:23:52.717994 | localhost | Product Name: OpenStack Nova
2026-02-27 00:23:52.718071 | localhost | Interface IP: 199.204.45.233
2026-02-27 00:23:52.743158 | 
2026-02-27 00:23:52.743349 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-27 00:23:53.228844 | localhost -> localhost | changed
2026-02-27 00:23:53.234584 | 
2026-02-27 00:23:53.234671 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-27 00:23:54.131172 | localhost -> localhost | changed
2026-02-27 00:23:54.158383 | 
2026-02-27 00:23:54.158465 | PLAY [all:!appliance*]
2026-02-27 00:23:54.175549 | 
2026-02-27 00:23:54.175624 | TASK [include_role : start-zuul-console]
2026-02-27 00:23:54.195857 | controller | ok
2026-02-27 00:23:54.208665 | 
2026-02-27 00:23:54.208734 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-02-27 00:23:54.591904 | controller | ok
2026-02-27 00:23:54.616417 | 
2026-02-27 00:23:54.616557 | TASK [use-our-mirror : Retrieve the IP address]
2026-02-27 00:23:55.722968 | controller | ok: Status code was 404 and not [200]: HTTP Error 404: Not Found
2026-02-27 00:23:55.733150 | 
2026-02-27 00:23:55.733252 | TASK [use-our-mirror : Ensure the registry service is running on the mirror]
2026-02-27 00:23:56.281539 | controller | skipping: Conditional result was False
2026-02-27 00:23:56.292118 | 
2026-02-27 00:23:56.292280 | TASK [use-our-mirror : Write the IP in /etc/hosts]
2026-02-27 00:23:56.329778 | controller | skipping: Conditional result was False
2026-02-27 00:23:56.338642 | 
2026-02-27 00:23:56.338802 | TASK [use-our-mirror : Create the podman configuration directory]
2026-02-27 00:23:56.364550 | controller | skipping: Conditional result was False
2026-02-27 00:23:56.375767 | 
2026-02-27 00:23:56.375903 | TASK [use-our-mirror : Copy the podman configuration]
2026-02-27 00:23:56.404362 | controller | skipping: Conditional result was False
2026-02-27 00:23:56.423158 | 
2026-02-27 00:23:56.423986 | TASK [use-our-mirror : Copy the Yum configuration (main)]
2026-02-27 00:23:56.455056 | controller | skipping: Conditional result was False
2026-02-27 00:23:56.464172 | 
2026-02-27 00:23:56.464262 | TASK [use-our-mirror : Copy the Yum configuration (update)]
2026-02-27 00:23:56.500673 | controller | skipping: Conditional result was False
2026-02-27 00:23:56.512235 | 
2026-02-27 00:23:56.512355 | TASK [Disable Fedora Modular]
2026-02-27 00:23:56.786796 | controller | changed
2026-02-27 00:23:56.800511 | 
2026-02-27 00:23:56.800685 | TASK [Enable EPEL]
2026-02-27 00:23:56.840490 | controller | skipping: Conditional result was False
2026-02-27 00:23:56.854927 | 
2026-02-27 00:23:56.855107 | TASK [Register the RHEL node]
2026-02-27 00:23:57.424234 | 
2026-02-27 00:23:57.424665 | TASK [Show the subscription-manager status]
2026-02-27 00:23:58.018535 | controller | skipping: Conditional result was False
2026-02-27 00:23:58.033924 | 
2026-02-27 00:23:58.034136 | TASK [Enable EPEL on RHEL]
2026-02-27 00:23:58.596978 | controller | skipping: Conditional result was False
2026-02-27 00:23:58.611337 | 
2026-02-27 00:23:58.611508 | TASK [Install git and tox]
2026-02-27 00:25:15.287473 | controller | changed
2026-02-27 00:25:15.301466 | 
2026-02-27 00:25:15.301723 | TASK [include_role : prepare-workspace]
2026-02-27 00:25:15.340405 | controller | ok
2026-02-27 00:25:15.379976 | 
2026-02-27 00:25:15.380127 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-27 00:25:15.624082 | controller | ok
2026-02-27 00:25:15.640412 | 
2026-02-27 00:25:15.640557 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-27 00:25:27.741851 | controller | Output suppressed because no_log was given
2026-02-27 00:25:27.803903 | 
2026-02-27 00:25:27.804071 | TASK [include_role : prepare-workspace-openshift]
2026-02-27 00:25:27.833855 | controller | skipping: Conditional result was False
2026-02-27 00:25:27.869264 | 
2026-02-27 00:25:27.869400 | PLAY [all:!appliance]
2026-02-27 00:25:27.891980 | 
2026-02-27 00:25:27.892166 | TASK [Run add-build-sshkey role (RSA)]
2026-02-27 00:25:27.923670 | controller | ok
2026-02-27 00:25:27.940483 | 
2026-02-27 00:25:27.940615 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-27 00:25:28.219995 | controller -> localhost | ok
2026-02-27 00:25:28.230001 | 
2026-02-27 00:25:28.230144 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-27 00:25:28.270272 | controller | ok
2026-02-27 00:25:28.291254 | controller | included: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-27 00:25:28.300903 | 
2026-02-27 00:25:28.301091 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-27 00:25:28.766222 | controller -> localhost | Generating public/private rsa key pair.
2026-02-27 00:25:28.766592 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/de108514d2a64444987e2e859f82c351_id_rsa.
2026-02-27 00:25:28.766654 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/de108514d2a64444987e2e859f82c351_id_rsa.pub.
2026-02-27 00:25:28.766698 | controller -> localhost | The key fingerprint is:
2026-02-27 00:25:28.766740 | controller -> localhost | SHA256:pzCjRyaHExbFEyyHGHs9q/5eE2leATd/Rbg21mO6Isg zuul-build-sshkey
2026-02-27 00:25:28.766780 | controller -> localhost | The key's randomart image is:
2026-02-27 00:25:28.766820 | controller -> localhost | +---[RSA 2048]----+
2026-02-27 00:25:28.766860 | controller -> localhost | |  .o.=o.. o    oo|
2026-02-27 00:25:28.766900 | controller -> localhost | |  ..oo=  o o  .. |
2026-02-27 00:25:28.766940 | controller -> localhost | |  . +oo.  . . .o |
2026-02-27 00:25:28.766979 | controller -> localhost | |   o o o . . .=o.|
2026-02-27 00:25:28.767045 | controller -> localhost | |    + O S o  oo..|
2026-02-27 00:25:28.767109 | controller -> localhost | |     O * =   .   |
2026-02-27 00:25:28.767158 | controller -> localhost | |    o o *     .  |
2026-02-27 00:25:28.767201 | controller -> localhost | |   . . E o . .   |
2026-02-27 00:25:28.767241 | controller -> localhost | |    .oo   . .    |
2026-02-27 00:25:28.767286 | controller -> localhost | +----[SHA256]-----+
2026-02-27 00:25:28.767384 | controller -> localhost | ok: Runtime: 0:00:00.080124
2026-02-27 00:25:28.783804 | 
2026-02-27 00:25:28.783969 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-27 00:25:28.821931 | controller | ok
2026-02-27 00:25:28.834698 | controller | included: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-27 00:25:28.843929 | 
2026-02-27 00:25:28.844100 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-27 00:25:28.868945 | controller | skipping: Conditional result was False
2026-02-27 00:25:28.875797 | 
2026-02-27 00:25:28.875883 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-27 00:25:29.315878 | controller | changed
2026-02-27 00:25:29.324326 | 
2026-02-27 00:25:29.324415 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-27 00:25:29.552733 | controller | ok
2026-02-27 00:25:29.561508 | 
2026-02-27 00:25:29.561594 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-27 00:25:30.163142 | controller | changed
2026-02-27 00:25:30.178213 | 
2026-02-27 00:25:30.178391 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-27 00:25:30.798118 | controller | changed
2026-02-27 00:25:30.806343 | 
2026-02-27 00:25:30.806428 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-27 00:25:30.831953 | controller | skipping: Conditional result was False
2026-02-27 00:25:30.841306 | 
2026-02-27 00:25:30.841410 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-27 00:25:31.275184 | controller -> localhost | changed
2026-02-27 00:25:31.300861 | 
2026-02-27 00:25:31.301059 | TASK [add-build-sshkey : Add back temp key]
2026-02-27 00:25:31.644805 | controller -> localhost | Identity added: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/de108514d2a64444987e2e859f82c351_id_rsa (zuul-build-sshkey)
2026-02-27 00:25:31.645069 | controller -> localhost | ok: Runtime: 0:00:00.016455
2026-02-27 00:25:31.652703 | 
2026-02-27 00:25:31.652769 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-27 00:25:32.011688 | controller | ok
2026-02-27 00:25:32.019421 | 
2026-02-27 00:25:32.019525 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-27 00:25:32.055305 | controller | skipping: Conditional result was False
2026-02-27 00:25:32.080856 | 
2026-02-27 00:25:32.081002 | TASK [Run add-build-sshkey role (ECDSA)]
2026-02-27 00:25:32.114790 | controller | ok
2026-02-27 00:25:32.139421 | 
2026-02-27 00:25:32.139514 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-27 00:25:32.461563 | controller -> localhost | ok
2026-02-27 00:25:32.468142 | 
2026-02-27 00:25:32.468212 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-27 00:25:32.495700 | controller | ok
2026-02-27 00:25:32.514814 | controller | included: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-27 00:25:32.524967 | 
2026-02-27 00:25:32.525101 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-27 00:25:32.848800 | controller -> localhost | Generating public/private ecdsa key pair.
2026-02-27 00:25:32.849046 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/de108514d2a64444987e2e859f82c351_id_ecdsa.
2026-02-27 00:25:32.849091 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/de108514d2a64444987e2e859f82c351_id_ecdsa.pub.
2026-02-27 00:25:32.849133 | controller -> localhost | The key fingerprint is:
2026-02-27 00:25:32.849163 | controller -> localhost | SHA256:Gmt/LL8YluPir233yyk9ckjeiW1ELTcLg48Xb82YHEg zuul-build-sshkey
2026-02-27 00:25:32.849192 | controller -> localhost | The key's randomart image is:
2026-02-27 00:25:32.849219 | controller -> localhost | +---[ECDSA 521]---+
2026-02-27 00:25:32.849244 | controller -> localhost | |                 |
2026-02-27 00:25:32.849270 | controller -> localhost | |            E    |
2026-02-27 00:25:32.849295 | controller -> localhost | |           o o   |
2026-02-27 00:25:32.849320 | controller -> localhost | |          . B =  |
2026-02-27 00:25:32.849346 | controller -> localhost | |      . S  + O O |
2026-02-27 00:25:32.849371 | controller -> localhost | |       + .o + B o|
2026-02-27 00:25:32.849396 | controller -> localhost | |      + =+ O o   |
2026-02-27 00:25:32.849422 | controller -> localhost | |     ..=o+X.O.   |
2026-02-27 00:25:32.849446 | controller -> localhost | |     .o=*=oB=o   |
2026-02-27 00:25:32.849471 | controller -> localhost | +----[SHA256]-----+
2026-02-27 00:25:32.849536 | controller -> localhost | ok: Runtime: 0:00:00.014389
2026-02-27 00:25:32.859172 | 
2026-02-27 00:25:32.859266 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-27 00:25:32.892920 | controller | ok
2026-02-27 00:25:32.903636 | controller | included: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-27 00:25:32.915661 | 
2026-02-27 00:25:32.915750 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-27 00:25:32.941403 | controller | skipping: Conditional result was False
2026-02-27 00:25:32.951966 | 
2026-02-27 00:25:32.952079 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-27 00:25:33.248316 | controller | changed
2026-02-27 00:25:33.262949 | 
2026-02-27 00:25:33.263163 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-27 00:25:33.498577 | controller | ok
2026-02-27 00:25:33.512488 | 
2026-02-27 00:25:33.512628 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-27 00:25:34.169333 | controller | changed
2026-02-27 00:25:34.178518 | 
2026-02-27 00:25:34.178595 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-27 00:25:34.804764 | controller | changed
2026-02-27 00:25:34.812586 | 
2026-02-27 00:25:34.812673 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-27 00:25:34.839798 | controller | skipping: Conditional result was False
2026-02-27 00:25:34.848391 | 
2026-02-27 00:25:34.848478 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-27 00:25:35.142491 | controller -> localhost | changed
2026-02-27 00:25:35.207700 | 
2026-02-27 00:25:35.207842 | TASK [add-build-sshkey : Add back temp key]
2026-02-27 00:25:35.556076 | controller -> localhost | Identity added: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/de108514d2a64444987e2e859f82c351_id_ecdsa (zuul-build-sshkey)
2026-02-27 00:25:35.556352 | controller -> localhost | ok: Runtime: 0:00:00.010833
2026-02-27 00:25:35.564869 | 
2026-02-27 00:25:35.564982 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-27 00:25:35.771982 | controller | ok
2026-02-27 00:25:35.778180 | 
2026-02-27 00:25:35.778242 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-27 00:25:35.807312 | controller | skipping: Conditional result was False
2026-02-27 00:25:35.822336 | 
2026-02-27 00:25:35.822433 | TASK [include_role : remove-zuul-sshkey]
2026-02-27 00:25:35.849671 | controller | skipping: Conditional result was False
2026-02-27 00:25:35.857065 | 
2026-02-27 00:25:35.857153 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-27 00:25:36.085949 | controller | ok: "logs"
2026-02-27 00:25:36.086363 | controller | ok: All items complete
2026-02-27 00:25:36.086473 | 
2026-02-27 00:25:36.285803 | controller | ok: "artifacts"
2026-02-27 00:25:36.485107 | controller | ok: "docs"
2026-02-27 00:25:36.513587 | 
2026-02-27 00:25:36.513789 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-27 00:25:36.772453 | controller | changed: "logs"
2026-02-27 00:25:36.991556 | controller | changed: "artifacts"
2026-02-27 00:25:37.226159 | controller | changed: "docs"
2026-02-27 00:25:37.289697 | 
2026-02-27 00:25:37.289865 | PLAY RECAP
2026-02-27 00:25:37.289951 | controller | ok: 30 changed: 16 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 0
2026-02-27 00:25:37.290006 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-27 00:25:37.290103 | 
2026-02-27 00:25:37.430735 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/ansible/zuul-config/playbooks/base/pre.yaml@master]
2026-02-27 00:25:37.431750 | PRE-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-27 00:25:38.023288 | 
2026-02-27 00:25:38.023471 | PLAY [all]
2026-02-27 00:25:38.049751 | 
2026-02-27 00:25:38.049879 | TASK [Install binary dependencies]
2026-02-27 00:25:38.101088 | controller | ok
2026-02-27 00:25:38.121937 | 
2026-02-27 00:25:38.122169 | TASK [bindep : Include find tasks]
2026-02-27 00:25:38.166185 | controller | ok
2026-02-27 00:25:38.176327 | controller | included: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/untrusted/project_0/opendev.org/zuul/zuul-jobs/roles/bindep/tasks/find.yaml
2026-02-27 00:25:38.183471 | 
2026-02-27 00:25:38.183612 | TASK [bindep : Look for bindep.txt]
2026-02-27 00:25:38.597536 | controller | ok
2026-02-27 00:25:38.608523 | 
2026-02-27 00:25:38.608708 | TASK [bindep : Define bindep_file fact]
2026-02-27 00:25:38.638458 | controller | skipping: Conditional result was False
2026-02-27 00:25:38.653632 | 
2026-02-27 00:25:38.653735 | TASK [bindep : Look for other-requirements.txt]
2026-02-27 00:25:38.882984 | controller | ok
2026-02-27 00:25:38.892811 | 
2026-02-27 00:25:38.893055 | TASK [bindep : Define bindep_file fact]
2026-02-27 00:25:38.921273 | controller | skipping: Conditional result was False
2026-02-27 00:25:38.932349 | 
2026-02-27 00:25:38.932537 | TASK [bindep : Look for bindep fallback file]
2026-02-27 00:25:38.960176 | controller | skipping: Conditional result was False
2026-02-27 00:25:38.971460 | 
2026-02-27 00:25:38.971687 | TASK [bindep : Define bindep_file fact]
2026-02-27 00:25:38.997792 | controller | skipping: Conditional result was False
2026-02-27 00:25:39.005032 | 
2026-02-27 00:25:39.005167 | TASK [bindep : Include bindep tasks]
2026-02-27 00:25:39.031784 | controller | skipping: Conditional result was False
2026-02-27 00:25:39.041699 | 
2026-02-27 00:25:39.041902 | TASK [bindep : Include install tasks]
2026-02-27 00:25:39.069966 | controller | skipping: Conditional result was False
2026-02-27 00:25:39.077500 | 
2026-02-27 00:25:39.077638 | LOOP [bindep : Include package tasks]
2026-02-27 00:25:39.148710 | 
2026-02-27 00:25:39.148953 | TASK [Run test-setup role]
2026-02-27 00:25:39.175114 | controller | ok
2026-02-27 00:25:39.197175 | 
2026-02-27 00:25:39.197343 | TASK [test-setup : Check if project's tools/test-setup.sh exists]
2026-02-27 00:25:39.404425 | controller | ok
2026-02-27 00:25:39.412110 | 
2026-02-27 00:25:39.412216 | TASK [test-setup : Run tools/test-setup.sh]
2026-02-27 00:25:39.949808 | controller | skipping: Conditional result was False
2026-02-27 00:25:40.014761 | 
2026-02-27 00:25:40.014940 | PLAY RECAP
2026-02-27 00:25:40.015002 | controller | ok: 4 changed: 0 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-27 00:25:40.015060 | 
2026-02-27 00:25:40.252828 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/pre.yaml@master]
2026-02-27 00:25:40.253836 | PRE-RUN START: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-27 00:25:41.033506 | 
2026-02-27 00:25:41.033642 | PLAY [controller]
2026-02-27 00:25:41.053815 | 
2026-02-27 00:25:41.053939 | TASK [Create the /root directory]
2026-02-27 00:27:53.387606 | controller | ERROR
2026-02-27 00:27:53.387847 | controller | {
2026-02-27 00:27:53.387886 | controller |   "msg": "Timeout (32s) waiting for privilege escalation prompt: "
2026-02-27 00:27:53.387914 | controller | }
failure
2026-02-27 00:27:53.389677 | 
2026-02-27 00:27:53.389726 | PLAY RECAP
2026-02-27 00:27:53.389860 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-27 00:27:53.389892 | 
2026-02-27 00:27:54.047828 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/ansible/ansible-zuul-jobs/playbooks/ansible-test-base/pre.yaml@master]
2026-02-27 00:27:54.049831 | POST-RUN START: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-27 00:27:54.597483 | 
2026-02-27 00:27:54.597638 | PLAY [all]
2026-02-27 00:27:54.621146 | 
2026-02-27 00:27:54.621292 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-27 00:31:55.341858 | controller | changed: non-zero return code
2026-02-27 00:31:55.354859 | 
2026-02-27 00:31:55.355044 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-27 00:31:55.383935 | controller | skipping: Conditional result was False
2026-02-27 00:31:55.398778 | 
2026-02-27 00:31:55.398964 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-27 00:31:55.442781 | 
2026-02-27 00:31:55.443068 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-27 00:31:55.485086 | 
2026-02-27 00:31:55.485346 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-27 00:31:55.514588 | controller | skipping: Conditional result was False
2026-02-27 00:31:55.529899 | 
2026-02-27 00:31:55.530098 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-27 00:31:55.573541 | 
2026-02-27 00:31:55.573835 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-27 00:31:55.602671 | controller | skipping: Conditional result was False
2026-02-27 00:31:55.618066 | 
2026-02-27 00:31:55.618240 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-27 00:31:55.645266 | controller | skipping: Conditional result was False
2026-02-27 00:31:55.660217 | 
2026-02-27 00:31:55.660356 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-27 00:31:55.698325 | controller | skipping: Conditional result was False
2026-02-27 00:31:55.747777 | 
2026-02-27 00:31:55.747871 | PLAY RECAP
2026-02-27 00:31:55.747934 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-02-27 00:31:55.747962 | 
2026-02-27 00:31:55.872185 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/zuul/zuul-jobs/playbooks/unittests/post.yaml@master]
2026-02-27 00:31:55.873107 | POST-RUN START: [trusted : github.com/ansible/zuul-config/playbooks/base/post.yaml@master]
2026-02-27 00:31:56.469861 | 
2026-02-27 00:31:56.469991 | PLAY [all:!appliance*]
2026-02-27 00:31:56.491246 | 
2026-02-27 00:31:56.491375 | TASK [unregister the node]
2026-02-27 00:31:57.022010 | controller | skipping: Conditional result was False
2026-02-27 00:31:57.037873 | 
2026-02-27 00:31:57.038150 | TASK [include_role : fetch-output]
2026-02-27 00:31:57.081165 | controller | ok
2026-02-27 00:31:57.125258 | 
2026-02-27 00:31:57.125416 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-27 00:31:57.181347 | controller | skipping: Conditional result was False
2026-02-27 00:31:57.195461 | 
2026-02-27 00:31:57.195681 | TASK [fetch-output : Set log path for single node]
2026-02-27 00:31:57.252937 | controller | ok
2026-02-27 00:31:57.268269 | 
2026-02-27 00:31:57.268546 | LOOP [fetch-output : Ensure local output dirs]
2026-02-27 00:31:57.745712 | controller -> localhost | ok: "/var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/logs"
2026-02-27 00:31:58.037140 | controller -> localhost | changed: "/var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/artifacts"
2026-02-27 00:31:58.301824 | controller -> localhost | changed: "/var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/work/docs"
2026-02-27 00:31:58.329214 | 
2026-02-27 00:31:58.329474 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-27 00:31:59.046167 | controller | changed: .d..t...... ./
2026-02-27 00:31:59.046577 | controller | changed: All items complete
2026-02-27 00:31:59.046615 | 
2026-02-27 00:31:59.569363 | controller | changed: .d..t...... ./
2026-02-27 00:32:00.069896 | controller | changed: .d..t...... ./
2026-02-27 00:32:00.100220 | 
2026-02-27 00:32:00.100433 | TASK [include_role : fetch-output-openshift]
2026-02-27 00:32:00.127827 | controller | skipping: Conditional result was False
2026-02-27 00:32:00.148047 | 
2026-02-27 00:32:00.148222 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-27 00:32:00.195224 | controller | skipping: Conditional result was False
2026-02-27 00:32:00.206765 | controller | skipping: Conditional result was False
2026-02-27 00:32:00.262995 | 
2026-02-27 00:32:00.263144 | PLAY [localhost]
2026-02-27 00:32:00.276236 | 
2026-02-27 00:32:00.276312 | TASK [Run Zuul manifest role]
2026-02-27 00:32:00.295237 | localhost | ok
2026-02-27 00:32:00.309065 | 
2026-02-27 00:32:00.309144 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-27 00:32:00.694413 | localhost | changed
2026-02-27 00:32:00.699960 | 
2026-02-27 00:32:00.700057 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-27 00:32:00.728647 | localhost | ok
2026-02-27 00:32:00.736771 | 
2026-02-27 00:32:00.736849 | TASK [Set zuul-log-path fact]
2026-02-27 00:32:00.754756 | localhost | ok
2026-02-27 00:32:00.777403 | 
2026-02-27 00:32:00.777576 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-27 00:32:00.815102 | localhost | ok
2026-02-27 00:32:00.827722 | 
2026-02-27 00:32:00.827809 | LOOP [Set zuul_log_targets (Swift with random Rackspace region)]
2026-02-27 00:32:00.875525 | localhost | ok:
2026-02-27 00:32:00.875650 | localhost | {
2026-02-27 00:32:00.875675 | localhost |   "auth": {
2026-02-27 00:32:00.875694 | localhost |     "password": "IonWCwcuOFib&&<}wp^:[2G/",
2026-02-27 00:32:00.875711 | localhost |     "username": "9LF91O0RK7JU"
2026-02-27 00:32:00.875731 | localhost |   },
2026-02-27 00:32:00.875749 | localhost |   "profile": "rackspace",
2026-02-27 00:32:00.875766 | localhost |   "region_name": "ORD"
2026-02-27 00:32:00.875782 | localhost | }
2026-02-27 00:32:00.875804 | 
2026-02-27 00:32:00.889639 | 
2026-02-27 00:32:00.889756 | TASK [Run upload-logs-failover role (S3 then Swift)]
2026-02-27 00:32:00.908331 | localhost | Output suppressed because no_log was given
2026-02-27 00:32:00.921558 | 
2026-02-27 00:32:00.921630 | TASK [upload-logs-failover : Initialize control vars]
2026-02-27 00:32:00.939572 | localhost | ok
2026-02-27 00:32:00.944172 | 
2026-02-27 00:32:00.944232 | LOOP [upload-logs-failover : Perform log upload]
2026-02-27 00:32:00.967745 | localhost | Output suppressed because no_log was given
2026-02-27 00:32:01.003702 | localhost | included: /var/lib/zuul/builds/de108514d2a64444987e2e859f82c351/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/upload-logs-failover/tasks/upload.yaml
2026-02-27 00:32:01.010496 | 
2026-02-27 00:32:01.010564 | LOOP [upload-logs-failover : Unpack vars for swift upload]
2026-02-27 00:32:01.126028 | localhost | Output suppressed because no_log was given
2026-02-27 00:32:01.131575 | 
2026-02-27 00:32:01.131653 | TASK [Upload logs to swift]
2026-02-27 00:32:01.159536 | localhost | ok
2026-02-27 00:32:01.186621 | 
2026-02-27 00:32:01.186721 | TASK [Set zuul-log-path fact]
2026-02-27 00:32:01.220644 | localhost | skipping: Conditional result was False
2026-02-27 00:32:01.226178 | 
2026-02-27 00:32:01.226253 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-02-27 00:32:01.625308 | localhost -> localhost | ok: Runtime: 0:00:00.005062
2026-02-27 00:32:01.631372 | 
2026-02-27 00:32:01.631466 | TASK [upload-logs-swift : Upload logs to swift]