2026-02-16 17:20:37.418680 | Job console starting
2026-02-16 17:20:37.428377 | Updating git repos
2026-02-16 17:20:40.488902 | Cloning repos into workspace
2026-02-16 17:20:47.802149 | Restoring repo states
2026-02-16 17:20:48.990636 | Merging changes
2026-02-16 17:20:49.638730 | Checking out repos
2026-02-16 17:20:56.005333 | Preparing playbooks
2026-02-16 17:20:58.594874 | Running Ansible setup
2026-02-16 17:21:02.887369 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:03.563672 | 
2026-02-16 17:21:03.563810 | PLAY [localhost]
2026-02-16 17:21:03.571341 | 
2026-02-16 17:21:03.571413 | TASK [Gathering Facts]
2026-02-16 17:21:04.533401 | localhost | ok
2026-02-16 17:21:04.545545 | 
2026-02-16 17:21:04.545634 | TASK [Setup log path fact]
2026-02-16 17:21:04.566781 | localhost | ok
2026-02-16 17:21:04.579822 | 
2026-02-16 17:21:04.579890 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:21:04.609093 | localhost | ok
2026-02-16 17:21:04.618819 | 
2026-02-16 17:21:04.618915 | TASK [emit-job-header : Print job information]
2026-02-16 17:21:04.659828 | # Job Information
2026-02-16 17:21:04.660154 | Ansible Version: 2.16.15
2026-02-16 17:21:04.660207 | Job: magnum-cluster-api-sonobuoy-v1.34.4-calico
2026-02-16 17:21:04.660241 | Pipeline: check
2026-02-16 17:21:04.660271 | Executor: 3a2793d2bd32
2026-02-16 17:21:04.660299 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:21:04.660334 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:21:04.664368 | 
2026-02-16 17:21:04.664467 | LOOP [emit-job-header : Print node information]
2026-02-16 17:21:04.760591 | localhost | ok:
2026-02-16 17:21:04.761120 | localhost | # Node Information
2026-02-16 17:21:04.761161 | localhost | Inventory Hostname: controller
2026-02-16 17:21:04.761185 | localhost | Hostname: np0000155588
2026-02-16 17:21:04.761205 | localhost | Username: zuul
2026-02-16 17:21:04.761227 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:21:04.761248 | localhost | Provider: yul1
2026-02-16 17:21:04.761268 | localhost | Region: ca-ymq-1
2026-02-16 17:21:04.761286 | localhost | Label: ubuntu-noble-16
2026-02-16 17:21:04.761305 | localhost | Product Name: OpenStack Nova
2026-02-16 17:21:04.761323 | localhost | Interface IP: 199.204.45.6
2026-02-16 17:21:04.781983 | 
2026-02-16 17:21:04.782176 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:21:05.172920 | localhost -> localhost | changed
2026-02-16 17:21:05.183050 | 
2026-02-16 17:21:05.183128 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:21:06.238911 | localhost -> localhost | changed
2026-02-16 17:21:06.245397 | 
2026-02-16 17:21:06.245455 | PLAY [all]
2026-02-16 17:21:06.253725 | 
2026-02-16 17:21:06.253792 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:21:06.500016 | controller -> localhost | ok
2026-02-16 17:21:06.515668 | 
2026-02-16 17:21:06.515921 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:21:06.553005 | controller | ok
2026-02-16 17:21:06.568799 | controller | included: /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:21:06.575467 | 
2026-02-16 17:21:06.575549 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:21:09.475227 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:21:09.475417 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/work/51ea8327af8e436f8e56b035c5a7030f_id_rsa
2026-02-16 17:21:09.475446 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/work/51ea8327af8e436f8e56b035c5a7030f_id_rsa.pub
2026-02-16 17:21:09.475468 | controller -> localhost | The key fingerprint is:
2026-02-16 17:21:09.475489 | controller -> localhost | SHA256:ZvvA27Qs7mcm9SvM2MgKM2jYDkKWR/RcpehGYuPmdJQ zuul-build-sshkey
2026-02-16 17:21:09.475522 | controller -> localhost | The key's randomart image is:
2026-02-16 17:21:09.475543 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:21:09.475567 | controller -> localhost | |   .   ...       |
2026-02-16 17:21:09.475589 | controller -> localhost | |  . o + .        |
2026-02-16 17:21:09.475608 | controller -> localhost | |   = E .         |
2026-02-16 17:21:09.475627 | controller -> localhost | |  = *            |
2026-02-16 17:21:09.475647 | controller -> localhost | | + = +  S        |
2026-02-16 17:21:09.475666 | controller -> localhost | |oo=.o  + ..      |
2026-02-16 17:21:09.475684 | controller -> localhost | |+ +.+  .+B..     |
2026-02-16 17:21:09.475703 | controller -> localhost | |.+   +  *BO..    |
2026-02-16 17:21:09.475724 | controller -> localhost | |  .   .++*=...   |
2026-02-16 17:21:09.475744 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:21:09.475791 | controller -> localhost | ok: Runtime: 0:00:02.455153
2026-02-16 17:21:09.481367 | 
2026-02-16 17:21:09.481431 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:21:09.509474 | controller | ok
2026-02-16 17:21:09.523415 | controller | included: /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:21:09.531345 | 
2026-02-16 17:21:09.531407 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:21:09.556429 | controller | skipping: Conditional result was False
2026-02-16 17:21:09.570405 | 
2026-02-16 17:21:09.570507 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:21:10.099356 | controller | changed
2026-02-16 17:21:10.108326 | 
2026-02-16 17:21:10.108414 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:21:10.337368 | controller | ok
2026-02-16 17:21:10.345701 | 
2026-02-16 17:21:10.345807 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:21:10.928052 | controller | changed
2026-02-16 17:21:10.934690 | 
2026-02-16 17:21:10.934779 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:21:11.517117 | controller | changed
2026-02-16 17:21:11.526317 | 
2026-02-16 17:21:11.526407 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:21:11.552193 | controller | skipping: Conditional result was False
2026-02-16 17:21:11.564521 | 
2026-02-16 17:21:11.564595 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:21:12.050252 | controller -> localhost | changed
2026-02-16 17:21:12.067161 | 
2026-02-16 17:21:12.067236 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:21:12.413208 | controller -> localhost | Identity added: /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/work/51ea8327af8e436f8e56b035c5a7030f_id_rsa (zuul-build-sshkey)
2026-02-16 17:21:12.413442 | controller -> localhost | ok: Runtime: 0:00:00.012971
2026-02-16 17:21:12.493272 | 
2026-02-16 17:21:12.493438 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:21:12.815352 | controller | ok
2026-02-16 17:21:12.829479 | 
2026-02-16 17:21:12.832023 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:21:12.861268 | controller | skipping: Conditional result was False
2026-02-16 17:21:12.893903 | 
2026-02-16 17:21:12.894079 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:21:13.234728 | controller | ok
2026-02-16 17:21:13.242696 | 
2026-02-16 17:21:13.242762 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:21:46.757901 | controller | Output suppressed because no_log was given
2026-02-16 17:21:46.769572 | 
2026-02-16 17:21:46.769636 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:21:47.007640 | controller | ok: "logs"
2026-02-16 17:21:47.007922 | controller | ok: All items complete
2026-02-16 17:21:47.007951 | 
2026-02-16 17:21:47.197624 | controller | ok: "artifacts"
2026-02-16 17:21:47.396573 | controller | ok: "docs"
2026-02-16 17:21:47.415516 | 
2026-02-16 17:21:47.415685 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:21:47.652150 | controller | changed: "logs"
2026-02-16 17:21:47.837503 | controller | changed: "artifacts"
2026-02-16 17:21:48.071977 | controller | changed: "docs"
2026-02-16 17:21:48.087261 | 
2026-02-16 17:21:48.087322 | PLAY RECAP
2026-02-16 17:21:48.087364 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:21:48.087392 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:21:48.087413 | 
2026-02-16 17:21:48.295177 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:48.311782 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:21:48.976748 | 
2026-02-16 17:21:48.976876 | PLAY [all]
2026-02-16 17:21:48.994344 | 
2026-02-16 17:21:48.994432 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:21:49.391370 | controller | changed
2026-02-16 17:21:49.398402 | 
2026-02-16 17:21:49.398492 | TASK [Gather minimum local MTU]
2026-02-16 17:21:49.475745 | controller | ok
2026-02-16 17:21:49.481313 | 
2026-02-16 17:21:49.481385 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:21:49.532847 | controller | ok
2026-02-16 17:21:49.538219 | 
2026-02-16 17:21:49.538292 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:21:49.563874 | controller | skipping: Conditional result was False
2026-02-16 17:21:49.572449 | 
2026-02-16 17:21:49.572524 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:21:50.111175 | controller | ok: Runtime: 0:00:00.010722
2026-02-16 17:21:50.117535 | 
2026-02-16 17:21:50.117601 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:21:50.142277 | controller | skipping: Conditional result was False
2026-02-16 17:21:50.153827 | 
2026-02-16 17:21:50.153896 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:21:50.188579 | controller | skipping: Conditional result was False
2026-02-16 17:21:50.198311 | 
2026-02-16 17:21:50.198407 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:21:50.254794 | controller | ok
2026-02-16 17:21:50.266241 | controller | included: /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:21:50.272169 | 
2026-02-16 17:21:50.272260 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:21:50.324485 | controller | ok
2026-02-16 17:21:50.331404 | 
2026-02-16 17:21:50.331469 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:21:50.638529 | controller | ext4
2026-02-16 17:21:50.906793 | controller | ok: Runtime: 0:00:00.014420
2026-02-16 17:21:50.912175 | 
2026-02-16 17:21:50.912238 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:21:50.945747 | controller | ok
2026-02-16 17:21:50.952290 | 
2026-02-16 17:21:50.952354 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:21:50.986902 | controller | ok:
2026-02-16 17:21:50.987094 | controller | {
2026-02-16 17:21:50.987119 | controller |   "root_filesystem": "ext4"
2026-02-16 17:21:50.987139 | controller | }
2026-02-16 17:21:50.993329 | 
2026-02-16 17:21:50.993391 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:22:01.037286 | controller | 8192+0 records in
2026-02-16 17:22:01.037434 | controller | 8192+0 records out
2026-02-16 17:22:01.037456 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.78467 s, 878 MB/s
2026-02-16 17:22:01.550269 | controller | ok: Runtime: 0:00:09.792375
2026-02-16 17:22:01.556143 | 
2026-02-16 17:22:01.556217 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:22:01.812219 | controller | changed
2026-02-16 17:22:01.818545 | 
2026-02-16 17:22:01.818613 | TASK [configure-swap : Make swapfile]
2026-02-16 17:22:40.931304 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:22:40.931505 | controller | no label, UUID=eb85734d-7f24-4c6d-a2c8-71cc13076dd6
2026-02-16 17:22:41.025767 | controller | ok: Runtime: 0:00:38.855331
2026-02-16 17:22:41.031292 | 
2026-02-16 17:22:41.031357 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:22:41.432906 | controller | changed
2026-02-16 17:22:41.438378 | 
2026-02-16 17:22:41.438442 | TASK [configure-swap : Add all swap]
2026-02-16 17:22:41.976323 | controller | ok: Runtime: 0:00:00.013243
2026-02-16 17:22:41.983602 | 
2026-02-16 17:22:41.983683 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:22:42.017187 | controller | ok:
2026-02-16 17:22:42.017381 | controller | {
2026-02-16 17:22:42.017417 | controller |   "swap_required": "8192"
2026-02-16 17:22:42.017447 | controller | }
2026-02-16 17:22:42.023326 | 
2026-02-16 17:22:42.023390 | TASK [configure-swap : Set swappiness]
2026-02-16 17:22:42.444054 | controller | changed
2026-02-16 17:22:42.450504 | 
2026-02-16 17:22:42.450571 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:22:42.485060 | controller | ok:
2026-02-16 17:22:42.485188 | controller | {
2026-02-16 17:22:42.485213 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:22:42.485239 | controller | }
2026-02-16 17:22:42.494836 | 
2026-02-16 17:22:42.494906 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:22:42.925156 | controller | changed
2026-02-16 17:22:42.932462 | 
2026-02-16 17:22:42.932540 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:22:43.180882 | controller | changed
2026-02-16 17:22:43.187393 | 
2026-02-16 17:22:43.187461 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:22:43.742719 | controller | changed
2026-02-16 17:22:43.749633 | 
2026-02-16 17:22:43.749699 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:22:44.009938 | controller | changed
2026-02-16 17:22:44.017151 | 
2026-02-16 17:22:44.017221 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:22:45.215122 | controller | changed
2026-02-16 17:22:45.221944 | 
2026-02-16 17:22:45.222012 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:22:45.463962 | controller | changed
2026-02-16 17:22:45.475120 | 
2026-02-16 17:22:45.475187 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:22:45.765724 | controller | changed
2026-02-16 17:22:45.770927 | 
2026-02-16 17:22:45.770995 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:22:46.128440 | controller | changed
2026-02-16 17:22:46.199416 | 
2026-02-16 17:22:46.199604 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:22:47.014443 | controller | changed
2026-02-16 17:22:47.025932 | 
2026-02-16 17:22:47.026004 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:22:47.381214 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:22:47.393378 | 
2026-02-16 17:22:47.393445 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:22:47.861488 | controller | ok: Item: Runtime: 0:00:00.194992
2026-02-16 17:22:48.450213 | controller | ok: Item: Runtime: 0:00:00.366568
2026-02-16 17:22:49.234168 | controller | ok: Item: Runtime: 0:00:00.561198
2026-02-16 17:22:49.518815 | controller | ok: Item: Runtime: 0:00:00.063238
2026-02-16 17:22:50.004402 | controller | ok: Item: Runtime: 0:00:00.264138
2026-02-16 17:22:51.531602 | controller | ok: Item: Runtime: 0:00:01.309766
2026-02-16 17:22:54.882748 | controller | ok: Item: Runtime: 0:00:03.118798
2026-02-16 17:22:55.252065 | controller | ok: Item: Runtime: 0:00:00.103583
2026-02-16 17:22:55.675648 | controller | ok: Item: Runtime: 0:00:00.202190
2026-02-16 17:22:56.008744 | controller | ok: Item: Runtime: 0:00:00.114549
2026-02-16 17:22:56.765717 | controller | ok: Item: Runtime: 0:00:00.532161
2026-02-16 17:22:57.538095 | controller | ok: Item: Runtime: 0:00:00.550918
2026-02-16 17:22:57.993293 | controller | ok: Item: Runtime: 0:00:00.239338
2026-02-16 17:22:58.428106 | controller | ok: Item: Runtime: 0:00:00.218098
2026-02-16 17:22:59.008341 | controller | ok: Item: Runtime: 0:00:00.359633
2026-02-16 17:23:00.317298 | controller | ok: Item: Runtime: 0:00:01.076493
2026-02-16 17:23:00.349278 | 
2026-02-16 17:23:00.349424 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:23:00.577856 | controller | ok: All paths examined
2026-02-16 17:23:00.585097 | 
2026-02-16 17:23:00.585166 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:23:00.843943 | controller | ok: All paths examined
2026-02-16 17:23:00.851694 | 
2026-02-16 17:23:00.851763 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:23:01.309580 | controller | ok: Item: Runtime: 0:00:00.163102
2026-02-16 17:23:01.309923 | controller | changed: All items complete
2026-02-16 17:23:01.309952 | 
2026-02-16 17:23:01.735164 | controller | ok: Item: Runtime: 0:00:00.185308
2026-02-16 17:23:01.749796 | 
2026-02-16 17:23:01.749965 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:23:01.792351 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.794735 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.798547 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.800942 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.804024 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.807259 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.810140 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.813283 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.816867 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.819522 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.822645 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.825543 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.828936 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.831891 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.834772 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.851731 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.886868 | 
2026-02-16 17:23:01.887115 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:23:04.298494 | controller | changed
2026-02-16 17:23:04.309438 | 
2026-02-16 17:23:04.309505 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:23:04.543852 | controller | changed
2026-02-16 17:23:04.598087 | 
2026-02-16 17:23:04.598228 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:23:04.859860 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:23:05.136420 | controller | ERROR
2026-02-16 17:23:05.136681 | controller | {
2026-02-16 17:23:05.136723 | controller |   "delta": "0:00:00.011104",
2026-02-16 17:23:05.136752 | controller |   "end": "2026-02-16 17:23:04.860726",
2026-02-16 17:23:05.136778 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:05.136804 | controller |   "rc": 1,
2026-02-16 17:23:05.136828 | controller |   "start": "2026-02-16 17:23:04.849622"
2026-02-16 17:23:05.136852 | controller | }
2026-02-16 17:23:05.136900 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:05.144688 | 
2026-02-16 17:23:05.144781 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:23:05.410420 | controller | ok
2026-02-16 17:23:05.424185 | 
2026-02-16 17:23:05.424257 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:23:05.658869 | controller | /usr/bin/journalctl
2026-02-16 17:23:05.965522 | controller | ok: Runtime: 0:00:00.006626
2026-02-16 17:23:05.973558 | 
2026-02-16 17:23:05.973652 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:23:06.235624 | controller | 2026-02-16 17:23:06
2026-02-16 17:23:06.514684 | controller | ok: Runtime: 0:00:00.009175
2026-02-16 17:23:06.521686 | 
2026-02-16 17:23:06.521755 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:23:07.116760 | controller | changed
2026-02-16 17:23:07.123896 | 
2026-02-16 17:23:07.123968 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:23:07.149998 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.158206 | 
2026-02-16 17:23:07.158293 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:23:07.688440 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.700616 | 
2026-02-16 17:23:07.700688 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:23:08.249654 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.262011 | 
2026-02-16 17:23:08.262079 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:23:08.287049 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.298386 | 
2026-02-16 17:23:08.298454 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:23:08.827369 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.840775 | 
2026-02-16 17:23:08.840920 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:23:09.370591 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.381216 | 
2026-02-16 17:23:09.381288 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:23:09.397471 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.407293 | 
2026-02-16 17:23:09.407382 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:23:09.425254 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.433918 | 
2026-02-16 17:23:09.433993 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:23:10.009131 | controller | skipping: Conditional result was False
2026-02-16 17:23:10.019196 | 
2026-02-16 17:23:10.019301 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:23:10.557300 | controller | skipping: Conditional result was False
2026-02-16 17:23:10.567864 | 
2026-02-16 17:23:10.567961 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:23:10.593970 | controller | skipping: Conditional result was False
2026-02-16 17:23:10.604792 | 
2026-02-16 17:23:10.604882 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:23:10.630955 | controller | skipping: Conditional result was False
2026-02-16 17:23:10.646556 | 
2026-02-16 17:23:10.646625 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:23:11.065316 | controller | ok
2026-02-16 17:23:11.075956 | 
2026-02-16 17:23:11.076008 | PLAY RECAP
2026-02-16 17:23:11.076056 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:23:11.076078 | 
2026-02-16 17:23:11.252568 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:23:11.261374 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:11.881654 | 
2026-02-16 17:23:11.881826 | PLAY [all]
2026-02-16 17:23:11.895016 | 
2026-02-16 17:23:11.895123 | TASK [ensure-rust : Use rustup]
2026-02-16 17:23:11.929358 | controller | ok
2026-02-16 17:23:11.939393 | controller | included: /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:23:11.944926 | 
2026-02-16 17:23:11.945015 | TASK [ensure-rust : Install Rust]
2026-02-16 17:23:12.356788 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:23:12.482282 | controller | ERROR
2026-02-16 17:23:12.482566 | controller | {
2026-02-16 17:23:12.482602 | controller |   "delta": "0:00:00.012263",
2026-02-16 17:23:12.482626 | controller |   "end": "2026-02-16 17:23:12.360375",
2026-02-16 17:23:12.482646 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:12.482664 | controller |   "rc": 127,
2026-02-16 17:23:12.482682 | controller |   "start": "2026-02-16 17:23:12.348112"
2026-02-16 17:23:12.482704 | controller | }
failure
2026-02-16 17:23:12.484174 | 
2026-02-16 17:23:12.484217 | PLAY RECAP
2026-02-16 17:23:12.484263 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:23:12.484284 | 
2026-02-16 17:23:12.600172 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:12.605878 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:13.232014 | 
2026-02-16 17:23:13.232156 | PLAY [all]
2026-02-16 17:23:13.249402 | 
2026-02-16 17:23:13.249508 | TASK [Return built artifacts to Zuul]
2026-02-16 17:23:13.285321 | controller | ok
2026-02-16 17:23:13.297106 | 
2026-02-16 17:23:13.297367 | TASK [Get the Docker volume ID]
2026-02-16 17:23:13.841977 | controller | ERROR
2026-02-16 17:23:13.842367 | controller | {
2026-02-16 17:23:13.842413 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:23:13.842444 | controller |   "rc": 2
2026-02-16 17:23:13.842471 | controller | }
2026-02-16 17:23:13.842508 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:13.850992 | 
2026-02-16 17:23:13.851063 | TASK [Copy all of the pod logs]
2026-02-16 17:23:13.881268 | controller | ERROR
2026-02-16 17:23:13.881494 | controller | {
2026-02-16 17:23:13.881525 | 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/51ea8327af8e436f8e56b035c5a7030f/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:13.881547 | controller | }
2026-02-16 17:23:13.881575 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:13.882727 | 
2026-02-16 17:23:13.882777 | PLAY RECAP
2026-02-16 17:23:13.882823 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:23:13.882844 | 
2026-02-16 17:23:14.005353 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:14.010783 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:14.637656 | 
2026-02-16 17:23:14.637804 | PLAY [all]
2026-02-16 17:23:14.655998 | 
2026-02-16 17:23:14.656079 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:23:15.084968 | controller | changed
2026-02-16 17:23:15.094378 | 
2026-02-16 17:23:15.094464 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:23:16.130870 | controller | ok: Runtime: 0:00:00.471313
2026-02-16 17:23:16.136921 | 
2026-02-16 17:23:16.137011 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:23:16.682464 | controller | ok: Runtime: 0:00:00.020342
2026-02-16 17:23:16.696386 | 
2026-02-16 17:23:16.696461 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:23:17.236290 | controller | ok: Runtime: 0:00:00.028872
2026-02-16 17:23:17.243715 | 
2026-02-16 17:23:17.243796 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:23:18.127437 | controller | changed
2026-02-16 17:23:18.173663 | 
2026-02-16 17:23:18.173817 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:23:18.430150 | controller | changed
2026-02-16 17:23:18.438029 | 
2026-02-16 17:23:18.438096 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:18.776426 | controller | Output suppressed because no_log was given
2026-02-16 17:23:18.787549 | 
2026-02-16 17:23:18.787813 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:18.825947 | 
2026-02-16 17:23:18.826118 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:18.861886 | 
2026-02-16 17:23:18.862062 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:18.898935 | 
2026-02-16 17:23:18.899157 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:18.934931 | 
2026-02-16 17:23:18.935099 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:18.972261 | 
2026-02-16 17:23:18.972513 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:23:19.261324 | controller | changed
2026-02-16 17:23:19.268682 | 
2026-02-16 17:23:19.268788 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:23:19.304024 | controller | ok
2026-02-16 17:23:19.311080 | 
2026-02-16 17:23:19.311152 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:23:19.574302 | controller | Output suppressed because no_log was given
2026-02-16 17:23:19.582489 | 
2026-02-16 17:23:19.582621 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:23:19.630090 | 
2026-02-16 17:23:19.630377 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:23:19.671966 | 
2026-02-16 17:23:19.672210 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:23:20.397727 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:23:20.418290 | controller | Using python 3.12 to install setuptools
2026-02-16 17:23:20.459691 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:23:20.472617 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:23:20.715934 | controller | ERROR
2026-02-16 17:23:20.716150 | controller | {
2026-02-16 17:23:20.716184 | controller |   "delta": "0:00:00.526871",
2026-02-16 17:23:20.716205 | controller |   "end": "2026-02-16 17:23:20.474746",
2026-02-16 17:23:20.716224 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:20.716246 | controller |   "rc": 127,
2026-02-16 17:23:20.716263 | controller |   "start": "2026-02-16 17:23:19.947875"
2026-02-16 17:23:20.716281 | controller | }
2026-02-16 17:23:20.716305 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:20.727780 | 
2026-02-16 17:23:20.727850 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:23:20.983945 | controller | changed
2026-02-16 17:23:20.991682 | 
2026-02-16 17:23:20.991760 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:23:24.897469 | controller | Output suppressed because no_log was given
2026-02-16 17:23:24.920194 | 
2026-02-16 17:23:24.920288 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:24.950667 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.951172 | 
2026-02-16 17:23:24.953153 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.954753 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.957050 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.958935 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.960833 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.963201 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.965652 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.968026 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.970450 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.972828 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.975251 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.977445 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.979905 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.982174 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.984480 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.986784 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.989823 | controller | skipping: Conditional result was False
2026-02-16 17:23:25.022365 | 
2026-02-16 17:23:25.022481 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:23:25.257342 | controller | ok
2026-02-16 17:23:25.266091 | 
2026-02-16 17:23:25.266166 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:25.851497 | controller | skipping: Conditional result was False
2026-02-16 17:23:25.863920 | 
2026-02-16 17:23:25.864006 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:23:26.127516 | controller | sudo: iptables-save: command not found
2026-02-16 17:23:26.168115 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:23:27.353573 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:23:27.413150 | controller | ok: Runtime: 0:00:01.245089
2026-02-16 17:23:27.424044 | 
2026-02-16 17:23:27.424113 | LOOP [stage-output : Register sources]
2026-02-16 17:23:37.223984 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.276920 | 
2026-02-16 17:23:37.277085 | TASK [stage-output : Check sudo]
2026-02-16 17:23:37.820720 | controller | ok: Runtime: 0:00:00.020941
2026-02-16 17:23:37.826913 | 
2026-02-16 17:23:37.826987 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:23:37.926101 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.926534 | 
2026-02-16 17:23:37.928130 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.929391 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.931833 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.967157 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.968230 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.968711 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.969716 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.971995 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.974117 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.976260 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.978427 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.980512 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.982709 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.002830 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.003582 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.004689 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.005473 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.006189 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.006941 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.007449 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.008520 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.010701 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.035564 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.036033 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.109122 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.119510 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.130274 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.135360 | controller | Output suppressed because no_log was given
2026-02-16 17:23:38.145103 | 
2026-02-16 17:23:38.145165 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:23:38.198760 | controller | ok
2026-02-16 17:23:38.207300 | 
2026-02-16 17:23:38.207361 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:23:38.474781 | controller | changed: "docs"
2026-02-16 17:23:38.706766 | controller | changed: "artifacts"
2026-02-16 17:23:38.919494 | controller | ok: "logs"
2026-02-16 17:23:38.932379 | 
2026-02-16 17:23:38.932551 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:23:39.224141 | controller | ok: Item: Runtime: 0:00:00.009380
2026-02-16 17:23:39.224460 | controller | changed: All items complete
2026-02-16 17:23:39.224489 | 
2026-02-16 17:23:39.474574 | controller | ok: Item: Runtime: 0:00:00.008860
2026-02-16 17:23:39.712821 | controller | ok: Item: Runtime: 0:00:00.009045
2026-02-16 17:23:39.962693 | controller | ok: Item: Runtime: 0:00:00.008688
2026-02-16 17:23:40.200333 | controller | ok: Item: Runtime: 0:00:00.010668
2026-02-16 17:23:40.448021 | controller | ok: Item: Runtime: 0:00:00.009156
2026-02-16 17:23:40.679975 | controller | ok: Item: Runtime: 0:00:00.008588
2026-02-16 17:23:40.907110 | controller | ok: Item: Runtime: 0:00:00.009179
2026-02-16 17:23:41.134413 | controller | ok: Item: Runtime: 0:00:00.008160
2026-02-16 17:23:41.371080 | controller | ok: Item: Runtime: 0:00:00.009296
2026-02-16 17:23:41.594847 | controller | ok: Item: Runtime: 0:00:00.007736
2026-02-16 17:23:41.887196 | controller | ok: Item: Runtime: 0:00:00.009467
2026-02-16 17:23:42.072985 | controller | ok: Item: Runtime: 0:00:00.009348
2026-02-16 17:23:42.315420 | controller | ok: Item: Runtime: 0:00:00.011030
2026-02-16 17:23:42.567866 | controller | ok: Item: Runtime: 0:00:00.009936
2026-02-16 17:23:42.811011 | controller | ok: Item: Runtime: 0:00:00.010056
2026-02-16 17:23:42.834917 | 
2026-02-16 17:23:42.835056 | TASK [stage-output : Make all log files readable]
2026-02-16 17:23:43.121288 | controller | changed
2026-02-16 17:23:43.129344 | 
2026-02-16 17:23:43.129428 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:23:43.457999 | controller | changed: Renamed files for staging.
2026-02-16 17:23:43.463424 | 
2026-02-16 17:23:43.463488 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:23:43.488616 | controller | skipping: Conditional result was False
2026-02-16 17:23:43.501435 | 
2026-02-16 17:23:43.501526 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:23:43.544746 | 
2026-02-16 17:23:43.544873 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:23:44.181725 | controller | changed:
2026-02-16 17:23:44.181915 | controller | created directory /var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/work/logs/controller
2026-02-16 17:23:44.181944 | controller | cd+++++++++ logs/
2026-02-16 17:23:44.181967 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:23:44.181988 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:23:44.182008 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:23:44.182029 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:23:44.182048 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:23:44.182067 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:23:44.182086 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:23:44.182104 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:23:44.182123 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:23:44.182141 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:23:44.182160 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:23:44.182179 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:23:44.182202 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:23:44.182235 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:23:44.182255 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:23:44.182274 | controller | cd+++++++++ logs/apache/
2026-02-16 17:23:44.182294 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:23:44.182312 | controller | cd+++++++++ logs/etc/
2026-02-16 17:23:44.182331 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:23:44.182349 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:23:44.182368 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:23:44.182386 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:23:44.182405 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:23:44.182423 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:23:44.194065 | 
2026-02-16 17:23:44.194125 | TASK [Check if a tempest log exits]
2026-02-16 17:23:44.447389 | controller | ok
2026-02-16 17:23:44.452654 | 
2026-02-16 17:23:44.452716 | TASK [Link post-devstack tempest.log]
2026-02-16 17:23:44.477832 | controller | skipping: Conditional result was False
2026-02-16 17:23:44.488845 | 
2026-02-16 17:23:44.488910 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:23:44.733228 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:23:45.031171 | controller | ERROR
2026-02-16 17:23:45.031363 | controller | {
2026-02-16 17:23:45.031396 | controller |   "delta": "0:00:00.005037",
2026-02-16 17:23:45.031417 | controller |   "end": "2026-02-16 17:23:44.733591",
2026-02-16 17:23:45.031436 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:45.031454 | controller |   "rc": 127,
2026-02-16 17:23:45.031472 | controller |   "start": "2026-02-16 17:23:44.728554"
2026-02-16 17:23:45.031490 | controller | }
2026-02-16 17:23:45.031514 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:45.033213 | 
2026-02-16 17:23:45.033262 | PLAY RECAP
2026-02-16 17:23:45.033312 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:23:45.033334 | 
2026-02-16 17:23:45.176936 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:45.187203 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:45.779679 | 
2026-02-16 17:23:45.780591 | PLAY [all]
2026-02-16 17:23:45.792128 | 
2026-02-16 17:23:45.792214 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:23:45.837770 | controller | skipping: Conditional result was False
2026-02-16 17:23:45.849691 | 
2026-02-16 17:23:45.849774 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:23:45.894621 | controller | ok
2026-02-16 17:23:45.903190 | 
2026-02-16 17:23:45.903285 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:23:46.302807 | controller -> localhost | ok: "/var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/work/logs"
2026-02-16 17:23:46.543149 | controller -> localhost | changed: "/var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/work/artifacts"
2026-02-16 17:23:46.748935 | controller -> localhost | changed: "/var/lib/zuul/builds/51ea8327af8e436f8e56b035c5a7030f/work/docs"
2026-02-16 17:23:46.761950 | 
2026-02-16 17:23:46.762090 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:23:47.408575 | controller | changed: .d..t...... ./
2026-02-16 17:23:47.408835 | controller | changed: All items complete
2026-02-16 17:23:47.408873 | 
2026-02-16 17:23:47.891107 | controller | changed: .d..t...... ./
2026-02-16 17:23:48.382827 | controller | changed: .d..t...... ./
2026-02-16 17:23:48.552737 | 
2026-02-16 17:23:48.552889 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:23:48.985005 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007796
2026-02-16 17:23:49.206744 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008076
2026-02-16 17:23:49.287703 | 
2026-02-16 17:23:49.288291 | PLAY [all]
2026-02-16 17:23:49.295794 | 
2026-02-16 17:23:49.295858 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:23:49.767003 | controller | changed
2026-02-16 17:23:49.774960 | 
2026-02-16 17:23:49.775028 | PLAY RECAP
2026-02-16 17:23:49.775088 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:23:49.775118 | 
2026-02-16 17:23:49.895797 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:49.904638 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:23:50.534304 | 
2026-02-16 17:23:50.534444 | PLAY [localhost]
2026-02-16 17:23:50.545353 | 
2026-02-16 17:23:50.545426 | TASK [Generate Zuul manifest]
2026-02-16 17:23:50.568251 | localhost | ok
2026-02-16 17:23:50.592623 | 
2026-02-16 17:23:50.592705 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:23:50.983280 | localhost | changed
2026-02-16 17:23:51.007801 | 
2026-02-16 17:23:51.007888 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:23:51.041275 | localhost | ok
2026-02-16 17:23:51.050977 | 
2026-02-16 17:23:51.051044 | TASK [Upload logs]
2026-02-16 17:23:51.073455 | localhost | ok
2026-02-16 17:23:51.136196 | 
2026-02-16 17:23:51.136320 | TASK [Set zuul-log-path fact]
2026-02-16 17:23:51.156720 | localhost | ok
2026-02-16 17:23:51.172986 | 
2026-02-16 17:23:51.173061 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:23:51.205241 | localhost | ok
2026-02-16 17:23:51.212783 | 
2026-02-16 17:23:51.212860 | TASK [upload-logs : Create log directories]
2026-02-16 17:23:51.657077 | localhost | changed
2026-02-16 17:23:51.664571 | 
2026-02-16 17:23:51.665106 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:23:52.107585 | localhost -> localhost | ok: Runtime: 0:00:00.006601
2026-02-16 17:23:52.112970 | 
2026-02-16 17:23:52.113070 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:23:52.586536 | localhost | Output suppressed because no_log was given
2026-02-16 17:23:52.592598 | 
2026-02-16 17:23:52.592699 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:23:52.638573 | localhost | skipping: Conditional result was False
2026-02-16 17:23:52.644458 | localhost | skipping: Conditional result was False
2026-02-16 17:23:52.659977 | 
2026-02-16 17:23:52.763494 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:23:52.808944 | localhost | skipping: Conditional result was False
2026-02-16 17:23:52.809434 | 
2026-02-16 17:23:52.812034 | localhost | skipping: Conditional result was False
2026-02-16 17:23:52.838744 | 
2026-02-16 17:23:52.838952 | LOOP [upload-logs : Upload console log and json output]