2026-02-16 17:42:03.900468 | Job console starting
2026-02-16 17:42:04.055627 | Updating git repos
2026-02-16 17:42:09.498249 | Cloning repos into workspace
2026-02-16 17:42:15.845490 | Restoring repo states
2026-02-16 17:42:18.244419 | Merging changes
2026-02-16 17:42:18.659706 | Checking out repos
2026-02-16 17:42:22.563965 | Preparing playbooks
2026-02-16 17:42:24.123137 | Running Ansible setup
2026-02-16 17:42:27.857401 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:28.456463 | 
2026-02-16 17:42:28.456604 | PLAY [localhost]
2026-02-16 17:42:28.464730 | 
2026-02-16 17:42:28.464802 | TASK [Gathering Facts]
2026-02-16 17:42:29.404827 | localhost | ok
2026-02-16 17:42:29.414895 | 
2026-02-16 17:42:29.414974 | TASK [Setup log path fact]
2026-02-16 17:42:29.438162 | localhost | ok
2026-02-16 17:42:29.449667 | 
2026-02-16 17:42:29.449731 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:42:29.479079 | localhost | ok
2026-02-16 17:42:29.486942 | 
2026-02-16 17:42:29.487047 | TASK [emit-job-header : Print job information]
2026-02-16 17:42:29.528580 | # Job Information
2026-02-16 17:42:29.528719 | Ansible Version: 2.16.15
2026-02-16 17:42:29.528764 | Job: magnum-cluster-api-sonobuoy-v1.35.1-calico
2026-02-16 17:42:29.528796 | Pipeline: check
2026-02-16 17:42:29.528825 | Executor: 3a2793d2bd32
2026-02-16 17:42:29.528853 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:42:29.528885 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:42:29.532666 | 
2026-02-16 17:42:29.532753 | LOOP [emit-job-header : Print node information]
2026-02-16 17:42:29.632597 | localhost | ok:
2026-02-16 17:42:29.632943 | localhost | # Node Information
2026-02-16 17:42:29.633043 | localhost | Inventory Hostname: controller
2026-02-16 17:42:29.633093 | localhost | Hostname: np0000155623
2026-02-16 17:42:29.633137 | localhost | Username: zuul
2026-02-16 17:42:29.633187 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:42:29.633230 | localhost | Provider: yul1
2026-02-16 17:42:29.633272 | localhost | Region: ca-ymq-1
2026-02-16 17:42:29.633313 | localhost | Label: ubuntu-noble-16
2026-02-16 17:42:29.633354 | localhost | Product Name: OpenStack Nova
2026-02-16 17:42:29.633395 | localhost | Interface IP: 199.204.45.169
2026-02-16 17:42:29.650128 | 
2026-02-16 17:42:29.650275 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:42:30.061484 | localhost -> localhost | changed
2026-02-16 17:42:30.071259 | 
2026-02-16 17:42:30.071393 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:42:30.966825 | localhost -> localhost | changed
2026-02-16 17:42:30.975253 | 
2026-02-16 17:42:30.975317 | PLAY [all]
2026-02-16 17:42:30.984320 | 
2026-02-16 17:42:30.984388 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:42:31.264749 | controller -> localhost | ok
2026-02-16 17:42:31.274084 | 
2026-02-16 17:42:31.274175 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:42:31.307582 | controller | ok
2026-02-16 17:42:31.330887 | controller | included: /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:42:31.341932 | 
2026-02-16 17:42:31.342033 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:42:32.706375 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:42:32.706609 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/work/3a34c6057b2147888e9d1c3fcbd4c21f_id_rsa
2026-02-16 17:42:32.706650 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/work/3a34c6057b2147888e9d1c3fcbd4c21f_id_rsa.pub
2026-02-16 17:42:32.706682 | controller -> localhost | The key fingerprint is:
2026-02-16 17:42:32.706713 | controller -> localhost | SHA256:M1aNC/ZxOv4VardhxTk/x2VeBPGZXCkPxAqKcb3ODTk zuul-build-sshkey
2026-02-16 17:42:32.706756 | controller -> localhost | The key's randomart image is:
2026-02-16 17:42:32.706785 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:42:32.706820 | controller -> localhost | |        .   o.o.o|
2026-02-16 17:42:32.706850 | controller -> localhost | |     . . o o +.++|
2026-02-16 17:42:32.706877 | controller -> localhost | |      + + B + ++o|
2026-02-16 17:42:32.706905 | controller -> localhost | |     . o E *   +.|
2026-02-16 17:42:32.706932 | controller -> localhost | |        S O   .o*|
2026-02-16 17:42:32.706960 | controller -> localhost | |       . * o . *=|
2026-02-16 17:42:32.706987 | controller -> localhost | |          . o =.=|
2026-02-16 17:42:32.707013 | controller -> localhost | |           o + oo|
2026-02-16 17:42:32.707044 | controller -> localhost | |            . .  |
2026-02-16 17:42:32.707072 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:42:32.707133 | controller -> localhost | ok: Runtime: 0:00:00.963008
2026-02-16 17:42:32.715015 | 
2026-02-16 17:42:32.715101 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:42:32.749990 | controller | ok
2026-02-16 17:42:32.760458 | controller | included: /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:42:32.768233 | 
2026-02-16 17:42:32.768292 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:42:32.792454 | controller | skipping: Conditional result was False
2026-02-16 17:42:32.804613 | 
2026-02-16 17:42:32.804745 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:42:33.375633 | controller | changed
2026-02-16 17:42:33.383937 | 
2026-02-16 17:42:33.384001 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:42:33.600685 | controller | ok
2026-02-16 17:42:33.607817 | 
2026-02-16 17:42:33.607881 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:42:34.158300 | controller | changed
2026-02-16 17:42:34.167024 | 
2026-02-16 17:42:34.167093 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:42:34.716533 | controller | changed
2026-02-16 17:42:34.723702 | 
2026-02-16 17:42:34.723767 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:42:34.749021 | controller | skipping: Conditional result was False
2026-02-16 17:42:34.759447 | 
2026-02-16 17:42:34.759535 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:42:35.128398 | controller -> localhost | changed
2026-02-16 17:42:35.141010 | 
2026-02-16 17:42:35.141081 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:42:35.505944 | controller -> localhost | Identity added: /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/work/3a34c6057b2147888e9d1c3fcbd4c21f_id_rsa (zuul-build-sshkey)
2026-02-16 17:42:35.506249 | controller -> localhost | ok: Runtime: 0:00:00.016267
2026-02-16 17:42:35.556336 | 
2026-02-16 17:42:35.556498 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:42:35.858827 | controller | ok
2026-02-16 17:42:35.867739 | 
2026-02-16 17:42:35.867812 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:42:35.893896 | controller | skipping: Conditional result was False
2026-02-16 17:42:35.910693 | 
2026-02-16 17:42:35.910775 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:42:36.240404 | controller | ok
2026-02-16 17:42:36.247060 | 
2026-02-16 17:42:36.247128 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:42:55.590563 | controller | Output suppressed because no_log was given
2026-02-16 17:42:55.604241 | 
2026-02-16 17:42:55.604307 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:42:55.851943 | controller | ok: "logs"
2026-02-16 17:42:55.852199 | controller | ok: All items complete
2026-02-16 17:42:55.852227 | 
2026-02-16 17:42:56.036646 | controller | ok: "artifacts"
2026-02-16 17:42:56.238474 | controller | ok: "docs"
2026-02-16 17:42:56.253296 | 
2026-02-16 17:42:56.253467 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:42:56.492011 | controller | changed: "logs"
2026-02-16 17:42:56.691631 | controller | changed: "artifacts"
2026-02-16 17:42:56.925277 | controller | changed: "docs"
2026-02-16 17:42:56.947163 | 
2026-02-16 17:42:56.947322 | PLAY RECAP
2026-02-16 17:42:56.947400 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:42:56.947447 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:42:56.947476 | 
2026-02-16 17:42:57.121276 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:57.129647 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:42:57.767433 | 
2026-02-16 17:42:57.767575 | PLAY [all]
2026-02-16 17:42:57.784783 | 
2026-02-16 17:42:57.784858 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:42:58.196676 | controller | changed
2026-02-16 17:42:58.204362 | 
2026-02-16 17:42:58.204438 | TASK [Gather minimum local MTU]
2026-02-16 17:42:58.284207 | controller | ok
2026-02-16 17:42:58.290632 | 
2026-02-16 17:42:58.290694 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:42:58.346331 | controller | ok
2026-02-16 17:42:58.351350 | 
2026-02-16 17:42:58.351414 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:42:58.376519 | controller | skipping: Conditional result was False
2026-02-16 17:42:58.389546 | 
2026-02-16 17:42:58.389637 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:42:58.930912 | controller | ok: Runtime: 0:00:00.009262
2026-02-16 17:42:58.939956 | 
2026-02-16 17:42:58.940029 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:42:58.969806 | controller | skipping: Conditional result was False
2026-02-16 17:42:58.982064 | 
2026-02-16 17:42:58.982174 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:42:59.015440 | controller | skipping: Conditional result was False
2026-02-16 17:42:59.026062 | 
2026-02-16 17:42:59.026191 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:42:59.091372 | controller | ok
2026-02-16 17:42:59.101392 | controller | included: /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:42:59.107318 | 
2026-02-16 17:42:59.107383 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:42:59.159412 | controller | ok
2026-02-16 17:42:59.165981 | 
2026-02-16 17:42:59.166040 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:42:59.444590 | controller | ext4
2026-02-16 17:42:59.748177 | controller | ok: Runtime: 0:00:00.009707
2026-02-16 17:42:59.753932 | 
2026-02-16 17:42:59.754038 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:42:59.789268 | controller | ok
2026-02-16 17:42:59.796735 | 
2026-02-16 17:42:59.796881 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:42:59.831143 | controller | ok:
2026-02-16 17:42:59.831392 | controller | {
2026-02-16 17:42:59.831429 | controller |   "root_filesystem": "ext4"
2026-02-16 17:42:59.831459 | controller | }
2026-02-16 17:42:59.838743 | 
2026-02-16 17:42:59.838890 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:43:09.699982 | controller | 8192+0 records in
2026-02-16 17:43:09.700109 | controller | 8192+0 records out
2026-02-16 17:43:09.700126 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.60889 s, 894 MB/s
2026-02-16 17:43:09.887282 | controller | ok: Runtime: 0:00:09.616435
2026-02-16 17:43:09.894587 | 
2026-02-16 17:43:09.894654 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:43:10.127720 | controller | changed
2026-02-16 17:43:10.134471 | 
2026-02-16 17:43:10.134538 | TASK [configure-swap : Make swapfile]
2026-02-16 17:43:17.364487 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:43:17.364582 | controller | no label, UUID=9053cc3d-ba57-4dd7-8179-d40b6cbb8ade
2026-02-16 17:43:17.680869 | controller | ok: Runtime: 0:00:07.002104
2026-02-16 17:43:17.687282 | 
2026-02-16 17:43:17.687347 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:43:18.066535 | controller | changed
2026-02-16 17:43:18.072923 | 
2026-02-16 17:43:18.073006 | TASK [configure-swap : Add all swap]
2026-02-16 17:43:18.614312 | controller | ok: Runtime: 0:00:00.013495
2026-02-16 17:43:18.621732 | 
2026-02-16 17:43:18.621800 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:43:18.656523 | controller | ok:
2026-02-16 17:43:18.656680 | controller | {
2026-02-16 17:43:18.656709 | controller |   "swap_required": "8192"
2026-02-16 17:43:18.656750 | controller | }
2026-02-16 17:43:18.661894 | 
2026-02-16 17:43:18.661959 | TASK [configure-swap : Set swappiness]
2026-02-16 17:43:19.081030 | controller | changed
2026-02-16 17:43:19.087677 | 
2026-02-16 17:43:19.087746 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:43:19.121553 | controller | ok:
2026-02-16 17:43:19.121684 | controller | {
2026-02-16 17:43:19.121709 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:43:19.121734 | controller | }
2026-02-16 17:43:19.131598 | 
2026-02-16 17:43:19.131662 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:43:19.551049 | controller | changed
2026-02-16 17:43:19.559018 | 
2026-02-16 17:43:19.559087 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:43:19.790101 | controller | changed
2026-02-16 17:43:19.795825 | 
2026-02-16 17:43:19.795893 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:43:20.327491 | controller | changed
2026-02-16 17:43:20.334836 | 
2026-02-16 17:43:20.334911 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:43:20.583980 | controller | changed
2026-02-16 17:43:20.590869 | 
2026-02-16 17:43:20.590940 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:43:21.637751 | controller | changed
2026-02-16 17:43:21.644846 | 
2026-02-16 17:43:21.644910 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:43:21.892686 | controller | changed
2026-02-16 17:43:21.907107 | 
2026-02-16 17:43:21.907175 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:43:22.205052 | controller | changed
2026-02-16 17:43:22.212013 | 
2026-02-16 17:43:22.212077 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:43:22.560530 | controller | changed
2026-02-16 17:43:22.605900 | 
2026-02-16 17:43:22.606056 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:43:23.400466 | controller | changed
2026-02-16 17:43:23.412334 | 
2026-02-16 17:43:23.412409 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:43:23.751560 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:43:23.765735 | 
2026-02-16 17:43:23.765826 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:43:24.224377 | controller | ok: Item: Runtime: 0:00:00.181648
2026-02-16 17:43:24.772239 | controller | ok: Item: Runtime: 0:00:00.338829
2026-02-16 17:43:25.507371 | controller | ok: Item: Runtime: 0:00:00.522513
2026-02-16 17:43:25.788790 | controller | ok: Item: Runtime: 0:00:00.060536
2026-02-16 17:43:26.265724 | controller | ok: Item: Runtime: 0:00:00.240370
2026-02-16 17:43:27.686955 | controller | ok: Item: Runtime: 0:00:01.190310
2026-02-16 17:43:30.767187 | controller | ok: Item: Runtime: 0:00:02.855004
2026-02-16 17:43:31.093903 | controller | ok: Item: Runtime: 0:00:00.098177
2026-02-16 17:43:31.505673 | controller | ok: Item: Runtime: 0:00:00.184916
2026-02-16 17:43:31.843356 | controller | ok: Item: Runtime: 0:00:00.122267
2026-02-16 17:43:32.560233 | controller | ok: Item: Runtime: 0:00:00.497479
2026-02-16 17:43:33.255582 | controller | ok: Item: Runtime: 0:00:00.467711
2026-02-16 17:43:33.729395 | controller | ok: Item: Runtime: 0:00:00.232567
2026-02-16 17:43:34.209698 | controller | ok: Item: Runtime: 0:00:00.220651
2026-02-16 17:43:34.775177 | controller | ok: Item: Runtime: 0:00:00.346437
2026-02-16 17:43:36.039524 | controller | ok: Item: Runtime: 0:00:01.039977
2026-02-16 17:43:36.070474 | 
2026-02-16 17:43:36.070638 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:43:36.286587 | controller | ok: All paths examined
2026-02-16 17:43:36.296470 | 
2026-02-16 17:43:36.296556 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:43:36.529877 | controller | ok: All paths examined
2026-02-16 17:43:36.537353 | 
2026-02-16 17:43:36.537422 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:43:36.955764 | controller | ok: Item: Runtime: 0:00:00.140937
2026-02-16 17:43:36.956014 | controller | changed: All items complete
2026-02-16 17:43:36.956042 | 
2026-02-16 17:43:37.363905 | controller | ok: Item: Runtime: 0:00:00.183079
2026-02-16 17:43:37.386622 | 
2026-02-16 17:43:37.386777 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:43:37.436695 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.437988 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.438703 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.442026 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.442722 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.444304 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.447420 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.450778 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.454141 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.457207 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.460164 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.463817 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.467260 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.470108 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.473155 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.476367 | controller | skipping: Conditional result was False
2026-02-16 17:43:37.505790 | 
2026-02-16 17:43:37.505914 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:43:39.934321 | controller | changed
2026-02-16 17:43:39.945262 | 
2026-02-16 17:43:39.945356 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:43:40.183192 | controller | changed
2026-02-16 17:43:40.238988 | 
2026-02-16 17:43:40.239129 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:43:40.499869 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:43:40.781175 | controller | ERROR
2026-02-16 17:43:40.781487 | controller | {
2026-02-16 17:43:40.781556 | controller |   "delta": "0:00:00.010122",
2026-02-16 17:43:40.781665 | controller |   "end": "2026-02-16 17:43:40.500464",
2026-02-16 17:43:40.781716 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:40.781762 | controller |   "rc": 1,
2026-02-16 17:43:40.781807 | controller |   "start": "2026-02-16 17:43:40.490342"
2026-02-16 17:43:40.781851 | controller | }
2026-02-16 17:43:40.781907 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:40.788065 | 
2026-02-16 17:43:40.788132 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:43:41.029973 | controller | ok
2026-02-16 17:43:41.044224 | 
2026-02-16 17:43:41.044293 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:43:41.276843 | controller | /usr/bin/journalctl
2026-02-16 17:43:41.584074 | controller | ok: Runtime: 0:00:00.007468
2026-02-16 17:43:41.591381 | 
2026-02-16 17:43:41.591463 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:43:41.858726 | controller | 2026-02-16 17:43:41
2026-02-16 17:43:42.131960 | controller | ok: Runtime: 0:00:00.009906
2026-02-16 17:43:42.137341 | 
2026-02-16 17:43:42.137404 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:43:42.705174 | controller | changed
2026-02-16 17:43:42.711008 | 
2026-02-16 17:43:42.711069 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:43:42.734178 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.745275 | 
2026-02-16 17:43:42.745340 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:43:43.276703 | controller | skipping: Conditional result was False
2026-02-16 17:43:43.291249 | 
2026-02-16 17:43:43.291556 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:43:43.833150 | controller | skipping: Conditional result was False
2026-02-16 17:43:43.842967 | 
2026-02-16 17:43:43.843061 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:43:43.868145 | controller | skipping: Conditional result was False
2026-02-16 17:43:43.880917 | 
2026-02-16 17:43:43.880995 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:43:44.410651 | controller | skipping: Conditional result was False
2026-02-16 17:43:44.422867 | 
2026-02-16 17:43:44.422955 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:43:44.958552 | controller | skipping: Conditional result was False
2026-02-16 17:43:44.965279 | 
2026-02-16 17:43:44.965347 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:43:44.981186 | controller | skipping: Conditional result was False
2026-02-16 17:43:44.991699 | 
2026-02-16 17:43:44.991775 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:43:45.005052 | controller | skipping: Conditional result was False
2026-02-16 17:43:45.011088 | 
2026-02-16 17:43:45.011152 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:43:45.583203 | controller | skipping: Conditional result was False
2026-02-16 17:43:45.594229 | 
2026-02-16 17:43:45.594322 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:43:46.125159 | controller | skipping: Conditional result was False
2026-02-16 17:43:46.135639 | 
2026-02-16 17:43:46.135731 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:43:46.160884 | controller | skipping: Conditional result was False
2026-02-16 17:43:46.173321 | 
2026-02-16 17:43:46.173461 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:43:46.199148 | controller | skipping: Conditional result was False
2026-02-16 17:43:46.213645 | 
2026-02-16 17:43:46.213715 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:43:46.605317 | controller | ok
2026-02-16 17:43:46.614534 | 
2026-02-16 17:43:46.614608 | PLAY RECAP
2026-02-16 17:43:46.614658 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:43:46.614700 | 
2026-02-16 17:43:46.780454 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:43:46.791148 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:43:47.376760 | 
2026-02-16 17:43:47.376946 | PLAY [all]
2026-02-16 17:43:47.390207 | 
2026-02-16 17:43:47.390359 | TASK [ensure-rust : Use rustup]
2026-02-16 17:43:47.424366 | controller | ok
2026-02-16 17:43:47.432534 | controller | included: /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:43:47.438059 | 
2026-02-16 17:43:47.438132 | TASK [ensure-rust : Install Rust]
2026-02-16 17:43:47.829378 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:43:47.977180 | controller | ERROR
2026-02-16 17:43:47.977444 | controller | {
2026-02-16 17:43:47.977487 | controller |   "delta": "0:00:00.008973",
2026-02-16 17:43:47.977521 | controller |   "end": "2026-02-16 17:43:47.830739",
2026-02-16 17:43:47.977550 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:47.977577 | controller |   "rc": 127,
2026-02-16 17:43:47.977604 | controller |   "start": "2026-02-16 17:43:47.821766"
2026-02-16 17:43:47.977636 | controller | }
failure
2026-02-16 17:43:47.979504 | 
2026-02-16 17:43:47.979570 | PLAY RECAP
2026-02-16 17:43:47.979652 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:43:47.979686 | 
2026-02-16 17:43:48.092723 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:43:48.101746 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:43:48.688330 | 
2026-02-16 17:43:48.688555 | PLAY [all]
2026-02-16 17:43:48.710216 | 
2026-02-16 17:43:48.710399 | TASK [Return built artifacts to Zuul]
2026-02-16 17:43:48.743001 | controller | ok
2026-02-16 17:43:48.750628 | 
2026-02-16 17:43:48.750727 | TASK [Get the Docker volume ID]
2026-02-16 17:43:49.298956 | controller | ERROR
2026-02-16 17:43:49.299326 | controller | {
2026-02-16 17:43:49.299370 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:43:49.299401 | controller |   "rc": 2
2026-02-16 17:43:49.299428 | controller | }
2026-02-16 17:43:49.299467 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:49.307101 | 
2026-02-16 17:43:49.307166 | TASK [Copy all of the pod logs]
2026-02-16 17:43:49.342119 | controller | ERROR
2026-02-16 17:43:49.342376 | controller | {
2026-02-16 17:43:49.342417 | 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/3a34c6057b2147888e9d1c3fcbd4c21f/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:43:49.342449 | controller | }
2026-02-16 17:43:49.342487 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:49.343752 | 
2026-02-16 17:43:49.343814 | PLAY RECAP
2026-02-16 17:43:49.343871 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:43:49.343900 | 
2026-02-16 17:43:49.476050 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:43:49.484508 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:43:50.109809 | 
2026-02-16 17:43:50.109950 | PLAY [all]
2026-02-16 17:43:50.129036 | 
2026-02-16 17:43:50.129128 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:43:50.539585 | controller | changed
2026-02-16 17:43:50.547030 | 
2026-02-16 17:43:50.547132 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:43:51.586520 | controller | ok: Runtime: 0:00:00.452283
2026-02-16 17:43:51.593442 | 
2026-02-16 17:43:51.593515 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:43:52.135993 | controller | ok: Runtime: 0:00:00.015454
2026-02-16 17:43:52.143935 | 
2026-02-16 17:43:52.144006 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:43:52.683130 | controller | ok: Runtime: 0:00:00.021684
2026-02-16 17:43:52.689595 | 
2026-02-16 17:43:52.689663 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:43:53.531979 | controller | changed
2026-02-16 17:43:53.578609 | 
2026-02-16 17:43:53.578766 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:43:53.816806 | controller | changed
2026-02-16 17:43:53.822457 | 
2026-02-16 17:43:53.822525 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:43:54.152764 | controller | Output suppressed because no_log was given
2026-02-16 17:43:54.158167 | 
2026-02-16 17:43:54.158229 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:43:54.196768 | 
2026-02-16 17:43:54.197043 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:43:54.232121 | 
2026-02-16 17:43:54.232296 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:43:54.266845 | 
2026-02-16 17:43:54.267007 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:43:54.303667 | 
2026-02-16 17:43:54.303902 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:43:54.340597 | 
2026-02-16 17:43:54.340734 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:43:54.579670 | controller | changed
2026-02-16 17:43:54.585442 | 
2026-02-16 17:43:54.585509 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:43:54.620843 | controller | ok
2026-02-16 17:43:54.626325 | 
2026-02-16 17:43:54.626387 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:43:54.873119 | controller | Output suppressed because no_log was given
2026-02-16 17:43:54.879546 | 
2026-02-16 17:43:54.879615 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:43:54.916189 | 
2026-02-16 17:43:54.916360 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:43:54.958358 | 
2026-02-16 17:43:54.958486 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:43:55.581224 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:43:55.599515 | controller | Using python 3.12 to install setuptools
2026-02-16 17:43:55.634808 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:43:55.644973 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:43:56.000073 | controller | ERROR
2026-02-16 17:43:56.000420 | controller | {
2026-02-16 17:43:56.000495 | controller |   "delta": "0:00:00.441114",
2026-02-16 17:43:56.000542 | controller |   "end": "2026-02-16 17:43:55.647086",
2026-02-16 17:43:56.000583 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:56.000631 | controller |   "rc": 127,
2026-02-16 17:43:56.000671 | controller |   "start": "2026-02-16 17:43:55.205972"
2026-02-16 17:43:56.000708 | controller | }
2026-02-16 17:43:56.000762 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:56.013594 | 
2026-02-16 17:43:56.013670 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:43:56.262619 | controller | changed
2026-02-16 17:43:56.269712 | 
2026-02-16 17:43:56.269785 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:44:00.643471 | controller | Output suppressed because no_log was given
2026-02-16 17:44:00.659132 | 
2026-02-16 17:44:00.659230 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:44:00.695415 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.696012 | 
2026-02-16 17:44:00.698075 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.699628 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.702130 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.703677 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.706038 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.708340 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.710865 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.713180 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.715518 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.718011 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.720388 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.722729 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.725218 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.727159 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.729520 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.731741 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.735058 | controller | skipping: Conditional result was False
2026-02-16 17:44:00.762938 | 
2026-02-16 17:44:00.763083 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:44:00.996556 | controller | ok
2026-02-16 17:44:01.002727 | 
2026-02-16 17:44:01.002792 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:44:01.574438 | controller | skipping: Conditional result was False
2026-02-16 17:44:01.590924 | 
2026-02-16 17:44:01.590999 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:44:01.874816 | controller | sudo: iptables-save: command not found
2026-02-16 17:44:01.917002 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:44:02.957443 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:44:03.132810 | controller | ok: Runtime: 0:00:01.103723
2026-02-16 17:44:03.144591 | 
2026-02-16 17:44:03.144663 | LOOP [stage-output : Register sources]
2026-02-16 17:44:12.738337 | controller | Output suppressed because no_log was given
2026-02-16 17:44:12.787486 | 
2026-02-16 17:44:12.787708 | TASK [stage-output : Check sudo]
2026-02-16 17:44:13.329609 | controller | ok: Runtime: 0:00:00.019982
2026-02-16 17:44:13.337719 | 
2026-02-16 17:44:13.337808 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:44:13.436715 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.437141 | 
2026-02-16 17:44:13.438869 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.440268 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.442452 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.479309 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.479815 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.480301 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.481490 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.483888 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.486610 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.488673 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.490871 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.493081 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.494943 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.513831 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.514423 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.514858 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.515193 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.515523 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.516540 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.518692 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.521074 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.523286 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.548144 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.548653 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.620842 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.631664 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.642229 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.648224 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.662122 | 
2026-02-16 17:44:13.662196 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:44:13.728196 | controller | ok
2026-02-16 17:44:13.736651 | 
2026-02-16 17:44:13.736730 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:44:13.973328 | controller | changed: "docs"
2026-02-16 17:44:14.189774 | controller | changed: "artifacts"
2026-02-16 17:44:14.393948 | controller | ok: "logs"
2026-02-16 17:44:14.409212 | 
2026-02-16 17:44:14.409355 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:44:14.681555 | controller | ok: Item: Runtime: 0:00:00.009564
2026-02-16 17:44:14.681828 | controller | changed: All items complete
2026-02-16 17:44:14.681857 | 
2026-02-16 17:44:14.899980 | controller | ok: Item: Runtime: 0:00:00.007087
2026-02-16 17:44:15.132776 | controller | ok: Item: Runtime: 0:00:00.008763
2026-02-16 17:44:15.364364 | controller | ok: Item: Runtime: 0:00:00.007464
2026-02-16 17:44:15.598261 | controller | ok: Item: Runtime: 0:00:00.009330
2026-02-16 17:44:15.835775 | controller | ok: Item: Runtime: 0:00:00.010223
2026-02-16 17:44:16.075611 | controller | ok: Item: Runtime: 0:00:00.006971
2026-02-16 17:44:16.313983 | controller | ok: Item: Runtime: 0:00:00.007106
2026-02-16 17:44:16.529935 | controller | ok: Item: Runtime: 0:00:00.005841
2026-02-16 17:44:16.753673 | controller | ok: Item: Runtime: 0:00:00.005632
2026-02-16 17:44:16.984403 | controller | ok: Item: Runtime: 0:00:00.007386
2026-02-16 17:44:17.263010 | controller | ok: Item: Runtime: 0:00:00.008453
2026-02-16 17:44:17.467827 | controller | ok: Item: Runtime: 0:00:00.007034
2026-02-16 17:44:17.694893 | controller | ok: Item: Runtime: 0:00:00.006250
2026-02-16 17:44:17.919939 | controller | ok: Item: Runtime: 0:00:00.004179
2026-02-16 17:44:18.147455 | controller | ok: Item: Runtime: 0:00:00.007808
2026-02-16 17:44:18.174287 | 
2026-02-16 17:44:18.174418 | TASK [stage-output : Make all log files readable]
2026-02-16 17:44:18.423466 | controller | changed
2026-02-16 17:44:18.429605 | 
2026-02-16 17:44:18.429667 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:44:18.775180 | controller | changed: Renamed files for staging.
2026-02-16 17:44:18.780740 | 
2026-02-16 17:44:18.780811 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:44:18.801727 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.813815 | 
2026-02-16 17:44:18.813916 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:44:18.857334 | 
2026-02-16 17:44:18.857536 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:44:19.484698 | controller | changed:
2026-02-16 17:44:19.486229 | controller | created directory /var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/work/logs/controller
2026-02-16 17:44:19.486280 | controller | cd+++++++++ logs/
2026-02-16 17:44:19.486312 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:44:19.486340 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:44:19.486368 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:44:19.486395 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:44:19.486422 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:44:19.486448 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:44:19.486474 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:44:19.486500 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:44:19.486526 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:44:19.486552 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:44:19.486578 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:44:19.486604 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:44:19.486634 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:44:19.486681 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:44:19.486707 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:44:19.486733 | controller | cd+++++++++ logs/apache/
2026-02-16 17:44:19.486759 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:44:19.486785 | controller | cd+++++++++ logs/etc/
2026-02-16 17:44:19.486811 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:44:19.486837 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:44:19.486863 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:44:19.486888 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:44:19.486914 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:44:19.486941 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:44:19.501360 | 
2026-02-16 17:44:19.501426 | TASK [Check if a tempest log exits]
2026-02-16 17:44:19.747296 | controller | ok
2026-02-16 17:44:19.753896 | 
2026-02-16 17:44:19.753958 | TASK [Link post-devstack tempest.log]
2026-02-16 17:44:19.779664 | controller | skipping: Conditional result was False
2026-02-16 17:44:19.790634 | 
2026-02-16 17:44:19.790721 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:44:20.042746 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:44:20.335267 | controller | ERROR
2026-02-16 17:44:20.335608 | controller | {
2026-02-16 17:44:20.335676 | controller |   "delta": "0:00:00.007435",
2026-02-16 17:44:20.335721 | controller |   "end": "2026-02-16 17:44:20.043365",
2026-02-16 17:44:20.335763 | controller |   "msg": "non-zero return code",
2026-02-16 17:44:20.335804 | controller |   "rc": 127,
2026-02-16 17:44:20.335845 | controller |   "start": "2026-02-16 17:44:20.035930"
2026-02-16 17:44:20.335933 | controller | }
2026-02-16 17:44:20.335985 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:20.338351 | 
2026-02-16 17:44:20.338401 | PLAY RECAP
2026-02-16 17:44:20.338455 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:44:20.338476 | 
2026-02-16 17:44:20.482175 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:44:20.493044 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:21.150765 | 
2026-02-16 17:44:21.150902 | PLAY [all]
2026-02-16 17:44:21.162465 | 
2026-02-16 17:44:21.162537 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:44:21.207767 | controller | skipping: Conditional result was False
2026-02-16 17:44:21.215879 | 
2026-02-16 17:44:21.215990 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:44:21.261983 | controller | ok
2026-02-16 17:44:21.270251 | 
2026-02-16 17:44:21.270321 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:44:21.722432 | controller -> localhost | ok: "/var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/work/logs"
2026-02-16 17:44:21.940016 | controller -> localhost | changed: "/var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/work/artifacts"
2026-02-16 17:44:22.241813 | controller -> localhost | changed: "/var/lib/zuul/builds/3a34c6057b2147888e9d1c3fcbd4c21f/work/docs"
2026-02-16 17:44:22.263301 | 
2026-02-16 17:44:22.263448 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:44:22.903771 | controller | changed: .d..t...... ./
2026-02-16 17:44:22.904055 | controller | changed: All items complete
2026-02-16 17:44:22.904094 | 
2026-02-16 17:44:23.359085 | controller | changed: .d..t...... ./
2026-02-16 17:44:23.807284 | controller | changed: .d..t...... ./
2026-02-16 17:44:23.828553 | 
2026-02-16 17:44:23.828695 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:44:24.274518 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008382
2026-02-16 17:44:24.498952 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008311
2026-02-16 17:44:24.521232 | 
2026-02-16 17:44:24.521406 | PLAY [all]
2026-02-16 17:44:24.529714 | 
2026-02-16 17:44:24.529780 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:44:25.041179 | controller | changed
2026-02-16 17:44:25.048357 | 
2026-02-16 17:44:25.048409 | PLAY RECAP
2026-02-16 17:44:25.048458 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:44:25.048479 | 
2026-02-16 17:44:25.170057 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:25.175552 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:44:25.766260 | 
2026-02-16 17:44:25.766395 | PLAY [localhost]
2026-02-16 17:44:25.776721 | 
2026-02-16 17:44:25.776795 | TASK [Generate Zuul manifest]
2026-02-16 17:44:25.794551 | localhost | ok
2026-02-16 17:44:25.808385 | 
2026-02-16 17:44:25.808461 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:44:26.185327 | localhost | changed
2026-02-16 17:44:26.198313 | 
2026-02-16 17:44:26.198390 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:44:26.229714 | localhost | ok
2026-02-16 17:44:26.238314 | 
2026-02-16 17:44:26.238394 | TASK [Upload logs]
2026-02-16 17:44:26.258608 | localhost | ok
2026-02-16 17:44:26.319799 | 
2026-02-16 17:44:26.319929 | TASK [Set zuul-log-path fact]
2026-02-16 17:44:26.342530 | localhost | ok
2026-02-16 17:44:26.354115 | 
2026-02-16 17:44:26.354181 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:44:26.386174 | localhost | ok
2026-02-16 17:44:26.394439 | 
2026-02-16 17:44:26.394503 | TASK [upload-logs : Create log directories]
2026-02-16 17:44:26.786705 | localhost | changed
2026-02-16 17:44:26.794676 | 
2026-02-16 17:44:26.794776 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:44:27.248301 | localhost -> localhost | ok: Runtime: 0:00:00.006779
2026-02-16 17:44:27.253031 | 
2026-02-16 17:44:27.253095 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:44:27.674522 | localhost | Output suppressed because no_log was given
2026-02-16 17:44:27.679158 | 
2026-02-16 17:44:27.679229 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:44:27.725132 | localhost | skipping: Conditional result was False
2026-02-16 17:44:27.731131 | localhost | skipping: Conditional result was False
2026-02-16 17:44:27.744768 | 
2026-02-16 17:44:27.744924 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:44:27.782731 | localhost | skipping: Conditional result was False
2026-02-16 17:44:27.783130 | 
2026-02-16 17:44:27.785936 | localhost | skipping: Conditional result was False
2026-02-16 17:44:27.800558 | 
2026-02-16 17:44:27.800699 | LOOP [upload-logs : Upload console log and json output]