2026-02-16 17:38:06.265600 | Job console starting
2026-02-16 17:38:06.273884 | Updating git repos
2026-02-16 17:38:12.136125 | Cloning repos into workspace
2026-02-16 17:38:18.792984 | Restoring repo states
2026-02-16 17:38:20.836767 | Merging changes
2026-02-16 17:38:21.562222 | Checking out repos
2026-02-16 17:38:26.572886 | Preparing playbooks
2026-02-16 17:38:29.651938 | Running Ansible setup
2026-02-16 17:38:33.615319 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:38:34.258911 | 
2026-02-16 17:38:34.259047 | PLAY [localhost]
2026-02-16 17:38:34.266871 | 
2026-02-16 17:38:34.266942 | TASK [Gathering Facts]
2026-02-16 17:38:35.206410 | localhost | ok
2026-02-16 17:38:35.230996 | 
2026-02-16 17:38:35.231201 | TASK [Setup log path fact]
2026-02-16 17:38:35.253948 | localhost | ok
2026-02-16 17:38:35.412111 | 
2026-02-16 17:38:35.412231 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:38:35.443428 | localhost | ok
2026-02-16 17:38:35.453637 | 
2026-02-16 17:38:35.453739 | TASK [emit-job-header : Print job information]
2026-02-16 17:38:35.503451 | # Job Information
2026-02-16 17:38:35.503609 | Ansible Version: 2.16.15
2026-02-16 17:38:35.503642 | Job: magnum-cluster-api-sonobuoy-v1.32.12-cilium
2026-02-16 17:38:35.503665 | Pipeline: check
2026-02-16 17:38:35.503686 | Executor: 3a2793d2bd32
2026-02-16 17:38:35.503706 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:38:35.503730 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:38:35.506441 | 
2026-02-16 17:38:35.506520 | LOOP [emit-job-header : Print node information]
2026-02-16 17:38:35.619224 | localhost | ok:
2026-02-16 17:38:35.628519 | localhost | # Node Information
2026-02-16 17:38:35.628581 | localhost | Inventory Hostname: controller
2026-02-16 17:38:35.628614 | localhost | Hostname: np0000155610
2026-02-16 17:38:35.628642 | localhost | Username: zuul
2026-02-16 17:38:35.628673 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:38:35.628699 | localhost | Provider: yul1
2026-02-16 17:38:35.628725 | localhost | Region: ca-ymq-1
2026-02-16 17:38:35.628750 | localhost | Label: ubuntu-noble-16
2026-02-16 17:38:35.628774 | localhost | Product Name: OpenStack Nova
2026-02-16 17:38:35.628799 | localhost | Interface IP: 199.204.45.3
2026-02-16 17:38:35.642772 | 
2026-02-16 17:38:35.642964 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:38:36.033351 | localhost -> localhost | changed
2026-02-16 17:38:36.038969 | 
2026-02-16 17:38:36.039045 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:38:36.971423 | localhost -> localhost | changed
2026-02-16 17:38:36.984033 | 
2026-02-16 17:38:36.984109 | PLAY [all]
2026-02-16 17:38:36.994061 | 
2026-02-16 17:38:36.994146 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:38:37.319239 | controller -> localhost | ok
2026-02-16 17:38:37.329084 | 
2026-02-16 17:38:37.329165 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:38:37.366571 | controller | ok
2026-02-16 17:38:37.387961 | controller | included: /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:38:37.395563 | 
2026-02-16 17:38:37.395712 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:38:39.483150 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:38:39.486330 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/work/f2ffe0d120514abbae4fde98e7cdb0df_id_rsa
2026-02-16 17:38:39.486432 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/work/f2ffe0d120514abbae4fde98e7cdb0df_id_rsa.pub
2026-02-16 17:38:39.486489 | controller -> localhost | The key fingerprint is:
2026-02-16 17:38:39.486539 | controller -> localhost | SHA256:+MXIsv+v/TXu9GR3cxjd9GTmSDd2XxQVlVUPc8Ab4gs zuul-build-sshkey
2026-02-16 17:38:39.486670 | controller -> localhost | The key's randomart image is:
2026-02-16 17:38:39.486757 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:38:39.486827 | controller -> localhost | |             .++/|
2026-02-16 17:38:39.486876 | controller -> localhost | |            . o*.|
2026-02-16 17:38:39.486921 | controller -> localhost | |           . ..=X|
2026-02-16 17:38:39.486965 | controller -> localhost | |       o oE ..oXB|
2026-02-16 17:38:39.487008 | controller -> localhost | |      o S o. .o *|
2026-02-16 17:38:39.487051 | controller -> localhost | |       + .  .  o |
2026-02-16 17:38:39.487093 | controller -> localhost | |      . .     .+B|
2026-02-16 17:38:39.487135 | controller -> localhost | |       .   .  o+B|
2026-02-16 17:38:39.487183 | controller -> localhost | |        ..ooo.oo.|
2026-02-16 17:38:39.487228 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:38:39.487389 | controller -> localhost | ok: Runtime: 0:00:01.607384
2026-02-16 17:38:39.496316 | 
2026-02-16 17:38:39.497509 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:38:39.533673 | controller | ok
2026-02-16 17:38:39.545059 | controller | included: /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:38:39.554539 | 
2026-02-16 17:38:39.554599 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:38:39.579881 | controller | skipping: Conditional result was False
2026-02-16 17:38:39.592945 | 
2026-02-16 17:38:39.593079 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:38:40.132579 | controller | changed
2026-02-16 17:38:40.140641 | 
2026-02-16 17:38:40.140706 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:38:40.366271 | controller | ok
2026-02-16 17:38:40.374908 | 
2026-02-16 17:38:40.374979 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:38:40.941823 | controller | changed
2026-02-16 17:38:40.990286 | 
2026-02-16 17:38:40.990401 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:38:41.579250 | controller | changed
2026-02-16 17:38:41.589406 | 
2026-02-16 17:38:41.589485 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:38:41.615212 | controller | skipping: Conditional result was False
2026-02-16 17:38:41.626023 | 
2026-02-16 17:38:41.626122 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:38:42.061895 | controller -> localhost | changed
2026-02-16 17:38:42.081231 | 
2026-02-16 17:38:42.081309 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:38:42.440735 | controller -> localhost | Identity added: /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/work/f2ffe0d120514abbae4fde98e7cdb0df_id_rsa (zuul-build-sshkey)
2026-02-16 17:38:42.441015 | controller -> localhost | ok: Runtime: 0:00:00.016171
2026-02-16 17:38:42.496024 | 
2026-02-16 17:38:42.496173 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:38:42.811533 | controller | ok
2026-02-16 17:38:42.817118 | 
2026-02-16 17:38:42.817210 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:38:42.842231 | controller | skipping: Conditional result was False
2026-02-16 17:38:42.868710 | 
2026-02-16 17:38:42.868810 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:38:43.208779 | controller | ok
2026-02-16 17:38:43.216885 | 
2026-02-16 17:38:43.216986 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:39:04.928761 | controller | Output suppressed because no_log was given
2026-02-16 17:39:04.942963 | 
2026-02-16 17:39:04.943203 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:39:05.196518 | controller | ok: "logs"
2026-02-16 17:39:05.196775 | controller | ok: All items complete
2026-02-16 17:39:05.196803 | 
2026-02-16 17:39:05.383042 | controller | ok: "artifacts"
2026-02-16 17:39:05.584248 | controller | ok: "docs"
2026-02-16 17:39:05.595192 | 
2026-02-16 17:39:05.595365 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:39:05.827685 | controller | changed: "logs"
2026-02-16 17:39:06.313696 | controller | changed: "artifacts"
2026-02-16 17:39:06.576256 | controller | changed: "docs"
2026-02-16 17:39:06.595340 | 
2026-02-16 17:39:06.595505 | PLAY RECAP
2026-02-16 17:39:06.595587 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:39:06.595630 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:39:06.595663 | 
2026-02-16 17:39:06.771901 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:39:06.780706 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:39:07.500810 | 
2026-02-16 17:39:07.501771 | PLAY [all]
2026-02-16 17:39:07.522612 | 
2026-02-16 17:39:07.522758 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:39:07.919962 | controller | changed
2026-02-16 17:39:07.927231 | 
2026-02-16 17:39:07.927376 | TASK [Gather minimum local MTU]
2026-02-16 17:39:08.010977 | controller | ok
2026-02-16 17:39:08.023633 | 
2026-02-16 17:39:08.024062 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:39:08.076553 | controller | ok
2026-02-16 17:39:08.083580 | 
2026-02-16 17:39:08.083706 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:39:08.119436 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.132409 | 
2026-02-16 17:39:08.132506 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:39:08.670904 | controller | ok: Runtime: 0:00:00.011134
2026-02-16 17:39:08.676842 | 
2026-02-16 17:39:08.676911 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:39:08.705246 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.713616 | 
2026-02-16 17:39:08.713693 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:39:08.737706 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.755268 | 
2026-02-16 17:39:08.755364 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:39:08.823259 | controller | ok
2026-02-16 17:39:08.840389 | controller | included: /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:39:08.851164 | 
2026-02-16 17:39:08.851284 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:39:08.907606 | controller | ok
2026-02-16 17:39:08.915042 | 
2026-02-16 17:39:08.915116 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:39:09.207020 | controller | ext4
2026-02-16 17:39:09.492846 | controller | ok: Runtime: 0:00:00.009945
2026-02-16 17:39:09.498825 | 
2026-02-16 17:39:09.498896 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:39:09.534249 | controller | ok
2026-02-16 17:39:09.544111 | 
2026-02-16 17:39:09.544227 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:39:09.579945 | controller | ok:
2026-02-16 17:39:09.580138 | controller | {
2026-02-16 17:39:09.580176 | controller |   "root_filesystem": "ext4"
2026-02-16 17:39:09.580237 | controller | }
2026-02-16 17:39:09.588675 | 
2026-02-16 17:39:09.588747 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:39:19.347042 | controller | 8192+0 records in
2026-02-16 17:39:19.357888 | controller | 8192+0 records out
2026-02-16 17:39:19.357962 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.50427 s, 904 MB/s
2026-02-16 17:39:19.660309 | controller | ok: Runtime: 0:00:09.525647
2026-02-16 17:39:19.703888 | 
2026-02-16 17:39:19.704035 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:39:19.951916 | controller | changed
2026-02-16 17:39:19.960742 | 
2026-02-16 17:39:19.960813 | TASK [configure-swap : Make swapfile]
2026-02-16 17:40:25.915513 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:40:25.915716 | controller | no label, UUID=38d90a67-7b57-439c-9cc1-411842b8120b
2026-02-16 17:40:26.198440 | controller | ok: Runtime: 0:01:05.718597
2026-02-16 17:40:26.205357 | 
2026-02-16 17:40:26.205446 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:40:26.595963 | controller | changed
2026-02-16 17:40:26.602696 | 
2026-02-16 17:40:26.602763 | TASK [configure-swap : Add all swap]
2026-02-16 17:40:27.147739 | controller | ok: Runtime: 0:00:00.069743
2026-02-16 17:40:27.154914 | 
2026-02-16 17:40:27.154982 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:40:27.192196 | controller | ok:
2026-02-16 17:40:27.192340 | controller | {
2026-02-16 17:40:27.192366 | controller |   "swap_required": "8192"
2026-02-16 17:40:27.192386 | controller | }
2026-02-16 17:40:27.198777 | 
2026-02-16 17:40:27.199003 | TASK [configure-swap : Set swappiness]
2026-02-16 17:40:27.639526 | controller | changed
2026-02-16 17:40:27.648122 | 
2026-02-16 17:40:27.648192 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:40:27.684255 | controller | ok:
2026-02-16 17:40:27.684492 | controller | {
2026-02-16 17:40:27.684531 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:40:27.684568 | controller | }
2026-02-16 17:40:27.699256 | 
2026-02-16 17:40:27.699358 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:40:28.334294 | controller | changed
2026-02-16 17:40:28.341541 | 
2026-02-16 17:40:28.341610 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:40:28.568361 | controller | changed
2026-02-16 17:40:28.576455 | 
2026-02-16 17:40:28.576554 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:40:29.572493 | controller | changed
2026-02-16 17:40:29.578296 | 
2026-02-16 17:40:29.578367 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:40:29.818470 | controller | changed
2026-02-16 17:40:29.826951 | 
2026-02-16 17:40:29.827027 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:40:30.948765 | controller | changed
2026-02-16 17:40:30.956021 | 
2026-02-16 17:40:30.956095 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:40:31.207054 | controller | changed
2026-02-16 17:40:31.220267 | 
2026-02-16 17:40:31.220335 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:40:31.630959 | controller | changed
2026-02-16 17:40:31.636252 | 
2026-02-16 17:40:31.636320 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:40:32.472613 | controller | changed
2026-02-16 17:40:32.525466 | 
2026-02-16 17:40:32.525644 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:40:33.333333 | controller | changed
2026-02-16 17:40:33.347262 | 
2026-02-16 17:40:33.347551 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:40:33.695034 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:40:33.708154 | 
2026-02-16 17:40:33.708232 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:40:34.185085 | controller | ok: Item: Runtime: 0:00:00.194191
2026-02-16 17:40:34.815200 | controller | ok: Item: Runtime: 0:00:00.415622
2026-02-16 17:40:35.519988 | controller | ok: Item: Runtime: 0:00:00.487251
2026-02-16 17:40:35.810752 | controller | ok: Item: Runtime: 0:00:00.062487
2026-02-16 17:40:36.306559 | controller | ok: Item: Runtime: 0:00:00.274281
2026-02-16 17:40:37.892150 | controller | ok: Item: Runtime: 0:00:01.334037
2026-02-16 17:40:41.024457 | controller | ok: Item: Runtime: 0:00:02.923346
2026-02-16 17:40:41.331748 | controller | ok: Item: Runtime: 0:00:00.094325
2026-02-16 17:40:41.741149 | controller | ok: Item: Runtime: 0:00:00.192010
2026-02-16 17:40:42.055008 | controller | ok: Item: Runtime: 0:00:00.105205
2026-02-16 17:40:42.774842 | controller | ok: Item: Runtime: 0:00:00.492860
2026-02-16 17:40:43.504616 | controller | ok: Item: Runtime: 0:00:00.518529
2026-02-16 17:40:43.959371 | controller | ok: Item: Runtime: 0:00:00.237894
2026-02-16 17:40:44.393570 | controller | ok: Item: Runtime: 0:00:00.223652
2026-02-16 17:40:44.999125 | controller | ok: Item: Runtime: 0:00:00.391422
2026-02-16 17:40:46.452404 | controller | ok: Item: Runtime: 0:00:01.221902
2026-02-16 17:40:46.493323 | 
2026-02-16 17:40:46.493498 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:40:46.712913 | controller | ok: All paths examined
2026-02-16 17:40:46.720778 | 
2026-02-16 17:40:46.720878 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:40:46.972100 | controller | ok: All paths examined
2026-02-16 17:40:46.978675 | 
2026-02-16 17:40:46.978774 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:40:47.421426 | controller | ok: Item: Runtime: 0:00:00.152452
2026-02-16 17:40:47.421667 | controller | changed: All items complete
2026-02-16 17:40:47.421695 | 
2026-02-16 17:40:47.828448 | controller | ok: Item: Runtime: 0:00:00.188210
2026-02-16 17:40:47.851312 | 
2026-02-16 17:40:47.851427 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:40:47.893983 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.896296 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.899048 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.902062 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.905233 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.915744 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.924005 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.927411 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.930828 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.933934 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.936997 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.940347 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.943824 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.946923 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.949831 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.953019 | controller | skipping: Conditional result was False
2026-02-16 17:40:47.975385 | 
2026-02-16 17:40:47.975528 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:40:50.332293 | controller | changed
2026-02-16 17:40:50.343783 | 
2026-02-16 17:40:50.343853 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:40:50.576184 | controller | changed
2026-02-16 17:40:50.626073 | 
2026-02-16 17:40:50.626223 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:40:50.874836 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:40:51.162955 | controller | ERROR
2026-02-16 17:40:51.171063 | controller | {
2026-02-16 17:40:51.171113 | controller |   "delta": "0:00:00.014310",
2026-02-16 17:40:51.171137 | controller |   "end": "2026-02-16 17:40:50.875443",
2026-02-16 17:40:51.171156 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:51.171175 | controller |   "rc": 1,
2026-02-16 17:40:51.171194 | controller |   "start": "2026-02-16 17:40:50.861133"
2026-02-16 17:40:51.171212 | controller | }
2026-02-16 17:40:51.171237 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:51.183726 | 
2026-02-16 17:40:51.183859 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:40:51.434337 | controller | ok
2026-02-16 17:40:51.447036 | 
2026-02-16 17:40:51.447143 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:40:51.695946 | controller | /usr/bin/journalctl
2026-02-16 17:40:51.991575 | controller | ok: Runtime: 0:00:00.014390
2026-02-16 17:40:51.998647 | 
2026-02-16 17:40:51.998727 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:40:52.248639 | controller | 2026-02-16 17:40:52
2026-02-16 17:40:52.679892 | controller | ok: Runtime: 0:00:00.007288
2026-02-16 17:40:52.685696 | 
2026-02-16 17:40:52.685765 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:40:53.279269 | controller | changed
2026-02-16 17:40:53.294491 | 
2026-02-16 17:40:53.294575 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:40:53.319105 | controller | skipping: Conditional result was False
2026-02-16 17:40:53.334435 | 
2026-02-16 17:40:53.334637 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:40:53.868117 | controller | skipping: Conditional result was False
2026-02-16 17:40:53.879607 | 
2026-02-16 17:40:53.879721 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:40:54.419453 | controller | skipping: Conditional result was False
2026-02-16 17:40:54.431504 | 
2026-02-16 17:40:54.431633 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:40:54.457277 | controller | skipping: Conditional result was False
2026-02-16 17:40:54.468588 | 
2026-02-16 17:40:54.468692 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:40:55.002558 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.009906 | 
2026-02-16 17:40:55.009982 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:40:55.539507 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.550881 | 
2026-02-16 17:40:55.550982 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:40:55.583162 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.594323 | 
2026-02-16 17:40:55.594418 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:40:55.620261 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.630963 | 
2026-02-16 17:40:55.631153 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:40:56.220383 | controller | skipping: Conditional result was False
2026-02-16 17:40:56.228176 | 
2026-02-16 17:40:56.228274 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:40:56.780898 | controller | skipping: Conditional result was False
2026-02-16 17:40:56.790611 | 
2026-02-16 17:40:56.791969 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:40:56.817057 | controller | skipping: Conditional result was False
2026-02-16 17:40:56.828866 | 
2026-02-16 17:40:56.828954 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:40:56.853630 | controller | skipping: Conditional result was False
2026-02-16 17:40:56.872879 | 
2026-02-16 17:40:56.873055 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:40:57.319455 | controller | ok
2026-02-16 17:40:57.330631 | 
2026-02-16 17:40:57.330704 | PLAY RECAP
2026-02-16 17:40:57.330757 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:40:57.330780 | 
2026-02-16 17:40:57.477687 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:40:57.488132 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:58.103101 | 
2026-02-16 17:40:58.103238 | PLAY [all]
2026-02-16 17:40:58.115268 | 
2026-02-16 17:40:58.115355 | TASK [ensure-rust : Use rustup]
2026-02-16 17:40:58.150183 | controller | ok
2026-02-16 17:40:58.157279 | controller | included: /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:40:58.162805 | 
2026-02-16 17:40:58.162887 | TASK [ensure-rust : Install Rust]
2026-02-16 17:40:58.599234 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:40:58.703782 | controller | ERROR
2026-02-16 17:40:58.704128 | controller | {
2026-02-16 17:40:58.704194 | controller |   "delta": "0:00:00.011169",
2026-02-16 17:40:58.704246 | controller |   "end": "2026-02-16 17:40:58.601995",
2026-02-16 17:40:58.704290 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:58.704331 | controller |   "rc": 127,
2026-02-16 17:40:58.704371 | controller |   "start": "2026-02-16 17:40:58.590826"
2026-02-16 17:40:58.704421 | controller | }
failure
2026-02-16 17:40:58.706577 | 
2026-02-16 17:40:58.706626 | PLAY RECAP
2026-02-16 17:40:58.706676 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:40:58.706698 | 
2026-02-16 17:40:58.828204 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:58.837926 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:59.442747 | 
2026-02-16 17:40:59.442892 | PLAY [all]
2026-02-16 17:40:59.460537 | 
2026-02-16 17:40:59.460633 | TASK [Return built artifacts to Zuul]
2026-02-16 17:40:59.494001 | controller | ok
2026-02-16 17:40:59.500619 | 
2026-02-16 17:40:59.500689 | TASK [Get the Docker volume ID]
2026-02-16 17:41:00.042102 | controller | ERROR
2026-02-16 17:41:00.042476 | controller | {
2026-02-16 17:41:00.042521 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:41:00.042553 | controller |   "rc": 2
2026-02-16 17:41:00.042579 | controller | }
2026-02-16 17:41:00.042615 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:00.050635 | 
2026-02-16 17:41:00.050711 | TASK [Copy all of the pod logs]
2026-02-16 17:41:00.080657 | controller | ERROR
2026-02-16 17:41:00.080841 | controller | {
2026-02-16 17:41:00.080871 | 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/f2ffe0d120514abbae4fde98e7cdb0df/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:41:00.080894 | controller | }
2026-02-16 17:41:00.080921 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:00.082185 | 
2026-02-16 17:41:00.082235 | PLAY RECAP
2026-02-16 17:41:00.082281 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:41:00.082302 | 
2026-02-16 17:41:00.228579 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:41:00.238315 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:00.907195 | 
2026-02-16 17:41:00.907357 | PLAY [all]
2026-02-16 17:41:00.928955 | 
2026-02-16 17:41:00.929167 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:41:01.386477 | controller | changed
2026-02-16 17:41:01.394623 | 
2026-02-16 17:41:01.394727 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:41:02.930260 | controller | ok: Runtime: 0:00:00.739182
2026-02-16 17:41:02.936044 | 
2026-02-16 17:41:02.936151 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:41:03.473637 | controller | ok: Runtime: 0:00:00.018328
2026-02-16 17:41:03.479584 | 
2026-02-16 17:41:03.479654 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:41:04.034981 | controller | ok: Runtime: 0:00:00.019450
2026-02-16 17:41:04.042501 | 
2026-02-16 17:41:04.042570 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:41:04.963059 | controller | changed
2026-02-16 17:41:05.007246 | 
2026-02-16 17:41:05.007395 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:41:05.248287 | controller | changed
2026-02-16 17:41:05.255625 | 
2026-02-16 17:41:05.255698 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:41:05.569055 | controller | Output suppressed because no_log was given
2026-02-16 17:41:05.574594 | 
2026-02-16 17:41:05.574664 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:41:05.612180 | 
2026-02-16 17:41:05.612310 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:41:05.648087 | 
2026-02-16 17:41:05.648229 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:41:05.680169 | 
2026-02-16 17:41:05.680322 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:41:05.715130 | 
2026-02-16 17:41:05.715290 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:41:05.754096 | 
2026-02-16 17:41:05.754340 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:41:06.009814 | controller | changed
2026-02-16 17:41:06.016449 | 
2026-02-16 17:41:06.016528 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:41:06.054934 | controller | ok
2026-02-16 17:41:06.061739 | 
2026-02-16 17:41:06.061805 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:41:06.304077 | controller | Output suppressed because no_log was given
2026-02-16 17:41:06.312488 | 
2026-02-16 17:41:06.312603 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:41:06.349002 | 
2026-02-16 17:41:06.349188 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:41:06.387061 | 
2026-02-16 17:41:06.387271 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:41:07.030110 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:41:07.049324 | controller | Using python 3.12 to install setuptools
2026-02-16 17:41:07.088669 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:41:07.099677 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:41:07.431978 | controller | ERROR
2026-02-16 17:41:07.432213 | controller | {
2026-02-16 17:41:07.432249 | controller |   "delta": "0:00:00.471520",
2026-02-16 17:41:07.432270 | controller |   "end": "2026-02-16 17:41:07.102018",
2026-02-16 17:41:07.432290 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:07.432314 | controller |   "rc": 127,
2026-02-16 17:41:07.432334 | controller |   "start": "2026-02-16 17:41:06.630498"
2026-02-16 17:41:07.432352 | controller | }
2026-02-16 17:41:07.432376 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:07.444625 | 
2026-02-16 17:41:07.444699 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:41:07.697918 | controller | changed
2026-02-16 17:41:07.703959 | 
2026-02-16 17:41:07.704029 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:41:11.371030 | controller | Output suppressed because no_log was given
2026-02-16 17:41:11.382987 | 
2026-02-16 17:41:11.383129 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:11.415032 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.415538 | 
2026-02-16 17:41:11.417574 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.419138 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.420745 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.423023 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.425356 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.427699 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.430066 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.432382 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.434691 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.437195 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.439494 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.441765 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.444142 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.446332 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.448579 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.454029 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.459707 | controller | skipping: Conditional result was False
2026-02-16 17:41:11.489370 | 
2026-02-16 17:41:11.489557 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:41:11.725109 | controller | ok
2026-02-16 17:41:11.733270 | 
2026-02-16 17:41:11.733359 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:12.318948 | controller | skipping: Conditional result was False
2026-02-16 17:41:12.335512 | 
2026-02-16 17:41:12.335594 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:41:12.595801 | controller | sudo: iptables-save: command not found
2026-02-16 17:41:12.632727 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:41:13.710707 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:41:13.875006 | controller | ok: Runtime: 0:00:01.140027
2026-02-16 17:41:13.886770 | 
2026-02-16 17:41:13.886845 | LOOP [stage-output : Register sources]
2026-02-16 17:41:22.963388 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.019864 | 
2026-02-16 17:41:23.020026 | TASK [stage-output : Check sudo]
2026-02-16 17:41:23.556696 | controller | ok: Runtime: 0:00:00.023100
2026-02-16 17:41:23.562667 | 
2026-02-16 17:41:23.562736 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:41:23.666926 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.667249 | 
2026-02-16 17:41:23.668317 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.670200 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.672361 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.709658 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.710024 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.710355 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.710687 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.712370 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.714550 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.716676 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.718790 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.721402 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.725991 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.743965 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.744468 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.746234 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.748030 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.748730 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.749730 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.750559 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.751045 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.752400 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.791630 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.792647 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.849996 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.858652 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.871575 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.883793 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.898919 | 
2026-02-16 17:41:23.899001 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:41:23.966447 | controller | ok
2026-02-16 17:41:23.976083 | 
2026-02-16 17:41:23.976151 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:41:24.234899 | controller | changed: "docs"
2026-02-16 17:41:24.437498 | controller | changed: "artifacts"
2026-02-16 17:41:24.672924 | controller | ok: "logs"
2026-02-16 17:41:24.687584 | 
2026-02-16 17:41:24.687762 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:41:24.970004 | controller | ok: Item: Runtime: 0:00:00.009903
2026-02-16 17:41:24.970392 | controller | changed: All items complete
2026-02-16 17:41:24.970444 | 
2026-02-16 17:41:25.209912 | controller | ok: Item: Runtime: 0:00:00.008911
2026-02-16 17:41:25.436421 | controller | ok: Item: Runtime: 0:00:00.008124
2026-02-16 17:41:25.674208 | controller | ok: Item: Runtime: 0:00:00.007592
2026-02-16 17:41:25.900262 | controller | ok: Item: Runtime: 0:00:00.007930
2026-02-16 17:41:26.124361 | controller | ok: Item: Runtime: 0:00:00.009145
2026-02-16 17:41:26.362271 | controller | ok: Item: Runtime: 0:00:00.007454
2026-02-16 17:41:26.592567 | controller | ok: Item: Runtime: 0:00:00.009623
2026-02-16 17:41:26.814433 | controller | ok: Item: Runtime: 0:00:00.008526
2026-02-16 17:41:27.046192 | controller | ok: Item: Runtime: 0:00:00.004638
2026-02-16 17:41:27.256193 | controller | ok: Item: Runtime: 0:00:00.005370
2026-02-16 17:41:27.530075 | controller | ok: Item: Runtime: 0:00:00.007219
2026-02-16 17:41:27.705691 | controller | ok: Item: Runtime: 0:00:00.007668
2026-02-16 17:41:27.931363 | controller | ok: Item: Runtime: 0:00:00.008151
2026-02-16 17:41:28.157883 | controller | ok: Item: Runtime: 0:00:00.008973
2026-02-16 17:41:28.380675 | controller | ok: Item: Runtime: 0:00:00.009144
2026-02-16 17:41:28.399805 | 
2026-02-16 17:41:28.399960 | TASK [stage-output : Make all log files readable]
2026-02-16 17:41:28.664337 | controller | changed
2026-02-16 17:41:28.670465 | 
2026-02-16 17:41:28.670533 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:41:28.999817 | controller | changed: Renamed files for staging.
2026-02-16 17:41:29.007160 | 
2026-02-16 17:41:29.007252 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:41:29.032879 | controller | skipping: Conditional result was False
2026-02-16 17:41:29.045190 | 
2026-02-16 17:41:29.045280 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:41:29.088872 | 
2026-02-16 17:41:29.089055 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:41:29.752640 | controller | changed:
2026-02-16 17:41:29.752915 | controller | created directory /var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/work/logs/controller
2026-02-16 17:41:29.753002 | controller | cd+++++++++ logs/
2026-02-16 17:41:29.753051 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:41:29.753094 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:41:29.753136 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:41:29.753176 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:41:29.753217 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:41:29.753257 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:41:29.753296 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:41:29.753336 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:41:29.753375 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:41:29.753413 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:41:29.753452 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:41:29.753490 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:41:29.753532 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:41:29.753597 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:41:29.753638 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:41:29.753678 | controller | cd+++++++++ logs/apache/
2026-02-16 17:41:29.753716 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:41:29.753755 | controller | cd+++++++++ logs/etc/
2026-02-16 17:41:29.753794 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:41:29.753833 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:41:29.753873 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:41:29.753913 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:41:29.753953 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:41:29.753992 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:41:29.766919 | 
2026-02-16 17:41:29.766980 | TASK [Check if a tempest log exits]
2026-02-16 17:41:30.024582 | controller | ok
2026-02-16 17:41:30.032442 | 
2026-02-16 17:41:30.032533 | TASK [Link post-devstack tempest.log]
2026-02-16 17:41:30.058678 | controller | skipping: Conditional result was False
2026-02-16 17:41:30.067904 | 
2026-02-16 17:41:30.067981 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:41:30.318615 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:41:30.607798 | controller | ERROR
2026-02-16 17:41:30.608045 | controller | {
2026-02-16 17:41:30.608090 | controller |   "delta": "0:00:00.007054",
2026-02-16 17:41:30.608120 | controller |   "end": "2026-02-16 17:41:30.319272",
2026-02-16 17:41:30.608146 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:30.608172 | controller |   "rc": 127,
2026-02-16 17:41:30.608198 | controller |   "start": "2026-02-16 17:41:30.312218"
2026-02-16 17:41:30.608222 | controller | }
2026-02-16 17:41:30.608256 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:30.610187 | 
2026-02-16 17:41:30.610252 | PLAY RECAP
2026-02-16 17:41:30.610317 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:41:30.610348 | 
2026-02-16 17:41:30.755481 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:30.766171 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:31.405536 | 
2026-02-16 17:41:31.405683 | PLAY [all]
2026-02-16 17:41:31.417907 | 
2026-02-16 17:41:31.417996 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:41:31.464372 | controller | skipping: Conditional result was False
2026-02-16 17:41:31.478939 | 
2026-02-16 17:41:31.479042 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:41:31.526350 | controller | ok
2026-02-16 17:41:31.533640 | 
2026-02-16 17:41:31.533740 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:41:31.973281 | controller -> localhost | ok: "/var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/work/logs"
2026-02-16 17:41:32.171978 | controller -> localhost | changed: "/var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/work/artifacts"
2026-02-16 17:41:32.460001 | controller -> localhost | changed: "/var/lib/zuul/builds/f2ffe0d120514abbae4fde98e7cdb0df/work/docs"
2026-02-16 17:41:32.476760 | 
2026-02-16 17:41:32.476943 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:41:33.176140 | controller | changed: .d..t...... ./
2026-02-16 17:41:33.176379 | controller | changed: All items complete
2026-02-16 17:41:33.176408 | 
2026-02-16 17:41:33.642456 | controller | changed: .d..t...... ./
2026-02-16 17:41:34.103691 | controller | changed: .d..t...... ./
2026-02-16 17:41:34.133502 | 
2026-02-16 17:41:34.133684 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:41:34.579507 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006844
2026-02-16 17:41:34.822260 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008195
2026-02-16 17:41:34.843499 | 
2026-02-16 17:41:34.843675 | PLAY [all]
2026-02-16 17:41:34.852281 | 
2026-02-16 17:41:34.852363 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:41:35.333312 | controller | changed
2026-02-16 17:41:35.339527 | 
2026-02-16 17:41:35.339581 | PLAY RECAP
2026-02-16 17:41:35.339629 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:41:35.339651 | 
2026-02-16 17:41:35.466093 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:35.475992 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:41:36.084017 | 
2026-02-16 17:41:36.084155 | PLAY [localhost]
2026-02-16 17:41:36.094928 | 
2026-02-16 17:41:36.095012 | TASK [Generate Zuul manifest]
2026-02-16 17:41:36.117283 | localhost | ok
2026-02-16 17:41:36.135104 | 
2026-02-16 17:41:36.135185 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:41:36.507060 | localhost | changed
2026-02-16 17:41:36.519152 | 
2026-02-16 17:41:36.519227 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:41:36.551842 | localhost | ok
2026-02-16 17:41:36.561456 | 
2026-02-16 17:41:36.561542 | TASK [Upload logs]
2026-02-16 17:41:36.583435 | localhost | ok
2026-02-16 17:41:36.645224 | 
2026-02-16 17:41:36.645326 | TASK [Set zuul-log-path fact]
2026-02-16 17:41:36.667469 | localhost | ok
2026-02-16 17:41:36.679485 | 
2026-02-16 17:41:36.679551 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:41:36.712284 | localhost | ok
2026-02-16 17:41:36.722216 | 
2026-02-16 17:41:36.722308 | TASK [upload-logs : Create log directories]
2026-02-16 17:41:37.143646 | localhost | changed
2026-02-16 17:41:37.151512 | 
2026-02-16 17:41:37.151604 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:41:37.607672 | localhost -> localhost | ok: Runtime: 0:00:00.006307
2026-02-16 17:41:37.613362 | 
2026-02-16 17:41:37.613432 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:41:38.075547 | localhost | Output suppressed because no_log was given
2026-02-16 17:41:38.081178 | 
2026-02-16 17:41:38.081274 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:41:38.127451 | localhost | skipping: Conditional result was False
2026-02-16 17:41:38.133613 | localhost | skipping: Conditional result was False
2026-02-16 17:41:38.148278 | 
2026-02-16 17:41:38.148435 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:41:38.190435 | localhost | skipping: Conditional result was False
2026-02-16 17:41:38.190826 | 
2026-02-16 17:41:38.193558 | localhost | skipping: Conditional result was False
2026-02-16 17:41:38.202551 | 
2026-02-16 17:41:38.202779 | LOOP [upload-logs : Upload console log and json output]