2026-02-16 17:38:07.616387 | Job console starting
2026-02-16 17:38:07.624842 | Updating git repos
2026-02-16 17:38:15.427603 | Cloning repos into workspace
2026-02-16 17:38:21.680442 | Restoring repo states
2026-02-16 17:38:24.245166 | Merging changes
2026-02-16 17:38:25.132039 | Checking out repos
2026-02-16 17:38:30.092936 | Preparing playbooks
2026-02-16 17:38:32.101445 | Running Ansible setup
2026-02-16 17:38:36.223334 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:38:36.885883 | 
2026-02-16 17:38:36.886018 | PLAY [localhost]
2026-02-16 17:38:36.894039 | 
2026-02-16 17:38:36.894110 | TASK [Gathering Facts]
2026-02-16 17:38:37.785858 | localhost | ok
2026-02-16 17:38:37.792693 | 
2026-02-16 17:38:37.792771 | TASK [Setup log path fact]
2026-02-16 17:38:37.811607 | localhost | ok
2026-02-16 17:38:37.824208 | 
2026-02-16 17:38:37.824288 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:38:37.855874 | localhost | ok
2026-02-16 17:38:37.864470 | 
2026-02-16 17:38:37.864548 | TASK [emit-job-header : Print job information]
2026-02-16 17:38:37.908544 | # Job Information
2026-02-16 17:38:37.908718 | Ansible Version: 2.16.15
2026-02-16 17:38:37.908751 | Job: magnum-cluster-api-sonobuoy-v1.32.12-calico
2026-02-16 17:38:37.908773 | Pipeline: check
2026-02-16 17:38:37.908793 | Executor: 3a2793d2bd32
2026-02-16 17:38:37.908813 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:38:37.908836 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:38:37.911623 | 
2026-02-16 17:38:37.911698 | LOOP [emit-job-header : Print node information]
2026-02-16 17:38:38.009746 | localhost | ok:
2026-02-16 17:38:38.009972 | localhost | # Node Information
2026-02-16 17:38:38.010014 | localhost | Inventory Hostname: controller
2026-02-16 17:38:38.010045 | localhost | Hostname: np0000155609
2026-02-16 17:38:38.010074 | localhost | Username: zuul
2026-02-16 17:38:38.010106 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:38:38.010136 | localhost | Provider: yul1
2026-02-16 17:38:38.010164 | localhost | Region: ca-ymq-1
2026-02-16 17:38:38.010191 | localhost | Label: ubuntu-noble-16
2026-02-16 17:38:38.010218 | localhost | Product Name: OpenStack Nova
2026-02-16 17:38:38.010245 | localhost | Interface IP: 199.204.45.65
2026-02-16 17:38:38.029797 | 
2026-02-16 17:38:38.097406 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:38:38.519777 | localhost -> localhost | changed
2026-02-16 17:38:38.528235 | 
2026-02-16 17:38:38.528308 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:38:39.463387 | localhost -> localhost | changed
2026-02-16 17:38:39.479914 | 
2026-02-16 17:38:39.480071 | PLAY [all]
2026-02-16 17:38:39.496279 | 
2026-02-16 17:38:39.496700 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:38:39.796355 | controller -> localhost | ok
2026-02-16 17:38:39.806909 | 
2026-02-16 17:38:39.807002 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:38:39.841835 | controller | ok
2026-02-16 17:38:39.859704 | controller | included: /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:38:39.865867 | 
2026-02-16 17:38:39.865930 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:38:40.720396 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:38:40.720691 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/work/dff50f59cedd4620af8f2f0c887cb462_id_rsa
2026-02-16 17:38:40.720757 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/work/dff50f59cedd4620af8f2f0c887cb462_id_rsa.pub
2026-02-16 17:38:40.720807 | controller -> localhost | The key fingerprint is:
2026-02-16 17:38:40.720854 | controller -> localhost | SHA256:vCRV6m7d60k5iwavcLD5z64CQHo1GqpAArf/apQDyFs zuul-build-sshkey
2026-02-16 17:38:40.720924 | controller -> localhost | The key's randomart image is:
2026-02-16 17:38:40.721101 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:38:40.721296 | controller -> localhost | |o .       .      |
2026-02-16 17:38:40.721357 | controller -> localhost | |.oo.o    o       |
2026-02-16 17:38:40.721404 | controller -> localhost | |+=.+ .  o        |
2026-02-16 17:38:40.721448 | controller -> localhost | |=.=E   +         |
2026-02-16 17:38:40.721492 | controller -> localhost | |o.oo..o S        |
2026-02-16 17:38:40.721535 | controller -> localhost | |..  =. B.o . .   |
2026-02-16 17:38:40.721579 | controller -> localhost | |   . o= =o. =    |
2026-02-16 17:38:40.721623 | controller -> localhost | |    ...= .oo =   |
2026-02-16 17:38:40.721671 | controller -> localhost | |   ..  .+*=.=    |
2026-02-16 17:38:40.721717 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:38:40.721825 | controller -> localhost | ok: Runtime: 0:00:00.438773
2026-02-16 17:38:40.727832 | 
2026-02-16 17:38:40.727899 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:38:40.764430 | controller | ok
2026-02-16 17:38:40.775957 | controller | included: /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:38:40.784183 | 
2026-02-16 17:38:40.784250 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:38:40.809199 | controller | skipping: Conditional result was False
2026-02-16 17:38:40.819885 | 
2026-02-16 17:38:40.820068 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:38:41.368132 | controller | changed
2026-02-16 17:38:41.375069 | 
2026-02-16 17:38:41.375134 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:38:41.613024 | controller | ok
2026-02-16 17:38:41.621167 | 
2026-02-16 17:38:41.621243 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:38:42.210571 | controller | changed
2026-02-16 17:38:42.218249 | 
2026-02-16 17:38:42.218339 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:38:42.812873 | controller | changed
2026-02-16 17:38:42.822780 | 
2026-02-16 17:38:42.822853 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:38:42.850639 | controller | skipping: Conditional result was False
2026-02-16 17:38:42.859607 | 
2026-02-16 17:38:42.859685 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:38:43.266913 | controller -> localhost | changed
2026-02-16 17:38:43.281975 | 
2026-02-16 17:38:43.282070 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:38:43.650837 | controller -> localhost | Identity added: /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/work/dff50f59cedd4620af8f2f0c887cb462_id_rsa (zuul-build-sshkey)
2026-02-16 17:38:43.651141 | controller -> localhost | ok: Runtime: 0:00:00.014640
2026-02-16 17:38:43.715918 | 
2026-02-16 17:38:43.716075 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:38:44.037670 | controller | ok
2026-02-16 17:38:44.043423 | 
2026-02-16 17:38:44.043499 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:38:44.067707 | controller | skipping: Conditional result was False
2026-02-16 17:38:44.085552 | 
2026-02-16 17:38:44.085628 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:38:44.431962 | controller | ok
2026-02-16 17:38:44.438844 | 
2026-02-16 17:38:44.438915 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:39:05.741577 | controller | Output suppressed because no_log was given
2026-02-16 17:39:05.758358 | 
2026-02-16 17:39:05.758476 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:39:06.008640 | controller | ok: "logs"
2026-02-16 17:39:06.009059 | controller | ok: All items complete
2026-02-16 17:39:06.009102 | 
2026-02-16 17:39:06.222450 | controller | ok: "artifacts"
2026-02-16 17:39:06.412493 | controller | ok: "docs"
2026-02-16 17:39:06.426827 | 
2026-02-16 17:39:06.427161 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:39:06.663781 | controller | changed: "logs"
2026-02-16 17:39:06.875153 | controller | changed: "artifacts"
2026-02-16 17:39:07.107164 | controller | changed: "docs"
2026-02-16 17:39:07.128570 | 
2026-02-16 17:39:07.128710 | PLAY RECAP
2026-02-16 17:39:07.128757 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:39:07.128801 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:39:07.128825 | 
2026-02-16 17:39:07.326880 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:39:07.348340 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:39:08.077866 | 
2026-02-16 17:39:08.078056 | PLAY [all]
2026-02-16 17:39:08.097734 | 
2026-02-16 17:39:08.097906 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:39:08.523314 | controller | changed
2026-02-16 17:39:08.530286 | 
2026-02-16 17:39:08.530365 | TASK [Gather minimum local MTU]
2026-02-16 17:39:08.612114 | controller | ok
2026-02-16 17:39:08.617494 | 
2026-02-16 17:39:08.617565 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:39:08.669250 | controller | ok
2026-02-16 17:39:08.674558 | 
2026-02-16 17:39:08.674630 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:39:08.700301 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.708782 | 
2026-02-16 17:39:08.708887 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:39:09.257700 | controller | ok: Runtime: 0:00:00.009540
2026-02-16 17:39:09.264298 | 
2026-02-16 17:39:09.264370 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:39:09.300036 | controller | skipping: Conditional result was False
2026-02-16 17:39:09.309517 | 
2026-02-16 17:39:09.309611 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:39:09.336461 | controller | skipping: Conditional result was False
2026-02-16 17:39:09.347698 | 
2026-02-16 17:39:09.347793 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:39:09.408018 | controller | ok
2026-02-16 17:39:09.422203 | controller | included: /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:39:09.430093 | 
2026-02-16 17:39:09.430211 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:39:09.486439 | controller | ok
2026-02-16 17:39:09.494294 | 
2026-02-16 17:39:09.494396 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:39:09.786653 | controller | ext4
2026-02-16 17:39:10.085755 | controller | ok: Runtime: 0:00:00.016722
2026-02-16 17:39:10.091186 | 
2026-02-16 17:39:10.091261 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:39:10.121972 | controller | ok
2026-02-16 17:39:10.127673 | 
2026-02-16 17:39:10.127745 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:39:10.158563 | controller | ok:
2026-02-16 17:39:10.158747 | controller | {
2026-02-16 17:39:10.158772 | controller |   "root_filesystem": "ext4"
2026-02-16 17:39:10.158792 | controller | }
2026-02-16 17:39:10.165031 | 
2026-02-16 17:39:10.165118 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:39:20.427389 | controller | 8192+0 records in
2026-02-16 17:39:20.427574 | controller | 8192+0 records out
2026-02-16 17:39:20.427597 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.96483 s, 862 MB/s
2026-02-16 17:39:20.721601 | controller | ok: Runtime: 0:00:09.975244
2026-02-16 17:39:20.733679 | 
2026-02-16 17:39:20.733869 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:39:21.083313 | controller | changed
2026-02-16 17:39:21.091876 | 
2026-02-16 17:39:21.091964 | TASK [configure-swap : Make swapfile]
2026-02-16 17:40:09.807028 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:40:09.807271 | controller | no label, UUID=5d4bf39e-64cf-4e76-9a06-6705bc45a133
2026-02-16 17:40:10.181592 | controller | ok: Runtime: 0:00:48.473416
2026-02-16 17:40:10.188648 | 
2026-02-16 17:40:10.188720 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:40:10.609250 | controller | changed
2026-02-16 17:40:10.616265 | 
2026-02-16 17:40:10.616330 | TASK [configure-swap : Add all swap]
2026-02-16 17:40:11.154705 | controller | ok: Runtime: 0:00:00.029013
2026-02-16 17:40:11.161596 | 
2026-02-16 17:40:11.161670 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:40:11.195572 | controller | ok:
2026-02-16 17:40:11.195726 | controller | {
2026-02-16 17:40:11.195759 | controller |   "swap_required": "8192"
2026-02-16 17:40:11.195786 | controller | }
2026-02-16 17:40:11.202585 | 
2026-02-16 17:40:11.202710 | TASK [configure-swap : Set swappiness]
2026-02-16 17:40:11.624183 | controller | changed
2026-02-16 17:40:11.630698 | 
2026-02-16 17:40:11.630772 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:40:11.665266 | controller | ok:
2026-02-16 17:40:11.665493 | controller | {
2026-02-16 17:40:11.665527 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:40:11.665561 | controller | }
2026-02-16 17:40:11.676778 | 
2026-02-16 17:40:11.676842 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:40:12.250429 | controller | changed
2026-02-16 17:40:12.259676 | 
2026-02-16 17:40:12.259771 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:40:12.509019 | controller | changed
2026-02-16 17:40:12.517255 | 
2026-02-16 17:40:12.517356 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:40:13.642317 | controller | changed
2026-02-16 17:40:13.648183 | 
2026-02-16 17:40:13.648254 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:40:13.891987 | controller | changed
2026-02-16 17:40:13.899480 | 
2026-02-16 17:40:13.899546 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:40:14.947146 | controller | changed
2026-02-16 17:40:14.955173 | 
2026-02-16 17:40:14.955268 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:40:15.194040 | controller | changed
2026-02-16 17:40:15.205437 | 
2026-02-16 17:40:15.205509 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:40:15.755621 | controller | changed
2026-02-16 17:40:15.762672 | 
2026-02-16 17:40:15.762741 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:40:16.648563 | controller | changed
2026-02-16 17:40:16.701907 | 
2026-02-16 17:40:16.702066 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:40:17.518678 | controller | changed
2026-02-16 17:40:17.529222 | 
2026-02-16 17:40:17.529301 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:40:17.862936 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:40:17.875732 | 
2026-02-16 17:40:17.875837 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:40:18.339358 | controller | ok: Item: Runtime: 0:00:00.184521
2026-02-16 17:40:18.943173 | controller | ok: Item: Runtime: 0:00:00.343301
2026-02-16 17:40:19.751028 | controller | ok: Item: Runtime: 0:00:00.592881
2026-02-16 17:40:20.030938 | controller | ok: Item: Runtime: 0:00:00.058199
2026-02-16 17:40:20.526064 | controller | ok: Item: Runtime: 0:00:00.260649
2026-02-16 17:40:21.981243 | controller | ok: Item: Runtime: 0:00:01.236033
2026-02-16 17:40:25.456175 | controller | ok: Item: Runtime: 0:00:03.219733
2026-02-16 17:40:25.782178 | controller | ok: Item: Runtime: 0:00:00.097561
2026-02-16 17:40:26.196836 | controller | ok: Item: Runtime: 0:00:00.201946
2026-02-16 17:40:26.556685 | controller | ok: Item: Runtime: 0:00:00.132741
2026-02-16 17:40:27.299343 | controller | ok: Item: Runtime: 0:00:00.527592
2026-02-16 17:40:28.057694 | controller | ok: Item: Runtime: 0:00:00.535829
2026-02-16 17:40:28.499654 | controller | ok: Item: Runtime: 0:00:00.231218
2026-02-16 17:40:28.948504 | controller | ok: Item: Runtime: 0:00:00.221812
2026-02-16 17:40:29.569639 | controller | ok: Item: Runtime: 0:00:00.394838
2026-02-16 17:40:30.939907 | controller | ok: Item: Runtime: 0:00:01.163690
2026-02-16 17:40:30.968792 | 
2026-02-16 17:40:30.968929 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:40:31.184033 | controller | ok: All paths examined
2026-02-16 17:40:31.190370 | 
2026-02-16 17:40:31.190435 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:40:31.448297 | controller | ok: All paths examined
2026-02-16 17:40:31.454673 | 
2026-02-16 17:40:31.454744 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:40:31.930003 | controller | ok: Item: Runtime: 0:00:00.198737
2026-02-16 17:40:31.930302 | controller | changed: All items complete
2026-02-16 17:40:31.930377 | 
2026-02-16 17:40:32.325731 | controller | ok: Item: Runtime: 0:00:00.184419
2026-02-16 17:40:32.341213 | 
2026-02-16 17:40:32.341384 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:40:32.385402 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.388089 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.390116 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.393182 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.396173 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.399188 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.402214 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.405277 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.408422 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.411433 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.422503 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.423598 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.424549 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.425522 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.427014 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.430125 | controller | skipping: Conditional result was False
2026-02-16 17:40:32.458488 | 
2026-02-16 17:40:32.458647 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:40:34.796124 | controller | changed
2026-02-16 17:40:34.813427 | 
2026-02-16 17:40:34.813645 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:40:35.052300 | controller | changed
2026-02-16 17:40:35.119891 | 
2026-02-16 17:40:35.120058 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:40:35.370672 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:40:35.662270 | controller | ERROR
2026-02-16 17:40:35.662664 | controller | {
2026-02-16 17:40:35.662729 | controller |   "delta": "0:00:00.011317",
2026-02-16 17:40:35.662774 | controller |   "end": "2026-02-16 17:40:35.371245",
2026-02-16 17:40:35.662818 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:35.662864 | controller |   "rc": 1,
2026-02-16 17:40:35.662910 | controller |   "start": "2026-02-16 17:40:35.359928"
2026-02-16 17:40:35.662951 | controller | }
2026-02-16 17:40:35.662980 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:35.668589 | 
2026-02-16 17:40:35.668663 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:40:35.918606 | controller | ok
2026-02-16 17:40:35.929962 | 
2026-02-16 17:40:35.930065 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:40:36.163617 | controller | /usr/bin/journalctl
2026-02-16 17:40:36.474258 | controller | ok: Runtime: 0:00:00.006551
2026-02-16 17:40:36.480234 | 
2026-02-16 17:40:36.480459 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:40:36.749901 | controller | 2026-02-16 17:40:36
2026-02-16 17:40:37.027274 | controller | ok: Runtime: 0:00:00.027441
2026-02-16 17:40:37.033140 | 
2026-02-16 17:40:37.033209 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:40:37.572835 | controller | changed
2026-02-16 17:40:37.579674 | 
2026-02-16 17:40:37.579740 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:40:37.605312 | controller | skipping: Conditional result was False
2026-02-16 17:40:37.615458 | 
2026-02-16 17:40:37.615547 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:40:38.149292 | controller | skipping: Conditional result was False
2026-02-16 17:40:38.160558 | 
2026-02-16 17:40:38.160629 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:40:38.688937 | controller | skipping: Conditional result was False
2026-02-16 17:40:38.702720 | 
2026-02-16 17:40:38.702865 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:40:38.727985 | controller | skipping: Conditional result was False
2026-02-16 17:40:38.741115 | 
2026-02-16 17:40:38.741264 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:40:39.274670 | controller | skipping: Conditional result was False
2026-02-16 17:40:39.285406 | 
2026-02-16 17:40:39.285500 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:40:39.816821 | controller | skipping: Conditional result was False
2026-02-16 17:40:39.828239 | 
2026-02-16 17:40:39.828312 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:40:39.853264 | controller | skipping: Conditional result was False
2026-02-16 17:40:39.866779 | 
2026-02-16 17:40:39.866923 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:40:39.892542 | controller | skipping: Conditional result was False
2026-02-16 17:40:39.903871 | 
2026-02-16 17:40:39.903939 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:40:40.500203 | controller | skipping: Conditional result was False
2026-02-16 17:40:40.513805 | 
2026-02-16 17:40:40.513958 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:40:41.043065 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.050173 | 
2026-02-16 17:40:41.050244 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:40:41.074929 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.085765 | 
2026-02-16 17:40:41.085857 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:40:41.111074 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.127451 | 
2026-02-16 17:40:41.127523 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:40:41.507544 | controller | ok
2026-02-16 17:40:41.523457 | 
2026-02-16 17:40:41.523519 | PLAY RECAP
2026-02-16 17:40:41.523574 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:40:41.523600 | 
2026-02-16 17:40:41.671164 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:40:41.677362 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:42.282112 | 
2026-02-16 17:40:42.283000 | PLAY [all]
2026-02-16 17:40:42.298396 | 
2026-02-16 17:40:42.298496 | TASK [ensure-rust : Use rustup]
2026-02-16 17:40:42.333027 | controller | ok
2026-02-16 17:40:42.343238 | controller | included: /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:40:42.349020 | 
2026-02-16 17:40:42.349112 | TASK [ensure-rust : Install Rust]
2026-02-16 17:40:42.751249 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:40:42.889729 | controller | ERROR
2026-02-16 17:40:42.890048 | controller | {
2026-02-16 17:40:42.890113 | controller |   "delta": "0:00:00.011777",
2026-02-16 17:40:42.890162 | controller |   "end": "2026-02-16 17:40:42.754245",
2026-02-16 17:40:42.890204 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:42.890243 | controller |   "rc": 127,
2026-02-16 17:40:42.890282 | controller |   "start": "2026-02-16 17:40:42.742468"
2026-02-16 17:40:42.890371 | controller | }
failure
2026-02-16 17:40:42.892220 | 
2026-02-16 17:40:42.892268 | PLAY RECAP
2026-02-16 17:40:42.892311 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:40:42.892339 | 
2026-02-16 17:40:43.009518 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:43.019512 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:43.657572 | 
2026-02-16 17:40:43.657753 | PLAY [all]
2026-02-16 17:40:43.683289 | 
2026-02-16 17:40:43.683501 | TASK [Return built artifacts to Zuul]
2026-02-16 17:40:43.717781 | controller | ok
2026-02-16 17:40:43.725058 | 
2026-02-16 17:40:43.725157 | TASK [Get the Docker volume ID]
2026-02-16 17:40:44.264146 | controller | ERROR
2026-02-16 17:40:44.264638 | controller | {
2026-02-16 17:40:44.264709 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:40:44.264760 | controller |   "rc": 2
2026-02-16 17:40:44.264805 | controller | }
2026-02-16 17:40:44.264862 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:44.272361 | 
2026-02-16 17:40:44.272430 | TASK [Copy all of the pod logs]
2026-02-16 17:40:44.307669 | controller | ERROR
2026-02-16 17:40:44.307885 | controller | {
2026-02-16 17:40:44.307927 | 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/dff50f59cedd4620af8f2f0c887cb462/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml': line 21, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-02-16 17:40:44.307958 | controller | }
2026-02-16 17:40:44.307996 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:44.309207 | 
2026-02-16 17:40:44.309271 | PLAY RECAP
2026-02-16 17:40:44.309332 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:40:44.309363 | 
2026-02-16 17:40:44.445726 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:44.456174 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:40:45.069255 | 
2026-02-16 17:40:45.069397 | PLAY [all]
2026-02-16 17:40:45.088056 | 
2026-02-16 17:40:45.088136 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:40:45.468083 | controller | changed
2026-02-16 17:40:45.475369 | 
2026-02-16 17:40:45.475448 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:40:46.515107 | controller | ok: Runtime: 0:00:00.493040
2026-02-16 17:40:46.521944 | 
2026-02-16 17:40:46.522021 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:40:47.066483 | controller | ok: Runtime: 0:00:00.020555
2026-02-16 17:40:47.073509 | 
2026-02-16 17:40:47.073583 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:40:47.610990 | controller | ok: Runtime: 0:00:00.022170
2026-02-16 17:40:47.616891 | 
2026-02-16 17:40:47.616965 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:40:48.492299 | controller | changed
2026-02-16 17:40:48.534138 | 
2026-02-16 17:40:48.534281 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:40:48.782874 | controller | changed
2026-02-16 17:40:48.789271 | 
2026-02-16 17:40:48.789337 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:49.132358 | controller | Output suppressed because no_log was given
2026-02-16 17:40:49.139291 | 
2026-02-16 17:40:49.139363 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:49.176349 | 
2026-02-16 17:40:49.176524 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:49.213150 | 
2026-02-16 17:40:49.213293 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:49.249816 | 
2026-02-16 17:40:49.249992 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:49.289687 | 
2026-02-16 17:40:49.289831 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:49.329111 | 
2026-02-16 17:40:49.329286 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:40:49.576514 | controller | changed
2026-02-16 17:40:49.582671 | 
2026-02-16 17:40:49.582740 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:40:49.618319 | controller | ok
2026-02-16 17:40:49.623566 | 
2026-02-16 17:40:49.623631 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:40:49.872866 | controller | Output suppressed because no_log was given
2026-02-16 17:40:49.878283 | 
2026-02-16 17:40:49.878352 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:40:49.915762 | 
2026-02-16 17:40:49.915903 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:40:49.958748 | 
2026-02-16 17:40:49.958903 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:40:50.685393 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:40:50.705754 | controller | Using python 3.12 to install setuptools
2026-02-16 17:40:50.783912 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:40:50.796151 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:40:51.004559 | controller | ERROR
2026-02-16 17:40:51.004890 | controller | {
2026-02-16 17:40:51.004974 | controller |   "delta": "0:00:00.592694",
2026-02-16 17:40:51.005049 | controller |   "end": "2026-02-16 17:40:50.798766",
2026-02-16 17:40:51.005101 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:51.005209 | controller |   "rc": 127,
2026-02-16 17:40:51.005262 | controller |   "start": "2026-02-16 17:40:50.206072"
2026-02-16 17:40:51.005306 | controller | }
2026-02-16 17:40:51.005445 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:51.017311 | 
2026-02-16 17:40:51.017403 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:40:51.284082 | controller | changed
2026-02-16 17:40:51.293370 | 
2026-02-16 17:40:51.293457 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:40:55.196939 | controller | Output suppressed because no_log was given
2026-02-16 17:40:55.210035 | 
2026-02-16 17:40:55.210162 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:40:55.254837 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.255177 | 
2026-02-16 17:40:55.256172 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.266244 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.269674 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.272494 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.274918 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.277547 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.280226 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.282902 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.285517 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.288359 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.290958 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.293457 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.295782 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.298098 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.300357 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.302691 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.306019 | controller | skipping: Conditional result was False
2026-02-16 17:40:55.341565 | 
2026-02-16 17:40:55.341783 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:40:55.601687 | controller | ok
2026-02-16 17:40:55.608362 | 
2026-02-16 17:40:55.608428 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:40:56.181834 | controller | skipping: Conditional result was False
2026-02-16 17:40:56.200264 | 
2026-02-16 17:40:56.200349 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:40:56.468979 | controller | sudo: iptables-save: command not found
2026-02-16 17:40:56.567865 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:40:57.721322 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:40:57.776796 | controller | ok: Runtime: 0:00:01.270021
2026-02-16 17:40:57.787320 | 
2026-02-16 17:40:57.787390 | LOOP [stage-output : Register sources]
2026-02-16 17:41:07.413076 | controller | Output suppressed because no_log was given
2026-02-16 17:41:07.491078 | 
2026-02-16 17:41:07.491263 | TASK [stage-output : Check sudo]
2026-02-16 17:41:08.028397 | controller | ok: Runtime: 0:00:00.020062
2026-02-16 17:41:08.034279 | 
2026-02-16 17:41:08.034346 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:41:08.148033 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.148469 | 
2026-02-16 17:41:08.150149 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.151216 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.153584 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.202846 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.203232 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.203663 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.203991 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.204434 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.205433 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.209436 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.211706 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.213808 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.215904 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.231925 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.232410 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.232866 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.233398 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.235496 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.237652 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.239740 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.242243 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.244475 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.267564 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.267906 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.341504 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.354281 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.364946 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.376981 | controller | Output suppressed because no_log was given
2026-02-16 17:41:08.393085 | 
2026-02-16 17:41:08.393190 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:41:08.447578 | controller | ok
2026-02-16 17:41:08.454760 | 
2026-02-16 17:41:08.454829 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:41:08.741117 | controller | changed: "docs"
2026-02-16 17:41:08.949541 | controller | changed: "artifacts"
2026-02-16 17:41:09.154750 | controller | ok: "logs"
2026-02-16 17:41:09.165738 | 
2026-02-16 17:41:09.165910 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:41:09.455632 | controller | ok: Item: Runtime: 0:00:00.011903
2026-02-16 17:41:09.455905 | controller | changed: All items complete
2026-02-16 17:41:09.455936 | 
2026-02-16 17:41:09.692249 | controller | ok: Item: Runtime: 0:00:00.009261
2026-02-16 17:41:09.930753 | controller | ok: Item: Runtime: 0:00:00.007047
2026-02-16 17:41:10.154946 | controller | ok: Item: Runtime: 0:00:00.008866
2026-02-16 17:41:10.380407 | controller | ok: Item: Runtime: 0:00:00.010266
2026-02-16 17:41:10.605643 | controller | ok: Item: Runtime: 0:00:00.006882
2026-02-16 17:41:10.838716 | controller | ok: Item: Runtime: 0:00:00.008553
2026-02-16 17:41:11.067632 | controller | ok: Item: Runtime: 0:00:00.010276
2026-02-16 17:41:11.290898 | controller | ok: Item: Runtime: 0:00:00.008107
2026-02-16 17:41:11.540414 | controller | ok: Item: Runtime: 0:00:00.008897
2026-02-16 17:41:11.777023 | controller | ok: Item: Runtime: 0:00:00.007574
2026-02-16 17:41:12.064046 | controller | ok: Item: Runtime: 0:00:00.008337
2026-02-16 17:41:12.263020 | controller | ok: Item: Runtime: 0:00:00.008362
2026-02-16 17:41:12.492222 | controller | ok: Item: Runtime: 0:00:00.008762
2026-02-16 17:41:12.729258 | controller | ok: Item: Runtime: 0:00:00.009812
2026-02-16 17:41:12.973589 | controller | ok: Item: Runtime: 0:00:00.010504
2026-02-16 17:41:12.998188 | 
2026-02-16 17:41:12.998321 | TASK [stage-output : Make all log files readable]
2026-02-16 17:41:13.254348 | controller | changed
2026-02-16 17:41:13.324760 | 
2026-02-16 17:41:13.324920 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:41:13.673162 | controller | changed: Renamed files for staging.
2026-02-16 17:41:13.680345 | 
2026-02-16 17:41:13.680478 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:41:13.705881 | controller | skipping: Conditional result was False
2026-02-16 17:41:13.719758 | 
2026-02-16 17:41:13.719930 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:41:13.763954 | 
2026-02-16 17:41:13.764148 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:41:14.387457 | controller | changed:
2026-02-16 17:41:14.387643 | controller | created directory /var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/work/logs/controller
2026-02-16 17:41:14.387671 | controller | cd+++++++++ logs/
2026-02-16 17:41:14.387693 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:41:14.387713 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:41:14.387732 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:41:14.387751 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:41:14.387770 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:41:14.387789 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:41:14.387807 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:41:14.387826 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:41:14.387844 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:41:14.387862 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:41:14.387880 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:41:14.387898 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:41:14.387928 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:41:14.387990 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:41:14.388013 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:41:14.388031 | controller | cd+++++++++ logs/apache/
2026-02-16 17:41:14.388050 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:41:14.388068 | controller | cd+++++++++ logs/etc/
2026-02-16 17:41:14.388086 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:41:14.388104 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:41:14.388122 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:41:14.388140 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:41:14.388158 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:41:14.388177 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:41:14.400611 | 
2026-02-16 17:41:14.400679 | TASK [Check if a tempest log exits]
2026-02-16 17:41:14.653590 | controller | ok
2026-02-16 17:41:14.659806 | 
2026-02-16 17:41:14.659982 | TASK [Link post-devstack tempest.log]
2026-02-16 17:41:14.697260 | controller | skipping: Conditional result was False
2026-02-16 17:41:14.704327 | 
2026-02-16 17:41:14.704431 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:41:14.957673 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:41:15.246114 | controller | ERROR
2026-02-16 17:41:15.246371 | controller | {
2026-02-16 17:41:15.246419 | controller |   "delta": "0:00:00.008870",
2026-02-16 17:41:15.246451 | controller |   "end": "2026-02-16 17:41:14.958368",
2026-02-16 17:41:15.246478 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:15.246505 | controller |   "rc": 127,
2026-02-16 17:41:15.246532 | controller |   "start": "2026-02-16 17:41:14.949498"
2026-02-16 17:41:15.246558 | controller | }
2026-02-16 17:41:15.246593 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:15.248484 | 
2026-02-16 17:41:15.249243 | PLAY RECAP
2026-02-16 17:41:15.249319 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:41:15.249352 | 
2026-02-16 17:41:15.409249 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:15.418231 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:16.018674 | 
2026-02-16 17:41:16.018806 | PLAY [all]
2026-02-16 17:41:16.030120 | 
2026-02-16 17:41:16.030197 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:41:16.075584 | controller | skipping: Conditional result was False
2026-02-16 17:41:16.081288 | 
2026-02-16 17:41:16.081367 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:41:16.120946 | controller | ok
2026-02-16 17:41:16.127220 | 
2026-02-16 17:41:16.127289 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:41:16.548257 | controller -> localhost | ok: "/var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/work/logs"
2026-02-16 17:41:16.774982 | controller -> localhost | changed: "/var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/work/artifacts"
2026-02-16 17:41:16.982722 | controller -> localhost | changed: "/var/lib/zuul/builds/dff50f59cedd4620af8f2f0c887cb462/work/docs"
2026-02-16 17:41:17.005240 | 
2026-02-16 17:41:17.005379 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:41:17.666415 | controller | changed: .d..t...... ./
2026-02-16 17:41:17.666742 | controller | changed: All items complete
2026-02-16 17:41:17.666777 | 
2026-02-16 17:41:18.143263 | controller | changed: .d..t...... ./
2026-02-16 17:41:18.626460 | controller | changed: .d..t...... ./
2026-02-16 17:41:18.645512 | 
2026-02-16 17:41:18.645616 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:41:19.110553 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008722
2026-02-16 17:41:19.350764 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009806
2026-02-16 17:41:19.370798 | 
2026-02-16 17:41:19.370977 | PLAY [all]
2026-02-16 17:41:19.379436 | 
2026-02-16 17:41:19.379500 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:41:19.867933 | controller | changed
2026-02-16 17:41:19.875218 | 
2026-02-16 17:41:19.875271 | PLAY RECAP
2026-02-16 17:41:19.875320 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:41:19.875344 | 
2026-02-16 17:41:20.017569 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:20.027522 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:41:20.682629 | 
2026-02-16 17:41:20.683469 | PLAY [localhost]
2026-02-16 17:41:20.695094 | 
2026-02-16 17:41:20.695173 | TASK [Generate Zuul manifest]
2026-02-16 17:41:20.719970 | localhost | ok
2026-02-16 17:41:20.738968 | 
2026-02-16 17:41:20.739052 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:41:21.155843 | localhost | changed
2026-02-16 17:41:21.169489 | 
2026-02-16 17:41:21.169589 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:41:21.202444 | localhost | ok
2026-02-16 17:41:21.212634 | 
2026-02-16 17:41:21.212726 | TASK [Upload logs]
2026-02-16 17:41:21.232756 | localhost | ok
2026-02-16 17:41:21.296314 | 
2026-02-16 17:41:21.296477 | TASK [Set zuul-log-path fact]
2026-02-16 17:41:21.318681 | localhost | ok
2026-02-16 17:41:21.331382 | 
2026-02-16 17:41:21.331463 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:41:21.362801 | localhost | ok
2026-02-16 17:41:21.372014 | 
2026-02-16 17:41:21.372150 | TASK [upload-logs : Create log directories]
2026-02-16 17:41:21.804437 | localhost | changed
2026-02-16 17:41:21.811587 | 
2026-02-16 17:41:21.811676 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:41:22.271618 | localhost -> localhost | ok: Runtime: 0:00:00.006195
2026-02-16 17:41:22.276569 | 
2026-02-16 17:41:22.276635 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:41:22.717864 | localhost | Output suppressed because no_log was given
2026-02-16 17:41:22.723576 | 
2026-02-16 17:41:22.723646 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:41:22.770951 | localhost | skipping: Conditional result was False
2026-02-16 17:41:22.777518 | localhost | skipping: Conditional result was False
2026-02-16 17:41:22.789473 | 
2026-02-16 17:41:22.789628 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:41:22.830880 | localhost | skipping: Conditional result was False
2026-02-16 17:41:22.831197 | 
2026-02-16 17:41:22.834375 | localhost | skipping: Conditional result was False
2026-02-16 17:41:22.845532 | 
2026-02-16 17:41:22.845670 | LOOP [upload-logs : Upload console log and json output]