2026-02-16 17:38:04.566977 | Job console starting
2026-02-16 17:38:04.574942 | Updating git repos
2026-02-16 17:38:08.988536 | Cloning repos into workspace
2026-02-16 17:38:17.184336 | Restoring repo states
2026-02-16 17:38:18.295539 | Merging changes
2026-02-16 17:38:19.088225 | Checking out repos
2026-02-16 17:38:24.123659 | Preparing playbooks
2026-02-16 17:38:27.252369 | Running Ansible setup
2026-02-16 17:38:31.654051 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:38:32.294672 | 
2026-02-16 17:38:32.295003 | PLAY [localhost]
2026-02-16 17:38:32.303764 | 
2026-02-16 17:38:32.317099 | TASK [Gathering Facts]
2026-02-16 17:38:33.240316 | localhost | ok
2026-02-16 17:38:33.252371 | 
2026-02-16 17:38:33.252451 | TASK [Setup log path fact]
2026-02-16 17:38:33.273497 | localhost | ok
2026-02-16 17:38:33.291004 | 
2026-02-16 17:38:33.291222 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:38:33.322718 | localhost | ok
2026-02-16 17:38:33.335195 | 
2026-02-16 17:38:33.335834 | TASK [emit-job-header : Print job information]
2026-02-16 17:38:33.380832 | # Job Information
2026-02-16 17:38:33.381093 | Ansible Version: 2.16.15
2026-02-16 17:38:33.381142 | Job: magnum-cluster-api-sonobuoy-v1.33.8-cilium
2026-02-16 17:38:33.381175 | Pipeline: check
2026-02-16 17:38:33.381204 | Executor: 3a2793d2bd32
2026-02-16 17:38:33.381232 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:38:33.381264 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:38:33.385353 | 
2026-02-16 17:38:33.385430 | LOOP [emit-job-header : Print node information]
2026-02-16 17:38:33.485118 | localhost | ok:
2026-02-16 17:38:33.485309 | localhost | # Node Information
2026-02-16 17:38:33.485345 | localhost | Inventory Hostname: controller
2026-02-16 17:38:33.485375 | localhost | Hostname: np0000155612
2026-02-16 17:38:33.485401 | localhost | Username: zuul
2026-02-16 17:38:33.485429 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:38:33.485456 | localhost | Provider: yul1
2026-02-16 17:38:33.485480 | localhost | Region: ca-ymq-1
2026-02-16 17:38:33.485504 | localhost | Label: ubuntu-noble-16
2026-02-16 17:38:33.485527 | localhost | Product Name: OpenStack Nova
2026-02-16 17:38:33.485551 | localhost | Interface IP: 199.204.45.102
2026-02-16 17:38:33.496391 | 
2026-02-16 17:38:33.496587 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:38:34.050503 | localhost -> localhost | changed
2026-02-16 17:38:34.059804 | 
2026-02-16 17:38:34.059909 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:38:34.976866 | localhost -> localhost | changed
2026-02-16 17:38:34.986432 | 
2026-02-16 17:38:34.986502 | PLAY [all]
2026-02-16 17:38:34.995068 | 
2026-02-16 17:38:34.995137 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:38:35.255996 | controller -> localhost | ok
2026-02-16 17:38:35.407655 | 
2026-02-16 17:38:35.407794 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:38:35.441789 | controller | ok
2026-02-16 17:38:35.458140 | controller | included: /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:38:35.464291 | 
2026-02-16 17:38:35.464370 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:38:36.686771 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:38:36.687016 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/work/81d4f0cf8d904753ae9fd71eebbd1877_id_rsa
2026-02-16 17:38:36.687084 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/work/81d4f0cf8d904753ae9fd71eebbd1877_id_rsa.pub
2026-02-16 17:38:36.687118 | controller -> localhost | The key fingerprint is:
2026-02-16 17:38:36.687144 | controller -> localhost | SHA256:Hig/y/exsdxxNHYJKcWA8ErRJJGtLoywRlkb0e8AndA zuul-build-sshkey
2026-02-16 17:38:36.687187 | controller -> localhost | The key's randomart image is:
2026-02-16 17:38:36.687219 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:38:36.687261 | controller -> localhost | |   o* .=B...o.   |
2026-02-16 17:38:36.687292 | controller -> localhost | |   + E .++  ...  |
2026-02-16 17:38:36.687319 | controller -> localhost | |  o + .... . o   |
2026-02-16 17:38:36.687346 | controller -> localhost | | + . ..+.   . . .|
2026-02-16 17:38:36.687370 | controller -> localhost | |. o + =.S     +..|
2026-02-16 17:38:36.687396 | controller -> localhost | | o . = + .   o o |
2026-02-16 17:38:36.687419 | controller -> localhost | |.     + . o . .  |
2026-02-16 17:38:36.687445 | controller -> localhost | |     . o.. * o   |
2026-02-16 17:38:36.687473 | controller -> localhost | |      o. .= .    |
2026-02-16 17:38:36.687498 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:38:36.687576 | controller -> localhost | ok: Runtime: 0:00:00.794219
2026-02-16 17:38:36.697196 | 
2026-02-16 17:38:36.697345 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:38:36.733148 | controller | ok
2026-02-16 17:38:36.746805 | controller | included: /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:38:36.760556 | 
2026-02-16 17:38:36.760722 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:38:36.791759 | controller | skipping: Conditional result was False
2026-02-16 17:38:36.804538 | 
2026-02-16 17:38:36.804697 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:38:37.645578 | controller | changed
2026-02-16 17:38:37.652398 | 
2026-02-16 17:38:37.652466 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:38:37.882874 | controller | ok
2026-02-16 17:38:37.889454 | 
2026-02-16 17:38:37.889521 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:38:38.474448 | controller | changed
2026-02-16 17:38:38.480793 | 
2026-02-16 17:38:38.480862 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:38:39.060206 | controller | changed
2026-02-16 17:38:39.070093 | 
2026-02-16 17:38:39.070161 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:38:39.094905 | controller | skipping: Conditional result was False
2026-02-16 17:38:39.106289 | 
2026-02-16 17:38:39.106359 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:38:39.504880 | controller -> localhost | changed
2026-02-16 17:38:39.521642 | 
2026-02-16 17:38:39.521806 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:38:39.863497 | controller -> localhost | Identity added: /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/work/81d4f0cf8d904753ae9fd71eebbd1877_id_rsa (zuul-build-sshkey)
2026-02-16 17:38:39.863801 | controller -> localhost | ok: Runtime: 0:00:00.022967
2026-02-16 17:38:39.912011 | 
2026-02-16 17:38:39.912241 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:38:40.241633 | controller | ok
2026-02-16 17:38:40.250331 | 
2026-02-16 17:38:40.250420 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:38:40.276651 | controller | skipping: Conditional result was False
2026-02-16 17:38:40.302228 | 
2026-02-16 17:38:40.302309 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:38:40.646462 | controller | ok
2026-02-16 17:38:40.676813 | 
2026-02-16 17:38:40.676909 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:39:04.945364 | controller | Output suppressed because no_log was given
2026-02-16 17:39:04.955145 | 
2026-02-16 17:39:04.955212 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:39:05.191357 | controller | ok: "logs"
2026-02-16 17:39:05.192471 | controller | ok: All items complete
2026-02-16 17:39:05.192509 | 
2026-02-16 17:39:05.396541 | controller | ok: "artifacts"
2026-02-16 17:39:05.596763 | controller | ok: "docs"
2026-02-16 17:39:05.610461 | 
2026-02-16 17:39:05.610652 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:39:05.828710 | controller | changed: "logs"
2026-02-16 17:39:06.023191 | controller | changed: "artifacts"
2026-02-16 17:39:06.254704 | controller | changed: "docs"
2026-02-16 17:39:06.276917 | 
2026-02-16 17:39:06.277084 | PLAY RECAP
2026-02-16 17:39:06.277134 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:39:06.277163 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:39:06.277188 | 
2026-02-16 17:39:06.438902 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:39:06.444759 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:39:07.090015 | 
2026-02-16 17:39:07.090868 | PLAY [all]
2026-02-16 17:39:07.108398 | 
2026-02-16 17:39:07.108493 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:39:07.521983 | controller | changed
2026-02-16 17:39:07.529005 | 
2026-02-16 17:39:07.529078 | TASK [Gather minimum local MTU]
2026-02-16 17:39:07.604737 | controller | ok
2026-02-16 17:39:07.611769 | 
2026-02-16 17:39:07.611853 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:39:07.665014 | controller | ok
2026-02-16 17:39:07.670051 | 
2026-02-16 17:39:07.670116 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:39:07.699211 | controller | skipping: Conditional result was False
2026-02-16 17:39:07.709226 | 
2026-02-16 17:39:07.709291 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:39:08.254428 | controller | ok: Runtime: 0:00:00.009769
2026-02-16 17:39:08.263376 | 
2026-02-16 17:39:08.263505 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:39:08.296030 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.304867 | 
2026-02-16 17:39:08.304945 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:39:08.329456 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.337969 | 
2026-02-16 17:39:08.338063 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:39:08.393044 | controller | ok
2026-02-16 17:39:08.406258 | controller | included: /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:39:08.412317 | 
2026-02-16 17:39:08.412389 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:39:08.482045 | controller | ok
2026-02-16 17:39:08.487756 | 
2026-02-16 17:39:08.513408 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:39:08.855974 | controller | ext4
2026-02-16 17:39:09.131842 | controller | ok: Runtime: 0:00:00.010373
2026-02-16 17:39:09.137258 | 
2026-02-16 17:39:09.137322 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:39:09.179645 | controller | ok
2026-02-16 17:39:09.188452 | 
2026-02-16 17:39:09.188569 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:39:09.233113 | controller | ok:
2026-02-16 17:39:09.233317 | controller | {
2026-02-16 17:39:09.233346 | controller |   "root_filesystem": "ext4"
2026-02-16 17:39:09.233367 | controller | }
2026-02-16 17:39:09.240750 | 
2026-02-16 17:39:09.240824 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:39:19.566797 | controller | 8192+0 records in
2026-02-16 17:39:19.568136 | controller | 8192+0 records out
2026-02-16 17:39:19.568166 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.0318 s, 856 MB/s
2026-02-16 17:39:19.795667 | controller | ok: Runtime: 0:00:10.044645
2026-02-16 17:39:19.803333 | 
2026-02-16 17:39:19.803422 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:39:20.052598 | controller | changed
2026-02-16 17:39:20.061606 | 
2026-02-16 17:39:20.061696 | TASK [configure-swap : Make swapfile]
2026-02-16 17:40:04.659102 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:40:04.659245 | controller | no label, UUID=501ca590-23b4-4818-9ab8-9bd1908be2d6
2026-02-16 17:40:04.715872 | controller | ok: Runtime: 0:00:44.357500
2026-02-16 17:40:04.723738 | 
2026-02-16 17:40:04.723828 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:40:05.123273 | controller | changed
2026-02-16 17:40:05.131130 | 
2026-02-16 17:40:05.131220 | TASK [configure-swap : Add all swap]
2026-02-16 17:40:05.673401 | controller | ok: Runtime: 0:00:00.046920
2026-02-16 17:40:05.679186 | 
2026-02-16 17:40:05.679251 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:40:05.716393 | controller | ok:
2026-02-16 17:40:05.716750 | controller | {
2026-02-16 17:40:05.716860 | controller |   "swap_required": "8192"
2026-02-16 17:40:05.716915 | controller | }
2026-02-16 17:40:05.722224 | 
2026-02-16 17:40:05.722292 | TASK [configure-swap : Set swappiness]
2026-02-16 17:40:06.174667 | controller | changed
2026-02-16 17:40:06.183894 | 
2026-02-16 17:40:06.183986 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:40:06.219874 | controller | ok:
2026-02-16 17:40:06.220080 | controller | {
2026-02-16 17:40:06.220175 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:40:06.220270 | controller | }
2026-02-16 17:40:06.230887 | 
2026-02-16 17:40:06.230952 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:40:07.076545 | controller | changed
2026-02-16 17:40:07.083620 | 
2026-02-16 17:40:07.083694 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:40:07.329115 | controller | changed
2026-02-16 17:40:07.334944 | 
2026-02-16 17:40:07.335009 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:40:08.703043 | controller | changed
2026-02-16 17:40:08.709141 | 
2026-02-16 17:40:08.709212 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:40:08.962486 | controller | changed
2026-02-16 17:40:08.969926 | 
2026-02-16 17:40:08.970005 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:40:10.034963 | controller | changed
2026-02-16 17:40:10.041880 | 
2026-02-16 17:40:10.041944 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:40:10.294555 | controller | changed
2026-02-16 17:40:10.305644 | 
2026-02-16 17:40:10.305712 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:40:11.103235 | controller | changed
2026-02-16 17:40:11.108980 | 
2026-02-16 17:40:11.109045 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:40:12.482821 | controller | changed
2026-02-16 17:40:12.531259 | 
2026-02-16 17:40:12.531412 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:40:13.313180 | controller | changed
2026-02-16 17:40:13.326042 | 
2026-02-16 17:40:13.326115 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:40:13.660876 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:40:13.672316 | 
2026-02-16 17:40:13.672383 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:40:14.131443 | controller | ok: Item: Runtime: 0:00:00.181756
2026-02-16 17:40:14.674917 | controller | ok: Item: Runtime: 0:00:00.321560
2026-02-16 17:40:15.434043 | controller | ok: Item: Runtime: 0:00:00.537083
2026-02-16 17:40:15.717538 | controller | ok: Item: Runtime: 0:00:00.063662
2026-02-16 17:40:16.179729 | controller | ok: Item: Runtime: 0:00:00.242937
2026-02-16 17:40:17.951996 | controller | ok: Item: Runtime: 0:00:01.553012
2026-02-16 17:40:21.113551 | controller | ok: Item: Runtime: 0:00:02.935644
2026-02-16 17:40:21.421235 | controller | ok: Item: Runtime: 0:00:00.095417
2026-02-16 17:40:21.829353 | controller | ok: Item: Runtime: 0:00:00.188298
2026-02-16 17:40:22.180796 | controller | ok: Item: Runtime: 0:00:00.119593
2026-02-16 17:40:22.966489 | controller | ok: Item: Runtime: 0:00:00.558767
2026-02-16 17:40:23.677667 | controller | ok: Item: Runtime: 0:00:00.497729
2026-02-16 17:40:24.140842 | controller | ok: Item: Runtime: 0:00:00.244658
2026-02-16 17:40:24.575193 | controller | ok: Item: Runtime: 0:00:00.224910
2026-02-16 17:40:25.175189 | controller | ok: Item: Runtime: 0:00:00.380460
2026-02-16 17:40:26.569061 | controller | ok: Item: Runtime: 0:00:01.167624
2026-02-16 17:40:26.601935 | 
2026-02-16 17:40:26.602156 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:40:26.815907 | controller | ok: All paths examined
2026-02-16 17:40:26.828361 | 
2026-02-16 17:40:26.828466 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:40:27.076894 | controller | ok: All paths examined
2026-02-16 17:40:27.085353 | 
2026-02-16 17:40:27.085443 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:40:27.518453 | controller | ok: Item: Runtime: 0:00:00.141981
2026-02-16 17:40:27.518792 | controller | changed: All items complete
2026-02-16 17:40:27.518826 | 
2026-02-16 17:40:27.920783 | controller | ok: Item: Runtime: 0:00:00.185921
2026-02-16 17:40:27.932728 | 
2026-02-16 17:40:27.934897 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:40:27.978961 | controller | skipping: Conditional result was False
2026-02-16 17:40:27.994993 | controller | skipping: Conditional result was False
2026-02-16 17:40:27.996882 | controller | skipping: Conditional result was False
2026-02-16 17:40:27.998500 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.000292 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.001854 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.003012 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.003822 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.004562 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.006534 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.009657 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.013063 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.016134 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.019639 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.022435 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.025825 | controller | skipping: Conditional result was False
2026-02-16 17:40:28.056523 | 
2026-02-16 17:40:28.056676 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:40:30.420428 | controller | changed
2026-02-16 17:40:30.431589 | 
2026-02-16 17:40:30.431656 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:40:30.695481 | controller | changed
2026-02-16 17:40:30.753038 | 
2026-02-16 17:40:30.753177 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:40:31.022779 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:40:31.290971 | controller | ERROR
2026-02-16 17:40:31.291190 | controller | {
2026-02-16 17:40:31.291232 | controller |   "delta": "0:00:00.016568",
2026-02-16 17:40:31.291264 | controller |   "end": "2026-02-16 17:40:31.023306",
2026-02-16 17:40:31.291293 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:31.291322 | controller |   "rc": 1,
2026-02-16 17:40:31.291349 | controller |   "start": "2026-02-16 17:40:31.006738"
2026-02-16 17:40:31.291377 | controller | }
2026-02-16 17:40:31.291412 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:31.299896 | 
2026-02-16 17:40:31.299966 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:40:31.558788 | controller | ok
2026-02-16 17:40:31.571552 | 
2026-02-16 17:40:31.571618 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:40:31.808545 | controller | /usr/bin/journalctl
2026-02-16 17:40:32.112827 | controller | ok: Runtime: 0:00:00.009298
2026-02-16 17:40:32.124514 | 
2026-02-16 17:40:32.124669 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:40:32.383208 | controller | 2026-02-16 17:40:32
2026-02-16 17:40:32.665772 | controller | ok: Runtime: 0:00:00.023114
2026-02-16 17:40:32.673714 | 
2026-02-16 17:40:32.673808 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:40:33.232413 | controller | changed
2026-02-16 17:40:33.238689 | 
2026-02-16 17:40:33.238757 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:40:33.264875 | controller | skipping: Conditional result was False
2026-02-16 17:40:33.277426 | 
2026-02-16 17:40:33.277566 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:40:33.808770 | controller | skipping: Conditional result was False
2026-02-16 17:40:33.821324 | 
2026-02-16 17:40:33.821475 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:40:34.352164 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.359363 | 
2026-02-16 17:40:34.359432 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:40:34.384346 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.395115 | 
2026-02-16 17:40:34.395214 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:40:34.929180 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.941183 | 
2026-02-16 17:40:34.941276 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:40:35.472350 | controller | skipping: Conditional result was False
2026-02-16 17:40:35.479322 | 
2026-02-16 17:40:35.479393 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:40:35.504101 | controller | skipping: Conditional result was False
2026-02-16 17:40:35.515710 | 
2026-02-16 17:40:35.515805 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:40:35.540983 | controller | skipping: Conditional result was False
2026-02-16 17:40:35.551522 | 
2026-02-16 17:40:35.551616 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:40:36.135310 | controller | skipping: Conditional result was False
2026-02-16 17:40:36.145457 | 
2026-02-16 17:40:36.145525 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:40:36.674934 | controller | skipping: Conditional result was False
2026-02-16 17:40:36.690561 | 
2026-02-16 17:40:36.690712 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:40:36.716027 | controller | skipping: Conditional result was False
2026-02-16 17:40:36.726085 | 
2026-02-16 17:40:36.726357 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:40:36.752348 | controller | skipping: Conditional result was False
2026-02-16 17:40:36.770969 | 
2026-02-16 17:40:36.771041 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:40:37.204951 | controller | ok
2026-02-16 17:40:37.216301 | 
2026-02-16 17:40:37.216366 | PLAY RECAP
2026-02-16 17:40:37.216430 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:40:37.216462 | 
2026-02-16 17:40:37.381658 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:40:37.390365 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:37.991238 | 
2026-02-16 17:40:37.991402 | PLAY [all]
2026-02-16 17:40:38.004453 | 
2026-02-16 17:40:38.004533 | TASK [ensure-rust : Use rustup]
2026-02-16 17:40:38.038158 | controller | ok
2026-02-16 17:40:38.047463 | controller | included: /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:40:38.052942 | 
2026-02-16 17:40:38.053056 | TASK [ensure-rust : Install Rust]
2026-02-16 17:40:38.455255 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:40:38.593407 | controller | ERROR
2026-02-16 17:40:38.593758 | controller | {
2026-02-16 17:40:38.593824 | controller |   "delta": "0:00:00.011727",
2026-02-16 17:40:38.593875 | controller |   "end": "2026-02-16 17:40:38.458176",
2026-02-16 17:40:38.593918 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:38.593958 | controller |   "rc": 127,
2026-02-16 17:40:38.593999 | controller |   "start": "2026-02-16 17:40:38.446449"
2026-02-16 17:40:38.594046 | controller | }
failure
2026-02-16 17:40:38.596092 | 
2026-02-16 17:40:38.596144 | PLAY RECAP
2026-02-16 17:40:38.596194 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:40:38.596216 | 
2026-02-16 17:40:38.735352 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:38.743950 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:39.357172 | 
2026-02-16 17:40:39.357326 | PLAY [all]
2026-02-16 17:40:39.374065 | 
2026-02-16 17:40:39.374147 | TASK [Return built artifacts to Zuul]
2026-02-16 17:40:39.404538 | controller | ok
2026-02-16 17:40:39.410221 | 
2026-02-16 17:40:39.410299 | TASK [Get the Docker volume ID]
2026-02-16 17:40:39.949052 | controller | ERROR
2026-02-16 17:40:39.949443 | controller | {
2026-02-16 17:40:39.949488 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:40:39.949519 | controller |   "rc": 2
2026-02-16 17:40:39.949547 | controller | }
2026-02-16 17:40:39.949583 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:39.957301 | 
2026-02-16 17:40:39.957368 | TASK [Copy all of the pod logs]
2026-02-16 17:40:39.991850 | controller | ERROR
2026-02-16 17:40:39.992016 | controller | {
2026-02-16 17:40:39.992046 | 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/81d4f0cf8d904753ae9fd71eebbd1877/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:40:39.992068 | controller | }
2026-02-16 17:40:39.992095 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:39.993095 | 
2026-02-16 17:40:39.993142 | PLAY RECAP
2026-02-16 17:40:39.993187 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:40:39.993207 | 
2026-02-16 17:40:40.129774 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:40.139749 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:40:40.774129 | 
2026-02-16 17:40:40.774295 | PLAY [all]
2026-02-16 17:40:40.793497 | 
2026-02-16 17:40:40.793653 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:40:41.184159 | controller | changed
2026-02-16 17:40:41.191559 | 
2026-02-16 17:40:41.191644 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:40:42.228685 | controller | ok: Runtime: 0:00:00.479169
2026-02-16 17:40:42.241856 | 
2026-02-16 17:40:42.242122 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:40:42.800691 | controller | ok: Runtime: 0:00:00.019110
2026-02-16 17:40:42.808676 | 
2026-02-16 17:40:42.808770 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:40:43.345088 | controller | ok: Runtime: 0:00:00.020461
2026-02-16 17:40:43.351316 | 
2026-02-16 17:40:43.351384 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:40:44.236270 | controller | changed
2026-02-16 17:40:44.290602 | 
2026-02-16 17:40:44.290799 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:40:44.528334 | controller | changed
2026-02-16 17:40:44.535984 | 
2026-02-16 17:40:44.536055 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:44.866728 | controller | Output suppressed because no_log was given
2026-02-16 17:40:44.873262 | 
2026-02-16 17:40:44.873332 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:44.909691 | 
2026-02-16 17:40:44.909830 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:44.947688 | 
2026-02-16 17:40:44.948052 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:44.984713 | 
2026-02-16 17:40:44.984838 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:45.023043 | 
2026-02-16 17:40:45.023308 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:45.064096 | 
2026-02-16 17:40:45.064325 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:40:45.302893 | controller | changed
2026-02-16 17:40:45.308823 | 
2026-02-16 17:40:45.308901 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:40:45.345669 | controller | ok
2026-02-16 17:40:45.352686 | 
2026-02-16 17:40:45.352793 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:40:45.587746 | controller | Output suppressed because no_log was given
2026-02-16 17:40:45.593707 | 
2026-02-16 17:40:45.593797 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:40:45.633453 | 
2026-02-16 17:40:45.633645 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:40:45.673275 | 
2026-02-16 17:40:45.673410 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:40:46.359991 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:40:46.380044 | controller | Using python 3.12 to install setuptools
2026-02-16 17:40:46.431305 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:40:46.445019 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:40:46.714290 | controller | ERROR
2026-02-16 17:40:46.714549 | controller | {
2026-02-16 17:40:46.714595 | controller |   "delta": "0:00:00.528411",
2026-02-16 17:40:46.714625 | controller |   "end": "2026-02-16 17:40:46.447062",
2026-02-16 17:40:46.714651 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:46.714681 | controller |   "rc": 127,
2026-02-16 17:40:46.714707 | controller |   "start": "2026-02-16 17:40:45.918651"
2026-02-16 17:40:46.714731 | controller | }
2026-02-16 17:40:46.714765 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:46.740214 | 
2026-02-16 17:40:46.740385 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:40:46.981539 | controller | changed
2026-02-16 17:40:46.987119 | 
2026-02-16 17:40:46.987190 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:40:50.804316 | controller | Output suppressed because no_log was given
2026-02-16 17:40:50.817225 | 
2026-02-16 17:40:50.817376 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:40:50.850772 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.851225 | 
2026-02-16 17:40:50.853283 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.855064 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.856257 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.858822 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.861131 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.863433 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.866900 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.869227 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.871497 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.873921 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.876183 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.878431 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.880829 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.883175 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.885398 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.892910 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.893945 | controller | skipping: Conditional result was False
2026-02-16 17:40:50.937779 | 
2026-02-16 17:40:50.937985 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:40:51.206736 | controller | ok
2026-02-16 17:40:51.212193 | 
2026-02-16 17:40:51.221011 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:40:51.800634 | controller | skipping: Conditional result was False
2026-02-16 17:40:51.815344 | 
2026-02-16 17:40:51.815429 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:40:52.091765 | controller | sudo: iptables-save: command not found
2026-02-16 17:40:52.129635 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:40:53.191015 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:40:53.356686 | controller | ok: Runtime: 0:00:01.122872
2026-02-16 17:40:53.369675 | 
2026-02-16 17:40:53.369761 | LOOP [stage-output : Register sources]
2026-02-16 17:41:02.972588 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.027857 | 
2026-02-16 17:41:03.028003 | TASK [stage-output : Check sudo]
2026-02-16 17:41:03.576812 | controller | ok: Runtime: 0:00:00.022572
2026-02-16 17:41:03.583656 | 
2026-02-16 17:41:03.583725 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:41:03.690943 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.691562 | 
2026-02-16 17:41:03.693467 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.694358 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.695939 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.740708 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.741316 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.741690 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.742039 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.742385 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.747469 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.750847 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.753426 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.755694 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.758185 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.774993 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.775577 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.776171 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.776637 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.778636 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.780798 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.782864 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.785166 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.787332 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.812153 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.812648 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.888739 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.904710 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.920172 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.937663 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.953233 | 
2026-02-16 17:41:03.953427 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:41:04.019286 | controller | ok
2026-02-16 17:41:04.027906 | 
2026-02-16 17:41:04.027981 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:41:04.283330 | controller | changed: "docs"
2026-02-16 17:41:04.534790 | controller | changed: "artifacts"
2026-02-16 17:41:04.749227 | controller | ok: "logs"
2026-02-16 17:41:04.766810 | 
2026-02-16 17:41:04.766975 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:41:05.071146 | controller | ok: Item: Runtime: 0:00:00.020682
2026-02-16 17:41:05.071428 | controller | changed: All items complete
2026-02-16 17:41:05.071477 | 
2026-02-16 17:41:05.306514 | controller | ok: Item: Runtime: 0:00:00.006939
2026-02-16 17:41:05.531620 | controller | ok: Item: Runtime: 0:00:00.006995
2026-02-16 17:41:05.778081 | controller | ok: Item: Runtime: 0:00:00.007786
2026-02-16 17:41:06.009725 | controller | ok: Item: Runtime: 0:00:00.006174
2026-02-16 17:41:06.251543 | controller | ok: Item: Runtime: 0:00:00.007080
2026-02-16 17:41:06.485912 | controller | ok: Item: Runtime: 0:00:00.007294
2026-02-16 17:41:06.697465 | controller | ok: Item: Runtime: 0:00:00.004338
2026-02-16 17:41:06.913347 | controller | ok: Item: Runtime: 0:00:00.007546
2026-02-16 17:41:07.167184 | controller | ok: Item: Runtime: 0:00:00.007617
2026-02-16 17:41:07.391457 | controller | ok: Item: Runtime: 0:00:00.007026
2026-02-16 17:41:07.656073 | controller | ok: Item: Runtime: 0:00:00.007191
2026-02-16 17:41:07.839709 | controller | ok: Item: Runtime: 0:00:00.004676
2026-02-16 17:41:08.063564 | controller | ok: Item: Runtime: 0:00:00.007327
2026-02-16 17:41:08.296740 | controller | ok: Item: Runtime: 0:00:00.009479
2026-02-16 17:41:08.561780 | controller | ok: Item: Runtime: 0:00:00.009424
2026-02-16 17:41:08.585220 | 
2026-02-16 17:41:08.585395 | TASK [stage-output : Make all log files readable]
2026-02-16 17:41:08.841053 | controller | changed
2026-02-16 17:41:08.847767 | 
2026-02-16 17:41:08.847906 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:41:09.185859 | controller | changed: Renamed files for staging.
2026-02-16 17:41:09.192917 | 
2026-02-16 17:41:09.193009 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:41:09.218951 | controller | skipping: Conditional result was False
2026-02-16 17:41:09.233217 | 
2026-02-16 17:41:09.233361 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:41:09.284579 | 
2026-02-16 17:41:09.284789 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:41:09.968402 | controller | changed:
2026-02-16 17:41:09.968577 | controller | created directory /var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/work/logs/controller
2026-02-16 17:41:09.968605 | controller | cd+++++++++ logs/
2026-02-16 17:41:09.968627 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:41:09.968647 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:41:09.968666 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:41:09.968684 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:41:09.968702 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:41:09.968720 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:41:09.968737 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:41:09.968759 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:41:09.968794 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:41:09.968811 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:41:09.968829 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:41:09.968846 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:41:09.968869 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:41:09.968904 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:41:09.968923 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:41:09.968940 | controller | cd+++++++++ logs/apache/
2026-02-16 17:41:09.968957 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:41:09.971685 | controller | cd+++++++++ logs/etc/
2026-02-16 17:41:09.971712 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:41:09.971732 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:41:09.971783 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:41:09.971809 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:41:09.971828 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:41:09.971846 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:41:09.984591 | 
2026-02-16 17:41:09.984654 | TASK [Check if a tempest log exits]
2026-02-16 17:41:10.250489 | controller | ok
2026-02-16 17:41:10.258406 | 
2026-02-16 17:41:10.258486 | TASK [Link post-devstack tempest.log]
2026-02-16 17:41:10.284882 | controller | skipping: Conditional result was False
2026-02-16 17:41:10.299052 | 
2026-02-16 17:41:10.299236 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:41:10.584018 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:41:10.837737 | controller | ERROR
2026-02-16 17:41:10.837964 | controller | {
2026-02-16 17:41:10.837998 | controller |   "delta": "0:00:00.007517",
2026-02-16 17:41:10.838020 | controller |   "end": "2026-02-16 17:41:10.584744",
2026-02-16 17:41:10.838039 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:10.838057 | controller |   "rc": 127,
2026-02-16 17:41:10.838076 | controller |   "start": "2026-02-16 17:41:10.577227"
2026-02-16 17:41:10.838095 | controller | }
2026-02-16 17:41:10.838119 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:10.839860 | 
2026-02-16 17:41:10.839906 | PLAY RECAP
2026-02-16 17:41:10.839956 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:41:10.839978 | 
2026-02-16 17:41:10.990443 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:10.999429 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:11.602523 | 
2026-02-16 17:41:11.602649 | PLAY [all]
2026-02-16 17:41:11.614159 | 
2026-02-16 17:41:11.614235 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:41:11.660367 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.670663 | 
2026-02-16 17:41:11.670739 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:41:11.716737 | controller | ok
2026-02-16 17:41:11.723212 | 
2026-02-16 17:41:11.723289 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:41:12.116870 | controller -> localhost | ok: "/var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/work/logs"
2026-02-16 17:41:12.341146 | controller -> localhost | changed: "/var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/work/artifacts"
2026-02-16 17:41:12.565907 | controller -> localhost | changed: "/var/lib/zuul/builds/81d4f0cf8d904753ae9fd71eebbd1877/work/docs"
2026-02-16 17:41:12.585101 | 
2026-02-16 17:41:12.585277 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:41:13.266722 | controller | changed: .d..t...... ./
2026-02-16 17:41:13.307248 | controller | changed: All items complete
2026-02-16 17:41:13.307333 | 
2026-02-16 17:41:13.781651 | controller | changed: .d..t...... ./
2026-02-16 17:41:14.284527 | controller | changed: .d..t...... ./
2026-02-16 17:41:14.306010 | 
2026-02-16 17:41:14.306127 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:41:14.789829 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006923
2026-02-16 17:41:15.033773 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008123
2026-02-16 17:41:15.053492 | 
2026-02-16 17:41:15.053621 | PLAY [all]
2026-02-16 17:41:15.061145 | 
2026-02-16 17:41:15.061213 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:41:15.530802 | controller | changed
2026-02-16 17:41:15.541535 | 
2026-02-16 17:41:15.541664 | PLAY RECAP
2026-02-16 17:41:15.541721 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:41:15.541744 | 
2026-02-16 17:41:15.683797 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:15.693210 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:41:16.315372 | 
2026-02-16 17:41:16.315539 | PLAY [localhost]
2026-02-16 17:41:16.338891 | 
2026-02-16 17:41:16.339051 | TASK [Generate Zuul manifest]
2026-02-16 17:41:16.367893 | localhost | ok
2026-02-16 17:41:16.384269 | 
2026-02-16 17:41:16.384450 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:41:16.753943 | localhost | changed
2026-02-16 17:41:16.766392 | 
2026-02-16 17:41:16.766521 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:41:16.800682 | localhost | ok
2026-02-16 17:41:16.809190 | 
2026-02-16 17:41:16.809265 | TASK [Upload logs]
2026-02-16 17:41:16.831341 | localhost | ok
2026-02-16 17:41:16.895084 | 
2026-02-16 17:41:16.895241 | TASK [Set zuul-log-path fact]
2026-02-16 17:41:16.918382 | localhost | ok
2026-02-16 17:41:16.930561 | 
2026-02-16 17:41:16.930647 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:41:16.963410 | localhost | ok
2026-02-16 17:41:16.970559 | 
2026-02-16 17:41:16.970647 | TASK [upload-logs : Create log directories]
2026-02-16 17:41:17.384881 | localhost | changed
2026-02-16 17:41:17.391321 | 
2026-02-16 17:41:17.391412 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:41:17.910542 | localhost -> localhost | ok: Runtime: 0:00:00.008176
2026-02-16 17:41:17.917061 | 
2026-02-16 17:41:17.917218 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:41:18.375965 | localhost | Output suppressed because no_log was given
2026-02-16 17:41:18.381382 | 
2026-02-16 17:41:18.381463 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:41:18.426925 | localhost | skipping: Conditional result was False
2026-02-16 17:41:18.433022 | localhost | skipping: Conditional result was False
2026-02-16 17:41:18.450444 | 
2026-02-16 17:41:18.450597 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:41:18.501587 | localhost | skipping: Conditional result was False
2026-02-16 17:41:18.501997 | 
2026-02-16 17:41:18.505435 | localhost | skipping: Conditional result was False
2026-02-16 17:41:18.515514 | 
2026-02-16 17:41:18.515715 | LOOP [upload-logs : Upload console log and json output]