2026-02-16 17:20:41.154509 | Job console starting
2026-02-16 17:20:41.341051 | Updating git repos
2026-02-16 17:20:49.048183 | Cloning repos into workspace
2026-02-16 17:20:56.726522 | Restoring repo states
2026-02-16 17:20:56.864225 | Merging changes
2026-02-16 17:20:57.436090 | Checking out repos
2026-02-16 17:21:02.258274 | Preparing playbooks
2026-02-16 17:21:04.292812 | Running Ansible setup
2026-02-16 17:21:08.285395 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:08.882755 | 
2026-02-16 17:21:08.882879 | PLAY [localhost]
2026-02-16 17:21:08.890598 | 
2026-02-16 17:21:08.890671 | TASK [Gathering Facts]
2026-02-16 17:21:09.783838 | localhost | ok
2026-02-16 17:21:09.791137 | 
2026-02-16 17:21:09.791213 | TASK [Setup log path fact]
2026-02-16 17:21:09.811001 | localhost | ok
2026-02-16 17:21:09.825497 | 
2026-02-16 17:21:09.825602 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:21:09.855900 | localhost | ok
2026-02-16 17:21:09.947058 | 
2026-02-16 17:21:09.947252 | TASK [emit-job-header : Print job information]
2026-02-16 17:21:09.992755 | # Job Information
2026-02-16 17:21:09.992924 | Ansible Version: 2.16.15
2026-02-16 17:21:09.992993 | Job: magnum-cluster-api-sonobuoy-v1.33.8-cilium
2026-02-16 17:21:09.993019 | Pipeline: check
2026-02-16 17:21:09.993039 | Executor: 3a2793d2bd32
2026-02-16 17:21:09.993058 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:21:09.993079 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:21:09.995964 | 
2026-02-16 17:21:09.996072 | LOOP [emit-job-header : Print node information]
2026-02-16 17:21:10.099401 | localhost | ok:
2026-02-16 17:21:10.099692 | localhost | # Node Information
2026-02-16 17:21:10.099735 | localhost | Inventory Hostname: controller
2026-02-16 17:21:10.099766 | localhost | Hostname: np0000155587
2026-02-16 17:21:10.099794 | localhost | Username: zuul
2026-02-16 17:21:10.099825 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:21:10.099852 | localhost | Provider: yul1
2026-02-16 17:21:10.099880 | localhost | Region: ca-ymq-1
2026-02-16 17:21:10.099906 | localhost | Label: ubuntu-noble-16
2026-02-16 17:21:10.099933 | localhost | Product Name: OpenStack Nova
2026-02-16 17:21:10.099960 | localhost | Interface IP: 199.204.45.109
2026-02-16 17:21:10.114549 | 
2026-02-16 17:21:10.114734 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:21:10.556710 | localhost -> localhost | changed
2026-02-16 17:21:10.562899 | 
2026-02-16 17:21:10.562977 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:21:11.431791 | localhost -> localhost | changed
2026-02-16 17:21:11.444405 | 
2026-02-16 17:21:11.444466 | PLAY [all]
2026-02-16 17:21:11.458092 | 
2026-02-16 17:21:11.458165 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:21:11.715392 | controller -> localhost | ok
2026-02-16 17:21:11.726330 | 
2026-02-16 17:21:11.726424 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:21:11.762609 | controller | ok
2026-02-16 17:21:11.780135 | controller | included: /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:21:11.786399 | 
2026-02-16 17:21:11.786462 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:21:12.880517 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:21:12.880743 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/work/4f2533728632466cbbcbda375b2686a0_id_rsa
2026-02-16 17:21:12.880785 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/work/4f2533728632466cbbcbda375b2686a0_id_rsa.pub
2026-02-16 17:21:12.880825 | controller -> localhost | The key fingerprint is:
2026-02-16 17:21:12.880863 | controller -> localhost | SHA256:I8kCfVC1LfYXxn1YbzpgMelSTDnvDA9KnfoeLFV7Rks zuul-build-sshkey
2026-02-16 17:21:12.880909 | controller -> localhost | The key's randomart image is:
2026-02-16 17:21:12.880940 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:21:12.880997 | controller -> localhost | |    .....  o+o  .|
2026-02-16 17:21:12.881030 | controller -> localhost | |   . .   o .*+ o.|
2026-02-16 17:21:12.881071 | controller -> localhost | |  . . . + .+*++E=|
2026-02-16 17:21:12.881142 | controller -> localhost | |   . o o ooo*+o*.|
2026-02-16 17:21:12.881185 | controller -> localhost | |    . + S..+o*+.o|
2026-02-16 17:21:12.881213 | controller -> localhost | |     . . .o+  ++ |
2026-02-16 17:21:12.881241 | controller -> localhost | |          ..o    |
2026-02-16 17:21:12.881269 | controller -> localhost | |           ...   |
2026-02-16 17:21:12.881299 | controller -> localhost | |           ..    |
2026-02-16 17:21:12.881327 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:21:12.881391 | controller -> localhost | ok: Runtime: 0:00:00.662386
2026-02-16 17:21:12.888214 | 
2026-02-16 17:21:12.888284 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:21:12.924034 | controller | ok
2026-02-16 17:21:12.935365 | controller | included: /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:21:12.946375 | 
2026-02-16 17:21:12.946505 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:21:12.972504 | controller | skipping: Conditional result was False
2026-02-16 17:21:12.983633 | 
2026-02-16 17:21:12.983756 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:21:13.519972 | controller | changed
2026-02-16 17:21:13.529021 | 
2026-02-16 17:21:13.529089 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:21:13.755534 | controller | ok
2026-02-16 17:21:13.763465 | 
2026-02-16 17:21:13.763535 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:21:14.347948 | controller | changed
2026-02-16 17:21:14.353939 | 
2026-02-16 17:21:14.354007 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:21:14.925765 | controller | changed
2026-02-16 17:21:14.934858 | 
2026-02-16 17:21:14.934928 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:21:14.960475 | controller | skipping: Conditional result was False
2026-02-16 17:21:14.970542 | 
2026-02-16 17:21:14.970609 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:21:15.344398 | controller -> localhost | changed
2026-02-16 17:21:15.362610 | 
2026-02-16 17:21:15.362679 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:21:15.721952 | controller -> localhost | Identity added: /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/work/4f2533728632466cbbcbda375b2686a0_id_rsa (zuul-build-sshkey)
2026-02-16 17:21:15.722154 | controller -> localhost | ok: Runtime: 0:00:00.014765
2026-02-16 17:21:15.790500 | 
2026-02-16 17:21:15.790643 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:21:16.100805 | controller | ok
2026-02-16 17:21:16.106115 | 
2026-02-16 17:21:16.106176 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:21:16.131357 | controller | skipping: Conditional result was False
2026-02-16 17:21:16.147177 | 
2026-02-16 17:21:16.147244 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:21:16.500069 | controller | ok
2026-02-16 17:21:16.506637 | 
2026-02-16 17:21:16.506702 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:21:45.309997 | controller | Output suppressed because no_log was given
2026-02-16 17:21:45.324188 | 
2026-02-16 17:21:45.324277 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:21:45.561039 | controller | ok: "logs"
2026-02-16 17:21:45.561283 | controller | ok: All items complete
2026-02-16 17:21:45.561312 | 
2026-02-16 17:21:45.752811 | controller | ok: "artifacts"
2026-02-16 17:21:45.965624 | controller | ok: "docs"
2026-02-16 17:21:45.984228 | 
2026-02-16 17:21:45.984399 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:21:46.241006 | controller | changed: "logs"
2026-02-16 17:21:46.430207 | controller | changed: "artifacts"
2026-02-16 17:21:46.662177 | controller | changed: "docs"
2026-02-16 17:21:46.689319 | 
2026-02-16 17:21:46.689434 | PLAY RECAP
2026-02-16 17:21:46.689483 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:21:46.689511 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:21:46.689534 | 
2026-02-16 17:21:46.857184 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:46.861927 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:21:47.529658 | 
2026-02-16 17:21:47.530124 | PLAY [all]
2026-02-16 17:21:47.550439 | 
2026-02-16 17:21:47.550517 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:21:47.960465 | controller | changed
2026-02-16 17:21:47.968554 | 
2026-02-16 17:21:47.968630 | TASK [Gather minimum local MTU]
2026-02-16 17:21:48.044396 | controller | ok
2026-02-16 17:21:48.049983 | 
2026-02-16 17:21:48.050116 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:21:48.124356 | controller | ok
2026-02-16 17:21:48.131315 | 
2026-02-16 17:21:48.131393 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:21:48.157390 | controller | skipping: Conditional result was False
2026-02-16 17:21:48.170202 | 
2026-02-16 17:21:48.170328 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:21:48.711919 | controller | ok: Runtime: 0:00:00.010301
2026-02-16 17:21:48.718839 | 
2026-02-16 17:21:48.718907 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:21:48.744693 | controller | skipping: Conditional result was False
2026-02-16 17:21:48.757462 | 
2026-02-16 17:21:48.757563 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:21:48.782664 | controller | skipping: Conditional result was False
2026-02-16 17:21:48.792627 | 
2026-02-16 17:21:48.792696 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:21:48.848672 | controller | ok
2026-02-16 17:21:48.858965 | controller | included: /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:21:48.865006 | 
2026-02-16 17:21:48.865107 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:21:48.919578 | controller | ok
2026-02-16 17:21:48.925626 | 
2026-02-16 17:21:48.925693 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:21:49.201687 | controller | ext4
2026-02-16 17:21:49.500505 | controller | ok: Runtime: 0:00:00.014410
2026-02-16 17:21:49.506010 | 
2026-02-16 17:21:49.506074 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:21:49.539627 | controller | ok
2026-02-16 17:21:49.546671 | 
2026-02-16 17:21:49.546745 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:21:49.580680 | controller | ok:
2026-02-16 17:21:49.580863 | controller | {
2026-02-16 17:21:49.580904 | controller |   "root_filesystem": "ext4"
2026-02-16 17:21:49.580932 | controller | }
2026-02-16 17:21:49.587684 | 
2026-02-16 17:21:49.587752 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:21:59.035226 | controller | 8192+0 records in
2026-02-16 17:21:59.035371 | controller | 8192+0 records out
2026-02-16 17:21:59.035386 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.1804 s, 936 MB/s
2026-02-16 17:21:59.145556 | controller | ok: Runtime: 0:00:09.189484
2026-02-16 17:21:59.151209 | 
2026-02-16 17:21:59.151279 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:21:59.389758 | controller | changed
2026-02-16 17:21:59.396411 | 
2026-02-16 17:21:59.396482 | TASK [configure-swap : Make swapfile]
2026-02-16 17:22:33.482466 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:22:33.483502 | controller | no label, UUID=1a7d61f1-6bff-447d-9e68-c734bfa386a1
2026-02-16 17:22:33.540286 | controller | ok: Runtime: 0:00:33.827033
2026-02-16 17:22:33.546101 | 
2026-02-16 17:22:33.546177 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:22:33.939378 | controller | changed
2026-02-16 17:22:33.945286 | 
2026-02-16 17:22:33.945359 | TASK [configure-swap : Add all swap]
2026-02-16 17:22:34.486926 | controller | ok: Runtime: 0:00:00.051639
2026-02-16 17:22:34.495073 | 
2026-02-16 17:22:34.495157 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:22:34.530228 | controller | ok:
2026-02-16 17:22:34.530387 | controller | {
2026-02-16 17:22:34.530422 | controller |   "swap_required": "8192"
2026-02-16 17:22:34.530450 | controller | }
2026-02-16 17:22:34.536732 | 
2026-02-16 17:22:34.536800 | TASK [configure-swap : Set swappiness]
2026-02-16 17:22:34.967500 | controller | changed
2026-02-16 17:22:34.975367 | 
2026-02-16 17:22:34.975463 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:22:35.010334 | controller | ok:
2026-02-16 17:22:35.010520 | controller | {
2026-02-16 17:22:35.010555 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:22:35.010590 | controller | }
2026-02-16 17:22:35.021522 | 
2026-02-16 17:22:35.021587 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:22:35.901379 | controller | changed
2026-02-16 17:22:35.908992 | 
2026-02-16 17:22:35.909062 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:22:36.161191 | controller | changed
2026-02-16 17:22:36.167903 | 
2026-02-16 17:22:36.167979 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:22:38.063402 | controller | changed
2026-02-16 17:22:38.069160 | 
2026-02-16 17:22:38.069227 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:22:38.335211 | controller | changed
2026-02-16 17:22:38.341618 | 
2026-02-16 17:22:38.341684 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:22:39.447319 | controller | changed
2026-02-16 17:22:39.454233 | 
2026-02-16 17:22:39.454297 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:22:39.702779 | controller | changed
2026-02-16 17:22:39.713799 | 
2026-02-16 17:22:39.713867 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:22:40.155673 | controller | changed
2026-02-16 17:22:40.161122 | 
2026-02-16 17:22:40.161193 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:22:40.684521 | controller | changed
2026-02-16 17:22:40.730970 | 
2026-02-16 17:22:40.731132 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:22:41.515295 | controller | changed
2026-02-16 17:22:41.527548 | 
2026-02-16 17:22:41.527622 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:22:41.861127 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:22:41.874157 | 
2026-02-16 17:22:41.874229 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:22:42.353152 | controller | ok: Item: Runtime: 0:00:00.192675
2026-02-16 17:22:42.937164 | controller | ok: Item: Runtime: 0:00:00.358024
2026-02-16 17:22:43.728372 | controller | ok: Item: Runtime: 0:00:00.564713
2026-02-16 17:22:44.011057 | controller | ok: Item: Runtime: 0:00:00.063523
2026-02-16 17:22:44.512037 | controller | ok: Item: Runtime: 0:00:00.285312
2026-02-16 17:22:45.996910 | controller | ok: Item: Runtime: 0:00:01.259389
2026-02-16 17:22:49.456354 | controller | ok: Item: Runtime: 0:00:03.217746
2026-02-16 17:22:49.777387 | controller | ok: Item: Runtime: 0:00:00.101331
2026-02-16 17:22:50.196553 | controller | ok: Item: Runtime: 0:00:00.200614
2026-02-16 17:22:50.558680 | controller | ok: Item: Runtime: 0:00:00.122191
2026-02-16 17:22:51.296506 | controller | ok: Item: Runtime: 0:00:00.518687
2026-02-16 17:22:51.985312 | controller | ok: Item: Runtime: 0:00:00.476279
2026-02-16 17:22:52.429929 | controller | ok: Item: Runtime: 0:00:00.227688
2026-02-16 17:22:52.863896 | controller | ok: Item: Runtime: 0:00:00.205642
2026-02-16 17:22:53.488283 | controller | ok: Item: Runtime: 0:00:00.400352
2026-02-16 17:22:54.896004 | controller | ok: Item: Runtime: 0:00:01.197167
2026-02-16 17:22:54.921905 | 
2026-02-16 17:22:54.922059 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:22:55.139744 | controller | ok: All paths examined
2026-02-16 17:22:55.146407 | 
2026-02-16 17:22:55.146479 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:22:55.403832 | controller | ok: All paths examined
2026-02-16 17:22:55.411008 | 
2026-02-16 17:22:55.411075 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:22:55.882315 | controller | ok: Item: Runtime: 0:00:00.192426
2026-02-16 17:22:55.882562 | controller | changed: All items complete
2026-02-16 17:22:55.882591 | 
2026-02-16 17:22:56.314017 | controller | ok: Item: Runtime: 0:00:00.212902
2026-02-16 17:22:56.334707 | 
2026-02-16 17:22:56.334903 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:22:56.379872 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.382720 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.386825 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.390397 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.393687 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.396713 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.399792 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.402893 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.406336 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.409388 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.412208 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.415134 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.418160 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.421135 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.424124 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.427290 | controller | skipping: Conditional result was False
2026-02-16 17:22:56.455260 | 
2026-02-16 17:22:56.455411 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:22:58.957166 | controller | changed
2026-02-16 17:22:58.967720 | 
2026-02-16 17:22:58.967821 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:22:59.210587 | controller | changed
2026-02-16 17:22:59.267944 | 
2026-02-16 17:22:59.268083 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:22:59.535062 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:22:59.809474 | controller | ERROR
2026-02-16 17:22:59.809734 | controller | {
2026-02-16 17:22:59.809775 | controller |   "delta": "0:00:00.010966",
2026-02-16 17:22:59.809804 | controller |   "end": "2026-02-16 17:22:59.535718",
2026-02-16 17:22:59.809829 | controller |   "msg": "non-zero return code",
2026-02-16 17:22:59.809855 | controller |   "rc": 1,
2026-02-16 17:22:59.809881 | controller |   "start": "2026-02-16 17:22:59.524752"
2026-02-16 17:22:59.809905 | controller | }
2026-02-16 17:22:59.809938 | controller | ERROR: Ignoring Errors
2026-02-16 17:22:59.817403 | 
2026-02-16 17:22:59.817470 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:23:00.074930 | controller | ok
2026-02-16 17:23:00.087172 | 
2026-02-16 17:23:00.087235 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:23:00.337034 | controller | /usr/bin/journalctl
2026-02-16 17:23:00.625477 | controller | ok: Runtime: 0:00:00.007754
2026-02-16 17:23:00.632310 | 
2026-02-16 17:23:00.632378 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:23:00.904956 | controller | 2026-02-16 17:23:00
2026-02-16 17:23:01.173181 | controller | ok: Runtime: 0:00:00.008896
2026-02-16 17:23:01.179288 | 
2026-02-16 17:23:01.179356 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:23:01.771095 | controller | changed
2026-02-16 17:23:01.778253 | 
2026-02-16 17:23:01.778318 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:23:01.803657 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.814207 | 
2026-02-16 17:23:01.814299 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:23:02.344886 | controller | skipping: Conditional result was False
2026-02-16 17:23:02.355363 | 
2026-02-16 17:23:02.355454 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:23:02.885770 | controller | skipping: Conditional result was False
2026-02-16 17:23:02.899177 | 
2026-02-16 17:23:02.899327 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:23:02.924654 | controller | skipping: Conditional result was False
2026-02-16 17:23:02.936656 | 
2026-02-16 17:23:02.936722 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:23:03.469031 | controller | skipping: Conditional result was False
2026-02-16 17:23:03.480922 | 
2026-02-16 17:23:03.481005 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:23:04.009805 | controller | skipping: Conditional result was False
2026-02-16 17:23:04.021157 | 
2026-02-16 17:23:04.021346 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:23:04.047998 | controller | skipping: Conditional result was False
2026-02-16 17:23:04.059215 | 
2026-02-16 17:23:04.059328 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:23:04.085377 | controller | skipping: Conditional result was False
2026-02-16 17:23:04.096498 | 
2026-02-16 17:23:04.096596 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:23:04.678929 | controller | skipping: Conditional result was False
2026-02-16 17:23:04.692168 | 
2026-02-16 17:23:04.692319 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:23:05.221385 | controller | skipping: Conditional result was False
2026-02-16 17:23:05.232463 | 
2026-02-16 17:23:05.232535 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:23:05.257668 | controller | skipping: Conditional result was False
2026-02-16 17:23:05.268505 | 
2026-02-16 17:23:05.268597 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:23:05.294390 | controller | skipping: Conditional result was False
2026-02-16 17:23:05.311139 | 
2026-02-16 17:23:05.311228 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:23:05.724079 | controller | ok
2026-02-16 17:23:05.735609 | 
2026-02-16 17:23:05.735663 | PLAY RECAP
2026-02-16 17:23:05.735712 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:23:05.735734 | 
2026-02-16 17:23:05.886819 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:23:05.897424 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:06.546551 | 
2026-02-16 17:23:06.546687 | PLAY [all]
2026-02-16 17:23:06.559394 | 
2026-02-16 17:23:06.559475 | TASK [ensure-rust : Use rustup]
2026-02-16 17:23:06.594523 | controller | ok
2026-02-16 17:23:06.603067 | controller | included: /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:23:06.608534 | 
2026-02-16 17:23:06.608612 | TASK [ensure-rust : Install Rust]
2026-02-16 17:23:07.010112 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:23:07.150157 | controller | ERROR
2026-02-16 17:23:07.150420 | controller | {
2026-02-16 17:23:07.150451 | controller |   "delta": "0:00:00.011197",
2026-02-16 17:23:07.150474 | controller |   "end": "2026-02-16 17:23:07.012958",
2026-02-16 17:23:07.150495 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:07.150513 | controller |   "rc": 127,
2026-02-16 17:23:07.150532 | controller |   "start": "2026-02-16 17:23:07.001761"
2026-02-16 17:23:07.150555 | controller | }
failure
2026-02-16 17:23:07.152329 | 
2026-02-16 17:23:07.152377 | PLAY RECAP
2026-02-16 17:23:07.152427 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:23:07.152449 | 
2026-02-16 17:23:07.273462 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:07.282494 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:07.942749 | 
2026-02-16 17:23:07.942903 | PLAY [all]
2026-02-16 17:23:07.960165 | 
2026-02-16 17:23:07.960243 | TASK [Return built artifacts to Zuul]
2026-02-16 17:23:07.994920 | controller | ok
2026-02-16 17:23:08.002509 | 
2026-02-16 17:23:08.002610 | TASK [Get the Docker volume ID]
2026-02-16 17:23:08.538751 | controller | ERROR
2026-02-16 17:23:08.539067 | controller | {
2026-02-16 17:23:08.539099 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:23:08.539122 | controller |   "rc": 2
2026-02-16 17:23:08.539142 | controller | }
2026-02-16 17:23:08.539169 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:08.545839 | 
2026-02-16 17:23:08.545912 | TASK [Copy all of the pod logs]
2026-02-16 17:23:08.579316 | controller | ERROR
2026-02-16 17:23:08.579568 | controller | {
2026-02-16 17:23:08.579611 | 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/4f2533728632466cbbcbda375b2686a0/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:23:08.579643 | controller | }
2026-02-16 17:23:08.579682 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:08.581058 | 
2026-02-16 17:23:08.581122 | PLAY RECAP
2026-02-16 17:23:08.581188 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:23:08.581218 | 
2026-02-16 17:23:08.727374 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:08.738498 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:09.354717 | 
2026-02-16 17:23:09.354852 | PLAY [all]
2026-02-16 17:23:09.372861 | 
2026-02-16 17:23:09.372964 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:23:09.762556 | controller | changed
2026-02-16 17:23:09.772301 | 
2026-02-16 17:23:09.772423 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:23:10.809289 | controller | ok: Runtime: 0:00:00.533301
2026-02-16 17:23:10.816822 | 
2026-02-16 17:23:10.816918 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:23:11.356831 | controller | ok: Runtime: 0:00:00.022434
2026-02-16 17:23:11.362998 | 
2026-02-16 17:23:11.363103 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:23:11.901669 | controller | ok: Runtime: 0:00:00.022984
2026-02-16 17:23:11.907934 | 
2026-02-16 17:23:11.908006 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:23:12.838452 | controller | changed
2026-02-16 17:23:12.887788 | 
2026-02-16 17:23:12.887927 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:23:13.131615 | controller | changed
2026-02-16 17:23:13.137264 | 
2026-02-16 17:23:13.137333 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:13.486648 | controller | Output suppressed because no_log was given
2026-02-16 17:23:13.493824 | 
2026-02-16 17:23:13.493893 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:13.543182 | 
2026-02-16 17:23:13.543317 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:13.578736 | 
2026-02-16 17:23:13.578868 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:13.614142 | 
2026-02-16 17:23:13.614272 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:13.648308 | 
2026-02-16 17:23:13.648489 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:13.687051 | 
2026-02-16 17:23:13.687194 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:23:13.924839 | controller | changed
2026-02-16 17:23:13.931096 | 
2026-02-16 17:23:13.931167 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:23:13.961582 | controller | ok
2026-02-16 17:23:13.966795 | 
2026-02-16 17:23:13.966858 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:23:14.199469 | controller | Output suppressed because no_log was given
2026-02-16 17:23:14.207922 | 
2026-02-16 17:23:14.208004 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:23:14.252488 | 
2026-02-16 17:23:14.252690 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:23:14.297328 | 
2026-02-16 17:23:14.297574 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:23:14.948698 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:23:14.968589 | controller | Using python 3.12 to install setuptools
2026-02-16 17:23:15.006478 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:23:15.017623 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:23:15.341232 | controller | ERROR
2026-02-16 17:23:15.341478 | controller | {
2026-02-16 17:23:15.341525 | controller |   "delta": "0:00:00.481790",
2026-02-16 17:23:15.341555 | controller |   "end": "2026-02-16 17:23:15.019821",
2026-02-16 17:23:15.341582 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:15.341614 | controller |   "rc": 127,
2026-02-16 17:23:15.341640 | controller |   "start": "2026-02-16 17:23:14.538031"
2026-02-16 17:23:15.341666 | controller | }
2026-02-16 17:23:15.341701 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:15.365662 | 
2026-02-16 17:23:15.366070 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:23:15.615185 | controller | changed
2026-02-16 17:23:15.622653 | 
2026-02-16 17:23:15.622732 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:23:19.499103 | controller | Output suppressed because no_log was given
2026-02-16 17:23:19.510915 | 
2026-02-16 17:23:19.510997 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:19.543637 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.543975 | 
2026-02-16 17:23:19.545268 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.547511 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.549811 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.552312 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.560696 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.563172 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.566059 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.568740 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.570699 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.573178 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.575431 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.577660 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.579963 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.582238 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.584474 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.586692 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.589729 | controller | skipping: Conditional result was False
2026-02-16 17:23:19.620223 | 
2026-02-16 17:23:19.620295 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:23:19.847917 | controller | ok
2026-02-16 17:23:19.855519 | 
2026-02-16 17:23:19.855613 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:20.450294 | controller | skipping: Conditional result was False
2026-02-16 17:23:20.465875 | 
2026-02-16 17:23:20.465958 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:23:20.734162 | controller | sudo: iptables-save: command not found
2026-02-16 17:23:20.771312 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:23:21.929264 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:23:22.005014 | controller | ok: Runtime: 0:00:01.220621
2026-02-16 17:23:22.017775 | 
2026-02-16 17:23:22.017842 | LOOP [stage-output : Register sources]
2026-02-16 17:23:31.906177 | controller | Output suppressed because no_log was given
2026-02-16 17:23:31.970986 | 
2026-02-16 17:23:31.971204 | TASK [stage-output : Check sudo]
2026-02-16 17:23:32.512002 | controller | ok: Runtime: 0:00:00.022339
2026-02-16 17:23:32.520005 | 
2026-02-16 17:23:32.520073 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:23:32.631272 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.631721 | 
2026-02-16 17:23:32.633579 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.634573 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.636879 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.673007 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.673374 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.673714 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.674063 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.675358 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.677475 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.679780 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.681897 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.683990 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.686469 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.706501 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.706848 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.707180 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.707506 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.707825 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.708556 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.710679 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.712926 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.715055 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.738698 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.739043 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.820441 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.834154 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.842472 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.853443 | controller | Output suppressed because no_log was given
2026-02-16 17:23:32.867686 | 
2026-02-16 17:23:32.867782 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:23:32.955280 | controller | ok
2026-02-16 17:23:32.964810 | 
2026-02-16 17:23:32.964903 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:23:33.216559 | controller | changed: "docs"
2026-02-16 17:23:33.446315 | controller | changed: "artifacts"
2026-02-16 17:23:33.673874 | controller | ok: "logs"
2026-02-16 17:23:33.688817 | 
2026-02-16 17:23:33.689019 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:23:33.969926 | controller | ok: Item: Runtime: 0:00:00.010759
2026-02-16 17:23:33.970219 | controller | changed: All items complete
2026-02-16 17:23:33.970248 | 
2026-02-16 17:23:34.203626 | controller | ok: Item: Runtime: 0:00:00.006472
2026-02-16 17:23:34.442274 | controller | ok: Item: Runtime: 0:00:00.009159
2026-02-16 17:23:34.703298 | controller | ok: Item: Runtime: 0:00:00.009412
2026-02-16 17:23:34.940048 | controller | ok: Item: Runtime: 0:00:00.008580
2026-02-16 17:23:35.180883 | controller | ok: Item: Runtime: 0:00:00.007659
2026-02-16 17:23:35.433697 | controller | ok: Item: Runtime: 0:00:00.008110
2026-02-16 17:23:35.661215 | controller | ok: Item: Runtime: 0:00:00.009395
2026-02-16 17:23:35.898556 | controller | ok: Item: Runtime: 0:00:00.008919
2026-02-16 17:23:36.133629 | controller | ok: Item: Runtime: 0:00:00.010480
2026-02-16 17:23:36.371317 | controller | ok: Item: Runtime: 0:00:00.009550
2026-02-16 17:23:36.651553 | controller | ok: Item: Runtime: 0:00:00.008981
2026-02-16 17:23:36.842153 | controller | ok: Item: Runtime: 0:00:00.008315
2026-02-16 17:23:37.101748 | controller | ok: Item: Runtime: 0:00:00.010221
2026-02-16 17:23:37.326257 | controller | ok: Item: Runtime: 0:00:00.009930
2026-02-16 17:23:37.560182 | controller | ok: Item: Runtime: 0:00:00.007000
2026-02-16 17:23:37.592427 | 
2026-02-16 17:23:37.592568 | TASK [stage-output : Make all log files readable]
2026-02-16 17:23:37.866726 | controller | changed
2026-02-16 17:23:37.872619 | 
2026-02-16 17:23:37.872684 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:23:38.266381 | controller | changed: Renamed files for staging.
2026-02-16 17:23:38.274479 | 
2026-02-16 17:23:38.274546 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:23:38.291442 | controller | skipping: Conditional result was False
2026-02-16 17:23:38.306009 | 
2026-02-16 17:23:38.306153 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:23:38.347796 | 
2026-02-16 17:23:38.347941 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:23:38.980927 | controller | changed:
2026-02-16 17:23:38.981256 | controller | created directory /var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/work/logs/controller
2026-02-16 17:23:38.981321 | controller | cd+++++++++ logs/
2026-02-16 17:23:38.981370 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:23:38.981400 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:23:38.981419 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:23:38.981438 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:23:38.981457 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:23:38.981475 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:23:38.981493 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:23:38.981513 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:23:38.981531 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:23:38.981550 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:23:38.981568 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:23:38.981585 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:23:38.981607 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:23:38.981643 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:23:38.981662 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:23:38.981680 | controller | cd+++++++++ logs/apache/
2026-02-16 17:23:38.981698 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:23:38.981716 | controller | cd+++++++++ logs/etc/
2026-02-16 17:23:38.981734 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:23:38.981752 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:23:38.981770 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:23:38.981787 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:23:38.981805 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:23:38.981823 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:23:38.995990 | 
2026-02-16 17:23:38.996192 | TASK [Check if a tempest log exits]
2026-02-16 17:23:39.277054 | controller | ok
2026-02-16 17:23:39.284268 | 
2026-02-16 17:23:39.284354 | TASK [Link post-devstack tempest.log]
2026-02-16 17:23:39.320594 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.331887 | 
2026-02-16 17:23:39.331979 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:23:39.591055 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:23:39.874561 | controller | ERROR
2026-02-16 17:23:39.874856 | controller | {
2026-02-16 17:23:39.874926 | controller |   "delta": "0:00:00.008965",
2026-02-16 17:23:39.874973 | controller |   "end": "2026-02-16 17:23:39.591722",
2026-02-16 17:23:39.875014 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:39.875053 | controller |   "rc": 127,
2026-02-16 17:23:39.875093 | controller |   "start": "2026-02-16 17:23:39.582757"
2026-02-16 17:23:39.875130 | controller | }
2026-02-16 17:23:39.875185 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:39.877702 | 
2026-02-16 17:23:39.877794 | PLAY RECAP
2026-02-16 17:23:39.877885 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:23:39.877934 | 
2026-02-16 17:23:40.045334 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:40.051619 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:40.673773 | 
2026-02-16 17:23:40.673943 | PLAY [all]
2026-02-16 17:23:40.686659 | 
2026-02-16 17:23:40.686745 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:23:40.732845 | controller | skipping: Conditional result was False
2026-02-16 17:23:40.743946 | 
2026-02-16 17:23:40.744052 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:23:40.789976 | controller | ok
2026-02-16 17:23:40.796295 | 
2026-02-16 17:23:40.796364 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:23:41.185521 | controller -> localhost | ok: "/var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/work/logs"
2026-02-16 17:23:41.404898 | controller -> localhost | changed: "/var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/work/artifacts"
2026-02-16 17:23:41.626324 | controller -> localhost | changed: "/var/lib/zuul/builds/4f2533728632466cbbcbda375b2686a0/work/docs"
2026-02-16 17:23:41.639364 | 
2026-02-16 17:23:41.639551 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:23:42.316801 | controller | changed: .d..t...... ./
2026-02-16 17:23:42.317082 | controller | changed: All items complete
2026-02-16 17:23:42.317110 | 
2026-02-16 17:23:42.783361 | controller | changed: .d..t...... ./
2026-02-16 17:23:43.283074 | controller | changed: .d..t...... ./
2026-02-16 17:23:43.310060 | 
2026-02-16 17:23:43.310216 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:23:43.753221 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007255
2026-02-16 17:23:43.972755 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007935
2026-02-16 17:23:43.991284 | 
2026-02-16 17:23:43.991468 | PLAY [all]
2026-02-16 17:23:44.001673 | 
2026-02-16 17:23:44.001740 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:23:44.502650 | controller | changed
2026-02-16 17:23:44.511648 | 
2026-02-16 17:23:44.511716 | PLAY RECAP
2026-02-16 17:23:44.511781 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:23:44.511814 | 
2026-02-16 17:23:44.636341 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:44.644321 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:23:45.206955 | 
2026-02-16 17:23:45.207081 | PLAY [localhost]
2026-02-16 17:23:45.217346 | 
2026-02-16 17:23:45.217417 | TASK [Generate Zuul manifest]
2026-02-16 17:23:45.237190 | localhost | ok
2026-02-16 17:23:45.254668 | 
2026-02-16 17:23:45.254742 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:23:45.660674 | localhost | changed
2026-02-16 17:23:45.671550 | 
2026-02-16 17:23:45.671627 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:23:45.703105 | localhost | ok
2026-02-16 17:23:45.715384 | 
2026-02-16 17:23:45.715448 | TASK [Upload logs]
2026-02-16 17:23:45.743250 | localhost | ok
2026-02-16 17:23:45.801729 | 
2026-02-16 17:23:45.801846 | TASK [Set zuul-log-path fact]
2026-02-16 17:23:45.822351 | localhost | ok
2026-02-16 17:23:45.837226 | 
2026-02-16 17:23:45.837321 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:23:45.866369 | localhost | ok
2026-02-16 17:23:45.875335 | 
2026-02-16 17:23:45.875424 | TASK [upload-logs : Create log directories]
2026-02-16 17:23:46.302518 | localhost | changed
2026-02-16 17:23:46.308276 | 
2026-02-16 17:23:46.308353 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:23:46.734130 | localhost -> localhost | ok: Runtime: 0:00:00.005033
2026-02-16 17:23:46.738748 | 
2026-02-16 17:23:46.738812 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:23:47.172269 | localhost | Output suppressed because no_log was given
2026-02-16 17:23:47.176076 | 
2026-02-16 17:23:47.176146 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:23:47.221911 | localhost | skipping: Conditional result was False
2026-02-16 17:23:47.227967 | localhost | skipping: Conditional result was False
2026-02-16 17:23:47.241999 | 
2026-02-16 17:23:47.242229 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:23:47.286214 | localhost | skipping: Conditional result was False
2026-02-16 17:23:47.286611 | 
2026-02-16 17:23:47.289165 | localhost | skipping: Conditional result was False
2026-02-16 17:23:47.308116 | 
2026-02-16 17:23:47.308290 | LOOP [upload-logs : Upload console log and json output]