2026-02-16 17:24:24.779722 | Job console starting
2026-02-16 17:24:24.791004 | Updating git repos
2026-02-16 17:24:28.437341 | Cloning repos into workspace
2026-02-16 17:24:34.217211 | Restoring repo states
2026-02-16 17:24:34.533587 | Merging changes
2026-02-16 17:24:35.119272 | Checking out repos
2026-02-16 17:24:39.156994 | Preparing playbooks
2026-02-16 17:24:41.385016 | Running Ansible setup
2026-02-16 17:24:45.334479 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:24:45.908529 | 
2026-02-16 17:24:45.908662 | PLAY [localhost]
2026-02-16 17:24:45.916042 | 
2026-02-16 17:24:45.916113 | TASK [Gathering Facts]
2026-02-16 17:24:46.820244 | localhost | ok
2026-02-16 17:24:46.830945 | 
2026-02-16 17:24:46.831066 | TASK [Setup log path fact]
2026-02-16 17:24:46.851443 | localhost | ok
2026-02-16 17:24:46.864858 | 
2026-02-16 17:24:46.864930 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:24:46.897331 | localhost | ok
2026-02-16 17:24:46.905065 | 
2026-02-16 17:24:46.905139 | TASK [emit-job-header : Print job information]
2026-02-16 17:24:46.948973 | # Job Information
2026-02-16 17:24:46.949236 | Ansible Version: 2.16.15
2026-02-16 17:24:46.949305 | Job: magnum-cluster-api-sonobuoy-v1.32.12-cilium
2026-02-16 17:24:46.949353 | Pipeline: check
2026-02-16 17:24:46.949397 | Executor: 3a2793d2bd32
2026-02-16 17:24:46.949440 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:24:46.949490 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:24:46.952162 | 
2026-02-16 17:24:46.952223 | LOOP [emit-job-header : Print node information]
2026-02-16 17:24:47.050084 | localhost | ok:
2026-02-16 17:24:47.050403 | localhost | # Node Information
2026-02-16 17:24:47.050481 | localhost | Inventory Hostname: controller
2026-02-16 17:24:47.050542 | localhost | Hostname: np0000155596
2026-02-16 17:24:47.050600 | localhost | Username: zuul
2026-02-16 17:24:47.050669 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:24:47.050781 | localhost | Provider: yul1
2026-02-16 17:24:47.050835 | localhost | Region: ca-ymq-1
2026-02-16 17:24:47.050881 | localhost | Label: ubuntu-noble-16
2026-02-16 17:24:47.050925 | localhost | Product Name: OpenStack Nova
2026-02-16 17:24:47.050970 | localhost | Interface IP: 199.204.45.3
2026-02-16 17:24:47.071529 | 
2026-02-16 17:24:47.071648 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:24:47.515386 | localhost -> localhost | changed
2026-02-16 17:24:47.522782 | 
2026-02-16 17:24:47.522879 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:24:48.332275 | localhost -> localhost | changed
2026-02-16 17:24:48.342756 | 
2026-02-16 17:24:48.342849 | PLAY [all]
2026-02-16 17:24:48.351921 | 
2026-02-16 17:24:48.351984 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:24:48.590242 | controller -> localhost | ok
2026-02-16 17:24:48.600954 | 
2026-02-16 17:24:48.601077 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:24:48.636778 | controller | ok
2026-02-16 17:24:48.651902 | controller | included: /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:24:48.658104 | 
2026-02-16 17:24:48.658164 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:24:49.347761 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:24:49.347964 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/work/2dcfe1c101994b5baf29ca79675f17f2_id_rsa
2026-02-16 17:24:49.347993 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/work/2dcfe1c101994b5baf29ca79675f17f2_id_rsa.pub
2026-02-16 17:24:49.348015 | controller -> localhost | The key fingerprint is:
2026-02-16 17:24:49.348036 | controller -> localhost | SHA256:MsIvneVOULLcvloETgUuio3xmNnWkfFleF19mzfBSZU zuul-build-sshkey
2026-02-16 17:24:49.348074 | controller -> localhost | The key's randomart image is:
2026-02-16 17:24:49.348095 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:24:49.348124 | controller -> localhost | |    . .o+. ...o.=|
2026-02-16 17:24:49.348145 | controller -> localhost | |     =.+. .   .E.|
2026-02-16 17:24:49.348165 | controller -> localhost | |.   + *..      .+|
2026-02-16 17:24:49.348185 | controller -> localhost | | @ + * *       +.|
2026-02-16 17:24:49.348204 | controller -> localhost | |* * + B S       o|
2026-02-16 17:24:49.348223 | controller -> localhost | | .   + X         |
2026-02-16 17:24:49.348241 | controller -> localhost | |    . + =        |
2026-02-16 17:24:49.348261 | controller -> localhost | |     . + .       |
2026-02-16 17:24:49.348283 | controller -> localhost | |      ..o        |
2026-02-16 17:24:49.348303 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:24:49.348348 | controller -> localhost | ok: Runtime: 0:00:00.252287
2026-02-16 17:24:49.353911 | 
2026-02-16 17:24:49.353979 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:24:49.390189 | controller | ok
2026-02-16 17:24:49.399342 | controller | included: /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:24:49.407083 | 
2026-02-16 17:24:49.407143 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:24:49.432250 | controller | skipping: Conditional result was False
2026-02-16 17:24:49.441884 | 
2026-02-16 17:24:49.441948 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:24:49.961544 | controller | changed
2026-02-16 17:24:49.968918 | 
2026-02-16 17:24:49.969020 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:24:50.196053 | controller | ok
2026-02-16 17:24:50.202953 | 
2026-02-16 17:24:50.203019 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:24:50.766391 | controller | changed
2026-02-16 17:24:50.772838 | 
2026-02-16 17:24:50.772905 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:24:51.324923 | controller | changed
2026-02-16 17:24:51.331771 | 
2026-02-16 17:24:51.331838 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:24:51.356400 | controller | skipping: Conditional result was False
2026-02-16 17:24:51.366450 | 
2026-02-16 17:24:51.366515 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:24:51.726129 | controller -> localhost | changed
2026-02-16 17:24:51.741853 | 
2026-02-16 17:24:51.741922 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:24:52.155441 | controller -> localhost | Identity added: /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/work/2dcfe1c101994b5baf29ca79675f17f2_id_rsa (zuul-build-sshkey)
2026-02-16 17:24:52.155651 | controller -> localhost | ok: Runtime: 0:00:00.015161
2026-02-16 17:24:52.203514 | 
2026-02-16 17:24:52.203687 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:24:52.515538 | controller | ok
2026-02-16 17:24:52.521093 | 
2026-02-16 17:24:52.521159 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:24:52.546954 | controller | skipping: Conditional result was False
2026-02-16 17:24:52.564227 | 
2026-02-16 17:24:52.564301 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:24:52.896924 | controller | ok
2026-02-16 17:24:52.903708 | 
2026-02-16 17:24:52.903777 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:25:12.375394 | controller | Output suppressed because no_log was given
2026-02-16 17:25:12.386185 | 
2026-02-16 17:25:12.386248 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:25:12.629814 | controller | ok: "logs"
2026-02-16 17:25:12.630053 | controller | ok: All items complete
2026-02-16 17:25:12.630081 | 
2026-02-16 17:25:12.828764 | controller | ok: "artifacts"
2026-02-16 17:25:13.062939 | controller | ok: "docs"
2026-02-16 17:25:13.080354 | 
2026-02-16 17:25:13.080521 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:25:13.322307 | controller | changed: "logs"
2026-02-16 17:25:13.523762 | controller | changed: "artifacts"
2026-02-16 17:25:13.766385 | controller | changed: "docs"
2026-02-16 17:25:13.785626 | 
2026-02-16 17:25:13.785751 | PLAY RECAP
2026-02-16 17:25:13.785815 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:25:13.785852 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:25:13.785882 | 
2026-02-16 17:25:13.967210 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:25:13.979963 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:25:14.586147 | 
2026-02-16 17:25:14.586293 | PLAY [all]
2026-02-16 17:25:14.603345 | 
2026-02-16 17:25:14.603421 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:25:14.982661 | controller | changed
2026-02-16 17:25:14.991372 | 
2026-02-16 17:25:14.991445 | TASK [Gather minimum local MTU]
2026-02-16 17:25:15.067684 | controller | ok
2026-02-16 17:25:15.073211 | 
2026-02-16 17:25:15.073282 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:25:15.130341 | controller | ok
2026-02-16 17:25:15.137778 | 
2026-02-16 17:25:15.137876 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:25:15.164645 | controller | skipping: Conditional result was False
2026-02-16 17:25:15.178128 | 
2026-02-16 17:25:15.178219 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:25:15.717720 | controller | ok: Runtime: 0:00:00.009319
2026-02-16 17:25:15.724287 | 
2026-02-16 17:25:15.724361 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:25:15.759934 | controller | skipping: Conditional result was False
2026-02-16 17:25:15.770045 | 
2026-02-16 17:25:15.770158 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:25:15.796387 | controller | skipping: Conditional result was False
2026-02-16 17:25:15.919028 | 
2026-02-16 17:25:15.919117 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:25:15.988573 | controller | ok
2026-02-16 17:25:16.000368 | controller | included: /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:25:16.006769 | 
2026-02-16 17:25:16.006830 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:25:16.064686 | controller | ok
2026-02-16 17:25:16.070624 | 
2026-02-16 17:25:16.070694 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:25:16.356119 | controller | ext4
2026-02-16 17:25:16.645131 | controller | ok: Runtime: 0:00:00.012305
2026-02-16 17:25:16.650853 | 
2026-02-16 17:25:16.650920 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:25:16.685125 | controller | ok
2026-02-16 17:25:16.692588 | 
2026-02-16 17:25:16.692682 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:25:16.726935 | controller | ok:
2026-02-16 17:25:16.727100 | controller | {
2026-02-16 17:25:16.727135 | controller |   "root_filesystem": "ext4"
2026-02-16 17:25:16.727163 | controller | }
2026-02-16 17:25:16.735019 | 
2026-02-16 17:25:16.735085 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:25:26.519412 | controller | 8192+0 records in
2026-02-16 17:25:26.519549 | controller | 8192+0 records out
2026-02-16 17:25:26.519571 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.50939 s, 903 MB/s
2026-02-16 17:25:26.782082 | controller | ok: Runtime: 0:00:09.520706
2026-02-16 17:25:26.788010 | 
2026-02-16 17:25:26.788090 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:25:27.046740 | controller | changed
2026-02-16 17:25:27.055226 | 
2026-02-16 17:25:27.055314 | TASK [configure-swap : Make swapfile]
2026-02-16 17:25:51.445957 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:25:51.446891 | controller | no label, UUID=b211d7c0-f011-4759-a5d4-07d5952eb11f
2026-02-16 17:25:51.751910 | controller | ok: Runtime: 0:00:24.165187
2026-02-16 17:25:51.759472 | 
2026-02-16 17:25:51.759538 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:25:52.148366 | controller | changed
2026-02-16 17:25:52.154770 | 
2026-02-16 17:25:52.154834 | TASK [configure-swap : Add all swap]
2026-02-16 17:25:52.694660 | controller | ok: Runtime: 0:00:00.022719
2026-02-16 17:25:52.778613 | 
2026-02-16 17:25:52.778768 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:25:52.813782 | controller | ok:
2026-02-16 17:25:52.813950 | controller | {
2026-02-16 17:25:52.813985 | controller |   "swap_required": "8192"
2026-02-16 17:25:52.814012 | controller | }
2026-02-16 17:25:52.819591 | 
2026-02-16 17:25:52.819657 | TASK [configure-swap : Set swappiness]
2026-02-16 17:25:53.221090 | controller | changed
2026-02-16 17:25:53.272795 | 
2026-02-16 17:25:53.272896 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:25:53.307311 | controller | ok:
2026-02-16 17:25:53.307446 | controller | {
2026-02-16 17:25:53.307472 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:25:53.307498 | controller | }
2026-02-16 17:25:53.317291 | 
2026-02-16 17:25:53.317357 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:25:53.747784 | controller | changed
2026-02-16 17:25:53.756190 | 
2026-02-16 17:25:53.756338 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:25:54.034478 | controller | changed
2026-02-16 17:25:54.040955 | 
2026-02-16 17:25:54.041055 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:25:54.600062 | controller | changed
2026-02-16 17:25:54.606300 | 
2026-02-16 17:25:54.606375 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:25:54.849741 | controller | changed
2026-02-16 17:25:54.857087 | 
2026-02-16 17:25:54.857156 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:25:55.958369 | controller | changed
2026-02-16 17:25:55.965208 | 
2026-02-16 17:25:55.965276 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:25:56.213719 | controller | changed
2026-02-16 17:25:56.226541 | 
2026-02-16 17:25:56.226624 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:25:56.512774 | controller | changed
2026-02-16 17:25:56.519115 | 
2026-02-16 17:25:56.519197 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:25:56.888071 | controller | changed
2026-02-16 17:25:56.940210 | 
2026-02-16 17:25:56.940374 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:25:57.710780 | controller | changed
2026-02-16 17:25:57.722269 | 
2026-02-16 17:25:57.722343 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:25:58.056748 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:25:58.069699 | 
2026-02-16 17:25:58.069769 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:25:58.568165 | controller | ok: Item: Runtime: 0:00:00.213579
2026-02-16 17:25:59.148528 | controller | ok: Item: Runtime: 0:00:00.351611
2026-02-16 17:25:59.900764 | controller | ok: Item: Runtime: 0:00:00.538021
2026-02-16 17:26:00.181197 | controller | ok: Item: Runtime: 0:00:00.063184
2026-02-16 17:26:00.652296 | controller | ok: Item: Runtime: 0:00:00.261919
2026-02-16 17:26:02.197763 | controller | ok: Item: Runtime: 0:00:01.313478
2026-02-16 17:26:05.427428 | controller | ok: Item: Runtime: 0:00:02.995986
2026-02-16 17:26:05.753578 | controller | ok: Item: Runtime: 0:00:00.096774
2026-02-16 17:26:06.177059 | controller | ok: Item: Runtime: 0:00:00.193454
2026-02-16 17:26:06.516565 | controller | ok: Item: Runtime: 0:00:00.117432
2026-02-16 17:26:07.262442 | controller | ok: Item: Runtime: 0:00:00.521317
2026-02-16 17:26:08.008796 | controller | ok: Item: Runtime: 0:00:00.530315
2026-02-16 17:26:08.466286 | controller | ok: Item: Runtime: 0:00:00.235403
2026-02-16 17:26:08.890661 | controller | ok: Item: Runtime: 0:00:00.213215
2026-02-16 17:26:09.488208 | controller | ok: Item: Runtime: 0:00:00.385667
2026-02-16 17:26:10.895035 | controller | ok: Item: Runtime: 0:00:01.190648
2026-02-16 17:26:10.926513 | 
2026-02-16 17:26:10.926692 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:26:11.146447 | controller | ok: All paths examined
2026-02-16 17:26:11.153744 | 
2026-02-16 17:26:11.153814 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:26:11.401761 | controller | ok: All paths examined
2026-02-16 17:26:11.408855 | 
2026-02-16 17:26:11.408920 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:26:11.841855 | controller | ok: Item: Runtime: 0:00:00.143122
2026-02-16 17:26:11.842115 | controller | changed: All items complete
2026-02-16 17:26:11.842146 | 
2026-02-16 17:26:12.253168 | controller | ok: Item: Runtime: 0:00:00.200819
2026-02-16 17:26:12.264640 | 
2026-02-16 17:26:12.264836 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:26:12.312834 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.315119 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.317297 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.320564 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.323646 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.326784 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.329808 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.333206 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.336346 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.339383 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.342374 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.345284 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.348281 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.351327 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.354248 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.357335 | controller | skipping: Conditional result was False
2026-02-16 17:26:12.387712 | 
2026-02-16 17:26:12.387860 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:26:14.729626 | controller | changed
2026-02-16 17:26:14.741389 | 
2026-02-16 17:26:14.741464 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:26:14.983128 | controller | changed
2026-02-16 17:26:15.053151 | 
2026-02-16 17:26:15.053297 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:26:15.299459 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:26:15.595071 | controller | ERROR
2026-02-16 17:26:15.595365 | controller | {
2026-02-16 17:26:15.595395 | controller |   "delta": "0:00:00.011810",
2026-02-16 17:26:15.595416 | controller |   "end": "2026-02-16 17:26:15.299959",
2026-02-16 17:26:15.595435 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:15.595458 | controller |   "rc": 1,
2026-02-16 17:26:15.595482 | controller |   "start": "2026-02-16 17:26:15.288149"
2026-02-16 17:26:15.595500 | controller | }
2026-02-16 17:26:15.595524 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:15.601545 | 
2026-02-16 17:26:15.601624 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:26:15.852628 | controller | ok
2026-02-16 17:26:15.869894 | 
2026-02-16 17:26:15.869979 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:26:16.122157 | controller | /usr/bin/journalctl
2026-02-16 17:26:16.417614 | controller | ok: Runtime: 0:00:00.008044
2026-02-16 17:26:16.425113 | 
2026-02-16 17:26:16.425202 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:26:16.675142 | controller | 2026-02-16 17:26:16
2026-02-16 17:26:16.964869 | controller | ok: Runtime: 0:00:00.009474
2026-02-16 17:26:16.970924 | 
2026-02-16 17:26:16.970992 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:26:17.542317 | controller | changed
2026-02-16 17:26:17.548373 | 
2026-02-16 17:26:17.548439 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:26:17.573928 | controller | skipping: Conditional result was False
2026-02-16 17:26:17.580144 | 
2026-02-16 17:26:17.580209 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:26:18.112295 | controller | skipping: Conditional result was False
2026-02-16 17:26:18.119083 | 
2026-02-16 17:26:18.119153 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:26:18.656536 | controller | skipping: Conditional result was False
2026-02-16 17:26:18.671515 | 
2026-02-16 17:26:18.671583 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:26:18.696593 | controller | skipping: Conditional result was False
2026-02-16 17:26:18.706595 | 
2026-02-16 17:26:18.706687 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:26:19.239895 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.250428 | 
2026-02-16 17:26:19.250520 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:26:19.782179 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.793497 | 
2026-02-16 17:26:19.793567 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:26:19.818317 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.830693 | 
2026-02-16 17:26:19.830759 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:26:19.855773 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.865895 | 
2026-02-16 17:26:19.865988 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:26:20.458076 | controller | skipping: Conditional result was False
2026-02-16 17:26:20.470966 | 
2026-02-16 17:26:20.471039 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:26:21.011332 | controller | skipping: Conditional result was False
2026-02-16 17:26:21.023838 | 
2026-02-16 17:26:21.023914 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:26:21.049998 | controller | skipping: Conditional result was False
2026-02-16 17:26:21.059354 | 
2026-02-16 17:26:21.059529 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:26:21.085019 | controller | skipping: Conditional result was False
2026-02-16 17:26:21.104227 | 
2026-02-16 17:26:21.104299 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:26:21.496417 | controller | ok
2026-02-16 17:26:21.507612 | 
2026-02-16 17:26:21.507666 | PLAY RECAP
2026-02-16 17:26:21.507712 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:26:21.507734 | 
2026-02-16 17:26:21.645359 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:26:21.655063 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:22.306226 | 
2026-02-16 17:26:22.306459 | PLAY [all]
2026-02-16 17:26:22.321212 | 
2026-02-16 17:26:22.321366 | TASK [ensure-rust : Use rustup]
2026-02-16 17:26:22.357857 | controller | ok
2026-02-16 17:26:22.368804 | controller | included: /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:26:22.374558 | 
2026-02-16 17:26:22.374633 | TASK [ensure-rust : Install Rust]
2026-02-16 17:26:22.777641 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:26:22.915579 | controller | ERROR
2026-02-16 17:26:22.915867 | controller | {
2026-02-16 17:26:22.915897 | controller |   "delta": "0:00:00.011498",
2026-02-16 17:26:22.915921 | controller |   "end": "2026-02-16 17:26:22.779681",
2026-02-16 17:26:22.915941 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:22.915959 | controller |   "rc": 127,
2026-02-16 17:26:22.915978 | controller |   "start": "2026-02-16 17:26:22.768183"
2026-02-16 17:26:22.916000 | controller | }
failure
2026-02-16 17:26:22.917908 | 
2026-02-16 17:26:22.917960 | PLAY RECAP
2026-02-16 17:26:22.918011 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:26:22.918033 | 
2026-02-16 17:26:23.036186 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:23.041325 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:23.677605 | 
2026-02-16 17:26:23.677790 | PLAY [all]
2026-02-16 17:26:23.694403 | 
2026-02-16 17:26:23.694491 | TASK [Return built artifacts to Zuul]
2026-02-16 17:26:23.729474 | controller | ok
2026-02-16 17:26:23.737413 | 
2026-02-16 17:26:23.737529 | TASK [Get the Docker volume ID]
2026-02-16 17:26:24.284077 | controller | ERROR
2026-02-16 17:26:24.284622 | controller | {
2026-02-16 17:26:24.284700 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:26:24.284750 | controller |   "rc": 2
2026-02-16 17:26:24.284796 | controller | }
2026-02-16 17:26:24.284854 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:24.291683 | 
2026-02-16 17:26:24.291754 | TASK [Copy all of the pod logs]
2026-02-16 17:26:24.325488 | controller | ERROR
2026-02-16 17:26:24.325820 | controller | {
2026-02-16 17:26:24.325885 | 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/2dcfe1c101994b5baf29ca79675f17f2/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:26:24.325935 | controller | }
2026-02-16 17:26:24.325994 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:24.327634 | 
2026-02-16 17:26:24.327733 | PLAY RECAP
2026-02-16 17:26:24.327836 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:26:24.327884 | 
2026-02-16 17:26:24.463953 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:24.475785 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:26:25.115765 | 
2026-02-16 17:26:25.115911 | PLAY [all]
2026-02-16 17:26:25.135578 | 
2026-02-16 17:26:25.135685 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:26:25.549058 | controller | changed
2026-02-16 17:26:25.566608 | 
2026-02-16 17:26:25.566743 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:26:26.608557 | controller | ok: Runtime: 0:00:00.460292
2026-02-16 17:26:26.615342 | 
2026-02-16 17:26:26.615414 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:26:27.154088 | controller | ok: Runtime: 0:00:00.017347
2026-02-16 17:26:27.160205 | 
2026-02-16 17:26:27.160275 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:26:27.699704 | controller | ok: Runtime: 0:00:00.022748
2026-02-16 17:26:27.707862 | 
2026-02-16 17:26:27.707929 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:26:28.555419 | controller | changed
2026-02-16 17:26:28.616092 | 
2026-02-16 17:26:28.616247 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:26:28.864668 | controller | changed
2026-02-16 17:26:28.870195 | 
2026-02-16 17:26:28.870262 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:26:29.201160 | controller | Output suppressed because no_log was given
2026-02-16 17:26:29.206399 | 
2026-02-16 17:26:29.206467 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:26:29.243681 | 
2026-02-16 17:26:29.243852 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:26:29.280560 | 
2026-02-16 17:26:29.280691 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:26:29.316677 | 
2026-02-16 17:26:29.316805 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:26:29.352285 | 
2026-02-16 17:26:29.352526 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:26:29.390630 | 
2026-02-16 17:26:29.390803 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:26:29.632686 | controller | changed
2026-02-16 17:26:29.638599 | 
2026-02-16 17:26:29.638667 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:26:29.672237 | controller | ok
2026-02-16 17:26:29.679261 | 
2026-02-16 17:26:29.679343 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:26:29.920461 | controller | Output suppressed because no_log was given
2026-02-16 17:26:29.928397 | 
2026-02-16 17:26:29.928457 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:26:29.967394 | 
2026-02-16 17:26:29.967633 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:26:30.009956 | 
2026-02-16 17:26:30.010123 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:26:30.647204 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:26:30.665150 | controller | Using python 3.12 to install setuptools
2026-02-16 17:26:30.704856 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:26:30.714758 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:26:31.051785 | controller | ERROR
2026-02-16 17:26:31.052042 | controller | {
2026-02-16 17:26:31.052116 | controller |   "delta": "0:00:00.467543",
2026-02-16 17:26:31.052209 | controller |   "end": "2026-02-16 17:26:30.717251",
2026-02-16 17:26:31.052260 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:31.052307 | controller |   "rc": 127,
2026-02-16 17:26:31.052350 | controller |   "start": "2026-02-16 17:26:30.249708"
2026-02-16 17:26:31.052392 | controller | }
2026-02-16 17:26:31.052453 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:31.064610 | 
2026-02-16 17:26:31.064686 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:26:31.309937 | controller | changed
2026-02-16 17:26:31.317978 | 
2026-02-16 17:26:31.318067 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:26:34.959190 | controller | Output suppressed because no_log was given
2026-02-16 17:26:34.971119 | 
2026-02-16 17:26:34.971213 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:26:35.004898 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.005387 | 
2026-02-16 17:26:35.007225 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.008827 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.010645 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.013092 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.015363 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.017644 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.020118 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.022557 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.024721 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.027171 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.029405 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.031688 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.033966 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.036217 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.038628 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.040911 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.043961 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.076982 | 
2026-02-16 17:26:35.077143 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:26:35.311769 | controller | ok
2026-02-16 17:26:35.319188 | 
2026-02-16 17:26:35.319277 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:26:35.899120 | controller | skipping: Conditional result was False
2026-02-16 17:26:35.917635 | 
2026-02-16 17:26:35.917754 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:26:36.180561 | controller | sudo: iptables-save: command not found
2026-02-16 17:26:36.224857 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:26:37.331219 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:26:37.458106 | controller | ok: Runtime: 0:00:01.174607
2026-02-16 17:26:37.470826 | 
2026-02-16 17:26:37.470892 | LOOP [stage-output : Register sources]
2026-02-16 17:26:46.658043 | controller | Output suppressed because no_log was given
2026-02-16 17:26:46.719082 | 
2026-02-16 17:26:46.719263 | TASK [stage-output : Check sudo]
2026-02-16 17:26:47.262524 | controller | ok: Runtime: 0:00:00.018558
2026-02-16 17:26:47.268406 | 
2026-02-16 17:26:47.268471 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:26:47.360420 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.361032 | 
2026-02-16 17:26:47.362902 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.363655 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.365180 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.403220 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.403582 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.403907 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.404228 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.404546 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.406107 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.408188 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.410384 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.412534 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.414681 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.434964 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.435728 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.436437 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.437182 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.437885 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.438650 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.439349 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.440254 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.442349 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.477997 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.478400 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.546272 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.556863 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.568005 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.578946 | controller | Output suppressed because no_log was given
2026-02-16 17:26:47.592890 | 
2026-02-16 17:26:47.593011 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:26:47.659890 | controller | ok
2026-02-16 17:26:47.667437 | 
2026-02-16 17:26:47.667507 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:26:47.944567 | controller | changed: "docs"
2026-02-16 17:26:48.158799 | controller | changed: "artifacts"
2026-02-16 17:26:48.381211 | controller | ok: "logs"
2026-02-16 17:26:48.403815 | 
2026-02-16 17:26:48.403896 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:26:48.688207 | controller | ok: Item: Runtime: 0:00:00.010039
2026-02-16 17:26:48.688481 | controller | changed: All items complete
2026-02-16 17:26:48.688509 | 
2026-02-16 17:26:48.919613 | controller | ok: Item: Runtime: 0:00:00.007996
2026-02-16 17:26:49.155076 | controller | ok: Item: Runtime: 0:00:00.008836
2026-02-16 17:26:49.390127 | controller | ok: Item: Runtime: 0:00:00.009797
2026-02-16 17:26:49.617426 | controller | ok: Item: Runtime: 0:00:00.009245
2026-02-16 17:26:49.846337 | controller | ok: Item: Runtime: 0:00:00.008292
2026-02-16 17:26:50.089778 | controller | ok: Item: Runtime: 0:00:00.009695
2026-02-16 17:26:50.322433 | controller | ok: Item: Runtime: 0:00:00.008068
2026-02-16 17:26:50.548176 | controller | ok: Item: Runtime: 0:00:00.009834
2026-02-16 17:26:50.772792 | controller | ok: Item: Runtime: 0:00:00.008590
2026-02-16 17:26:51.001646 | controller | ok: Item: Runtime: 0:00:00.009418
2026-02-16 17:26:51.281872 | controller | ok: Item: Runtime: 0:00:00.009855
2026-02-16 17:26:51.456501 | controller | ok: Item: Runtime: 0:00:00.008473
2026-02-16 17:26:51.681066 | controller | ok: Item: Runtime: 0:00:00.008682
2026-02-16 17:26:51.906705 | controller | ok: Item: Runtime: 0:00:00.009187
2026-02-16 17:26:52.128589 | controller | ok: Item: Runtime: 0:00:00.009414
2026-02-16 17:26:52.156049 | 
2026-02-16 17:26:52.156193 | TASK [stage-output : Make all log files readable]
2026-02-16 17:26:52.399587 | controller | changed
2026-02-16 17:26:52.406778 | 
2026-02-16 17:26:52.406844 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:26:52.738282 | controller | changed: Renamed files for staging.
2026-02-16 17:26:52.745415 | 
2026-02-16 17:26:52.745478 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:26:52.770318 | controller | skipping: Conditional result was False
2026-02-16 17:26:52.783681 | 
2026-02-16 17:26:52.783773 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:26:52.824725 | 
2026-02-16 17:26:52.824846 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:26:53.444103 | controller | changed:
2026-02-16 17:26:53.444297 | controller | created directory /var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/work/logs/controller
2026-02-16 17:26:53.444324 | controller | cd+++++++++ logs/
2026-02-16 17:26:53.444345 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:26:53.444364 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:26:53.444383 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:26:53.444401 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:26:53.444419 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:26:53.444437 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:26:53.444455 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:26:53.444473 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:26:53.444491 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:26:53.444508 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:26:53.444526 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:26:53.444543 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:26:53.444563 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:26:53.444601 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:26:53.444620 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:26:53.444638 | controller | cd+++++++++ logs/apache/
2026-02-16 17:26:53.444656 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:26:53.444673 | controller | cd+++++++++ logs/etc/
2026-02-16 17:26:53.444691 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:26:53.444708 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:26:53.444726 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:26:53.444743 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:26:53.444760 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:26:53.444777 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:26:53.456290 | 
2026-02-16 17:26:53.456357 | TASK [Check if a tempest log exits]
2026-02-16 17:26:53.717661 | controller | ok
2026-02-16 17:26:53.723264 | 
2026-02-16 17:26:53.723364 | TASK [Link post-devstack tempest.log]
2026-02-16 17:26:53.749250 | controller | skipping: Conditional result was False
2026-02-16 17:26:53.761399 | 
2026-02-16 17:26:53.761466 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:26:54.022109 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:26:54.307912 | controller | ERROR
2026-02-16 17:26:54.308238 | controller | {
2026-02-16 17:26:54.308314 | controller |   "delta": "0:00:00.008827",
2026-02-16 17:26:54.308349 | controller |   "end": "2026-02-16 17:26:54.022792",
2026-02-16 17:26:54.308383 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:54.308417 | controller |   "rc": 127,
2026-02-16 17:26:54.308444 | controller |   "start": "2026-02-16 17:26:54.013965"
2026-02-16 17:26:54.308469 | controller | }
2026-02-16 17:26:54.308503 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:54.311074 | 
2026-02-16 17:26:54.311128 | PLAY RECAP
2026-02-16 17:26:54.311178 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:26:54.311201 | 
2026-02-16 17:26:54.481742 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:26:54.488748 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:26:55.080513 | 
2026-02-16 17:26:55.080673 | PLAY [all]
2026-02-16 17:26:55.093866 | 
2026-02-16 17:26:55.093956 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:26:55.139809 | controller | skipping: Conditional result was False
2026-02-16 17:26:55.145682 | 
2026-02-16 17:26:55.145760 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:26:55.185724 | controller | ok
2026-02-16 17:26:55.192103 | 
2026-02-16 17:26:55.192198 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:26:55.618035 | controller -> localhost | ok: "/var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/work/logs"
2026-02-16 17:26:55.839875 | controller -> localhost | changed: "/var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/work/artifacts"
2026-02-16 17:26:56.050909 | controller -> localhost | changed: "/var/lib/zuul/builds/2dcfe1c101994b5baf29ca79675f17f2/work/docs"
2026-02-16 17:26:56.068849 | 
2026-02-16 17:26:56.068985 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:26:56.715107 | controller | changed: .d..t...... ./
2026-02-16 17:26:56.715319 | controller | changed: All items complete
2026-02-16 17:26:56.715346 | 
2026-02-16 17:26:57.199339 | controller | changed: .d..t...... ./
2026-02-16 17:26:57.681908 | controller | changed: .d..t...... ./
2026-02-16 17:26:57.706336 | 
2026-02-16 17:26:57.706501 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:26:58.124247 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008519
2026-02-16 17:26:58.361103 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008062
2026-02-16 17:26:58.382905 | 
2026-02-16 17:26:58.383083 | PLAY [all]
2026-02-16 17:26:58.391209 | 
2026-02-16 17:26:58.391277 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:26:58.887335 | controller | changed
2026-02-16 17:26:58.893917 | 
2026-02-16 17:26:58.894016 | PLAY RECAP
2026-02-16 17:26:58.894087 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:26:58.894119 | 
2026-02-16 17:26:59.033268 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:26:59.038795 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:26:59.653197 | 
2026-02-16 17:26:59.653441 | PLAY [localhost]
2026-02-16 17:26:59.666407 | 
2026-02-16 17:26:59.666569 | TASK [Generate Zuul manifest]
2026-02-16 17:26:59.687746 | localhost | ok
2026-02-16 17:26:59.703543 | 
2026-02-16 17:26:59.703666 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:27:00.070668 | localhost | changed
2026-02-16 17:27:00.084145 | 
2026-02-16 17:27:00.084223 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:27:00.115907 | localhost | ok
2026-02-16 17:27:00.124227 | 
2026-02-16 17:27:00.124293 | TASK [Upload logs]
2026-02-16 17:27:00.146913 | localhost | ok
2026-02-16 17:27:00.207400 | 
2026-02-16 17:27:00.207546 | TASK [Set zuul-log-path fact]
2026-02-16 17:27:00.230065 | localhost | ok
2026-02-16 17:27:00.241298 | 
2026-02-16 17:27:00.241364 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:27:00.272921 | localhost | ok
2026-02-16 17:27:00.281485 | 
2026-02-16 17:27:00.281552 | TASK [upload-logs : Create log directories]
2026-02-16 17:27:00.683834 | localhost | changed
2026-02-16 17:27:00.688770 | 
2026-02-16 17:27:00.688843 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:27:01.139435 | localhost -> localhost | ok: Runtime: 0:00:00.005638
2026-02-16 17:27:01.144265 | 
2026-02-16 17:27:01.144330 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:27:01.563873 | localhost | Output suppressed because no_log was given
2026-02-16 17:27:01.568051 | 
2026-02-16 17:27:01.568146 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:27:01.612554 | localhost | skipping: Conditional result was False
2026-02-16 17:27:01.618522 | localhost | skipping: Conditional result was False
2026-02-16 17:27:01.631514 | 
2026-02-16 17:27:01.631651 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:27:01.674883 | localhost | skipping: Conditional result was False
2026-02-16 17:27:01.675202 | 
2026-02-16 17:27:01.678151 | localhost | skipping: Conditional result was False
2026-02-16 17:27:01.696753 | 
2026-02-16 17:27:01.696967 | LOOP [upload-logs : Upload console log and json output]