2026-02-16 17:41:46.126354 | Job console starting
2026-02-16 17:41:46.134946 | Updating git repos
2026-02-16 17:41:48.992778 | Cloning repos into workspace
2026-02-16 17:41:54.963462 | Restoring repo states
2026-02-16 17:41:57.969637 | Merging changes
2026-02-16 17:41:58.340319 | Checking out repos
2026-02-16 17:42:01.374859 | Preparing playbooks
2026-02-16 17:42:02.773420 | Running Ansible setup
2026-02-16 17:42:06.652534 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:07.277042 | 
2026-02-16 17:42:07.277184 | PLAY [localhost]
2026-02-16 17:42:07.285022 | 
2026-02-16 17:42:07.285094 | TASK [Gathering Facts]
2026-02-16 17:42:08.144915 | localhost | ok
2026-02-16 17:42:08.153497 | 
2026-02-16 17:42:08.153578 | TASK [Setup log path fact]
2026-02-16 17:42:08.174788 | localhost | ok
2026-02-16 17:42:08.189937 | 
2026-02-16 17:42:08.190013 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:42:08.221053 | localhost | ok
2026-02-16 17:42:08.229544 | 
2026-02-16 17:42:08.229617 | TASK [emit-job-header : Print job information]
2026-02-16 17:42:08.272526 | # Job Information
2026-02-16 17:42:08.272692 | Ansible Version: 2.16.15
2026-02-16 17:42:08.272735 | Job: magnum-cluster-api-sonobuoy-v1.32.12-calico
2026-02-16 17:42:08.272766 | Pipeline: check
2026-02-16 17:42:08.272793 | Executor: 3a2793d2bd32
2026-02-16 17:42:08.272819 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:42:08.272849 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:42:08.276569 | 
2026-02-16 17:42:08.276682 | LOOP [emit-job-header : Print node information]
2026-02-16 17:42:08.371048 | localhost | ok:
2026-02-16 17:42:08.371276 | localhost | # Node Information
2026-02-16 17:42:08.371316 | localhost | Inventory Hostname: controller
2026-02-16 17:42:08.371355 | localhost | Hostname: np0000155621
2026-02-16 17:42:08.371382 | localhost | Username: zuul
2026-02-16 17:42:08.371412 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:42:08.371440 | localhost | Provider: yul1
2026-02-16 17:42:08.371466 | localhost | Region: ca-ymq-1
2026-02-16 17:42:08.371492 | localhost | Label: ubuntu-noble-16
2026-02-16 17:42:08.371518 | localhost | Product Name: OpenStack Nova
2026-02-16 17:42:08.371544 | localhost | Interface IP: 199.204.45.242
2026-02-16 17:42:08.383415 | 
2026-02-16 17:42:08.383566 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:42:08.764128 | localhost -> localhost | changed
2026-02-16 17:42:08.772201 | 
2026-02-16 17:42:08.772301 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:42:09.617697 | localhost -> localhost | changed
2026-02-16 17:42:09.627675 | 
2026-02-16 17:42:09.627735 | PLAY [all]
2026-02-16 17:42:09.636507 | 
2026-02-16 17:42:09.636575 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:42:09.863689 | controller -> localhost | ok
2026-02-16 17:42:09.874908 | 
2026-02-16 17:42:09.874986 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:42:09.908535 | controller | ok
2026-02-16 17:42:09.925071 | controller | included: /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:42:09.931386 | 
2026-02-16 17:42:09.931448 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:42:11.063790 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:42:11.063983 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/work/3ccb6a1809cf480987bd453099524651_id_rsa
2026-02-16 17:42:11.064013 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/work/3ccb6a1809cf480987bd453099524651_id_rsa.pub
2026-02-16 17:42:11.064035 | controller -> localhost | The key fingerprint is:
2026-02-16 17:42:11.064056 | controller -> localhost | SHA256:O8h+gjWloSSilbYgg0FotboGNpzwZTsq81bwz4xvVns zuul-build-sshkey
2026-02-16 17:42:11.064089 | controller -> localhost | The key's randomart image is:
2026-02-16 17:42:11.064111 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:42:11.064137 | controller -> localhost | |o...             |
2026-02-16 17:42:11.064158 | controller -> localhost | |o.  .            |
2026-02-16 17:42:11.064178 | controller -> localhost | |+. oo            |
2026-02-16 17:42:11.064198 | controller -> localhost | |O.Oo... .        |
2026-02-16 17:42:11.064218 | controller -> localhost | |+%.*o. +S        |
2026-02-16 17:42:11.064237 | controller -> localhost | |+ +.+o+...       |
2026-02-16 17:42:11.064256 | controller -> localhost | |oo.. Booo.       |
2026-02-16 17:42:11.064275 | controller -> localhost | |.+. o.B o.E      |
2026-02-16 17:42:11.064297 | controller -> localhost | | ..  +oo .       |
2026-02-16 17:42:11.064317 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:42:11.064366 | controller -> localhost | ok: Runtime: 0:00:00.707658
2026-02-16 17:42:11.070028 | 
2026-02-16 17:42:11.070092 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:42:11.108406 | controller | ok
2026-02-16 17:42:11.118759 | controller | included: /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:42:11.233147 | 
2026-02-16 17:42:11.233372 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:42:11.257995 | controller | skipping: Conditional result was False
2026-02-16 17:42:11.268657 | 
2026-02-16 17:42:11.268750 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:42:11.830233 | controller | changed
2026-02-16 17:42:11.839317 | 
2026-02-16 17:42:11.839398 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:42:12.067482 | controller | ok
2026-02-16 17:42:12.074157 | 
2026-02-16 17:42:12.074229 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:42:12.646963 | controller | changed
2026-02-16 17:42:12.652632 | 
2026-02-16 17:42:12.652699 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:42:13.188175 | controller | changed
2026-02-16 17:42:13.195956 | 
2026-02-16 17:42:13.196032 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:42:13.220919 | controller | skipping: Conditional result was False
2026-02-16 17:42:13.231121 | 
2026-02-16 17:42:13.231207 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:42:13.590631 | controller -> localhost | changed
2026-02-16 17:42:13.607859 | 
2026-02-16 17:42:13.607957 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:42:13.947254 | controller -> localhost | Identity added: /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/work/3ccb6a1809cf480987bd453099524651_id_rsa (zuul-build-sshkey)
2026-02-16 17:42:13.947454 | controller -> localhost | ok: Runtime: 0:00:00.015007
2026-02-16 17:42:13.990535 | 
2026-02-16 17:42:13.990706 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:42:14.290370 | controller | ok
2026-02-16 17:42:14.385976 | 
2026-02-16 17:42:14.386194 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:42:14.412292 | controller | skipping: Conditional result was False
2026-02-16 17:42:14.428745 | 
2026-02-16 17:42:14.428813 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:42:14.745199 | controller | ok
2026-02-16 17:42:14.753658 | 
2026-02-16 17:42:14.753722 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:42:34.710494 | controller | Output suppressed because no_log was given
2026-02-16 17:42:34.723444 | 
2026-02-16 17:42:34.723509 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:42:34.980357 | controller | ok: "logs"
2026-02-16 17:42:34.980648 | controller | ok: All items complete
2026-02-16 17:42:34.980676 | 
2026-02-16 17:42:35.182891 | controller | ok: "artifacts"
2026-02-16 17:42:35.372325 | controller | ok: "docs"
2026-02-16 17:42:35.390578 | 
2026-02-16 17:42:35.390753 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:42:35.621931 | controller | changed: "logs"
2026-02-16 17:42:35.818931 | controller | changed: "artifacts"
2026-02-16 17:42:36.069107 | controller | changed: "docs"
2026-02-16 17:42:36.087019 | 
2026-02-16 17:42:36.087145 | PLAY RECAP
2026-02-16 17:42:36.087330 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:42:36.087364 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:42:36.087386 | 
2026-02-16 17:42:36.232706 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:36.243144 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:42:36.881552 | 
2026-02-16 17:42:36.881690 | PLAY [all]
2026-02-16 17:42:36.899011 | 
2026-02-16 17:42:36.899102 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:42:37.256292 | controller | changed
2026-02-16 17:42:37.265002 | 
2026-02-16 17:42:37.265077 | TASK [Gather minimum local MTU]
2026-02-16 17:42:37.338955 | controller | ok
2026-02-16 17:42:37.345997 | 
2026-02-16 17:42:37.346087 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:42:37.401938 | controller | ok
2026-02-16 17:42:37.409225 | 
2026-02-16 17:42:37.409324 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:42:37.445610 | controller | skipping: Conditional result was False
2026-02-16 17:42:37.460235 | 
2026-02-16 17:42:37.460307 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:42:38.000154 | controller | ok: Runtime: 0:00:00.010761
2026-02-16 17:42:38.006719 | 
2026-02-16 17:42:38.006800 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:42:38.031883 | controller | skipping: Conditional result was False
2026-02-16 17:42:38.042950 | 
2026-02-16 17:42:38.043020 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:42:38.067776 | controller | skipping: Conditional result was False
2026-02-16 17:42:38.077428 | 
2026-02-16 17:42:38.077519 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:42:38.133183 | controller | ok
2026-02-16 17:42:38.145048 | controller | included: /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:42:38.151039 | 
2026-02-16 17:42:38.151103 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:42:38.207023 | controller | ok
2026-02-16 17:42:38.212971 | 
2026-02-16 17:42:38.213057 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:42:38.505795 | controller | ext4
2026-02-16 17:42:38.791646 | controller | ok: Runtime: 0:00:00.013861
2026-02-16 17:42:38.797269 | 
2026-02-16 17:42:38.797336 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:42:38.830983 | controller | ok
2026-02-16 17:42:38.838367 | 
2026-02-16 17:42:38.838452 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:42:38.884058 | controller | ok:
2026-02-16 17:42:38.884285 | controller | {
2026-02-16 17:42:38.884342 | controller |   "root_filesystem": "ext4"
2026-02-16 17:42:38.884386 | controller | }
2026-02-16 17:42:38.891638 | 
2026-02-16 17:42:38.891699 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:42:49.762733 | controller | 8192+0 records in
2026-02-16 17:42:49.763211 | controller | 8192+0 records out
2026-02-16 17:42:49.763230 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.6088 s, 810 MB/s
2026-02-16 17:42:49.943578 | controller | ok: Runtime: 0:00:10.622999
2026-02-16 17:42:49.952314 | 
2026-02-16 17:42:49.952410 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:42:50.235557 | controller | changed
2026-02-16 17:42:50.253711 | 
2026-02-16 17:42:50.253952 | TASK [configure-swap : Make swapfile]
2026-02-16 17:43:06.863524 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:43:06.863907 | controller | no label, UUID=7b7b4e08-03f0-490e-8903-b67688e5c44f
2026-02-16 17:43:06.937416 | controller | ok: Runtime: 0:00:16.345844
2026-02-16 17:43:06.943878 | 
2026-02-16 17:43:06.943945 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:43:07.345442 | controller | changed
2026-02-16 17:43:07.351814 | 
2026-02-16 17:43:07.351890 | TASK [configure-swap : Add all swap]
2026-02-16 17:43:07.895470 | controller | ok: Runtime: 0:00:00.020644
2026-02-16 17:43:07.900951 | 
2026-02-16 17:43:07.901034 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:43:07.935249 | controller | ok:
2026-02-16 17:43:07.935444 | controller | {
2026-02-16 17:43:07.935480 | controller |   "swap_required": "8192"
2026-02-16 17:43:07.935508 | controller | }
2026-02-16 17:43:07.941445 | 
2026-02-16 17:43:07.941508 | TASK [configure-swap : Set swappiness]
2026-02-16 17:43:08.342176 | controller | changed
2026-02-16 17:43:08.350507 | 
2026-02-16 17:43:08.350571 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:43:08.380235 | controller | ok:
2026-02-16 17:43:08.380414 | controller | {
2026-02-16 17:43:08.380472 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:43:08.380511 | controller | }
2026-02-16 17:43:08.392003 | 
2026-02-16 17:43:08.392069 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:43:08.843894 | controller | changed
2026-02-16 17:43:08.851884 | 
2026-02-16 17:43:08.851948 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:43:09.100550 | controller | changed
2026-02-16 17:43:09.106314 | 
2026-02-16 17:43:09.106418 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:43:09.660377 | controller | changed
2026-02-16 17:43:09.670641 | 
2026-02-16 17:43:09.670716 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:43:09.914473 | controller | changed
2026-02-16 17:43:09.920564 | 
2026-02-16 17:43:09.920626 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:43:11.041041 | controller | changed
2026-02-16 17:43:11.046843 | 
2026-02-16 17:43:11.046913 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:43:11.310852 | controller | changed
2026-02-16 17:43:11.321925 | 
2026-02-16 17:43:11.321988 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:43:11.631063 | controller | changed
2026-02-16 17:43:11.636174 | 
2026-02-16 17:43:11.636234 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:43:12.024784 | controller | changed
2026-02-16 17:43:12.072465 | 
2026-02-16 17:43:12.072614 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:43:12.842311 | controller | changed
2026-02-16 17:43:12.852540 | 
2026-02-16 17:43:12.852606 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:43:13.171561 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:43:13.183010 | 
2026-02-16 17:43:13.183072 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:43:13.644985 | controller | ok: Item: Runtime: 0:00:00.187541
2026-02-16 17:43:14.222892 | controller | ok: Item: Runtime: 0:00:00.353778
2026-02-16 17:43:14.985675 | controller | ok: Item: Runtime: 0:00:00.544716
2026-02-16 17:43:15.271737 | controller | ok: Item: Runtime: 0:00:00.063733
2026-02-16 17:43:15.765486 | controller | ok: Item: Runtime: 0:00:00.269687
2026-02-16 17:43:17.387086 | controller | ok: Item: Runtime: 0:00:01.401584
2026-02-16 17:43:20.698489 | controller | ok: Item: Runtime: 0:00:03.092487
2026-02-16 17:43:21.023790 | controller | ok: Item: Runtime: 0:00:00.096314
2026-02-16 17:43:21.434516 | controller | ok: Item: Runtime: 0:00:00.191231
2026-02-16 17:43:21.768823 | controller | ok: Item: Runtime: 0:00:00.122222
2026-02-16 17:43:22.547542 | controller | ok: Item: Runtime: 0:00:00.552220
2026-02-16 17:43:23.337841 | controller | ok: Item: Runtime: 0:00:00.565505
2026-02-16 17:43:23.798402 | controller | ok: Item: Runtime: 0:00:00.240745
2026-02-16 17:43:24.244479 | controller | ok: Item: Runtime: 0:00:00.221614
2026-02-16 17:43:24.872630 | controller | ok: Item: Runtime: 0:00:00.393022
2026-02-16 17:43:26.263940 | controller | ok: Item: Runtime: 0:00:01.167906
2026-02-16 17:43:26.290075 | 
2026-02-16 17:43:26.290158 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:43:26.504382 | controller | ok: All paths examined
2026-02-16 17:43:26.510556 | 
2026-02-16 17:43:26.510618 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:43:26.748329 | controller | ok: All paths examined
2026-02-16 17:43:26.754273 | 
2026-02-16 17:43:26.754333 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:43:27.195811 | controller | ok: Item: Runtime: 0:00:00.162490
2026-02-16 17:43:27.196078 | controller | changed: All items complete
2026-02-16 17:43:27.196109 | 
2026-02-16 17:43:27.611742 | controller | ok: Item: Runtime: 0:00:00.197104
2026-02-16 17:43:27.632291 | 
2026-02-16 17:43:27.632469 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:43:27.677669 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.679830 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.682545 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.685265 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.688298 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.691794 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.695052 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.698599 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.701917 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.704487 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.707482 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.710455 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.714239 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.717394 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.720251 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.723125 | controller | skipping: Conditional result was False
2026-02-16 17:43:27.752113 | 
2026-02-16 17:43:27.752240 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:43:30.138881 | controller | changed
2026-02-16 17:43:30.150355 | 
2026-02-16 17:43:30.150426 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:43:30.388982 | controller | changed
2026-02-16 17:43:30.464095 | 
2026-02-16 17:43:30.464222 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:43:30.712940 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:43:31.004547 | controller | ERROR
2026-02-16 17:43:31.004801 | controller | {
2026-02-16 17:43:31.004831 | controller |   "delta": "0:00:00.011206",
2026-02-16 17:43:31.004851 | controller |   "end": "2026-02-16 17:43:30.713584",
2026-02-16 17:43:31.004870 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:31.004888 | controller |   "rc": 1,
2026-02-16 17:43:31.004906 | controller |   "start": "2026-02-16 17:43:30.702378"
2026-02-16 17:43:31.004923 | controller | }
2026-02-16 17:43:31.004946 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:31.010708 | 
2026-02-16 17:43:31.010775 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:43:31.269571 | controller | ok
2026-02-16 17:43:31.281786 | 
2026-02-16 17:43:31.281941 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:43:31.522506 | controller | /usr/bin/journalctl
2026-02-16 17:43:31.832226 | controller | ok: Runtime: 0:00:00.008705
2026-02-16 17:43:31.838885 | 
2026-02-16 17:43:31.838949 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:43:32.086584 | controller | 2026-02-16 17:43:32
2026-02-16 17:43:32.374076 | controller | ok: Runtime: 0:00:00.012443
2026-02-16 17:43:32.379426 | 
2026-02-16 17:43:32.379489 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:43:32.971620 | controller | changed
2026-02-16 17:43:32.977876 | 
2026-02-16 17:43:32.977938 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:43:33.003682 | controller | skipping: Conditional result was False
2026-02-16 17:43:33.015619 | 
2026-02-16 17:43:33.015685 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:43:33.545963 | controller | skipping: Conditional result was False
2026-02-16 17:43:33.559313 | 
2026-02-16 17:43:33.559407 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:43:34.089404 | controller | skipping: Conditional result was False
2026-02-16 17:43:34.101401 | 
2026-02-16 17:43:34.101474 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:43:34.126187 | controller | skipping: Conditional result was False
2026-02-16 17:43:34.136274 | 
2026-02-16 17:43:34.136367 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:43:34.669087 | controller | skipping: Conditional result was False
2026-02-16 17:43:34.679139 | 
2026-02-16 17:43:34.679234 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:43:35.209152 | controller | skipping: Conditional result was False
2026-02-16 17:43:35.220449 | 
2026-02-16 17:43:35.220541 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:43:35.245852 | controller | skipping: Conditional result was False
2026-02-16 17:43:35.256217 | 
2026-02-16 17:43:35.256306 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:43:35.281894 | controller | skipping: Conditional result was False
2026-02-16 17:43:35.292222 | 
2026-02-16 17:43:35.292308 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:43:35.872453 | controller | skipping: Conditional result was False
2026-02-16 17:43:35.882848 | 
2026-02-16 17:43:35.882938 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:43:36.412663 | controller | skipping: Conditional result was False
2026-02-16 17:43:36.425939 | 
2026-02-16 17:43:36.426069 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:43:36.451395 | controller | skipping: Conditional result was False
2026-02-16 17:43:36.463375 | 
2026-02-16 17:43:36.463473 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:43:36.488782 | controller | skipping: Conditional result was False
2026-02-16 17:43:36.504335 | 
2026-02-16 17:43:36.504436 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:43:36.906762 | controller | ok
2026-02-16 17:43:36.916126 | 
2026-02-16 17:43:36.916175 | PLAY RECAP
2026-02-16 17:43:36.916223 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:43:36.916253 | 
2026-02-16 17:43:37.072361 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:43:37.082622 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:43:37.664785 | 
2026-02-16 17:43:37.664919 | PLAY [all]
2026-02-16 17:43:37.676625 | 
2026-02-16 17:43:37.676712 | TASK [ensure-rust : Use rustup]
2026-02-16 17:43:37.711494 | controller | ok
2026-02-16 17:43:37.719328 | controller | included: /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:43:37.724774 | 
2026-02-16 17:43:37.724849 | TASK [ensure-rust : Install Rust]
2026-02-16 17:43:38.102557 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:43:38.264395 | controller | ERROR
2026-02-16 17:43:38.264616 | controller | {
2026-02-16 17:43:38.264646 | controller |   "delta": "0:00:00.012262",
2026-02-16 17:43:38.264669 | controller |   "end": "2026-02-16 17:43:38.105458",
2026-02-16 17:43:38.264692 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:38.264713 | controller |   "rc": 127,
2026-02-16 17:43:38.264732 | controller |   "start": "2026-02-16 17:43:38.093196"
2026-02-16 17:43:38.264754 | controller | }
failure
2026-02-16 17:43:38.266225 | 
2026-02-16 17:43:38.266274 | PLAY RECAP
2026-02-16 17:43:38.266317 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:43:38.266338 | 
2026-02-16 17:43:38.375599 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:43:38.385319 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:43:38.951036 | 
2026-02-16 17:43:38.951173 | PLAY [all]
2026-02-16 17:43:38.967149 | 
2026-02-16 17:43:38.967239 | TASK [Return built artifacts to Zuul]
2026-02-16 17:43:39.000345 | controller | ok
2026-02-16 17:43:39.006769 | 
2026-02-16 17:43:39.006841 | TASK [Get the Docker volume ID]
2026-02-16 17:43:39.544028 | controller | ERROR
2026-02-16 17:43:39.544418 | controller | {
2026-02-16 17:43:39.544489 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:43:39.544521 | controller |   "rc": 2
2026-02-16 17:43:39.544549 | controller | }
2026-02-16 17:43:39.544586 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:39.552152 | 
2026-02-16 17:43:39.552220 | TASK [Copy all of the pod logs]
2026-02-16 17:43:39.586109 | controller | ERROR
2026-02-16 17:43:39.586277 | controller | {
2026-02-16 17:43:39.586307 | controller |   "msg": "The task includes an option with an undefined variable. The error was: list object has no element 0. list object has no element 0\n\nThe error appears to be in '/var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml': line 21, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-02-16 17:43:39.586329 | controller | }
2026-02-16 17:43:39.586356 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:39.587358 | 
2026-02-16 17:43:39.587406 | PLAY RECAP
2026-02-16 17:43:39.587449 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:43:39.587470 | 
2026-02-16 17:43:39.699949 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:43:39.710119 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:43:40.316421 | 
2026-02-16 17:43:40.316561 | PLAY [all]
2026-02-16 17:43:40.335182 | 
2026-02-16 17:43:40.335263 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:43:40.734331 | controller | changed
2026-02-16 17:43:40.743437 | 
2026-02-16 17:43:40.743521 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:43:41.783797 | controller | ok: Runtime: 0:00:00.459644
2026-02-16 17:43:41.789511 | 
2026-02-16 17:43:41.789584 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:43:42.329904 | controller | ok: Runtime: 0:00:00.020871
2026-02-16 17:43:42.337691 | 
2026-02-16 17:43:42.337770 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:43:42.878256 | controller | ok: Runtime: 0:00:00.024150
2026-02-16 17:43:42.885256 | 
2026-02-16 17:43:42.885329 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:43:43.750512 | controller | changed
2026-02-16 17:43:43.796617 | 
2026-02-16 17:43:43.796762 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:43:44.039631 | controller | changed
2026-02-16 17:43:44.045108 | 
2026-02-16 17:43:44.045173 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:43:44.369505 | controller | Output suppressed because no_log was given
2026-02-16 17:43:44.374994 | 
2026-02-16 17:43:44.375064 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:43:44.411144 | 
2026-02-16 17:43:44.411304 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:43:44.438177 | 
2026-02-16 17:43:44.438304 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:43:44.474602 | 
2026-02-16 17:43:44.474834 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:43:44.511063 | 
2026-02-16 17:43:44.511210 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:43:44.548970 | 
2026-02-16 17:43:44.549133 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:43:44.799446 | controller | changed
2026-02-16 17:43:44.805198 | 
2026-02-16 17:43:44.805264 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:43:44.838013 | controller | ok
2026-02-16 17:43:44.844184 | 
2026-02-16 17:43:44.844247 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:43:45.082875 | controller | Output suppressed because no_log was given
2026-02-16 17:43:45.088220 | 
2026-02-16 17:43:45.088287 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:43:45.124627 | 
2026-02-16 17:43:45.124757 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:43:45.165130 | 
2026-02-16 17:43:45.165261 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:43:45.836934 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:43:45.856567 | controller | Using python 3.12 to install setuptools
2026-02-16 17:43:45.907093 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:43:45.918249 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:43:46.203506 | controller | ERROR
2026-02-16 17:43:46.203671 | controller | {
2026-02-16 17:43:46.203715 | controller |   "delta": "0:00:00.503666",
2026-02-16 17:43:46.203743 | controller |   "end": "2026-02-16 17:43:45.920241",
2026-02-16 17:43:46.203769 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:46.203801 | controller |   "rc": 127,
2026-02-16 17:43:46.203838 | controller |   "start": "2026-02-16 17:43:45.416575"
2026-02-16 17:43:46.203866 | controller | }
2026-02-16 17:43:46.203902 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:46.218523 | 
2026-02-16 17:43:46.218612 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:43:46.467735 | controller | changed
2026-02-16 17:43:46.474150 | 
2026-02-16 17:43:46.474216 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:43:50.323988 | controller | Output suppressed because no_log was given
2026-02-16 17:43:50.336107 | 
2026-02-16 17:43:50.336213 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:43:50.367114 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.367623 | 
2026-02-16 17:43:50.369646 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.370881 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.372583 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.375540 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.378114 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.380504 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.382956 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.385310 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.387631 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.390059 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.392304 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.394643 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.396880 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.399260 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.401548 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.403796 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.406888 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.443981 | 
2026-02-16 17:43:50.444351 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:43:50.681786 | controller | ok
2026-02-16 17:43:50.689543 | 
2026-02-16 17:43:50.689622 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:43:51.271159 | controller | skipping: Conditional result was False
2026-02-16 17:43:51.287269 | 
2026-02-16 17:43:51.287383 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:43:51.549459 | controller | sudo: iptables-save: command not found
2026-02-16 17:43:51.599154 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:43:52.838851 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:43:53.329152 | controller | ok: Runtime: 0:00:01.317525
2026-02-16 17:43:53.339897 | 
2026-02-16 17:43:53.339970 | LOOP [stage-output : Register sources]
2026-02-16 17:44:02.769372 | controller | Output suppressed because no_log was given
2026-02-16 17:44:02.815121 | 
2026-02-16 17:44:02.815267 | TASK [stage-output : Check sudo]
2026-02-16 17:44:03.356718 | controller | ok: Runtime: 0:00:00.021736
2026-02-16 17:44:03.364232 | 
2026-02-16 17:44:03.364315 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:44:03.465027 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.465424 | 
2026-02-16 17:44:03.466999 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.467517 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.469408 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.505249 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.505800 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.506292 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.507396 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.509672 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.511751 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.513853 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.516021 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.518090 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.520478 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.538061 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.538627 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.539129 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.539619 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.540178 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.541795 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.543752 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.546368 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.548473 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.571845 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.572187 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.647066 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.657776 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.665921 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.676828 | controller | Output suppressed because no_log was given
2026-02-16 17:44:03.691030 | 
2026-02-16 17:44:03.691104 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:44:03.758089 | controller | ok
2026-02-16 17:44:03.765170 | 
2026-02-16 17:44:03.765237 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:44:04.022624 | controller | changed: "docs"
2026-02-16 17:44:04.235786 | controller | changed: "artifacts"
2026-02-16 17:44:04.453805 | controller | ok: "logs"
2026-02-16 17:44:04.473624 | 
2026-02-16 17:44:04.473793 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:44:04.757312 | controller | ok: Item: Runtime: 0:00:00.011165
2026-02-16 17:44:04.757583 | controller | changed: All items complete
2026-02-16 17:44:04.757611 | 
2026-02-16 17:44:04.989331 | controller | ok: Item: Runtime: 0:00:00.008624
2026-02-16 17:44:05.215221 | controller | ok: Item: Runtime: 0:00:00.007173
2026-02-16 17:44:05.447888 | controller | ok: Item: Runtime: 0:00:00.008959
2026-02-16 17:44:05.673658 | controller | ok: Item: Runtime: 0:00:00.009319
2026-02-16 17:44:05.902288 | controller | ok: Item: Runtime: 0:00:00.008266
2026-02-16 17:44:06.146173 | controller | ok: Item: Runtime: 0:00:00.010034
2026-02-16 17:44:06.370721 | controller | ok: Item: Runtime: 0:00:00.008684
2026-02-16 17:44:06.594487 | controller | ok: Item: Runtime: 0:00:00.008775
2026-02-16 17:44:06.818831 | controller | ok: Item: Runtime: 0:00:00.008455
2026-02-16 17:44:07.048586 | controller | ok: Item: Runtime: 0:00:00.008289
2026-02-16 17:44:07.337867 | controller | ok: Item: Runtime: 0:00:00.011011
2026-02-16 17:44:07.505036 | controller | ok: Item: Runtime: 0:00:00.007500
2026-02-16 17:44:07.733885 | controller | ok: Item: Runtime: 0:00:00.009861
2026-02-16 17:44:07.961535 | controller | ok: Item: Runtime: 0:00:00.007911
2026-02-16 17:44:08.197154 | controller | ok: Item: Runtime: 0:00:00.009121
2026-02-16 17:44:08.217964 | 
2026-02-16 17:44:08.218116 | TASK [stage-output : Make all log files readable]
2026-02-16 17:44:08.467538 | controller | changed
2026-02-16 17:44:08.474032 | 
2026-02-16 17:44:08.474100 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:44:08.804027 | controller | changed: Renamed files for staging.
2026-02-16 17:44:08.811012 | 
2026-02-16 17:44:08.811079 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:44:08.836719 | controller | skipping: Conditional result was False
2026-02-16 17:44:08.848358 | 
2026-02-16 17:44:08.848424 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:44:08.887431 | 
2026-02-16 17:44:08.887607 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:44:09.482548 | controller | changed:
2026-02-16 17:44:09.482813 | controller | created directory /var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/work/logs/controller
2026-02-16 17:44:09.482857 | controller | cd+++++++++ logs/
2026-02-16 17:44:09.482890 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:44:09.482920 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:44:09.482948 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:44:09.482974 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:44:09.482999 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:44:09.483027 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:44:09.483052 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:44:09.483077 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:44:09.483105 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:44:09.483133 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:44:09.483162 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:44:09.483190 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:44:09.483227 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:44:09.483279 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:44:09.483312 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:44:09.483340 | controller | cd+++++++++ logs/apache/
2026-02-16 17:44:09.483368 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:44:09.483393 | controller | cd+++++++++ logs/etc/
2026-02-16 17:44:09.483418 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:44:09.483442 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:44:09.483467 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:44:09.483492 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:44:09.483516 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:44:09.483541 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:44:09.495896 | 
2026-02-16 17:44:09.495962 | TASK [Check if a tempest log exits]
2026-02-16 17:44:09.754125 | controller | ok
2026-02-16 17:44:09.760696 | 
2026-02-16 17:44:09.760763 | TASK [Link post-devstack tempest.log]
2026-02-16 17:44:09.786744 | controller | skipping: Conditional result was False
2026-02-16 17:44:09.797633 | 
2026-02-16 17:44:09.797723 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:44:10.051728 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:44:10.342458 | controller | ERROR
2026-02-16 17:44:10.342724 | controller | {
2026-02-16 17:44:10.342760 | controller |   "delta": "0:00:00.008124",
2026-02-16 17:44:10.342780 | controller |   "end": "2026-02-16 17:44:10.052380",
2026-02-16 17:44:10.342799 | controller |   "msg": "non-zero return code",
2026-02-16 17:44:10.342816 | controller |   "rc": 127,
2026-02-16 17:44:10.342833 | controller |   "start": "2026-02-16 17:44:10.044256"
2026-02-16 17:44:10.342850 | controller | }
2026-02-16 17:44:10.342875 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:10.344531 | 
2026-02-16 17:44:10.344594 | PLAY RECAP
2026-02-16 17:44:10.344665 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:44:10.344700 | 
2026-02-16 17:44:10.492834 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:44:10.503307 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:11.118134 | 
2026-02-16 17:44:11.118270 | PLAY [all]
2026-02-16 17:44:11.129872 | 
2026-02-16 17:44:11.129948 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:44:11.175340 | controller | skipping: Conditional result was False
2026-02-16 17:44:11.184924 | 
2026-02-16 17:44:11.185087 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:44:11.231050 | controller | ok
2026-02-16 17:44:11.237808 | 
2026-02-16 17:44:11.237879 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:44:11.645049 | controller -> localhost | ok: "/var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/work/logs"
2026-02-16 17:44:11.850301 | controller -> localhost | changed: "/var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/work/artifacts"
2026-02-16 17:44:12.063801 | controller -> localhost | changed: "/var/lib/zuul/builds/3ccb6a1809cf480987bd453099524651/work/docs"
2026-02-16 17:44:12.084210 | 
2026-02-16 17:44:12.084406 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:44:12.728990 | controller | changed: .d..t...... ./
2026-02-16 17:44:12.729226 | controller | changed: All items complete
2026-02-16 17:44:12.729259 | 
2026-02-16 17:44:13.194934 | controller | changed: .d..t...... ./
2026-02-16 17:44:13.693741 | controller | changed: .d..t...... ./
2026-02-16 17:44:13.715078 | 
2026-02-16 17:44:13.715251 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:44:14.130356 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011806
2026-02-16 17:44:14.349595 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007378
2026-02-16 17:44:14.367388 | 
2026-02-16 17:44:14.367533 | PLAY [all]
2026-02-16 17:44:14.375492 | 
2026-02-16 17:44:14.375556 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:44:14.886609 | controller | changed
2026-02-16 17:44:14.895932 | 
2026-02-16 17:44:14.895988 | PLAY RECAP
2026-02-16 17:44:14.896034 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:44:14.896056 | 
2026-02-16 17:44:15.012290 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:15.018030 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:44:15.611068 | 
2026-02-16 17:44:15.611220 | PLAY [localhost]
2026-02-16 17:44:15.623123 | 
2026-02-16 17:44:15.623201 | TASK [Generate Zuul manifest]
2026-02-16 17:44:15.645226 | localhost | ok
2026-02-16 17:44:15.662430 | 
2026-02-16 17:44:15.662509 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:44:16.049761 | localhost | changed
2026-02-16 17:44:16.061985 | 
2026-02-16 17:44:16.062064 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:44:16.094391 | localhost | ok
2026-02-16 17:44:16.103030 | 
2026-02-16 17:44:16.103095 | TASK [Upload logs]
2026-02-16 17:44:16.122855 | localhost | ok
2026-02-16 17:44:16.184482 | 
2026-02-16 17:44:16.184584 | TASK [Set zuul-log-path fact]
2026-02-16 17:44:16.208464 | localhost | ok
2026-02-16 17:44:16.219997 | 
2026-02-16 17:44:16.220063 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:44:16.255420 | localhost | ok
2026-02-16 17:44:16.264016 | 
2026-02-16 17:44:16.264087 | TASK [upload-logs : Create log directories]
2026-02-16 17:44:16.676616 | localhost | changed
2026-02-16 17:44:16.682410 | 
2026-02-16 17:44:16.682477 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:44:17.142347 | localhost -> localhost | ok: Runtime: 0:00:00.006042
2026-02-16 17:44:17.152825 | 
2026-02-16 17:44:17.152893 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:44:17.571409 | localhost | Output suppressed because no_log was given
2026-02-16 17:44:17.576304 | 
2026-02-16 17:44:17.576393 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:44:17.620466 | localhost | skipping: Conditional result was False
2026-02-16 17:44:17.626284 | localhost | skipping: Conditional result was False
2026-02-16 17:44:17.641373 | 
2026-02-16 17:44:17.641620 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:44:17.680845 | localhost | skipping: Conditional result was False
2026-02-16 17:44:17.681276 | 
2026-02-16 17:44:17.684005 | localhost | skipping: Conditional result was False
2026-02-16 17:44:17.697989 | 
2026-02-16 17:44:17.698169 | LOOP [upload-logs : Upload console log and json output]