2026-02-16 17:45:41.640260 | Job console starting
2026-02-16 17:45:41.657326 | Updating git repos
2026-02-16 17:45:45.605678 | Cloning repos into workspace
2026-02-16 17:45:52.400980 | Restoring repo states
2026-02-16 17:45:52.582121 | Merging changes
2026-02-16 17:45:53.392530 | Checking out repos
2026-02-16 17:45:58.319398 | Preparing playbooks
2026-02-16 17:46:02.697224 | Running Ansible setup
2026-02-16 17:46:06.793511 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:46:07.424192 | 
2026-02-16 17:46:07.424337 | PLAY [localhost]
2026-02-16 17:46:07.431951 | 
2026-02-16 17:46:07.432024 | TASK [Gathering Facts]
2026-02-16 17:46:08.366772 | localhost | ok
2026-02-16 17:46:08.375624 | 
2026-02-16 17:46:08.375708 | TASK [Setup log path fact]
2026-02-16 17:46:08.400537 | localhost | ok
2026-02-16 17:46:08.416995 | 
2026-02-16 17:46:08.417071 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:46:08.454495 | localhost | ok
2026-02-16 17:46:08.463185 | 
2026-02-16 17:46:08.463278 | TASK [emit-job-header : Print job information]
2026-02-16 17:46:08.506191 | # Job Information
2026-02-16 17:46:08.506320 | Ansible Version: 2.16.15
2026-02-16 17:46:08.506444 | Job: magnum-cluster-api-sonobuoy-v1.34.4-cilium
2026-02-16 17:46:08.506470 | Pipeline: check
2026-02-16 17:46:08.506492 | Executor: 3a2793d2bd32
2026-02-16 17:46:08.506512 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:46:08.506537 | Event ID: 37691bf0-0b5f-11f1-8a15-a065d2c16437
2026-02-16 17:46:08.509498 | 
2026-02-16 17:46:08.509594 | LOOP [emit-job-header : Print node information]
2026-02-16 17:46:09.274402 | localhost | ok:
2026-02-16 17:46:09.274610 | localhost | # Node Information
2026-02-16 17:46:09.274651 | localhost | Inventory Hostname: controller
2026-02-16 17:46:09.274684 | localhost | Hostname: np0000155635
2026-02-16 17:46:09.274712 | localhost | Username: zuul
2026-02-16 17:46:09.274744 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:46:09.274773 | localhost | Provider: yul1
2026-02-16 17:46:09.274801 | localhost | Region: ca-ymq-1
2026-02-16 17:46:09.274827 | localhost | Label: ubuntu-noble-16
2026-02-16 17:46:09.274854 | localhost | Product Name: OpenStack Nova
2026-02-16 17:46:09.274882 | localhost | Interface IP: 199.204.45.35
2026-02-16 17:46:09.299597 | 
2026-02-16 17:46:09.299790 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:46:09.690103 | localhost -> localhost | changed
2026-02-16 17:46:09.695890 | 
2026-02-16 17:46:09.695991 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:46:10.616470 | localhost -> localhost | changed
2026-02-16 17:46:10.624351 | 
2026-02-16 17:46:10.624410 | PLAY [all]
2026-02-16 17:46:10.634655 | 
2026-02-16 17:46:10.634723 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:46:10.894497 | controller -> localhost | ok
2026-02-16 17:46:10.901599 | 
2026-02-16 17:46:10.901668 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:46:10.934084 | controller | ok
2026-02-16 17:46:10.951519 | controller | included: /var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:46:10.957759 | 
2026-02-16 17:46:10.957846 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:46:11.841331 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:46:11.841602 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/work/1a898b7b16cf44a5997712f7858d7d18_id_rsa
2026-02-16 17:46:11.841667 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/work/1a898b7b16cf44a5997712f7858d7d18_id_rsa.pub
2026-02-16 17:46:11.841704 | controller -> localhost | The key fingerprint is:
2026-02-16 17:46:11.841736 | controller -> localhost | SHA256:hM9oyASl+Jm+lu2LiEW9BiQEmy+BhCDIjgDaw5R/TJc zuul-build-sshkey
2026-02-16 17:46:11.841779 | controller -> localhost | The key's randomart image is:
2026-02-16 17:46:11.841810 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:46:11.841850 | controller -> localhost | |%ooo.    .       |
2026-02-16 17:46:11.841881 | controller -> localhost | |XO.o  ..E        |
2026-02-16 17:46:11.841910 | controller -> localhost | |% *..o...        |
2026-02-16 17:46:11.841939 | controller -> localhost | |.B B..o=         |
2026-02-16 17:46:11.841967 | controller -> localhost | |. B +.o S        |
2026-02-16 17:46:11.841994 | controller -> localhost | | + . o           |
2026-02-16 17:46:11.842021 | controller -> localhost | |  ooo            |
2026-02-16 17:46:11.842050 | controller -> localhost | |.oo+.            |
2026-02-16 17:46:11.842082 | controller -> localhost | |o.o.o.           |
2026-02-16 17:46:11.842111 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:46:11.842181 | controller -> localhost | ok: Runtime: 0:00:00.447883
2026-02-16 17:46:11.849240 | 
2026-02-16 17:46:11.849307 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:46:11.880279 | controller | ok
2026-02-16 17:46:11.889271 | controller | included: /var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:46:11.897521 | 
2026-02-16 17:46:11.897647 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:46:11.924315 | controller | skipping: Conditional result was False
2026-02-16 17:46:11.935161 | 
2026-02-16 17:46:11.935298 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:46:12.490988 | controller | changed
2026-02-16 17:46:12.508988 | 
2026-02-16 17:46:12.509088 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:46:12.746666 | controller | ok
2026-02-16 17:46:12.754242 | 
2026-02-16 17:46:12.754334 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:46:13.321033 | controller | changed
2026-02-16 17:46:13.327114 | 
2026-02-16 17:46:13.327228 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:46:13.896846 | controller | changed
2026-02-16 17:46:13.904170 | 
2026-02-16 17:46:13.904234 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:46:13.928436 | controller | skipping: Conditional result was False
2026-02-16 17:46:13.940515 | 
2026-02-16 17:46:13.940583 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:46:14.298927 | controller -> localhost | changed
2026-02-16 17:46:14.315178 | 
2026-02-16 17:46:14.315245 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:46:14.638615 | controller -> localhost | Identity added: /var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/work/1a898b7b16cf44a5997712f7858d7d18_id_rsa (zuul-build-sshkey)
2026-02-16 17:46:14.638843 | controller -> localhost | ok: Runtime: 0:00:00.014125
2026-02-16 17:46:14.680157 | 
2026-02-16 17:46:14.680309 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:46:14.999696 | controller | ok
2026-02-16 17:46:15.007547 | 
2026-02-16 17:46:15.007642 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:46:15.033305 | controller | skipping: Conditional result was False
2026-02-16 17:46:15.049508 | 
2026-02-16 17:46:15.049618 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:46:15.371954 | controller | ok
2026-02-16 17:46:15.379240 | 
2026-02-16 17:46:15.379313 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:46:54.122756 | controller | Output suppressed because no_log was given
2026-02-16 17:46:54.133094 | 
2026-02-16 17:46:54.133174 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:46:54.358459 | controller | ok: "logs"
2026-02-16 17:46:54.358700 | controller | ok: All items complete
2026-02-16 17:46:54.358729 | 
2026-02-16 17:46:54.537479 | controller | ok: "artifacts"
2026-02-16 17:46:54.734062 | controller | ok: "docs"
2026-02-16 17:46:54.753647 | 
2026-02-16 17:46:54.753837 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:46:55.142438 | controller | changed: "logs"
2026-02-16 17:46:55.346818 | controller | changed: "artifacts"
2026-02-16 17:46:55.591802 | controller | changed: "docs"
2026-02-16 17:46:55.612343 | 
2026-02-16 17:46:55.612483 | PLAY RECAP
2026-02-16 17:46:55.612534 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:46:55.612563 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:46:55.612584 | 
2026-02-16 17:46:55.770690 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:46:55.775690 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:46:56.384086 | 
2026-02-16 17:46:56.384825 | PLAY [all]
2026-02-16 17:46:56.402358 | 
2026-02-16 17:46:56.402445 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:46:56.802141 | controller | changed
2026-02-16 17:46:56.810337 | 
2026-02-16 17:46:56.810416 | TASK [Gather minimum local MTU]
2026-02-16 17:46:56.881745 | controller | ok
2026-02-16 17:46:56.886836 | 
2026-02-16 17:46:56.886901 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:46:56.938644 | controller | ok
2026-02-16 17:46:56.945325 | 
2026-02-16 17:46:56.945395 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:46:56.968359 | controller | skipping: Conditional result was False
2026-02-16 17:46:56.981395 | 
2026-02-16 17:46:56.981483 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:46:57.538230 | controller | ok: Runtime: 0:00:00.024922
2026-02-16 17:46:57.546778 | 
2026-02-16 17:46:57.546913 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:46:57.573585 | controller | skipping: Conditional result was False
2026-02-16 17:46:57.586281 | 
2026-02-16 17:46:57.586401 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:46:57.612948 | controller | skipping: Conditional result was False
2026-02-16 17:46:57.622142 | 
2026-02-16 17:46:57.622248 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:46:57.690598 | controller | ok
2026-02-16 17:46:57.705578 | controller | included: /var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:46:57.715008 | 
2026-02-16 17:46:57.715118 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:46:57.778375 | controller | ok
2026-02-16 17:46:57.785488 | 
2026-02-16 17:46:57.785596 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:46:58.096385 | controller | ext4
2026-02-16 17:46:58.388287 | controller | ok: Runtime: 0:00:00.026875
2026-02-16 17:46:58.394132 | 
2026-02-16 17:46:58.394208 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:46:58.428478 | controller | ok
2026-02-16 17:46:58.434165 | 
2026-02-16 17:46:58.434276 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:46:58.487707 | controller | ok:
2026-02-16 17:46:58.619840 | controller | {
2026-02-16 17:46:58.620102 | controller |   "root_filesystem": "ext4"
2026-02-16 17:46:58.620160 | controller | }
2026-02-16 17:46:58.633693 | 
2026-02-16 17:46:58.633767 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:47:09.145016 | controller | 8192+0 records in
2026-02-16 17:47:09.145248 | controller | 8192+0 records out
2026-02-16 17:47:09.145277 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.2164 s, 841 MB/s
2026-02-16 17:47:09.206746 | controller | ok: Runtime: 0:00:10.233985
2026-02-16 17:47:09.212351 | 
2026-02-16 17:47:09.212449 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:47:09.466010 | controller | changed
2026-02-16 17:47:09.474765 | 
2026-02-16 17:47:09.474898 | TASK [configure-swap : Make swapfile]
2026-02-16 17:48:52.512880 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:48:52.513102 | controller | no label, UUID=7b08aaf6-878c-4f45-b48e-a21bd224d4b6
2026-02-16 17:48:52.777581 | controller | ok: Runtime: 0:01:42.802700
2026-02-16 17:48:52.785439 | 
2026-02-16 17:48:52.785506 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:48:53.186353 | controller | changed
2026-02-16 17:48:53.191842 | 
2026-02-16 17:48:53.191906 | TASK [configure-swap : Add all swap]
2026-02-16 17:48:53.733344 | controller | ok: Runtime: 0:00:00.101422
2026-02-16 17:48:53.739417 | 
2026-02-16 17:48:53.739486 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:48:53.773716 | controller | ok:
2026-02-16 17:48:53.773882 | controller | {
2026-02-16 17:48:53.773917 | controller |   "swap_required": "8192"
2026-02-16 17:48:53.773945 | controller | }
2026-02-16 17:48:53.780088 | 
2026-02-16 17:48:53.780158 | TASK [configure-swap : Set swappiness]
2026-02-16 17:48:54.210947 | controller | changed
2026-02-16 17:48:54.217277 | 
2026-02-16 17:48:54.217345 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:48:54.250437 | controller | ok:
2026-02-16 17:48:54.250582 | controller | {
2026-02-16 17:48:54.250616 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:48:54.250649 | controller | }
2026-02-16 17:48:54.261217 | 
2026-02-16 17:48:54.261282 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:48:55.617136 | controller | changed
2026-02-16 17:48:55.623674 | 
2026-02-16 17:48:55.623740 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:48:55.869505 | controller | changed
2026-02-16 17:48:56.037226 | 
2026-02-16 17:48:56.037350 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:48:59.477205 | controller | changed
2026-02-16 17:48:59.804573 | 
2026-02-16 17:48:59.804685 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:49:00.066593 | controller | changed
2026-02-16 17:49:00.072805 | 
2026-02-16 17:49:00.072871 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:49:01.211182 | controller | changed
2026-02-16 17:49:01.243601 | 
2026-02-16 17:49:01.243698 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:49:01.492637 | controller | changed
2026-02-16 17:49:01.505060 | 
2026-02-16 17:49:01.505237 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:49:02.596770 | controller | changed
2026-02-16 17:49:02.602093 | 
2026-02-16 17:49:02.602160 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:49:05.198845 | controller | changed
2026-02-16 17:49:05.241019 | 
2026-02-16 17:49:05.241189 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:49:06.059854 | controller | changed
2026-02-16 17:49:06.070394 | 
2026-02-16 17:49:06.070497 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:49:06.379676 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:49:06.565457 | 
2026-02-16 17:49:06.565571 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:49:07.027199 | controller | ok: Item: Runtime: 0:00:00.195103
2026-02-16 17:49:07.555748 | controller | ok: Item: Runtime: 0:00:00.322232
2026-02-16 17:49:08.280820 | controller | ok: Item: Runtime: 0:00:00.504778
2026-02-16 17:49:08.571648 | controller | ok: Item: Runtime: 0:00:00.062211
2026-02-16 17:49:09.061248 | controller | ok: Item: Runtime: 0:00:00.271437
2026-02-16 17:49:10.736201 | controller | ok: Item: Runtime: 0:00:01.444817
2026-02-16 17:49:14.119696 | controller | ok: Item: Runtime: 0:00:03.166630
2026-02-16 17:49:14.501354 | controller | ok: Item: Runtime: 0:00:00.099304
2026-02-16 17:49:14.851485 | controller | ok: Item: Runtime: 0:00:00.192442
2026-02-16 17:49:16.157501 | controller | ok: Item: Runtime: 0:00:00.117501
2026-02-16 17:49:16.269216 | controller | ok: Item: Runtime: 0:00:00.486550
2026-02-16 17:49:16.653146 | controller | ok: Item: Runtime: 0:00:00.529135
2026-02-16 17:49:17.093863 | controller | ok: Item: Runtime: 0:00:00.220162
2026-02-16 17:49:17.532397 | controller | ok: Item: Runtime: 0:00:00.215834
2026-02-16 17:49:18.114857 | controller | ok: Item: Runtime: 0:00:00.375039
2026-02-16 17:49:19.627519 | controller | ok: Item: Runtime: 0:00:01.284679
2026-02-16 17:49:19.651834 | 
2026-02-16 17:49:19.651991 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:49:19.865320 | controller | ok: All paths examined
2026-02-16 17:49:19.881970 | 
2026-02-16 17:49:19.882096 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:49:20.118719 | controller | ok: All paths examined
2026-02-16 17:49:20.125667 | 
2026-02-16 17:49:20.125742 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:49:20.649359 | controller | ok: Item: Runtime: 0:00:00.228130
2026-02-16 17:49:20.649699 | controller | changed: All items complete
2026-02-16 17:49:20.649751 | 
2026-02-16 17:49:21.079408 | controller | ok: Item: Runtime: 0:00:00.204192
2026-02-16 17:49:21.104120 | 
2026-02-16 17:49:21.104303 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:49:21.147678 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.150076 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.152293 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.154993 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.158082 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.161118 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.164631 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.167948 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.171170 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.174102 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.177206 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.180075 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.183159 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.186152 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.189436 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.192606 | controller | skipping: Conditional result was False
2026-02-16 17:49:21.233310 | 
2026-02-16 17:49:21.233477 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:49:23.594560 | controller | changed
2026-02-16 17:49:23.607960 | 
2026-02-16 17:49:23.608211 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:49:23.861454 | controller | changed
2026-02-16 17:49:23.913206 | 
2026-02-16 17:49:23.913350 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:49:24.170488 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:49:24.459171 | controller | ERROR
2026-02-16 17:49:24.462188 | controller | {
2026-02-16 17:49:24.462274 | controller |   "delta": "0:00:00.011211",
2026-02-16 17:49:24.462342 | controller |   "end": "2026-02-16 17:49:24.170912",
2026-02-16 17:49:24.462406 | controller |   "msg": "non-zero return code",
2026-02-16 17:49:24.462437 | controller |   "rc": 1,
2026-02-16 17:49:24.462491 | controller |   "start": "2026-02-16 17:49:24.159701"
2026-02-16 17:49:24.462520 | controller | }
2026-02-16 17:49:24.462585 | controller | ERROR: Ignoring Errors
2026-02-16 17:49:24.470550 | 
2026-02-16 17:49:24.470642 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:49:24.705779 | controller | ok
2026-02-16 17:49:24.716875 | 
2026-02-16 17:49:24.716946 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:49:24.956092 | controller | /usr/bin/journalctl
2026-02-16 17:49:25.256791 | controller | ok: Runtime: 0:00:00.006850
2026-02-16 17:49:25.264438 | 
2026-02-16 17:49:25.264504 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:49:25.524560 | controller | 2026-02-16 17:49:25
2026-02-16 17:49:25.802463 | controller | ok: Runtime: 0:00:00.009083
2026-02-16 17:49:25.808953 | 
2026-02-16 17:49:25.809050 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:49:26.411363 | controller | changed
2026-02-16 17:49:26.419014 | 
2026-02-16 17:49:26.419081 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:49:26.443719 | controller | skipping: Conditional result was False
2026-02-16 17:49:26.454074 | 
2026-02-16 17:49:26.454169 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:49:26.988347 | controller | skipping: Conditional result was False
2026-02-16 17:49:27.000348 | 
2026-02-16 17:49:27.000452 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:49:27.533419 | controller | skipping: Conditional result was False
2026-02-16 17:49:27.546485 | 
2026-02-16 17:49:27.546591 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:49:27.571353 | controller | skipping: Conditional result was False
2026-02-16 17:49:27.582079 | 
2026-02-16 17:49:27.582178 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:49:28.113551 | controller | skipping: Conditional result was False
2026-02-16 17:49:28.127448 | 
2026-02-16 17:49:28.127549 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:49:28.655641 | controller | skipping: Conditional result was False
2026-02-16 17:49:28.667917 | 
2026-02-16 17:49:28.667993 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:49:28.693590 | controller | skipping: Conditional result was False
2026-02-16 17:49:28.705127 | 
2026-02-16 17:49:28.705209 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:49:28.721690 | controller | skipping: Conditional result was False
2026-02-16 17:49:28.732086 | 
2026-02-16 17:49:28.732210 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:49:29.315914 | controller | skipping: Conditional result was False
2026-02-16 17:49:29.327414 | 
2026-02-16 17:49:29.327510 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:49:29.857772 | controller | skipping: Conditional result was False
2026-02-16 17:49:29.865211 | 
2026-02-16 17:49:29.865284 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:49:29.890007 | controller | skipping: Conditional result was False
2026-02-16 17:49:29.900109 | 
2026-02-16 17:49:29.900209 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:49:29.925173 | controller | skipping: Conditional result was False
2026-02-16 17:49:29.941114 | 
2026-02-16 17:49:29.941187 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:49:30.349839 | controller | ok
2026-02-16 17:49:30.359036 | 
2026-02-16 17:49:30.359086 | PLAY RECAP
2026-02-16 17:49:30.359132 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:49:30.359154 | 
2026-02-16 17:49:30.494977 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:49:30.499982 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:49:31.170568 | 
2026-02-16 17:49:31.170721 | PLAY [all]
2026-02-16 17:49:31.182808 | 
2026-02-16 17:49:31.182883 | TASK [Install curl]
2026-02-16 17:49:31.826979 | controller | ERROR
2026-02-16 17:49:31.827312 | controller | {
2026-02-16 17:49:31.827369 | controller |   "msg": "No package matching 'curl' is available"
2026-02-16 17:49:31.827411 | controller | }
failure
2026-02-16 17:49:31.828983 | 
2026-02-16 17:49:31.829045 | PLAY RECAP
2026-02-16 17:49:31.829118 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:49:31.829151 | 
2026-02-16 17:49:31.942853 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:49:31.948123 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:49:32.575086 | 
2026-02-16 17:49:32.575548 | PLAY [all]
2026-02-16 17:49:32.592229 | 
2026-02-16 17:49:32.592316 | TASK [Return built artifacts to Zuul]
2026-02-16 17:49:32.618647 | controller | ok
2026-02-16 17:49:32.625539 | 
2026-02-16 17:49:32.625623 | TASK [Get the Docker volume ID]
2026-02-16 17:49:33.168253 | controller | ERROR
2026-02-16 17:49:33.168580 | controller | {
2026-02-16 17:49:33.168613 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:49:33.168636 | controller |   "rc": 2
2026-02-16 17:49:33.168658 | controller | }
2026-02-16 17:49:33.168686 | controller | ERROR: Ignoring Errors
2026-02-16 17:49:33.180284 | 
2026-02-16 17:49:33.180455 | TASK [Copy all of the pod logs]
2026-02-16 17:49:33.218772 | controller | ERROR
2026-02-16 17:49:33.239442 | controller | {
2026-02-16 17:49:33.239546 | 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/1a898b7b16cf44a5997712f7858d7d18/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:49:33.239602 | controller | }
2026-02-16 17:49:33.239668 | controller | ERROR: Ignoring Errors
2026-02-16 17:49:33.241705 | 
2026-02-16 17:49:33.241808 | PLAY RECAP
2026-02-16 17:49:33.241895 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:49:33.241942 | 
2026-02-16 17:49:33.429161 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:49:33.441697 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:49:34.089689 | 
2026-02-16 17:49:34.089836 | PLAY [all]
2026-02-16 17:49:34.108351 | 
2026-02-16 17:49:34.108446 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:49:34.515027 | controller | changed
2026-02-16 17:49:34.522596 | 
2026-02-16 17:49:34.522681 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:49:35.590353 | controller | ok: Runtime: 0:00:00.497007
2026-02-16 17:49:35.633251 | 
2026-02-16 17:49:35.633353 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:49:36.173742 | controller | ok: Runtime: 0:00:00.020184
2026-02-16 17:49:36.181327 | 
2026-02-16 17:49:36.181419 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:49:36.738537 | controller | ok: Runtime: 0:00:00.041705
2026-02-16 17:49:36.746101 | 
2026-02-16 17:49:36.746171 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:49:37.628167 | controller | changed
2026-02-16 17:49:37.673782 | 
2026-02-16 17:49:37.673930 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:49:37.918012 | controller | changed
2026-02-16 17:49:37.924369 | 
2026-02-16 17:49:37.924453 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:49:38.284816 | controller | Output suppressed because no_log was given
2026-02-16 17:49:38.294426 | 
2026-02-16 17:49:38.294627 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:49:38.333466 | 
2026-02-16 17:49:38.333602 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:49:38.371785 | 
2026-02-16 17:49:38.573980 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:49:38.608439 | 
2026-02-16 17:49:38.608993 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:49:38.681676 | 
2026-02-16 17:49:38.681873 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:49:38.722685 | 
2026-02-16 17:49:38.722849 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:49:39.024158 | controller | changed
2026-02-16 17:49:39.106240 | 
2026-02-16 17:49:39.106327 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:49:39.141547 | controller | ok
2026-02-16 17:49:39.146868 | 
2026-02-16 17:49:39.146934 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:49:39.385010 | controller | Output suppressed because no_log was given
2026-02-16 17:49:39.392136 | 
2026-02-16 17:49:39.392233 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:49:39.429336 | 
2026-02-16 17:49:39.429502 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:49:39.462146 | 
2026-02-16 17:49:39.462360 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:49:40.209641 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:49:40.235270 | controller | Using python 3.12 to install setuptools
2026-02-16 17:49:40.292888 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:49:40.304913 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:49:40.514508 | controller | ERROR
2026-02-16 17:49:40.514737 | controller | {
2026-02-16 17:49:40.514775 | controller |   "delta": "0:00:00.597541",
2026-02-16 17:49:40.514798 | controller |   "end": "2026-02-16 17:49:40.307220",
2026-02-16 17:49:40.514818 | controller |   "msg": "non-zero return code",
2026-02-16 17:49:40.514847 | controller |   "rc": 127,
2026-02-16 17:49:40.514867 | controller |   "start": "2026-02-16 17:49:39.709679"
2026-02-16 17:49:40.514886 | controller | }
2026-02-16 17:49:40.514914 | controller | ERROR: Ignoring Errors
2026-02-16 17:49:40.527127 | 
2026-02-16 17:49:40.527259 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:49:40.793699 | controller | changed
2026-02-16 17:49:40.799286 | 
2026-02-16 17:49:40.799352 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:49:44.736770 | controller | Output suppressed because no_log was given
2026-02-16 17:49:44.766578 | 
2026-02-16 17:49:44.766701 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:49:44.804723 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.805266 | 
2026-02-16 17:49:44.807238 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.809041 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.810824 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.812801 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.815205 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.817644 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.820293 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.822711 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.828288 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.831069 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.833499 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.835828 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.837914 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.840285 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.842778 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.845126 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.848572 | controller | skipping: Conditional result was False
2026-02-16 17:49:44.878134 | 
2026-02-16 17:49:44.878318 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:49:45.122182 | controller | ok
2026-02-16 17:49:45.127841 | 
2026-02-16 17:49:45.127910 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:49:45.225947 | controller | skipping: Conditional result was False
2026-02-16 17:49:45.696126 | 
2026-02-16 17:49:45.696282 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:49:45.969773 | controller | sudo: iptables-save: command not found
2026-02-16 17:49:46.010877 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:49:47.060680 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:49:47.234835 | controller | ok: Runtime: 0:00:01.112437
2026-02-16 17:49:47.254306 | 
2026-02-16 17:49:47.254380 | LOOP [stage-output : Register sources]
2026-02-16 17:49:57.817005 | controller | Output suppressed because no_log was given
2026-02-16 17:49:57.911816 | 
2026-02-16 17:49:57.911997 | TASK [stage-output : Check sudo]
2026-02-16 17:49:58.454860 | controller | ok: Runtime: 0:00:00.040511
2026-02-16 17:49:58.464013 | 
2026-02-16 17:49:58.464080 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:49:58.560248 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.560793 | 
2026-02-16 17:49:58.562717 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.563777 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.565378 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.601123 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.601549 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.601893 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.603742 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.606082 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.608247 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.610445 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.613514 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.614959 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.617314 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.635004 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.635666 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.636172 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.636666 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.637187 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.639086 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.641209 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.643463 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.645541 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.668759 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.669130 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.743471 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.755202 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.766294 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.773948 | controller | Output suppressed because no_log was given
2026-02-16 17:49:58.787973 | 
2026-02-16 17:49:58.788060 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:49:58.854589 | controller | ok
2026-02-16 17:49:58.862622 | 
2026-02-16 17:49:58.862693 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:49:59.113871 | controller | changed: "docs"
2026-02-16 17:49:59.344458 | controller | changed: "artifacts"
2026-02-16 17:49:59.545351 | controller | ok: "logs"
2026-02-16 17:49:59.560234 | 
2026-02-16 17:49:59.560412 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:49:59.864345 | controller | ok: Item: Runtime: 0:00:00.031584
2026-02-16 17:49:59.864585 | controller | changed: All items complete
2026-02-16 17:49:59.864613 | 
2026-02-16 17:50:00.084416 | controller | ok: Item: Runtime: 0:00:00.007905
2026-02-16 17:50:00.317620 | controller | ok: Item: Runtime: 0:00:00.008543
2026-02-16 17:50:00.544727 | controller | ok: Item: Runtime: 0:00:00.007209
2026-02-16 17:50:00.842731 | controller | ok: Item: Runtime: 0:00:00.019374
2026-02-16 17:50:01.070189 | controller | ok: Item: Runtime: 0:00:00.007713
2026-02-16 17:50:01.298184 | controller | ok: Item: Runtime: 0:00:00.007994
2026-02-16 17:50:01.509363 | controller | ok: Item: Runtime: 0:00:00.007866
2026-02-16 17:50:01.757487 | controller | ok: Item: Runtime: 0:00:00.009030
2026-02-16 17:50:01.984494 | controller | ok: Item: Runtime: 0:00:00.007918
2026-02-16 17:50:02.214465 | controller | ok: Item: Runtime: 0:00:00.007421
2026-02-16 17:50:02.491452 | controller | ok: Item: Runtime: 0:00:00.005792
2026-02-16 17:50:02.702556 | controller | ok: Item: Runtime: 0:00:00.008147
2026-02-16 17:50:02.927791 | controller | ok: Item: Runtime: 0:00:00.007631
2026-02-16 17:50:03.155980 | controller | ok: Item: Runtime: 0:00:00.008104
2026-02-16 17:50:03.387055 | controller | ok: Item: Runtime: 0:00:00.007688
2026-02-16 17:50:03.405887 | 
2026-02-16 17:50:03.405958 | TASK [stage-output : Make all log files readable]
2026-02-16 17:50:03.662391 | controller | changed
2026-02-16 17:50:03.671292 | 
2026-02-16 17:50:03.671359 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:50:03.988757 | controller | changed: Renamed files for staging.
2026-02-16 17:50:04.452576 | 
2026-02-16 17:50:04.452681 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:50:04.477364 | controller | skipping: Conditional result was False
2026-02-16 17:50:04.489480 | 
2026-02-16 17:50:04.489565 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:50:04.531297 | 
2026-02-16 17:50:04.572142 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:50:05.238473 | controller | changed:
2026-02-16 17:50:05.259055 | controller | created directory /var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/work/logs/controller
2026-02-16 17:50:05.259271 | controller | cd+++++++++ logs/
2026-02-16 17:50:05.259393 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:50:05.259475 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:50:05.259540 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:50:05.259590 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:50:05.259636 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:50:05.259681 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:50:05.259743 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:50:05.259854 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:50:05.259928 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:50:05.259997 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:50:05.260069 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:50:05.260141 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:50:05.260309 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:50:05.260744 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:50:05.260940 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:50:05.261091 | controller | cd+++++++++ logs/apache/
2026-02-16 17:50:05.261190 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:50:05.261286 | controller | cd+++++++++ logs/etc/
2026-02-16 17:50:05.261382 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:50:05.261479 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:50:05.261575 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:50:05.261627 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:50:05.261698 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:50:05.261807 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:50:05.281060 | 
2026-02-16 17:50:05.281145 | TASK [Check if a tempest log exits]
2026-02-16 17:50:05.537839 | controller | ok
2026-02-16 17:50:05.544406 | 
2026-02-16 17:50:05.544482 | TASK [Link post-devstack tempest.log]
2026-02-16 17:50:05.576243 | controller | skipping: Conditional result was False
2026-02-16 17:50:05.585422 | 
2026-02-16 17:50:05.585527 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:50:05.889564 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:50:06.130627 | controller | ERROR
2026-02-16 17:50:06.130868 | controller | {
2026-02-16 17:50:06.130914 | controller |   "delta": "0:00:00.007051",
2026-02-16 17:50:06.130944 | controller |   "end": "2026-02-16 17:50:05.890051",
2026-02-16 17:50:06.130971 | controller |   "msg": "non-zero return code",
2026-02-16 17:50:06.130996 | controller |   "rc": 127,
2026-02-16 17:50:06.131022 | controller |   "start": "2026-02-16 17:50:05.883000"
2026-02-16 17:50:06.131047 | controller | }
2026-02-16 17:50:06.131081 | controller | ERROR: Ignoring Errors
2026-02-16 17:50:06.133466 | 
2026-02-16 17:50:06.133533 | PLAY RECAP
2026-02-16 17:50:06.133601 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:50:06.133633 | 
2026-02-16 17:50:06.389146 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:50:06.412107 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:50:07.100893 | 
2026-02-16 17:50:07.101060 | PLAY [all]
2026-02-16 17:50:07.113384 | 
2026-02-16 17:50:07.113470 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:50:07.159543 | controller | skipping: Conditional result was False
2026-02-16 17:50:07.293652 | 
2026-02-16 17:50:07.293808 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:50:07.342389 | controller | ok
2026-02-16 17:50:07.440830 | 
2026-02-16 17:50:07.487885 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:50:08.454038 | controller -> localhost | ok: "/var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/work/logs"
2026-02-16 17:50:08.683919 | controller -> localhost | changed: "/var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/work/artifacts"
2026-02-16 17:50:09.462923 | controller -> localhost | changed: "/var/lib/zuul/builds/1a898b7b16cf44a5997712f7858d7d18/work/docs"
2026-02-16 17:50:09.479454 | 
2026-02-16 17:50:09.479585 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:50:11.731405 | controller | changed: .d..t...... ./
2026-02-16 17:50:11.731795 | controller | changed: All items complete
2026-02-16 17:50:11.731835 | 
2026-02-16 17:50:12.248053 | controller | changed: .d..t...... ./
2026-02-16 17:50:12.772714 | controller | changed: .d..t...... ./
2026-02-16 17:50:12.797789 | 
2026-02-16 17:50:12.797965 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:50:13.246283 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007532
2026-02-16 17:50:13.476647 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007612
2026-02-16 17:50:13.495611 | 
2026-02-16 17:50:13.495731 | PLAY [all]
2026-02-16 17:50:13.505729 | 
2026-02-16 17:50:13.505854 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:50:13.993880 | controller | changed
2026-02-16 17:50:14.000172 | 
2026-02-16 17:50:14.000221 | PLAY RECAP
2026-02-16 17:50:14.000265 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:50:14.000287 | 
2026-02-16 17:50:14.164629 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:50:14.172036 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:50:14.804375 | 
2026-02-16 17:50:15.734551 | PLAY [localhost]
2026-02-16 17:50:15.748671 | 
2026-02-16 17:50:15.748781 | TASK [Generate Zuul manifest]
2026-02-16 17:50:15.771184 | localhost | ok
2026-02-16 17:50:15.788905 | 
2026-02-16 17:50:15.789004 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:50:16.336702 | localhost | changed
2026-02-16 17:50:16.349891 | 
2026-02-16 17:50:16.349962 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:50:16.509719 | localhost | ok
2026-02-16 17:50:16.518140 | 
2026-02-16 17:50:16.518204 | TASK [Upload logs]
2026-02-16 17:50:16.540686 | localhost | ok
2026-02-16 17:50:16.603157 | 
2026-02-16 17:50:16.603325 | TASK [Set zuul-log-path fact]
2026-02-16 17:50:16.624908 | localhost | ok
2026-02-16 17:50:16.638293 | 
2026-02-16 17:50:16.638411 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:50:16.702910 | localhost | ok
2026-02-16 17:50:16.710499 | 
2026-02-16 17:50:16.710574 | TASK [upload-logs : Create log directories]
2026-02-16 17:50:17.258578 | localhost | changed
2026-02-16 17:50:17.265137 | 
2026-02-16 17:50:17.265224 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:50:17.695124 | localhost -> localhost | ok: Runtime: 0:00:00.004484
2026-02-16 17:50:17.699927 | 
2026-02-16 17:50:17.699994 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:50:18.159586 | localhost | Output suppressed because no_log was given
2026-02-16 17:50:18.165367 | 
2026-02-16 17:50:18.165461 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:50:18.217210 | localhost | skipping: Conditional result was False
2026-02-16 17:50:18.227532 | localhost | skipping: Conditional result was False
2026-02-16 17:50:18.240178 | 
2026-02-16 17:50:18.240382 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:50:18.284395 | localhost | skipping: Conditional result was False
2026-02-16 17:50:18.285266 | 
2026-02-16 17:50:18.287471 | localhost | skipping: Conditional result was False
2026-02-16 17:50:18.295901 | 
2026-02-16 17:50:18.297088 | LOOP [upload-logs : Upload console log and json output]