2026-02-16 17:38:09.568886 | Job console starting
2026-02-16 17:38:09.581215 | Updating git repos
2026-02-16 17:38:18.354272 | Cloning repos into workspace
2026-02-16 17:38:24.259235 | Restoring repo states
2026-02-16 17:38:27.243157 | Merging changes
2026-02-16 17:38:27.921619 | Checking out repos
2026-02-16 17:38:32.457368 | Preparing playbooks
2026-02-16 17:38:36.412530 | Running Ansible setup
2026-02-16 17:38:41.222625 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:38:41.863793 | 
2026-02-16 17:38:41.863938 | PLAY [localhost]
2026-02-16 17:38:41.871571 | 
2026-02-16 17:38:41.871645 | TASK [Gathering Facts]
2026-02-16 17:38:42.798872 | localhost | ok
2026-02-16 17:38:42.810332 | 
2026-02-16 17:38:42.810482 | TASK [Setup log path fact]
2026-02-16 17:38:42.834377 | localhost | ok
2026-02-16 17:38:42.845335 | 
2026-02-16 17:38:42.845437 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:38:42.874796 | localhost | ok
2026-02-16 17:38:42.894284 | 
2026-02-16 17:38:42.894482 | TASK [emit-job-header : Print job information]
2026-02-16 17:38:42.943351 | # Job Information
2026-02-16 17:38:42.943549 | Ansible Version: 2.16.15
2026-02-16 17:38:42.943582 | Job: magnum-cluster-api-sonobuoy-v1.35.1-calico
2026-02-16 17:38:42.943605 | Pipeline: check
2026-02-16 17:38:42.943624 | Executor: 3a2793d2bd32
2026-02-16 17:38:42.943643 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:38:42.943665 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:38:42.946702 | 
2026-02-16 17:38:42.946775 | LOOP [emit-job-header : Print node information]
2026-02-16 17:38:43.070286 | localhost | ok:
2026-02-16 17:38:43.070598 | localhost | # Node Information
2026-02-16 17:38:43.070659 | localhost | Inventory Hostname: controller
2026-02-16 17:38:43.070707 | localhost | Hostname: np0000155615
2026-02-16 17:38:43.070750 | localhost | Username: zuul
2026-02-16 17:38:43.070799 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:38:43.070842 | localhost | Provider: yul1
2026-02-16 17:38:43.070883 | localhost | Region: ca-ymq-1
2026-02-16 17:38:43.070925 | localhost | Label: ubuntu-noble-16
2026-02-16 17:38:43.070966 | localhost | Product Name: OpenStack Nova
2026-02-16 17:38:43.071007 | localhost | Interface IP: 199.204.45.157
2026-02-16 17:38:43.088863 | 
2026-02-16 17:38:43.089357 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:38:43.607772 | localhost -> localhost | changed
2026-02-16 17:38:43.615904 | 
2026-02-16 17:38:43.616013 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:38:44.551398 | localhost -> localhost | changed
2026-02-16 17:38:44.562775 | 
2026-02-16 17:38:44.562835 | PLAY [all]
2026-02-16 17:38:44.571836 | 
2026-02-16 17:38:44.571901 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:38:44.830602 | controller -> localhost | ok
2026-02-16 17:38:44.841792 | 
2026-02-16 17:38:44.841902 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:38:44.878607 | controller | ok
2026-02-16 17:38:44.899557 | controller | included: /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:38:44.958805 | 
2026-02-16 17:38:44.958904 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:38:46.366202 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:38:46.366405 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/work/eb8c1b03625848c8823b34b0d16f47d9_id_rsa
2026-02-16 17:38:46.366434 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/work/eb8c1b03625848c8823b34b0d16f47d9_id_rsa.pub
2026-02-16 17:38:46.366456 | controller -> localhost | The key fingerprint is:
2026-02-16 17:38:46.366476 | controller -> localhost | SHA256:ZNzAk4l9Dn68rnSlU8uNQaEoE4fe2uspfKZIdX5IsRw zuul-build-sshkey
2026-02-16 17:38:46.366508 | controller -> localhost | The key's randomart image is:
2026-02-16 17:38:46.366529 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:38:46.366560 | controller -> localhost | |      .=oo  .    |
2026-02-16 17:38:46.366582 | controller -> localhost | |      o+B+.. .   |
2026-02-16 17:38:46.366602 | controller -> localhost | |     .ooE*o .    |
2026-02-16 17:38:46.366622 | controller -> localhost | |      .*o++.     |
2026-02-16 17:38:46.366641 | controller -> localhost | |      .oS. .+    |
2026-02-16 17:38:46.366660 | controller -> localhost | |     ..+...= =   |
2026-02-16 17:38:46.366678 | controller -> localhost | |    ..  +o= + .  |
2026-02-16 17:38:46.366697 | controller -> localhost | |   . .o.++..     |
2026-02-16 17:38:46.366718 | controller -> localhost | |    . .*+.       |
2026-02-16 17:38:46.366737 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:38:46.366786 | controller -> localhost | ok: Runtime: 0:00:00.915360
2026-02-16 17:38:46.372463 | 
2026-02-16 17:38:46.372527 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:38:46.412150 | controller | ok
2026-02-16 17:38:46.423579 | controller | included: /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:38:46.431675 | 
2026-02-16 17:38:46.431740 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:38:46.456435 | controller | skipping: Conditional result was False
2026-02-16 17:38:46.467349 | 
2026-02-16 17:38:46.467454 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:38:47.031779 | controller | changed
2026-02-16 17:38:47.042330 | 
2026-02-16 17:38:47.042414 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:38:47.271091 | controller | ok
2026-02-16 17:38:47.284561 | 
2026-02-16 17:38:47.284717 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:38:47.838351 | controller | changed
2026-02-16 17:38:47.846125 | 
2026-02-16 17:38:47.846221 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:38:48.460647 | controller | changed
2026-02-16 17:38:48.472057 | 
2026-02-16 17:38:48.472246 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:38:48.498346 | controller | skipping: Conditional result was False
2026-02-16 17:38:48.509182 | 
2026-02-16 17:38:48.509283 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:38:48.900320 | controller -> localhost | changed
2026-02-16 17:38:48.916708 | 
2026-02-16 17:38:48.916808 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:38:49.262842 | controller -> localhost | Identity added: /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/work/eb8c1b03625848c8823b34b0d16f47d9_id_rsa (zuul-build-sshkey)
2026-02-16 17:38:49.263128 | controller -> localhost | ok: Runtime: 0:00:00.023178
2026-02-16 17:38:49.313657 | 
2026-02-16 17:38:49.313799 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:38:49.610445 | controller | ok
2026-02-16 17:38:49.615772 | 
2026-02-16 17:38:49.615842 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:38:49.642238 | controller | skipping: Conditional result was False
2026-02-16 17:38:49.658097 | 
2026-02-16 17:38:49.658173 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:38:49.969900 | controller | ok
2026-02-16 17:38:49.979886 | 
2026-02-16 17:38:49.980009 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:39:10.892899 | controller | Output suppressed because no_log was given
2026-02-16 17:39:10.904556 | 
2026-02-16 17:39:10.904673 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:39:11.144624 | controller | ok: "logs"
2026-02-16 17:39:11.144879 | controller | ok: All items complete
2026-02-16 17:39:11.144908 | 
2026-02-16 17:39:11.325376 | controller | ok: "artifacts"
2026-02-16 17:39:11.512237 | controller | ok: "docs"
2026-02-16 17:39:11.531799 | 
2026-02-16 17:39:11.531984 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:39:11.782281 | controller | changed: "logs"
2026-02-16 17:39:11.972156 | controller | changed: "artifacts"
2026-02-16 17:39:12.192851 | controller | changed: "docs"
2026-02-16 17:39:12.213386 | 
2026-02-16 17:39:12.213520 | PLAY RECAP
2026-02-16 17:39:12.213571 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:39:12.213598 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:39:12.213621 | 
2026-02-16 17:39:12.386359 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:39:12.395891 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:39:13.004082 | 
2026-02-16 17:39:13.004252 | PLAY [all]
2026-02-16 17:39:13.023338 | 
2026-02-16 17:39:13.023506 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:39:13.429319 | controller | changed
2026-02-16 17:39:13.436054 | 
2026-02-16 17:39:13.436136 | TASK [Gather minimum local MTU]
2026-02-16 17:39:13.513737 | controller | ok
2026-02-16 17:39:13.519171 | 
2026-02-16 17:39:13.519242 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:39:13.575939 | controller | ok
2026-02-16 17:39:13.583200 | 
2026-02-16 17:39:13.583313 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:39:13.619484 | controller | skipping: Conditional result was False
2026-02-16 17:39:13.632301 | 
2026-02-16 17:39:13.632374 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:39:14.170614 | controller | ok: Runtime: 0:00:00.009985
2026-02-16 17:39:14.178225 | 
2026-02-16 17:39:14.178321 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:39:14.213961 | controller | skipping: Conditional result was False
2026-02-16 17:39:14.225806 | 
2026-02-16 17:39:14.225878 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:39:14.251129 | controller | skipping: Conditional result was False
2026-02-16 17:39:14.260864 | 
2026-02-16 17:39:14.260986 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:39:14.327788 | controller | ok
2026-02-16 17:39:14.340103 | controller | included: /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:39:14.348139 | 
2026-02-16 17:39:14.348206 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:39:14.402751 | controller | ok
2026-02-16 17:39:14.410281 | 
2026-02-16 17:39:14.410373 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:39:14.710776 | controller | ext4
2026-02-16 17:39:14.986871 | controller | ok: Runtime: 0:00:00.034564
2026-02-16 17:39:14.992602 | 
2026-02-16 17:39:14.992700 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:39:15.024612 | controller | ok
2026-02-16 17:39:15.032717 | 
2026-02-16 17:39:15.032814 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:39:15.075909 | controller | ok:
2026-02-16 17:39:15.076072 | controller | {
2026-02-16 17:39:15.076108 | controller |   "root_filesystem": "ext4"
2026-02-16 17:39:15.076136 | controller | }
2026-02-16 17:39:15.085003 | 
2026-02-16 17:39:15.085069 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:39:25.191266 | controller | 8192+0 records in
2026-02-16 17:39:25.191424 | controller | 8192+0 records out
2026-02-16 17:39:25.191439 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.76638 s, 880 MB/s
2026-02-16 17:39:25.639078 | controller | ok: Runtime: 0:00:09.786019
2026-02-16 17:39:25.647114 | 
2026-02-16 17:39:25.647198 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:39:25.884915 | controller | changed
2026-02-16 17:39:25.893938 | 
2026-02-16 17:39:25.894004 | TASK [configure-swap : Make swapfile]
2026-02-16 17:40:16.991410 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:40:16.991637 | controller | no label, UUID=434accfc-d868-4876-ac49-9b1d790d6ac4
2026-02-16 17:40:17.051013 | controller | ok: Runtime: 0:00:50.859978
2026-02-16 17:40:17.072459 | 
2026-02-16 17:40:17.072562 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:40:17.493951 | controller | changed
2026-02-16 17:40:17.499466 | 
2026-02-16 17:40:17.499531 | TASK [configure-swap : Add all swap]
2026-02-16 17:40:18.043925 | controller | ok: Runtime: 0:00:00.016469
2026-02-16 17:40:18.051161 | 
2026-02-16 17:40:18.051230 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:40:18.086856 | controller | ok:
2026-02-16 17:40:18.087018 | controller | {
2026-02-16 17:40:18.087053 | controller |   "swap_required": "8192"
2026-02-16 17:40:18.087079 | controller | }
2026-02-16 17:40:18.094437 | 
2026-02-16 17:40:18.094525 | TASK [configure-swap : Set swappiness]
2026-02-16 17:40:18.503711 | controller | changed
2026-02-16 17:40:18.512075 | 
2026-02-16 17:40:18.512149 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:40:18.547470 | controller | ok:
2026-02-16 17:40:18.547640 | controller | {
2026-02-16 17:40:18.547678 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:40:18.547712 | controller | }
2026-02-16 17:40:18.559794 | 
2026-02-16 17:40:18.559860 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:40:19.013859 | controller | changed
2026-02-16 17:40:19.020512 | 
2026-02-16 17:40:19.020575 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:40:19.264161 | controller | changed
2026-02-16 17:40:19.272904 | 
2026-02-16 17:40:19.273084 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:40:19.810507 | controller | changed
2026-02-16 17:40:19.816242 | 
2026-02-16 17:40:19.816311 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:40:20.075278 | controller | changed
2026-02-16 17:40:20.081907 | 
2026-02-16 17:40:20.081973 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:40:21.257432 | controller | changed
2026-02-16 17:40:21.264559 | 
2026-02-16 17:40:21.264624 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:40:21.516296 | controller | changed
2026-02-16 17:40:21.531180 | 
2026-02-16 17:40:21.531303 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:40:21.830424 | controller | changed
2026-02-16 17:40:21.835954 | 
2026-02-16 17:40:21.836020 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:40:22.238729 | controller | changed
2026-02-16 17:40:22.288912 | 
2026-02-16 17:40:22.289080 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:40:23.073574 | controller | changed
2026-02-16 17:40:23.085246 | 
2026-02-16 17:40:23.085313 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:40:23.422079 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:40:23.437404 | 
2026-02-16 17:40:23.437486 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:40:23.931253 | controller | ok: Item: Runtime: 0:00:00.205320
2026-02-16 17:40:24.517658 | controller | ok: Item: Runtime: 0:00:00.360205
2026-02-16 17:40:25.291016 | controller | ok: Item: Runtime: 0:00:00.554766
2026-02-16 17:40:25.580735 | controller | ok: Item: Runtime: 0:00:00.062930
2026-02-16 17:40:26.048516 | controller | ok: Item: Runtime: 0:00:00.264015
2026-02-16 17:40:27.585885 | controller | ok: Item: Runtime: 0:00:01.330441
2026-02-16 17:40:30.826842 | controller | ok: Item: Runtime: 0:00:03.010225
2026-02-16 17:40:31.144205 | controller | ok: Item: Runtime: 0:00:00.101745
2026-02-16 17:40:31.595911 | controller | ok: Item: Runtime: 0:00:00.205558
2026-02-16 17:40:31.932921 | controller | ok: Item: Runtime: 0:00:00.116172
2026-02-16 17:40:32.704439 | controller | ok: Item: Runtime: 0:00:00.554583
2026-02-16 17:40:33.425518 | controller | ok: Item: Runtime: 0:00:00.503988
2026-02-16 17:40:33.900603 | controller | ok: Item: Runtime: 0:00:00.238150
2026-02-16 17:40:34.348919 | controller | ok: Item: Runtime: 0:00:00.229175
2026-02-16 17:40:34.970820 | controller | ok: Item: Runtime: 0:00:00.376269
2026-02-16 17:40:36.391494 | controller | ok: Item: Runtime: 0:00:01.205537
2026-02-16 17:40:36.421426 | 
2026-02-16 17:40:36.421582 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:40:36.660238 | controller | ok: All paths examined
2026-02-16 17:40:36.666735 | 
2026-02-16 17:40:36.666799 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:40:36.944772 | controller | ok: All paths examined
2026-02-16 17:40:36.952826 | 
2026-02-16 17:40:36.952900 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:40:37.415550 | controller | ok: Item: Runtime: 0:00:00.182790
2026-02-16 17:40:37.415831 | controller | changed: All items complete
2026-02-16 17:40:37.415869 | 
2026-02-16 17:40:37.823152 | controller | ok: Item: Runtime: 0:00:00.189881
2026-02-16 17:40:37.845621 | 
2026-02-16 17:40:37.845851 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:40:37.893568 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.895868 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.898911 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.902076 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.904923 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.908173 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.911303 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.914568 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.918182 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.921434 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.924931 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.928073 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.931119 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.933983 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.940513 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.941433 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.970781 | 
2026-02-16 17:40:37.970955 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:40:40.356389 | controller | changed
2026-02-16 17:40:40.368181 | 
2026-02-16 17:40:40.368252 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:40:40.616351 | controller | changed
2026-02-16 17:40:40.675830 | 
2026-02-16 17:40:40.675969 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:40:40.925928 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:40:41.212629 | controller | ERROR
2026-02-16 17:40:41.212845 | controller | {
2026-02-16 17:40:41.212878 | controller |   "delta": "0:00:00.011328",
2026-02-16 17:40:41.212899 | controller |   "end": "2026-02-16 17:40:40.926422",
2026-02-16 17:40:41.212918 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:41.212936 | controller |   "rc": 1,
2026-02-16 17:40:41.212972 | controller |   "start": "2026-02-16 17:40:40.915094"
2026-02-16 17:40:41.212993 | controller | }
2026-02-16 17:40:41.213017 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:41.218897 | 
2026-02-16 17:40:41.218971 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:40:41.469135 | controller | ok
2026-02-16 17:40:41.483027 | 
2026-02-16 17:40:41.483097 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:40:41.754049 | controller | /usr/bin/journalctl
2026-02-16 17:40:42.035412 | controller | ok: Runtime: 0:00:00.008452
2026-02-16 17:40:42.041209 | 
2026-02-16 17:40:42.041276 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:40:42.317131 | controller | 2026-02-16 17:40:42
2026-02-16 17:40:42.577841 | controller | ok: Runtime: 0:00:00.009872
2026-02-16 17:40:42.583383 | 
2026-02-16 17:40:42.583445 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:40:43.155805 | controller | changed
2026-02-16 17:40:43.163282 | 
2026-02-16 17:40:43.163346 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:40:43.188722 | controller | skipping: Conditional result was False
2026-02-16 17:40:43.199259 | 
2026-02-16 17:40:43.199355 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:40:43.729390 | controller | skipping: Conditional result was False
2026-02-16 17:40:43.736716 | 
2026-02-16 17:40:43.736805 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:40:44.268720 | controller | skipping: Conditional result was False
2026-02-16 17:40:44.280551 | 
2026-02-16 17:40:44.280622 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:40:44.305828 | controller | skipping: Conditional result was False
2026-02-16 17:40:44.315725 | 
2026-02-16 17:40:44.315819 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:40:44.848913 | controller | skipping: Conditional result was False
2026-02-16 17:40:44.859414 | 
2026-02-16 17:40:44.859517 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:40:45.401814 | controller | skipping: Conditional result was False
2026-02-16 17:40:45.411927 | 
2026-02-16 17:40:45.412019 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:40:45.437144 | controller | skipping: Conditional result was False
2026-02-16 17:40:45.447678 | 
2026-02-16 17:40:45.447772 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:40:45.473045 | controller | skipping: Conditional result was False
2026-02-16 17:40:45.491495 | 
2026-02-16 17:40:45.491731 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:40:46.083227 | controller | skipping: Conditional result was False
2026-02-16 17:40:46.094237 | 
2026-02-16 17:40:46.099432 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:40:46.634683 | controller | skipping: Conditional result was False
2026-02-16 17:40:46.645306 | 
2026-02-16 17:40:46.645443 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:40:46.670922 | controller | skipping: Conditional result was False
2026-02-16 17:40:46.682839 | 
2026-02-16 17:40:46.682937 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:40:46.709052 | controller | skipping: Conditional result was False
2026-02-16 17:40:46.725864 | 
2026-02-16 17:40:46.725935 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:40:47.131461 | controller | ok
2026-02-16 17:40:47.140895 | 
2026-02-16 17:40:47.140961 | PLAY RECAP
2026-02-16 17:40:47.141012 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:40:47.141034 | 
2026-02-16 17:40:47.304412 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:40:47.310192 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:47.902228 | 
2026-02-16 17:40:47.902368 | PLAY [all]
2026-02-16 17:40:47.914603 | 
2026-02-16 17:40:47.914685 | TASK [ensure-rust : Use rustup]
2026-02-16 17:40:47.945049 | controller | ok
2026-02-16 17:40:47.951850 | controller | included: /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:40:47.957290 | 
2026-02-16 17:40:47.957362 | TASK [ensure-rust : Install Rust]
2026-02-16 17:40:48.369624 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:40:48.497813 | controller | ERROR
2026-02-16 17:40:48.498196 | controller | {
2026-02-16 17:40:48.498264 | controller |   "delta": "0:00:00.011270",
2026-02-16 17:40:48.498293 | controller |   "end": "2026-02-16 17:40:48.372291",
2026-02-16 17:40:48.498312 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:48.498330 | controller |   "rc": 127,
2026-02-16 17:40:48.498348 | controller |   "start": "2026-02-16 17:40:48.361021"
2026-02-16 17:40:48.498370 | controller | }
failure
2026-02-16 17:40:48.499861 | 
2026-02-16 17:40:48.499907 | PLAY RECAP
2026-02-16 17:40:48.499955 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:40:48.499977 | 
2026-02-16 17:40:48.619578 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:48.626851 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:49.275429 | 
2026-02-16 17:40:49.275580 | PLAY [all]
2026-02-16 17:40:49.292683 | 
2026-02-16 17:40:49.292765 | TASK [Return built artifacts to Zuul]
2026-02-16 17:40:49.323013 | controller | ok
2026-02-16 17:40:49.328425 | 
2026-02-16 17:40:49.328500 | TASK [Get the Docker volume ID]
2026-02-16 17:40:49.870758 | controller | ERROR
2026-02-16 17:40:49.871221 | controller | {
2026-02-16 17:40:49.871288 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:40:49.871310 | controller |   "rc": 2
2026-02-16 17:40:49.871330 | controller | }
2026-02-16 17:40:49.871356 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:49.877643 | 
2026-02-16 17:40:49.877714 | TASK [Copy all of the pod logs]
2026-02-16 17:40:49.913388 | controller | ERROR
2026-02-16 17:40:49.913561 | controller | {
2026-02-16 17:40:49.913590 | 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/eb8c1b03625848c8823b34b0d16f47d9/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:49.913612 | controller | }
2026-02-16 17:40:49.913638 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:49.914583 | 
2026-02-16 17:40:49.914628 | PLAY RECAP
2026-02-16 17:40:49.914677 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:40:49.914697 | 
2026-02-16 17:40:50.073748 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:50.083762 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:40:50.752381 | 
2026-02-16 17:40:50.752532 | PLAY [all]
2026-02-16 17:40:50.771015 | 
2026-02-16 17:40:50.771100 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:40:51.173907 | controller | changed
2026-02-16 17:40:51.181363 | 
2026-02-16 17:40:51.181448 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:40:52.216582 | controller | ok: Runtime: 0:00:00.470863
2026-02-16 17:40:52.222271 | 
2026-02-16 17:40:52.222344 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:40:52.803078 | controller | ok: Runtime: 0:00:00.017214
2026-02-16 17:40:52.810379 | 
2026-02-16 17:40:52.810490 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:40:53.350063 | controller | ok: Runtime: 0:00:00.023492
2026-02-16 17:40:53.358134 | 
2026-02-16 17:40:53.358205 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:40:54.264409 | controller | changed
2026-02-16 17:40:54.316602 | 
2026-02-16 17:40:54.316738 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:40:54.556691 | controller | changed
2026-02-16 17:40:54.562407 | 
2026-02-16 17:40:54.562482 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:54.892988 | controller | Output suppressed because no_log was given
2026-02-16 17:40:54.898576 | 
2026-02-16 17:40:54.898688 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:54.935505 | 
2026-02-16 17:40:54.935692 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:54.970123 | 
2026-02-16 17:40:54.970300 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:55.007596 | 
2026-02-16 17:40:55.014766 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:55.050446 | 
2026-02-16 17:40:55.050626 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:55.089084 | 
2026-02-16 17:40:55.089325 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:40:55.338670 | controller | changed
2026-02-16 17:40:55.345839 | 
2026-02-16 17:40:55.345909 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:40:55.378704 | controller | ok
2026-02-16 17:40:55.386445 | 
2026-02-16 17:40:55.386541 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:40:55.644007 | controller | Output suppressed because no_log was given
2026-02-16 17:40:55.649232 | 
2026-02-16 17:40:55.649300 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:40:55.685596 | 
2026-02-16 17:40:55.685774 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:40:55.725979 | 
2026-02-16 17:40:55.726105 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:40:56.422811 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:40:56.444215 | controller | Using python 3.12 to install setuptools
2026-02-16 17:40:56.482537 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:40:56.494687 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:40:56.766091 | controller | ERROR
2026-02-16 17:40:56.766364 | controller | {
2026-02-16 17:40:56.766412 | controller |   "delta": "0:00:00.506279",
2026-02-16 17:40:56.766442 | controller |   "end": "2026-02-16 17:40:56.496772",
2026-02-16 17:40:56.766469 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:56.766501 | controller |   "rc": 127,
2026-02-16 17:40:56.766527 | controller |   "start": "2026-02-16 17:40:55.990493"
2026-02-16 17:40:56.766551 | controller | }
2026-02-16 17:40:56.766586 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:56.788068 | 
2026-02-16 17:40:56.791484 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:40:57.051635 | controller | changed
2026-02-16 17:40:57.064204 | 
2026-02-16 17:40:57.064281 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:41:00.926568 | controller | Output suppressed because no_log was given
2026-02-16 17:41:00.938423 | 
2026-02-16 17:41:00.938554 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:00.979270 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.979734 | 
2026-02-16 17:41:00.981037 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.982358 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.984859 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.987932 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.990295 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.992708 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.995336 | controller | skipping: Conditional result was False
2026-02-16 17:41:00.997838 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.000143 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.002619 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.004941 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.007899 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.010160 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.012456 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.014748 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.016765 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.023655 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.050640 | 
2026-02-16 17:41:01.050927 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:41:01.270171 | controller | ok
2026-02-16 17:41:01.276450 | 
2026-02-16 17:41:01.276522 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:01.866784 | controller | skipping: Conditional result was False
2026-02-16 17:41:01.884749 | 
2026-02-16 17:41:01.884853 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:41:02.182152 | controller | sudo: iptables-save: command not found
2026-02-16 17:41:02.228036 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:41:03.646687 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:41:03.932491 | controller | ok: Runtime: 0:00:01.485401
2026-02-16 17:41:03.944588 | 
2026-02-16 17:41:03.944699 | LOOP [stage-output : Register sources]
2026-02-16 17:41:14.360302 | controller | Output suppressed because no_log was given
2026-02-16 17:41:14.413323 | 
2026-02-16 17:41:14.413482 | TASK [stage-output : Check sudo]
2026-02-16 17:41:14.956144 | controller | ok: Runtime: 0:00:00.023823
2026-02-16 17:41:14.963497 | 
2026-02-16 17:41:14.963568 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:41:15.092995 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.093418 | 
2026-02-16 17:41:15.095376 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.096196 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.098154 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.141740 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.142237 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.142629 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.143685 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.146692 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.148785 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.150995 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.153182 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.156292 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.158356 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.179112 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.179473 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.179870 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.180216 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.180552 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.181042 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.183168 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.185509 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.188803 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.213529 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.214021 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.294859 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.316074 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.339239 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.356067 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.366833 | 
2026-02-16 17:41:15.366921 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:41:15.430055 | controller | ok
2026-02-16 17:41:15.438606 | 
2026-02-16 17:41:15.438696 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:41:15.720046 | controller | changed: "docs"
2026-02-16 17:41:15.924349 | controller | changed: "artifacts"
2026-02-16 17:41:16.129740 | controller | ok: "logs"
2026-02-16 17:41:16.146912 | 
2026-02-16 17:41:16.146992 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:41:16.446564 | controller | ok: Item: Runtime: 0:00:00.010625
2026-02-16 17:41:16.446821 | controller | changed: All items complete
2026-02-16 17:41:16.446851 | 
2026-02-16 17:41:16.707289 | controller | ok: Item: Runtime: 0:00:00.008201
2026-02-16 17:41:16.923685 | controller | ok: Item: Runtime: 0:00:00.007977
2026-02-16 17:41:17.148158 | controller | ok: Item: Runtime: 0:00:00.008379
2026-02-16 17:41:17.359689 | controller | ok: Item: Runtime: 0:00:00.006973
2026-02-16 17:41:17.585278 | controller | ok: Item: Runtime: 0:00:00.006177
2026-02-16 17:41:17.809346 | controller | ok: Item: Runtime: 0:00:00.010757
2026-02-16 17:41:18.028172 | controller | ok: Item: Runtime: 0:00:00.007537
2026-02-16 17:41:18.269495 | controller | ok: Item: Runtime: 0:00:00.008748
2026-02-16 17:41:18.489693 | controller | ok: Item: Runtime: 0:00:00.008585
2026-02-16 17:41:18.717714 | controller | ok: Item: Runtime: 0:00:00.007769
2026-02-16 17:41:19.000750 | controller | ok: Item: Runtime: 0:00:00.008504
2026-02-16 17:41:19.172051 | controller | ok: Item: Runtime: 0:00:00.007355
2026-02-16 17:41:19.411822 | controller | ok: Item: Runtime: 0:00:00.008764
2026-02-16 17:41:19.674531 | controller | ok: Item: Runtime: 0:00:00.009103
2026-02-16 17:41:19.898735 | controller | ok: Item: Runtime: 0:00:00.007132
2026-02-16 17:41:19.913154 | 
2026-02-16 17:41:19.913220 | TASK [stage-output : Make all log files readable]
2026-02-16 17:41:20.164813 | controller | changed
2026-02-16 17:41:20.172934 | 
2026-02-16 17:41:20.173045 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:41:20.542424 | controller | changed: Renamed files for staging.
2026-02-16 17:41:20.550339 | 
2026-02-16 17:41:20.550408 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:41:20.576353 | controller | skipping: Conditional result was False
2026-02-16 17:41:20.587983 | 
2026-02-16 17:41:20.588081 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:41:20.638846 | 
2026-02-16 17:41:20.639035 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:41:21.278849 | controller | changed:
2026-02-16 17:41:21.279159 | controller | created directory /var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/work/logs/controller
2026-02-16 17:41:21.279204 | controller | cd+++++++++ logs/
2026-02-16 17:41:21.279280 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:41:21.279345 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:41:21.279425 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:41:21.279642 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:41:21.279671 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:41:21.279697 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:41:21.279723 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:41:21.279749 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:41:21.279774 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:41:21.280439 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:41:21.280481 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:41:21.280509 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:41:21.280541 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:41:21.280591 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:41:21.280618 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:41:21.280644 | controller | cd+++++++++ logs/apache/
2026-02-16 17:41:21.280670 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:41:21.280695 | controller | cd+++++++++ logs/etc/
2026-02-16 17:41:21.280720 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:41:21.280745 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:41:21.280770 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:41:21.280795 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:41:21.280820 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:41:21.280845 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:41:21.300122 | 
2026-02-16 17:41:21.301036 | TASK [Check if a tempest log exits]
2026-02-16 17:41:21.573893 | controller | ok
2026-02-16 17:41:21.583232 | 
2026-02-16 17:41:21.583329 | TASK [Link post-devstack tempest.log]
2026-02-16 17:41:21.609263 | controller | skipping: Conditional result was False
2026-02-16 17:41:21.623590 | 
2026-02-16 17:41:21.623661 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:41:21.869035 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:41:22.164901 | controller | ERROR
2026-02-16 17:41:22.165180 | controller | {
2026-02-16 17:41:22.165226 | controller |   "delta": "0:00:00.007813",
2026-02-16 17:41:22.165256 | controller |   "end": "2026-02-16 17:41:21.869785",
2026-02-16 17:41:22.165283 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:22.165308 | controller |   "rc": 127,
2026-02-16 17:41:22.165334 | controller |   "start": "2026-02-16 17:41:21.861972"
2026-02-16 17:41:22.165358 | controller | }
2026-02-16 17:41:22.165389 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:22.167368 | 
2026-02-16 17:41:22.167431 | PLAY RECAP
2026-02-16 17:41:22.167495 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:41:22.167525 | 
2026-02-16 17:41:22.344398 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:22.352576 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:22.949246 | 
2026-02-16 17:41:22.949385 | PLAY [all]
2026-02-16 17:41:22.961262 | 
2026-02-16 17:41:22.961339 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:41:23.007193 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.016938 | 
2026-02-16 17:41:23.017072 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:41:23.062294 | controller | ok
2026-02-16 17:41:23.071530 | 
2026-02-16 17:41:23.071611 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:41:23.497718 | controller -> localhost | ok: "/var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/work/logs"
2026-02-16 17:41:23.710609 | controller -> localhost | changed: "/var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/work/artifacts"
2026-02-16 17:41:23.914600 | controller -> localhost | changed: "/var/lib/zuul/builds/eb8c1b03625848c8823b34b0d16f47d9/work/docs"
2026-02-16 17:41:23.931505 | 
2026-02-16 17:41:23.931691 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:41:24.616626 | controller | changed: .d..t...... ./
2026-02-16 17:41:24.617095 | controller | changed: All items complete
2026-02-16 17:41:24.617168 | 
2026-02-16 17:41:25.097259 | controller | changed: .d..t...... ./
2026-02-16 17:41:25.602955 | controller | changed: .d..t...... ./
2026-02-16 17:41:25.626251 | 
2026-02-16 17:41:25.626407 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:41:26.052476 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008112
2026-02-16 17:41:26.342983 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006156
2026-02-16 17:41:26.366469 | 
2026-02-16 17:41:26.366615 | PLAY [all]
2026-02-16 17:41:26.374374 | 
2026-02-16 17:41:26.374445 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:41:26.867453 | controller | changed
2026-02-16 17:41:26.919898 | 
2026-02-16 17:41:26.920001 | PLAY RECAP
2026-02-16 17:41:26.920065 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:41:26.920095 | 
2026-02-16 17:41:27.039103 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:27.044933 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:41:27.728489 | 
2026-02-16 17:41:27.728659 | PLAY [localhost]
2026-02-16 17:41:27.740595 | 
2026-02-16 17:41:27.740681 | TASK [Generate Zuul manifest]
2026-02-16 17:41:27.764253 | localhost | ok
2026-02-16 17:41:27.783753 | 
2026-02-16 17:41:27.783876 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:41:28.191063 | localhost | changed
2026-02-16 17:41:28.205996 | 
2026-02-16 17:41:28.206127 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:41:28.237645 | localhost | ok
2026-02-16 17:41:28.247133 | 
2026-02-16 17:41:28.247218 | TASK [Upload logs]
2026-02-16 17:41:28.268286 | localhost | ok
2026-02-16 17:41:28.327944 | 
2026-02-16 17:41:28.328074 | TASK [Set zuul-log-path fact]
2026-02-16 17:41:28.350174 | localhost | ok
2026-02-16 17:41:28.367824 | 
2026-02-16 17:41:28.368137 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:41:28.402109 | localhost | ok
2026-02-16 17:41:28.411459 | 
2026-02-16 17:41:28.411551 | TASK [upload-logs : Create log directories]
2026-02-16 17:41:28.808310 | localhost | changed
2026-02-16 17:41:28.814679 | 
2026-02-16 17:41:28.814778 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:41:29.274277 | localhost -> localhost | ok: Runtime: 0:00:00.006420
2026-02-16 17:41:29.279737 | 
2026-02-16 17:41:29.279837 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:41:29.730809 | localhost | Output suppressed because no_log was given
2026-02-16 17:41:29.735749 | 
2026-02-16 17:41:29.735857 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:41:29.782746 | localhost | skipping: Conditional result was False
2026-02-16 17:41:29.789243 | localhost | skipping: Conditional result was False
2026-02-16 17:41:29.802400 | 
2026-02-16 17:41:29.802585 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:41:29.845198 | localhost | skipping: Conditional result was False
2026-02-16 17:41:29.845600 | 
2026-02-16 17:41:29.848643 | localhost | skipping: Conditional result was False
2026-02-16 17:41:29.859598 | 
2026-02-16 17:41:29.859822 | LOOP [upload-logs : Upload console log and json output]