2026-02-16 17:38:13.353860 | Job console starting
2026-02-16 17:38:13.485406 | Updating git repos
2026-02-16 17:38:24.343708 | Cloning repos into workspace
2026-02-16 17:38:30.464454 | Restoring repo states
2026-02-16 17:38:31.366255 | Merging changes
2026-02-16 17:38:31.976316 | Checking out repos
2026-02-16 17:38:37.658758 | Preparing playbooks
2026-02-16 17:38:42.047708 | Running Ansible setup
2026-02-16 17:38:46.093940 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:38:46.719880 | 
2026-02-16 17:38:46.720018 | PLAY [localhost]
2026-02-16 17:38:46.727594 | 
2026-02-16 17:38:46.727664 | TASK [Gathering Facts]
2026-02-16 17:38:47.630001 | localhost | ok
2026-02-16 17:38:47.637864 | 
2026-02-16 17:38:47.637948 | TASK [Setup log path fact]
2026-02-16 17:38:47.658578 | localhost | ok
2026-02-16 17:38:47.675109 | 
2026-02-16 17:38:47.675205 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:38:47.705212 | localhost | ok
2026-02-16 17:38:47.713169 | 
2026-02-16 17:38:47.713270 | TASK [emit-job-header : Print job information]
2026-02-16 17:38:47.756285 | # Job Information
2026-02-16 17:38:47.756438 | Ansible Version: 2.16.15
2026-02-16 17:38:47.756486 | Job: magnum-cluster-api-sonobuoy-v1.34.4-cilium
2026-02-16 17:38:47.756518 | Pipeline: check
2026-02-16 17:38:47.756548 | Executor: 3a2793d2bd32
2026-02-16 17:38:47.756576 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:38:47.756609 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:38:47.761541 | 
2026-02-16 17:38:47.761695 | LOOP [emit-job-header : Print node information]
2026-02-16 17:38:47.902248 | localhost | ok:
2026-02-16 17:38:47.902519 | localhost | # Node Information
2026-02-16 17:38:47.902548 | localhost | Inventory Hostname: controller
2026-02-16 17:38:47.902570 | localhost | Hostname: np0000155614
2026-02-16 17:38:47.902590 | localhost | Username: zuul
2026-02-16 17:38:47.902612 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:38:47.902632 | localhost | Provider: yul1
2026-02-16 17:38:47.902651 | localhost | Region: ca-ymq-1
2026-02-16 17:38:47.902670 | localhost | Label: ubuntu-noble-16
2026-02-16 17:38:47.902688 | localhost | Product Name: OpenStack Nova
2026-02-16 17:38:47.902707 | localhost | Interface IP: 199.204.45.35
2026-02-16 17:38:47.927856 | 
2026-02-16 17:38:47.928038 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:38:48.379718 | localhost -> localhost | changed
2026-02-16 17:38:48.389051 | 
2026-02-16 17:38:48.389151 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:38:49.245810 | localhost -> localhost | changed
2026-02-16 17:38:49.266693 | 
2026-02-16 17:38:49.266900 | PLAY [all]
2026-02-16 17:38:49.278808 | 
2026-02-16 17:38:49.278894 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:38:49.517354 | controller -> localhost | ok
2026-02-16 17:38:49.529668 | 
2026-02-16 17:38:49.529770 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:38:49.584327 | controller | ok
2026-02-16 17:38:49.600295 | controller | included: /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:38:49.623354 | 
2026-02-16 17:38:49.624546 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:38:51.088889 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:38:51.089167 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/work/3d953ea6ff394e70b4424c2a9455583b_id_rsa
2026-02-16 17:38:51.089216 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/work/3d953ea6ff394e70b4424c2a9455583b_id_rsa.pub
2026-02-16 17:38:51.089252 | controller -> localhost | The key fingerprint is:
2026-02-16 17:38:51.089284 | controller -> localhost | SHA256:xt20YxYfpa+NwG0T3puH8O79OaQ+Vow5dEdavoQc4Xk zuul-build-sshkey
2026-02-16 17:38:51.089335 | controller -> localhost | The key's randomart image is:
2026-02-16 17:38:51.089368 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:38:51.089407 | controller -> localhost | |             .. .|
2026-02-16 17:38:51.089475 | controller -> localhost | |            ...oo|
2026-02-16 17:38:51.089511 | controller -> localhost | |            +o*E |
2026-02-16 17:38:51.089545 | controller -> localhost | |       . . + XoBo|
2026-02-16 17:38:51.089586 | controller -> localhost | |        S . @ % =|
2026-02-16 17:38:51.089634 | controller -> localhost | |       .   o X @o|
2026-02-16 17:38:51.089667 | controller -> localhost | |              Xoo|
2026-02-16 17:38:51.089700 | controller -> localhost | |             = oo|
2026-02-16 17:38:51.089734 | controller -> localhost | |            oo+.=|
2026-02-16 17:38:51.089765 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:38:51.089837 | controller -> localhost | ok: Runtime: 0:00:01.036698
2026-02-16 17:38:51.099046 | 
2026-02-16 17:38:51.099143 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:38:51.134708 | controller | ok
2026-02-16 17:38:51.148553 | controller | included: /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:38:51.160990 | 
2026-02-16 17:38:51.161119 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:38:51.186439 | controller | skipping: Conditional result was False
2026-02-16 17:38:51.195204 | 
2026-02-16 17:38:51.195297 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:38:51.717322 | controller | changed
2026-02-16 17:38:51.726039 | 
2026-02-16 17:38:51.726110 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:38:51.965098 | controller | ok
2026-02-16 17:38:51.973024 | 
2026-02-16 17:38:51.973092 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:38:52.591042 | controller | changed
2026-02-16 17:38:52.602871 | 
2026-02-16 17:38:52.603054 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:38:53.159843 | controller | changed
2026-02-16 17:38:53.170518 | 
2026-02-16 17:38:53.170619 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:38:53.197512 | controller | skipping: Conditional result was False
2026-02-16 17:38:53.205285 | 
2026-02-16 17:38:53.205362 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:38:53.646137 | controller -> localhost | changed
2026-02-16 17:38:53.663362 | 
2026-02-16 17:38:53.663433 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:38:54.030601 | controller -> localhost | Identity added: /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/work/3d953ea6ff394e70b4424c2a9455583b_id_rsa (zuul-build-sshkey)
2026-02-16 17:38:54.030976 | controller -> localhost | ok: Runtime: 0:00:00.033758
2026-02-16 17:38:54.110670 | 
2026-02-16 17:38:54.110883 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:38:54.422845 | controller | ok
2026-02-16 17:38:54.430118 | 
2026-02-16 17:38:54.430206 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:38:54.456150 | controller | skipping: Conditional result was False
2026-02-16 17:38:54.474287 | 
2026-02-16 17:38:54.474356 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:38:54.802252 | controller | ok
2026-02-16 17:38:54.810212 | 
2026-02-16 17:38:54.810277 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:39:15.876202 | controller | Output suppressed because no_log was given
2026-02-16 17:39:15.893722 | 
2026-02-16 17:39:15.893938 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:39:16.126585 | controller | ok: "logs"
2026-02-16 17:39:16.126857 | controller | ok: All items complete
2026-02-16 17:39:16.126886 | 
2026-02-16 17:39:16.324626 | controller | ok: "artifacts"
2026-02-16 17:39:16.523496 | controller | ok: "docs"
2026-02-16 17:39:16.542019 | 
2026-02-16 17:39:16.542181 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:39:16.773951 | controller | changed: "logs"
2026-02-16 17:39:16.978878 | controller | changed: "artifacts"
2026-02-16 17:39:17.260200 | controller | changed: "docs"
2026-02-16 17:39:17.276800 | 
2026-02-16 17:39:17.276938 | PLAY RECAP
2026-02-16 17:39:17.277017 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:39:17.277047 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:39:17.277069 | 
2026-02-16 17:39:17.490070 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:39:17.498436 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:39:18.131535 | 
2026-02-16 17:39:18.131667 | PLAY [all]
2026-02-16 17:39:18.155762 | 
2026-02-16 17:39:18.155948 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:39:18.627211 | controller | changed
2026-02-16 17:39:18.634497 | 
2026-02-16 17:39:18.634576 | TASK [Gather minimum local MTU]
2026-02-16 17:39:18.726999 | controller | ok
2026-02-16 17:39:18.734737 | 
2026-02-16 17:39:18.734830 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:39:18.784816 | controller | ok
2026-02-16 17:39:18.792859 | 
2026-02-16 17:39:18.797286 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:39:18.825840 | controller | skipping: Conditional result was False
2026-02-16 17:39:18.838987 | 
2026-02-16 17:39:18.839084 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:39:19.382483 | controller | ok: Runtime: 0:00:00.008705
2026-02-16 17:39:19.388443 | 
2026-02-16 17:39:19.388515 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:39:19.423618 | controller | skipping: Conditional result was False
2026-02-16 17:39:19.434607 | 
2026-02-16 17:39:19.434707 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:39:19.460653 | controller | skipping: Conditional result was False
2026-02-16 17:39:19.470696 | 
2026-02-16 17:39:19.470790 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:39:19.539794 | controller | ok
2026-02-16 17:39:19.550518 | controller | included: /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:39:19.557681 | 
2026-02-16 17:39:19.557751 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:39:19.611238 | controller | ok
2026-02-16 17:39:19.618837 | 
2026-02-16 17:39:19.618920 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:39:19.951566 | controller | ext4
2026-02-16 17:39:20.194820 | controller | ok: Runtime: 0:00:00.048638
2026-02-16 17:39:20.204533 | 
2026-02-16 17:39:20.204622 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:39:20.239588 | controller | ok
2026-02-16 17:39:20.252675 | 
2026-02-16 17:39:20.253011 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:39:20.297076 | controller | ok:
2026-02-16 17:39:20.297243 | controller | {
2026-02-16 17:39:20.297279 | controller |   "root_filesystem": "ext4"
2026-02-16 17:39:20.297307 | controller | }
2026-02-16 17:39:20.304976 | 
2026-02-16 17:39:20.305046 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:39:30.533594 | controller | 8192+0 records in
2026-02-16 17:39:30.533801 | controller | 8192+0 records out
2026-02-16 17:39:30.533835 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.89633 s, 868 MB/s
2026-02-16 17:39:30.863597 | controller | ok: Runtime: 0:00:09.912106
2026-02-16 17:39:30.871410 | 
2026-02-16 17:39:30.871477 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:39:31.130417 | controller | changed
2026-02-16 17:39:31.139490 | 
2026-02-16 17:39:31.139554 | TASK [configure-swap : Make swapfile]
2026-02-16 17:40:19.815689 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:40:19.816036 | controller | no label, UUID=863cdb27-f124-494d-816d-ff59b9a73b20
2026-02-16 17:40:20.253786 | controller | ok: Runtime: 0:00:48.417606
2026-02-16 17:40:20.260869 | 
2026-02-16 17:40:20.260935 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:40:20.689046 | controller | changed
2026-02-16 17:40:20.695174 | 
2026-02-16 17:40:20.695240 | TASK [configure-swap : Add all swap]
2026-02-16 17:40:21.234293 | controller | ok: Runtime: 0:00:00.011180
2026-02-16 17:40:21.243098 | 
2026-02-16 17:40:21.243182 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:40:21.279533 | controller | ok:
2026-02-16 17:40:21.279694 | controller | {
2026-02-16 17:40:21.279729 | controller |   "swap_required": "8192"
2026-02-16 17:40:21.279757 | controller | }
2026-02-16 17:40:21.288031 | 
2026-02-16 17:40:21.288166 | TASK [configure-swap : Set swappiness]
2026-02-16 17:40:21.752835 | controller | changed
2026-02-16 17:40:21.762992 | 
2026-02-16 17:40:21.763060 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:40:21.799391 | controller | ok:
2026-02-16 17:40:21.799530 | controller | {
2026-02-16 17:40:21.799555 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:40:21.799582 | controller | }
2026-02-16 17:40:21.819072 | 
2026-02-16 17:40:21.819287 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:40:22.270206 | controller | changed
2026-02-16 17:40:22.281269 | 
2026-02-16 17:40:22.281528 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:40:22.527653 | controller | changed
2026-02-16 17:40:22.534452 | 
2026-02-16 17:40:22.534530 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:40:23.087170 | controller | changed
2026-02-16 17:40:23.092861 | 
2026-02-16 17:40:23.092924 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:40:23.341741 | controller | changed
2026-02-16 17:40:23.348698 | 
2026-02-16 17:40:23.348764 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:40:24.436555 | controller | changed
2026-02-16 17:40:24.445061 | 
2026-02-16 17:40:24.445158 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:40:24.675307 | controller | changed
2026-02-16 17:40:24.686418 | 
2026-02-16 17:40:24.686520 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:40:24.967807 | controller | changed
2026-02-16 17:40:24.973276 | 
2026-02-16 17:40:24.973338 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:40:25.355630 | controller | changed
2026-02-16 17:40:25.408381 | 
2026-02-16 17:40:25.408544 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:40:26.209207 | controller | changed
2026-02-16 17:40:26.220418 | 
2026-02-16 17:40:26.220512 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:40:26.553883 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:40:26.566906 | 
2026-02-16 17:40:26.566986 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:40:27.039569 | controller | ok: Item: Runtime: 0:00:00.188558
2026-02-16 17:40:27.624538 | controller | ok: Item: Runtime: 0:00:00.342312
2026-02-16 17:40:28.376465 | controller | ok: Item: Runtime: 0:00:00.514928
2026-02-16 17:40:28.679547 | controller | ok: Item: Runtime: 0:00:00.064988
2026-02-16 17:40:29.171858 | controller | ok: Item: Runtime: 0:00:00.268010
2026-02-16 17:40:30.738396 | controller | ok: Item: Runtime: 0:00:01.332489
2026-02-16 17:40:34.204536 | controller | ok: Item: Runtime: 0:00:03.245395
2026-02-16 17:40:34.512454 | controller | ok: Item: Runtime: 0:00:00.100912
2026-02-16 17:40:34.911447 | controller | ok: Item: Runtime: 0:00:00.190112
2026-02-16 17:40:35.229193 | controller | ok: Item: Runtime: 0:00:00.108299
2026-02-16 17:40:35.917699 | controller | ok: Item: Runtime: 0:00:00.474776
2026-02-16 17:40:36.610869 | controller | ok: Item: Runtime: 0:00:00.489595
2026-02-16 17:40:37.099829 | controller | ok: Item: Runtime: 0:00:00.259107
2026-02-16 17:40:37.543150 | controller | ok: Item: Runtime: 0:00:00.226687
2026-02-16 17:40:38.141902 | controller | ok: Item: Runtime: 0:00:00.380535
2026-02-16 17:40:39.551284 | controller | ok: Item: Runtime: 0:00:01.182195
2026-02-16 17:40:39.582150 | 
2026-02-16 17:40:39.582301 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:40:39.800144 | controller | ok: All paths examined
2026-02-16 17:40:39.806728 | 
2026-02-16 17:40:39.806792 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:40:40.036485 | controller | ok: All paths examined
2026-02-16 17:40:40.043776 | 
2026-02-16 17:40:40.043844 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:40:40.547474 | controller | ok: Item: Runtime: 0:00:00.144192
2026-02-16 17:40:40.547729 | controller | changed: All items complete
2026-02-16 17:40:40.547764 | 
2026-02-16 17:40:40.945329 | controller | ok: Item: Runtime: 0:00:00.192401
2026-02-16 17:40:40.985646 | 
2026-02-16 17:40:40.986114 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:40:41.034549 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.036702 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.039278 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.042109 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.045435 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.048652 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.051715 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.055097 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.058063 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.061100 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.064174 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.067384 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.070386 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.073265 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.076089 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.079514 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.111110 | 
2026-02-16 17:40:41.111259 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:40:43.485947 | controller | changed
2026-02-16 17:40:43.506810 | 
2026-02-16 17:40:43.506897 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:40:43.755149 | controller | changed
2026-02-16 17:40:43.809148 | 
2026-02-16 17:40:43.809281 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:40:44.058275 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:40:44.349485 | controller | ERROR
2026-02-16 17:40:44.349779 | controller | {
2026-02-16 17:40:44.349842 | controller |   "delta": "0:00:00.010546",
2026-02-16 17:40:44.349888 | controller |   "end": "2026-02-16 17:40:44.058752",
2026-02-16 17:40:44.349930 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:44.349971 | controller |   "rc": 1,
2026-02-16 17:40:44.350011 | controller |   "start": "2026-02-16 17:40:44.048206"
2026-02-16 17:40:44.350052 | controller | }
2026-02-16 17:40:44.350105 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:44.356325 | 
2026-02-16 17:40:44.356396 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:40:44.609648 | controller | ok
2026-02-16 17:40:44.622208 | 
2026-02-16 17:40:44.622288 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:40:44.849113 | controller | /usr/bin/journalctl
2026-02-16 17:40:45.161973 | controller | ok: Runtime: 0:00:00.008426
2026-02-16 17:40:45.168153 | 
2026-02-16 17:40:45.168219 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:40:45.421980 | controller | 2026-02-16 17:40:45
2026-02-16 17:40:45.707710 | controller | ok: Runtime: 0:00:00.012950
2026-02-16 17:40:45.713332 | 
2026-02-16 17:40:45.713396 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:40:46.292777 | controller | changed
2026-02-16 17:40:46.298791 | 
2026-02-16 17:40:46.298852 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:40:46.324270 | controller | skipping: Conditional result was False
2026-02-16 17:40:46.336207 | 
2026-02-16 17:40:46.336275 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:40:46.871665 | controller | skipping: Conditional result was False
2026-02-16 17:40:46.883169 | 
2026-02-16 17:40:46.883242 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:40:47.413457 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.425817 | 
2026-02-16 17:40:47.425923 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:40:47.450850 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.461535 | 
2026-02-16 17:40:47.461659 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:40:47.992576 | controller | skipping: Conditional result was False
2026-02-16 17:40:48.005325 | 
2026-02-16 17:40:48.005414 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:40:48.541115 | controller | skipping: Conditional result was False
2026-02-16 17:40:48.551000 | 
2026-02-16 17:40:48.551065 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:40:48.575599 | controller | skipping: Conditional result was False
2026-02-16 17:40:48.586560 | 
2026-02-16 17:40:48.586691 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:40:48.612830 | controller | skipping: Conditional result was False
2026-02-16 17:40:48.624208 | 
2026-02-16 17:40:48.624275 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:40:49.201145 | controller | skipping: Conditional result was False
2026-02-16 17:40:49.213484 | 
2026-02-16 17:40:49.213560 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:40:49.742493 | controller | skipping: Conditional result was False
2026-02-16 17:40:49.753962 | 
2026-02-16 17:40:49.754096 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:40:49.779318 | controller | skipping: Conditional result was False
2026-02-16 17:40:49.791328 | 
2026-02-16 17:40:49.791475 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:40:49.817004 | controller | skipping: Conditional result was False
2026-02-16 17:40:49.833355 | 
2026-02-16 17:40:49.833450 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:40:50.215880 | controller | ok
2026-02-16 17:40:50.226712 | 
2026-02-16 17:40:50.226771 | PLAY RECAP
2026-02-16 17:40:50.226822 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:40:50.226846 | 
2026-02-16 17:40:50.378315 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:40:50.386915 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:50.983511 | 
2026-02-16 17:40:50.983650 | PLAY [all]
2026-02-16 17:40:50.995852 | 
2026-02-16 17:40:50.995931 | TASK [ensure-rust : Use rustup]
2026-02-16 17:40:51.030124 | controller | ok
2026-02-16 17:40:51.038360 | controller | included: /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:40:51.043888 | 
2026-02-16 17:40:51.043961 | TASK [ensure-rust : Install Rust]
2026-02-16 17:40:51.453679 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:40:51.642155 | controller | ERROR
2026-02-16 17:40:51.642310 | controller | {
2026-02-16 17:40:51.642339 | controller |   "delta": "0:00:00.010952",
2026-02-16 17:40:51.642362 | controller |   "end": "2026-02-16 17:40:51.456792",
2026-02-16 17:40:51.642381 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:51.642400 | controller |   "rc": 127,
2026-02-16 17:40:51.642417 | controller |   "start": "2026-02-16 17:40:51.445840"
2026-02-16 17:40:51.642439 | controller | }
failure
2026-02-16 17:40:51.644446 | 
2026-02-16 17:40:51.644491 | PLAY RECAP
2026-02-16 17:40:51.644536 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:40:51.644557 | 
2026-02-16 17:40:51.801441 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:51.812782 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:52.420741 | 
2026-02-16 17:40:52.420873 | PLAY [all]
2026-02-16 17:40:52.436818 | 
2026-02-16 17:40:52.436901 | TASK [Return built artifacts to Zuul]
2026-02-16 17:40:52.474239 | controller | ok
2026-02-16 17:40:52.479699 | 
2026-02-16 17:40:52.479773 | TASK [Get the Docker volume ID]
2026-02-16 17:40:53.026081 | controller | ERROR
2026-02-16 17:40:53.026623 | controller | {
2026-02-16 17:40:53.026695 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:40:53.026744 | controller |   "rc": 2
2026-02-16 17:40:53.026788 | controller | }
2026-02-16 17:40:53.026850 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:53.033369 | 
2026-02-16 17:40:53.033444 | TASK [Copy all of the pod logs]
2026-02-16 17:40:53.068006 | controller | ERROR
2026-02-16 17:40:53.068177 | controller | {
2026-02-16 17:40:53.068206 | 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/3d953ea6ff394e70b4424c2a9455583b/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:53.068228 | controller | }
2026-02-16 17:40:53.068254 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:53.069234 | 
2026-02-16 17:40:53.069281 | PLAY RECAP
2026-02-16 17:40:53.069331 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:40:53.069352 | 
2026-02-16 17:40:53.200367 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:53.211793 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:40:53.816490 | 
2026-02-16 17:40:53.816613 | PLAY [all]
2026-02-16 17:40:53.834576 | 
2026-02-16 17:40:53.834664 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:40:54.263345 | controller | changed
2026-02-16 17:40:54.270850 | 
2026-02-16 17:40:54.270931 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:40:55.329198 | controller | ok: Runtime: 0:00:00.521838
2026-02-16 17:40:55.335580 | 
2026-02-16 17:40:55.335653 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:40:55.876296 | controller | ok: Runtime: 0:00:00.019386
2026-02-16 17:40:55.882989 | 
2026-02-16 17:40:55.883061 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:40:56.423541 | controller | ok: Runtime: 0:00:00.021055
2026-02-16 17:40:56.431102 | 
2026-02-16 17:40:56.431185 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:40:57.358811 | controller | changed
2026-02-16 17:40:57.404170 | 
2026-02-16 17:40:57.404324 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:40:57.642535 | controller | changed
2026-02-16 17:40:57.648402 | 
2026-02-16 17:40:57.648477 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:57.990556 | controller | Output suppressed because no_log was given
2026-02-16 17:40:57.996823 | 
2026-02-16 17:40:57.996890 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:58.033614 | 
2026-02-16 17:40:58.034094 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:58.070889 | 
2026-02-16 17:40:58.071143 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:58.110347 | 
2026-02-16 17:40:58.110498 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:58.145920 | 
2026-02-16 17:40:58.146101 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:58.185261 | 
2026-02-16 17:40:58.185457 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:40:58.436969 | controller | changed
2026-02-16 17:40:58.443370 | 
2026-02-16 17:40:58.443438 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:40:58.483812 | controller | ok
2026-02-16 17:40:58.489332 | 
2026-02-16 17:40:58.489397 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:40:58.721414 | controller | Output suppressed because no_log was given
2026-02-16 17:40:58.734634 | 
2026-02-16 17:40:58.734705 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:40:58.788621 | 
2026-02-16 17:40:58.788777 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:40:58.829971 | 
2026-02-16 17:40:58.830107 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:40:59.690517 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:40:59.709590 | controller | Using python 3.12 to install setuptools
2026-02-16 17:40:59.752846 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:40:59.764969 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:40:59.867937 | controller | ERROR
2026-02-16 17:40:59.868166 | controller | {
2026-02-16 17:40:59.868201 | controller |   "delta": "0:00:00.665505",
2026-02-16 17:40:59.868223 | controller |   "end": "2026-02-16 17:40:59.767416",
2026-02-16 17:40:59.868242 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:59.868268 | controller |   "rc": 127,
2026-02-16 17:40:59.868287 | controller |   "start": "2026-02-16 17:40:59.101911"
2026-02-16 17:40:59.868320 | controller | }
2026-02-16 17:40:59.868347 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:59.888627 | 
2026-02-16 17:40:59.888827 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:41:00.127802 | controller | changed
2026-02-16 17:41:00.133511 | 
2026-02-16 17:41:00.133578 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:41:03.745545 | controller | Output suppressed because no_log was given
2026-02-16 17:41:03.757383 | 
2026-02-16 17:41:03.757454 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:03.818963 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.819464 | 
2026-02-16 17:41:03.821663 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.823299 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.824964 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.826850 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.828990 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.830981 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.833344 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.835648 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.838063 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.840483 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.842729 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.845005 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.847304 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.849549 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.851873 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.854102 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.858237 | controller | skipping: Conditional result was False
2026-02-16 17:41:03.893073 | 
2026-02-16 17:41:03.893240 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:41:04.131775 | controller | ok
2026-02-16 17:41:04.141046 | 
2026-02-16 17:41:04.141210 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:04.732747 | controller | skipping: Conditional result was False
2026-02-16 17:41:04.748769 | 
2026-02-16 17:41:04.748930 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:41:05.007805 | controller | sudo: iptables-save: command not found
2026-02-16 17:41:05.051703 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:41:06.128523 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:41:06.312481 | controller | ok: Runtime: 0:00:01.142041
2026-02-16 17:41:06.324446 | 
2026-02-16 17:41:06.324517 | LOOP [stage-output : Register sources]
2026-02-16 17:41:15.470754 | controller | Output suppressed because no_log was given
2026-02-16 17:41:15.529439 | 
2026-02-16 17:41:15.529594 | TASK [stage-output : Check sudo]
2026-02-16 17:41:16.072628 | controller | ok: Runtime: 0:00:00.016156
2026-02-16 17:41:16.080447 | 
2026-02-16 17:41:16.080517 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:41:16.177033 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.177553 | 
2026-02-16 17:41:16.179422 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.179916 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.181765 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.219391 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.219759 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.220096 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.220430 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.222281 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.224586 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.226749 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.228914 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.231046 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.233151 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.254013 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.254388 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.254722 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.255048 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.255373 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.255787 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.256926 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.259435 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.261465 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.286370 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.286853 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.362206 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.370118 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.384845 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.395784 | controller | Output suppressed because no_log was given
2026-02-16 17:41:16.409663 | 
2026-02-16 17:41:16.409770 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:41:16.475037 | controller | ok
2026-02-16 17:41:16.484209 | 
2026-02-16 17:41:16.484277 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:41:16.769534 | controller | changed: "docs"
2026-02-16 17:41:16.967018 | controller | changed: "artifacts"
2026-02-16 17:41:17.177173 | controller | ok: "logs"
2026-02-16 17:41:17.191962 | 
2026-02-16 17:41:17.192145 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:41:17.486878 | controller | ok: Item: Runtime: 0:00:00.013875
2026-02-16 17:41:17.487131 | controller | changed: All items complete
2026-02-16 17:41:17.487159 | 
2026-02-16 17:41:17.704864 | controller | ok: Item: Runtime: 0:00:00.007569
2026-02-16 17:41:17.934405 | controller | ok: Item: Runtime: 0:00:00.008678
2026-02-16 17:41:18.158452 | controller | ok: Item: Runtime: 0:00:00.008641
2026-02-16 17:41:18.390529 | controller | ok: Item: Runtime: 0:00:00.008479
2026-02-16 17:41:18.619133 | controller | ok: Item: Runtime: 0:00:00.007793
2026-02-16 17:41:18.854553 | controller | ok: Item: Runtime: 0:00:00.008003
2026-02-16 17:41:19.087194 | controller | ok: Item: Runtime: 0:00:00.008544
2026-02-16 17:41:19.319078 | controller | ok: Item: Runtime: 0:00:00.008125
2026-02-16 17:41:19.543483 | controller | ok: Item: Runtime: 0:00:00.008321
2026-02-16 17:41:19.784785 | controller | ok: Item: Runtime: 0:00:00.008664
2026-02-16 17:41:20.088212 | controller | ok: Item: Runtime: 0:00:00.007932
2026-02-16 17:41:20.256669 | controller | ok: Item: Runtime: 0:00:00.007575
2026-02-16 17:41:20.499599 | controller | ok: Item: Runtime: 0:00:00.007883
2026-02-16 17:41:20.732771 | controller | ok: Item: Runtime: 0:00:00.008844
2026-02-16 17:41:20.958851 | controller | ok: Item: Runtime: 0:00:00.008203
2026-02-16 17:41:20.974920 | 
2026-02-16 17:41:20.975056 | TASK [stage-output : Make all log files readable]
2026-02-16 17:41:21.222065 | controller | changed
2026-02-16 17:41:21.231142 | 
2026-02-16 17:41:21.231211 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:41:21.581772 | controller | changed: Renamed files for staging.
2026-02-16 17:41:21.589254 | 
2026-02-16 17:41:21.589344 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:41:21.614499 | controller | skipping: Conditional result was False
2026-02-16 17:41:21.625836 | 
2026-02-16 17:41:21.625929 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:41:21.669497 | 
2026-02-16 17:41:21.669657 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:41:22.308658 | controller | changed:
2026-02-16 17:41:22.308889 | controller | created directory /var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/work/logs/controller
2026-02-16 17:41:22.308934 | controller | cd+++++++++ logs/
2026-02-16 17:41:22.309000 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:41:22.309035 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:41:22.309067 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:41:22.309099 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:41:22.309130 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:41:22.309161 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:41:22.309192 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:41:22.309223 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:41:22.309253 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:41:22.309284 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:41:22.309316 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:41:22.309346 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:41:22.309383 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:41:22.309439 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:41:22.309474 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:41:22.309507 | controller | cd+++++++++ logs/apache/
2026-02-16 17:41:22.309539 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:41:22.309571 | controller | cd+++++++++ logs/etc/
2026-02-16 17:41:22.309603 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:41:22.309636 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:41:22.309669 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:41:22.309701 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:41:22.309736 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:41:22.309769 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:41:22.324904 | 
2026-02-16 17:41:22.324999 | TASK [Check if a tempest log exits]
2026-02-16 17:41:22.591470 | controller | ok
2026-02-16 17:41:22.598545 | 
2026-02-16 17:41:22.598614 | TASK [Link post-devstack tempest.log]
2026-02-16 17:41:22.623187 | controller | skipping: Conditional result was False
2026-02-16 17:41:22.635281 | 
2026-02-16 17:41:22.635411 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:41:22.911804 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:41:23.176603 | controller | ERROR
2026-02-16 17:41:23.176835 | controller | {
2026-02-16 17:41:23.176869 | controller |   "delta": "0:00:00.009214",
2026-02-16 17:41:23.176890 | controller |   "end": "2026-02-16 17:41:22.912416",
2026-02-16 17:41:23.176909 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:23.176927 | controller |   "rc": 127,
2026-02-16 17:41:23.176963 | controller |   "start": "2026-02-16 17:41:22.903202"
2026-02-16 17:41:23.176984 | controller | }
2026-02-16 17:41:23.177009 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:23.178605 | 
2026-02-16 17:41:23.178650 | PLAY RECAP
2026-02-16 17:41:23.178702 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:41:23.178723 | 
2026-02-16 17:41:23.374223 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:23.382629 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:24.050535 | 
2026-02-16 17:41:24.051221 | PLAY [all]
2026-02-16 17:41:24.063759 | 
2026-02-16 17:41:24.063836 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:41:24.109655 | controller | skipping: Conditional result was False
2026-02-16 17:41:24.120538 | 
2026-02-16 17:41:24.120619 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:41:24.163900 | controller | ok
2026-02-16 17:41:24.171584 | 
2026-02-16 17:41:24.171741 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:41:24.616037 | controller -> localhost | ok: "/var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/work/logs"
2026-02-16 17:41:24.837773 | controller -> localhost | changed: "/var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/work/artifacts"
2026-02-16 17:41:25.061241 | controller -> localhost | changed: "/var/lib/zuul/builds/3d953ea6ff394e70b4424c2a9455583b/work/docs"
2026-02-16 17:41:25.083056 | 
2026-02-16 17:41:25.083160 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:41:25.762955 | controller | changed: .d..t...... ./
2026-02-16 17:41:25.763197 | controller | changed: All items complete
2026-02-16 17:41:25.763226 | 
2026-02-16 17:41:26.236651 | controller | changed: .d..t...... ./
2026-02-16 17:41:26.706109 | controller | changed: .d..t...... ./
2026-02-16 17:41:26.734593 | 
2026-02-16 17:41:26.734726 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:41:27.182581 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009599
2026-02-16 17:41:27.435266 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008470
2026-02-16 17:41:27.457343 | 
2026-02-16 17:41:27.457521 | PLAY [all]
2026-02-16 17:41:27.466026 | 
2026-02-16 17:41:27.466091 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:41:27.926614 | controller | changed
2026-02-16 17:41:27.932883 | 
2026-02-16 17:41:27.932935 | PLAY RECAP
2026-02-16 17:41:27.933004 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:41:27.933028 | 
2026-02-16 17:41:28.053988 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:28.062967 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:41:28.730587 | 
2026-02-16 17:41:28.730748 | PLAY [localhost]
2026-02-16 17:41:28.741509 | 
2026-02-16 17:41:28.741583 | TASK [Generate Zuul manifest]
2026-02-16 17:41:28.763642 | localhost | ok
2026-02-16 17:41:28.779885 | 
2026-02-16 17:41:28.780037 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:41:29.150330 | localhost | changed
2026-02-16 17:41:29.163824 | 
2026-02-16 17:41:29.163965 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:41:29.196981 | localhost | ok
2026-02-16 17:41:29.204579 | 
2026-02-16 17:41:29.204671 | TASK [Upload logs]
2026-02-16 17:41:29.228901 | localhost | ok
2026-02-16 17:41:29.291306 | 
2026-02-16 17:41:29.291463 | TASK [Set zuul-log-path fact]
2026-02-16 17:41:29.317287 | localhost | ok
2026-02-16 17:41:29.333904 | 
2026-02-16 17:41:29.334028 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:41:29.367006 | localhost | ok
2026-02-16 17:41:29.375121 | 
2026-02-16 17:41:29.375189 | TASK [upload-logs : Create log directories]
2026-02-16 17:41:29.778986 | localhost | changed
2026-02-16 17:41:29.785500 | 
2026-02-16 17:41:29.785582 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:41:30.226007 | localhost -> localhost | ok: Runtime: 0:00:00.004825
2026-02-16 17:41:30.231277 | 
2026-02-16 17:41:30.231347 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:41:30.723954 | localhost | Output suppressed because no_log was given
2026-02-16 17:41:30.729062 | 
2026-02-16 17:41:30.729158 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:41:30.774180 | localhost | skipping: Conditional result was False
2026-02-16 17:41:30.780220 | localhost | skipping: Conditional result was False
2026-02-16 17:41:30.794806 | 
2026-02-16 17:41:30.794952 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:41:30.841405 | localhost | skipping: Conditional result was False
2026-02-16 17:41:30.841917 | 
2026-02-16 17:41:30.844747 | localhost | skipping: Conditional result was False
2026-02-16 17:41:30.860055 | 
2026-02-16 17:41:30.870746 | LOOP [upload-logs : Upload console log and json output]