2026-02-16 17:20:39.625147 | Job console starting
2026-02-16 17:20:39.638083 | Updating git repos
2026-02-16 17:20:43.746397 | Cloning repos into workspace
2026-02-16 17:20:49.596615 | Restoring repo states
2026-02-16 17:20:52.499627 | Merging changes
2026-02-16 17:20:53.063845 | Checking out repos
2026-02-16 17:20:58.775243 | Preparing playbooks
2026-02-16 17:21:01.189993 | Running Ansible setup
2026-02-16 17:21:05.280278 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:05.887688 | 
2026-02-16 17:21:05.887834 | PLAY [localhost]
2026-02-16 17:21:05.895723 | 
2026-02-16 17:21:05.895794 | TASK [Gathering Facts]
2026-02-16 17:21:06.784489 | localhost | ok
2026-02-16 17:21:06.798195 | 
2026-02-16 17:21:06.798275 | TASK [Setup log path fact]
2026-02-16 17:21:06.828318 | localhost | ok
2026-02-16 17:21:06.839150 | 
2026-02-16 17:21:06.839214 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:21:06.868219 | localhost | ok
2026-02-16 17:21:06.876363 | 
2026-02-16 17:21:06.876454 | TASK [emit-job-header : Print job information]
2026-02-16 17:21:06.919621 | # Job Information
2026-02-16 17:21:06.919837 | Ansible Version: 2.16.15
2026-02-16 17:21:06.919905 | Job: magnum-cluster-api-sonobuoy-v1.32.12-cilium
2026-02-16 17:21:06.919954 | Pipeline: check
2026-02-16 17:21:06.919999 | Executor: 3a2793d2bd32
2026-02-16 17:21:06.920043 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:21:06.920092 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:21:06.923520 | 
2026-02-16 17:21:06.923584 | LOOP [emit-job-header : Print node information]
2026-02-16 17:21:07.023650 | localhost | ok:
2026-02-16 17:21:07.024229 | localhost | # Node Information
2026-02-16 17:21:07.024279 | localhost | Inventory Hostname: controller
2026-02-16 17:21:07.024311 | localhost | Hostname: np0000155585
2026-02-16 17:21:07.024340 | localhost | Username: zuul
2026-02-16 17:21:07.024371 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:21:07.024399 | localhost | Provider: yul1
2026-02-16 17:21:07.024426 | localhost | Region: ca-ymq-1
2026-02-16 17:21:07.024453 | localhost | Label: ubuntu-noble-16
2026-02-16 17:21:07.024480 | localhost | Product Name: OpenStack Nova
2026-02-16 17:21:07.024509 | localhost | Interface IP: 199.204.45.169
2026-02-16 17:21:07.042167 | 
2026-02-16 17:21:07.042270 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:21:07.514610 | localhost -> localhost | changed
2026-02-16 17:21:07.522481 | 
2026-02-16 17:21:07.522611 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:21:08.395596 | localhost -> localhost | changed
2026-02-16 17:21:08.409236 | 
2026-02-16 17:21:08.409298 | PLAY [all]
2026-02-16 17:21:08.419614 | 
2026-02-16 17:21:08.419685 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:21:08.660211 | controller -> localhost | ok
2026-02-16 17:21:08.670341 | 
2026-02-16 17:21:08.670436 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:21:08.706502 | controller | ok
2026-02-16 17:21:08.727762 | controller | included: /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:21:08.734794 | 
2026-02-16 17:21:08.734859 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:21:09.848893 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:21:09.932616 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/work/47fb0c12c6014195a132b28aad339bf7_id_rsa
2026-02-16 17:21:09.932699 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/work/47fb0c12c6014195a132b28aad339bf7_id_rsa.pub
2026-02-16 17:21:09.932744 | controller -> localhost | The key fingerprint is:
2026-02-16 17:21:09.932777 | controller -> localhost | SHA256:XQ4svEbmOtnM5NNAB9HlJJWCPyUF5c+kAqblPSkIdq4 zuul-build-sshkey
2026-02-16 17:21:09.932825 | controller -> localhost | The key's randomart image is:
2026-02-16 17:21:09.932856 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:21:09.932888 | controller -> localhost | |        o+o**.   |
2026-02-16 17:21:09.932919 | controller -> localhost | |       ..o+=o    |
2026-02-16 17:21:09.932948 | controller -> localhost | |    o . X.+++ .  |
2026-02-16 17:21:09.933008 | controller -> localhost | |   . + @ Bo= =   |
2026-02-16 17:21:09.933038 | controller -> localhost | |      + S *.o o  |
2026-02-16 17:21:09.933068 | controller -> localhost | |     . X + o     |
2026-02-16 17:21:09.933095 | controller -> localhost | |    E + * .      |
2026-02-16 17:21:09.933128 | controller -> localhost | |       . .       |
2026-02-16 17:21:09.933161 | controller -> localhost | |                 |
2026-02-16 17:21:09.933191 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:21:09.933267 | controller -> localhost | ok: Runtime: 0:00:00.707641
2026-02-16 17:21:09.942152 | 
2026-02-16 17:21:09.942245 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:21:09.979375 | controller | ok
2026-02-16 17:21:09.990617 | controller | included: /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:21:09.998659 | 
2026-02-16 17:21:09.998720 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:21:10.023138 | controller | skipping: Conditional result was False
2026-02-16 17:21:10.033393 | 
2026-02-16 17:21:10.033483 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:21:10.588266 | controller | changed
2026-02-16 17:21:10.595581 | 
2026-02-16 17:21:10.595656 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:21:10.834884 | controller | ok
2026-02-16 17:21:10.844127 | 
2026-02-16 17:21:10.844270 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:21:11.426034 | controller | changed
2026-02-16 17:21:11.434678 | 
2026-02-16 17:21:11.434750 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:21:11.994899 | controller | changed
2026-02-16 17:21:12.001589 | 
2026-02-16 17:21:12.001660 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:21:12.026959 | controller | skipping: Conditional result was False
2026-02-16 17:21:12.037768 | 
2026-02-16 17:21:12.037837 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:21:12.395390 | controller -> localhost | changed
2026-02-16 17:21:12.425371 | 
2026-02-16 17:21:12.425465 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:21:12.818571 | controller -> localhost | Identity added: /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/work/47fb0c12c6014195a132b28aad339bf7_id_rsa (zuul-build-sshkey)
2026-02-16 17:21:12.819062 | controller -> localhost | ok: Runtime: 0:00:00.015640
2026-02-16 17:21:12.896153 | 
2026-02-16 17:21:12.896313 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:21:13.248122 | controller | ok
2026-02-16 17:21:13.256964 | 
2026-02-16 17:21:13.257051 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:21:13.284065 | controller | skipping: Conditional result was False
2026-02-16 17:21:13.302701 | 
2026-02-16 17:21:13.302774 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:21:13.628402 | controller | ok
2026-02-16 17:21:13.636806 | 
2026-02-16 17:21:13.636873 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:21:46.173546 | controller | Output suppressed because no_log was given
2026-02-16 17:21:46.185514 | 
2026-02-16 17:21:46.185636 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:21:46.448486 | controller | ok: "logs"
2026-02-16 17:21:46.448757 | controller | ok: All items complete
2026-02-16 17:21:46.448787 | 
2026-02-16 17:21:46.616060 | controller | ok: "artifacts"
2026-02-16 17:21:46.800571 | controller | ok: "docs"
2026-02-16 17:21:46.817185 | 
2026-02-16 17:21:46.817344 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:21:47.046951 | controller | changed: "logs"
2026-02-16 17:21:47.255094 | controller | changed: "artifacts"
2026-02-16 17:21:47.522463 | controller | changed: "docs"
2026-02-16 17:21:47.538995 | 
2026-02-16 17:21:47.539134 | PLAY RECAP
2026-02-16 17:21:47.539195 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:21:47.539235 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:21:47.539262 | 
2026-02-16 17:21:47.737142 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:47.746104 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:21:48.400871 | 
2026-02-16 17:21:48.401037 | PLAY [all]
2026-02-16 17:21:48.420105 | 
2026-02-16 17:21:48.420216 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:21:48.807419 | controller | changed
2026-02-16 17:21:48.816401 | 
2026-02-16 17:21:48.816485 | TASK [Gather minimum local MTU]
2026-02-16 17:21:48.897539 | controller | ok
2026-02-16 17:21:48.903547 | 
2026-02-16 17:21:48.903621 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:21:48.959181 | controller | ok
2026-02-16 17:21:48.964602 | 
2026-02-16 17:21:48.964675 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:21:48.990133 | controller | skipping: Conditional result was False
2026-02-16 17:21:49.002121 | 
2026-02-16 17:21:49.002226 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:21:49.541978 | controller | ok: Runtime: 0:00:00.012136
2026-02-16 17:21:49.550157 | 
2026-02-16 17:21:49.550247 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:21:49.576203 | controller | skipping: Conditional result was False
2026-02-16 17:21:49.586408 | 
2026-02-16 17:21:49.586531 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:21:49.612846 | controller | skipping: Conditional result was False
2026-02-16 17:21:49.623397 | 
2026-02-16 17:21:49.623494 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:21:49.680249 | controller | ok
2026-02-16 17:21:49.691302 | controller | included: /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:21:49.697487 | 
2026-02-16 17:21:49.697552 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:21:49.753203 | controller | ok
2026-02-16 17:21:49.760624 | 
2026-02-16 17:21:49.760690 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:21:50.046533 | controller | ext4
2026-02-16 17:21:50.334208 | controller | ok: Runtime: 0:00:00.011725
2026-02-16 17:21:50.339892 | 
2026-02-16 17:21:50.339976 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:21:50.373061 | controller | ok
2026-02-16 17:21:50.379468 | 
2026-02-16 17:21:50.379530 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:21:50.415574 | controller | ok:
2026-02-16 17:21:50.415807 | controller | {
2026-02-16 17:21:50.415843 | controller |   "root_filesystem": "ext4"
2026-02-16 17:21:50.415871 | controller | }
2026-02-16 17:21:50.423908 | 
2026-02-16 17:21:50.423976 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:22:00.016539 | controller | 8192+0 records in
2026-02-16 17:22:00.016696 | controller | 8192+0 records out
2026-02-16 17:22:00.016717 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.31415 s, 922 MB/s
2026-02-16 17:22:00.472356 | controller | ok: Runtime: 0:00:09.325556
2026-02-16 17:22:00.479836 | 
2026-02-16 17:22:00.479920 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:22:00.737157 | controller | changed
2026-02-16 17:22:00.743361 | 
2026-02-16 17:22:00.743424 | TASK [configure-swap : Make swapfile]
2026-02-16 17:22:38.865300 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:22:38.865500 | controller | no label, UUID=ca4cb706-c54c-42f4-9453-b1dd457a1f90
2026-02-16 17:22:39.323157 | controller | ok: Runtime: 0:00:37.883035
2026-02-16 17:22:39.329382 | 
2026-02-16 17:22:39.329463 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:22:39.720210 | controller | changed
2026-02-16 17:22:39.726441 | 
2026-02-16 17:22:39.726513 | TASK [configure-swap : Add all swap]
2026-02-16 17:22:40.267618 | controller | ok: Runtime: 0:00:00.038015
2026-02-16 17:22:40.273178 | 
2026-02-16 17:22:40.273246 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:22:40.313623 | controller | ok:
2026-02-16 17:22:40.313804 | controller | {
2026-02-16 17:22:40.313829 | controller |   "swap_required": "8192"
2026-02-16 17:22:40.313848 | controller | }
2026-02-16 17:22:40.319235 | 
2026-02-16 17:22:40.319305 | TASK [configure-swap : Set swappiness]
2026-02-16 17:22:41.755232 | controller | changed
2026-02-16 17:22:41.764075 | 
2026-02-16 17:22:41.764142 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:22:41.800223 | controller | ok:
2026-02-16 17:22:41.800348 | controller | {
2026-02-16 17:22:41.800373 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:22:41.800398 | controller | }
2026-02-16 17:22:41.810210 | 
2026-02-16 17:22:41.810271 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:22:42.225502 | controller | changed
2026-02-16 17:22:42.233761 | 
2026-02-16 17:22:42.233831 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:22:42.483401 | controller | changed
2026-02-16 17:22:42.490214 | 
2026-02-16 17:22:42.490282 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:22:43.029481 | controller | changed
2026-02-16 17:22:43.037486 | 
2026-02-16 17:22:43.037613 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:22:43.304133 | controller | changed
2026-02-16 17:22:43.311800 | 
2026-02-16 17:22:43.311870 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:22:44.453153 | controller | changed
2026-02-16 17:22:44.459073 | 
2026-02-16 17:22:44.459141 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:22:44.714302 | controller | changed
2026-02-16 17:22:44.727389 | 
2026-02-16 17:22:44.727460 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:22:45.027371 | controller | changed
2026-02-16 17:22:45.032880 | 
2026-02-16 17:22:45.032948 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:22:45.420525 | controller | changed
2026-02-16 17:22:45.497019 | 
2026-02-16 17:22:45.497207 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:22:46.287624 | controller | changed
2026-02-16 17:22:46.300738 | 
2026-02-16 17:22:46.300813 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:22:46.610957 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:22:46.623387 | 
2026-02-16 17:22:46.623455 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:22:47.101836 | controller | ok: Item: Runtime: 0:00:00.188150
2026-02-16 17:22:47.686845 | controller | ok: Item: Runtime: 0:00:00.340533
2026-02-16 17:22:48.423543 | controller | ok: Item: Runtime: 0:00:00.530415
2026-02-16 17:22:48.700870 | controller | ok: Item: Runtime: 0:00:00.063131
2026-02-16 17:22:49.193655 | controller | ok: Item: Runtime: 0:00:00.265605
2026-02-16 17:22:50.707405 | controller | ok: Item: Runtime: 0:00:01.279688
2026-02-16 17:22:53.979393 | controller | ok: Item: Runtime: 0:00:03.040291
2026-02-16 17:22:54.299052 | controller | ok: Item: Runtime: 0:00:00.099128
2026-02-16 17:22:54.715106 | controller | ok: Item: Runtime: 0:00:00.197678
2026-02-16 17:22:55.044792 | controller | ok: Item: Runtime: 0:00:00.123283
2026-02-16 17:22:55.800626 | controller | ok: Item: Runtime: 0:00:00.540645
2026-02-16 17:22:56.530904 | controller | ok: Item: Runtime: 0:00:00.516746
2026-02-16 17:22:56.980157 | controller | ok: Item: Runtime: 0:00:00.221212
2026-02-16 17:22:57.438697 | controller | ok: Item: Runtime: 0:00:00.224261
2026-02-16 17:22:58.016914 | controller | ok: Item: Runtime: 0:00:00.364557
2026-02-16 17:22:59.357594 | controller | ok: Item: Runtime: 0:00:01.119113
2026-02-16 17:22:59.388757 | 
2026-02-16 17:22:59.388933 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:22:59.615163 | controller | ok: All paths examined
2026-02-16 17:22:59.630668 | 
2026-02-16 17:22:59.630843 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:22:59.870047 | controller | ok: All paths examined
2026-02-16 17:22:59.877299 | 
2026-02-16 17:22:59.877372 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:23:00.309770 | controller | ok: Item: Runtime: 0:00:00.146684
2026-02-16 17:23:00.310361 | controller | changed: All items complete
2026-02-16 17:23:00.310395 | 
2026-02-16 17:23:00.720232 | controller | ok: Item: Runtime: 0:00:00.183400
2026-02-16 17:23:00.735609 | 
2026-02-16 17:23:00.735686 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:23:00.776098 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.778295 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.780772 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.783923 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.787745 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.790173 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.793381 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.796338 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.799566 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.802625 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.805605 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.808541 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.811563 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.814583 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.817594 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.821029 | controller | skipping: Conditional result was False
2026-02-16 17:23:00.852562 | 
2026-02-16 17:23:00.852656 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:23:03.150180 | controller | changed
2026-02-16 17:23:03.161662 | 
2026-02-16 17:23:03.161783 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:23:03.401203 | controller | changed
2026-02-16 17:23:03.456077 | 
2026-02-16 17:23:03.456214 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:23:03.699806 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:23:03.991138 | controller | ERROR
2026-02-16 17:23:03.991335 | controller | {
2026-02-16 17:23:03.991364 | controller |   "delta": "0:00:00.008994",
2026-02-16 17:23:03.991384 | controller |   "end": "2026-02-16 17:23:03.700435",
2026-02-16 17:23:03.991403 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:03.991421 | controller |   "rc": 1,
2026-02-16 17:23:03.991438 | controller |   "start": "2026-02-16 17:23:03.691441"
2026-02-16 17:23:03.991456 | controller | }
2026-02-16 17:23:03.991478 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:03.997358 | 
2026-02-16 17:23:03.997424 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:23:04.235361 | controller | ok
2026-02-16 17:23:04.246612 | 
2026-02-16 17:23:04.246675 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:23:04.481547 | controller | /usr/bin/journalctl
2026-02-16 17:23:04.787967 | controller | ok: Runtime: 0:00:00.007847
2026-02-16 17:23:04.793890 | 
2026-02-16 17:23:04.793956 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:23:05.040524 | controller | 2026-02-16 17:23:05
2026-02-16 17:23:05.334787 | controller | ok: Runtime: 0:00:00.008145
2026-02-16 17:23:05.342644 | 
2026-02-16 17:23:05.342734 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:23:05.901222 | controller | changed
2026-02-16 17:23:05.908191 | 
2026-02-16 17:23:05.908261 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:23:05.934515 | controller | skipping: Conditional result was False
2026-02-16 17:23:05.944873 | 
2026-02-16 17:23:05.945015 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:23:06.479783 | controller | skipping: Conditional result was False
2026-02-16 17:23:06.493585 | 
2026-02-16 17:23:06.493732 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:23:07.025583 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.037912 | 
2026-02-16 17:23:07.037984 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:23:07.063349 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.073985 | 
2026-02-16 17:23:07.074098 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:23:07.608227 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.618865 | 
2026-02-16 17:23:07.618938 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:23:08.150288 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.160162 | 
2026-02-16 17:23:08.160258 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:23:08.186203 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.197709 | 
2026-02-16 17:23:08.197816 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:23:08.224587 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.239089 | 
2026-02-16 17:23:08.239316 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:23:08.833124 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.843997 | 
2026-02-16 17:23:08.844094 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:23:09.377193 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.388579 | 
2026-02-16 17:23:09.388655 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:23:09.426799 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.435670 | 
2026-02-16 17:23:09.435781 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:23:09.461365 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.479123 | 
2026-02-16 17:23:09.479214 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:23:09.871718 | controller | ok
2026-02-16 17:23:09.883842 | 
2026-02-16 17:23:09.883966 | PLAY RECAP
2026-02-16 17:23:09.884021 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:23:09.884048 | 
2026-02-16 17:23:10.033895 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:23:10.038949 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:10.664850 | 
2026-02-16 17:23:10.665057 | PLAY [all]
2026-02-16 17:23:10.678130 | 
2026-02-16 17:23:10.678215 | TASK [ensure-rust : Use rustup]
2026-02-16 17:23:10.713414 | controller | ok
2026-02-16 17:23:10.720579 | controller | included: /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:23:10.726253 | 
2026-02-16 17:23:10.726331 | TASK [ensure-rust : Install Rust]
2026-02-16 17:23:11.119699 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:23:11.265992 | controller | ERROR
2026-02-16 17:23:11.266244 | controller | {
2026-02-16 17:23:11.266287 | controller |   "delta": "0:00:00.007514",
2026-02-16 17:23:11.266319 | controller |   "end": "2026-02-16 17:23:11.122126",
2026-02-16 17:23:11.266347 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:11.266374 | controller |   "rc": 127,
2026-02-16 17:23:11.266399 | controller |   "start": "2026-02-16 17:23:11.114612"
2026-02-16 17:23:11.266431 | controller | }
failure
2026-02-16 17:23:11.268287 | 
2026-02-16 17:23:11.268364 | PLAY RECAP
2026-02-16 17:23:11.268423 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:23:11.268455 | 
2026-02-16 17:23:11.392610 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:11.402082 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:11.972339 | 
2026-02-16 17:23:11.972478 | PLAY [all]
2026-02-16 17:23:11.989665 | 
2026-02-16 17:23:11.989750 | TASK [Return built artifacts to Zuul]
2026-02-16 17:23:12.024867 | controller | ok
2026-02-16 17:23:12.030146 | 
2026-02-16 17:23:12.030217 | TASK [Get the Docker volume ID]
2026-02-16 17:23:12.569920 | controller | ERROR
2026-02-16 17:23:12.570419 | controller | {
2026-02-16 17:23:12.570489 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:23:12.570540 | controller |   "rc": 2
2026-02-16 17:23:12.570584 | controller | }
2026-02-16 17:23:12.570641 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:12.579069 | 
2026-02-16 17:23:12.579196 | TASK [Copy all of the pod logs]
2026-02-16 17:23:12.613850 | controller | ERROR
2026-02-16 17:23:12.614139 | controller | {
2026-02-16 17:23:12.614180 | 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/47fb0c12c6014195a132b28aad339bf7/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml': line 21, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-02-16 17:23:12.614212 | controller | }
2026-02-16 17:23:12.614250 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:12.615459 | 
2026-02-16 17:23:12.615518 | PLAY RECAP
2026-02-16 17:23:12.615575 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:23:12.615604 | 
2026-02-16 17:23:12.765186 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:12.770102 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:13.389614 | 
2026-02-16 17:23:13.389812 | PLAY [all]
2026-02-16 17:23:13.410751 | 
2026-02-16 17:23:13.410839 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:23:13.800268 | controller | changed
2026-02-16 17:23:13.807015 | 
2026-02-16 17:23:13.807093 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:23:14.845303 | controller | ok: Runtime: 0:00:00.490337
2026-02-16 17:23:14.853013 | 
2026-02-16 17:23:14.853115 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:23:15.390654 | controller | ok: Runtime: 0:00:00.016938
2026-02-16 17:23:15.396584 | 
2026-02-16 17:23:15.396657 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:23:15.932161 | controller | ok: Runtime: 0:00:00.019015
2026-02-16 17:23:15.938330 | 
2026-02-16 17:23:15.938401 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:23:16.821879 | controller | changed
2026-02-16 17:23:16.869683 | 
2026-02-16 17:23:16.869835 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:23:17.118033 | controller | changed
2026-02-16 17:23:17.125836 | 
2026-02-16 17:23:17.125926 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:17.474301 | controller | Output suppressed because no_log was given
2026-02-16 17:23:17.481716 | 
2026-02-16 17:23:17.481809 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:17.507357 | 
2026-02-16 17:23:17.507533 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:17.533548 | 
2026-02-16 17:23:17.533788 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:17.571403 | 
2026-02-16 17:23:17.571573 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:17.607163 | 
2026-02-16 17:23:17.607299 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:17.644763 | 
2026-02-16 17:23:17.644897 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:23:17.909501 | controller | changed
2026-02-16 17:23:17.915985 | 
2026-02-16 17:23:17.916055 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:23:17.950724 | controller | ok
2026-02-16 17:23:17.958049 | 
2026-02-16 17:23:17.958139 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:23:18.198765 | controller | Output suppressed because no_log was given
2026-02-16 17:23:18.205180 | 
2026-02-16 17:23:18.205251 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:23:18.242204 | 
2026-02-16 17:23:18.242380 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:23:18.272916 | 
2026-02-16 17:23:18.273110 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:23:18.929757 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:23:18.949196 | controller | Using python 3.12 to install setuptools
2026-02-16 17:23:19.002996 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:23:19.014248 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:23:19.309626 | controller | ERROR
2026-02-16 17:23:19.309754 | controller | {
2026-02-16 17:23:19.309787 | controller |   "delta": "0:00:00.495084",
2026-02-16 17:23:19.309808 | controller |   "end": "2026-02-16 17:23:19.016770",
2026-02-16 17:23:19.309827 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:19.309851 | controller |   "rc": 127,
2026-02-16 17:23:19.309869 | controller |   "start": "2026-02-16 17:23:18.521686"
2026-02-16 17:23:19.309886 | controller | }
2026-02-16 17:23:19.309911 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:19.321354 | 
2026-02-16 17:23:19.321426 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:23:19.568127 | controller | changed
2026-02-16 17:23:19.573586 | 
2026-02-16 17:23:19.573650 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:23:23.310074 | controller | Output suppressed because no_log was given
2026-02-16 17:23:23.322390 | 
2026-02-16 17:23:23.322488 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:23.353202 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.356574 | 
2026-02-16 17:23:23.357973 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.359005 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.360013 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.361377 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.363862 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.366218 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.368689 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.371059 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.373407 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.375910 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.378286 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.380573 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.383221 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.385317 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.387594 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.389884 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.393634 | controller | skipping: Conditional result was False
2026-02-16 17:23:23.421673 | 
2026-02-16 17:23:23.421849 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:23:23.658832 | controller | ok
2026-02-16 17:23:23.666455 | 
2026-02-16 17:23:23.666553 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:24.239984 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.255713 | 
2026-02-16 17:23:24.255799 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:23:24.520463 | controller | sudo: iptables-save: command not found
2026-02-16 17:23:24.561026 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:23:25.909660 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:23:26.297654 | controller | ok: Runtime: 0:00:01.411905
2026-02-16 17:23:26.307752 | 
2026-02-16 17:23:26.307821 | LOOP [stage-output : Register sources]
2026-02-16 17:23:35.869089 | controller | Output suppressed because no_log was given
2026-02-16 17:23:35.942468 | 
2026-02-16 17:23:35.942623 | TASK [stage-output : Check sudo]
2026-02-16 17:23:36.480999 | controller | ok: Runtime: 0:00:00.019703
2026-02-16 17:23:36.488844 | 
2026-02-16 17:23:36.488945 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:23:36.584364 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.584681 | 
2026-02-16 17:23:36.591801 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.595371 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.599186 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.635453 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.635826 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.636316 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.638835 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.641211 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.643311 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.645590 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.648306 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.650123 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.652501 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.668305 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.668778 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.669268 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.670176 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.672286 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.674686 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.676771 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.679101 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.681248 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.705112 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.705533 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.781578 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.792092 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.803579 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.814409 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.827342 | 
2026-02-16 17:23:36.827438 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:23:36.896818 | controller | ok
2026-02-16 17:23:36.906192 | 
2026-02-16 17:23:36.906267 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:23:37.152785 | controller | changed: "docs"
2026-02-16 17:23:37.360047 | controller | changed: "artifacts"
2026-02-16 17:23:37.577144 | controller | ok: "logs"
2026-02-16 17:23:37.599027 | 
2026-02-16 17:23:37.599276 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:23:37.879717 | controller | ok: Item: Runtime: 0:00:00.008576
2026-02-16 17:23:37.879952 | controller | changed: All items complete
2026-02-16 17:23:37.879979 | 
2026-02-16 17:23:38.099332 | controller | ok: Item: Runtime: 0:00:00.007296
2026-02-16 17:23:38.315118 | controller | ok: Item: Runtime: 0:00:00.007275
2026-02-16 17:23:38.532240 | controller | ok: Item: Runtime: 0:00:00.006735
2026-02-16 17:23:38.750808 | controller | ok: Item: Runtime: 0:00:00.007041
2026-02-16 17:23:38.974286 | controller | ok: Item: Runtime: 0:00:00.008458
2026-02-16 17:23:39.195854 | controller | ok: Item: Runtime: 0:00:00.007806
2026-02-16 17:23:39.417105 | controller | ok: Item: Runtime: 0:00:00.004172
2026-02-16 17:23:39.637884 | controller | ok: Item: Runtime: 0:00:00.007230
2026-02-16 17:23:39.869091 | controller | ok: Item: Runtime: 0:00:00.004823
2026-02-16 17:23:40.102965 | controller | ok: Item: Runtime: 0:00:00.008136
2026-02-16 17:23:40.375488 | controller | ok: Item: Runtime: 0:00:00.004191
2026-02-16 17:23:40.534085 | controller | ok: Item: Runtime: 0:00:00.006756
2026-02-16 17:23:40.754918 | controller | ok: Item: Runtime: 0:00:00.006828
2026-02-16 17:23:40.983121 | controller | ok: Item: Runtime: 0:00:00.008160
2026-02-16 17:23:41.211469 | controller | ok: Item: Runtime: 0:00:00.006342
2026-02-16 17:23:41.232818 | 
2026-02-16 17:23:41.233029 | TASK [stage-output : Make all log files readable]
2026-02-16 17:23:41.482350 | controller | changed
2026-02-16 17:23:41.490659 | 
2026-02-16 17:23:41.490751 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:23:41.835937 | controller | changed: Renamed files for staging.
2026-02-16 17:23:41.841609 | 
2026-02-16 17:23:41.841677 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:23:41.867223 | controller | skipping: Conditional result was False
2026-02-16 17:23:41.882899 | 
2026-02-16 17:23:41.883043 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:23:41.929932 | 
2026-02-16 17:23:41.930170 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:23:42.572261 | controller | changed:
2026-02-16 17:23:42.572464 | controller | created directory /var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/work/logs/controller
2026-02-16 17:23:42.572503 | controller | cd+++++++++ logs/
2026-02-16 17:23:42.572533 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:23:42.572565 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:23:42.572591 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:23:42.572617 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:23:42.572642 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:23:42.572667 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:23:42.572691 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:23:42.572715 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:23:42.572739 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:23:42.572764 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:23:42.572789 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:23:42.572817 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:23:42.572849 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:23:42.572895 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:23:42.572921 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:23:42.572946 | controller | cd+++++++++ logs/apache/
2026-02-16 17:23:42.573023 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:23:42.573049 | controller | cd+++++++++ logs/etc/
2026-02-16 17:23:42.573074 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:23:42.573098 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:23:42.573123 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:23:42.573148 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:23:42.573172 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:23:42.573197 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:23:42.586704 | 
2026-02-16 17:23:42.586772 | TASK [Check if a tempest log exits]
2026-02-16 17:23:42.831396 | controller | ok
2026-02-16 17:23:42.836905 | 
2026-02-16 17:23:42.836996 | TASK [Link post-devstack tempest.log]
2026-02-16 17:23:42.867001 | controller | skipping: Conditional result was False
2026-02-16 17:23:42.874245 | 
2026-02-16 17:23:42.874330 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:23:43.121016 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:23:43.414024 | controller | ERROR
2026-02-16 17:23:43.414308 | controller | {
2026-02-16 17:23:43.414358 | controller |   "delta": "0:00:00.007009",
2026-02-16 17:23:43.414388 | controller |   "end": "2026-02-16 17:23:43.121672",
2026-02-16 17:23:43.414415 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:43.414442 | controller |   "rc": 127,
2026-02-16 17:23:43.414468 | controller |   "start": "2026-02-16 17:23:43.114663"
2026-02-16 17:23:43.414494 | controller | }
2026-02-16 17:23:43.414532 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:43.416802 | 
2026-02-16 17:23:43.416857 | PLAY RECAP
2026-02-16 17:23:43.416912 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:23:43.416936 | 
2026-02-16 17:23:43.591228 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:43.603385 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:44.256094 | 
2026-02-16 17:23:44.256800 | PLAY [all]
2026-02-16 17:23:44.268766 | 
2026-02-16 17:23:44.268840 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:23:44.314284 | controller | skipping: Conditional result was False
2026-02-16 17:23:44.325152 | 
2026-02-16 17:23:44.325232 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:23:44.371688 | controller | ok
2026-02-16 17:23:44.378032 | 
2026-02-16 17:23:44.378098 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:23:44.779386 | controller -> localhost | ok: "/var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/work/logs"
2026-02-16 17:23:44.989576 | controller -> localhost | changed: "/var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/work/artifacts"
2026-02-16 17:23:45.199915 | controller -> localhost | changed: "/var/lib/zuul/builds/47fb0c12c6014195a132b28aad339bf7/work/docs"
2026-02-16 17:23:45.215435 | 
2026-02-16 17:23:45.215591 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:23:45.870162 | controller | changed: .d..t...... ./
2026-02-16 17:23:45.870427 | controller | changed: All items complete
2026-02-16 17:23:45.870468 | 
2026-02-16 17:23:46.360893 | controller | changed: .d..t...... ./
2026-02-16 17:23:46.848312 | controller | changed: .d..t...... ./
2026-02-16 17:23:46.875706 | 
2026-02-16 17:23:46.875859 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:23:47.312855 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008679
2026-02-16 17:23:47.533353 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007325
2026-02-16 17:23:47.547261 | 
2026-02-16 17:23:47.547420 | PLAY [all]
2026-02-16 17:23:47.555778 | 
2026-02-16 17:23:47.555848 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:23:48.023094 | controller | changed
2026-02-16 17:23:48.029997 | 
2026-02-16 17:23:48.030051 | PLAY RECAP
2026-02-16 17:23:48.030095 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:23:48.030117 | 
2026-02-16 17:23:48.166218 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:48.180828 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:23:48.823829 | 
2026-02-16 17:23:48.823965 | PLAY [localhost]
2026-02-16 17:23:48.834412 | 
2026-02-16 17:23:48.834488 | TASK [Generate Zuul manifest]
2026-02-16 17:23:48.854097 | localhost | ok
2026-02-16 17:23:48.879339 | 
2026-02-16 17:23:48.879415 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:23:49.313344 | localhost | changed
2026-02-16 17:23:49.328407 | 
2026-02-16 17:23:49.328490 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:23:49.360144 | localhost | ok
2026-02-16 17:23:49.369145 | 
2026-02-16 17:23:49.369233 | TASK [Upload logs]
2026-02-16 17:23:49.391527 | localhost | ok
2026-02-16 17:23:49.451908 | 
2026-02-16 17:23:49.452030 | TASK [Set zuul-log-path fact]
2026-02-16 17:23:49.475489 | localhost | ok
2026-02-16 17:23:49.486656 | 
2026-02-16 17:23:49.486727 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:23:49.518248 | localhost | ok
2026-02-16 17:23:49.526904 | 
2026-02-16 17:23:49.526995 | TASK [upload-logs : Create log directories]
2026-02-16 17:23:49.911331 | localhost | changed
2026-02-16 17:23:49.917833 | 
2026-02-16 17:23:49.917929 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:23:50.380557 | localhost -> localhost | ok: Runtime: 0:00:00.005729
2026-02-16 17:23:50.385397 | 
2026-02-16 17:23:50.385461 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:23:50.809726 | localhost | Output suppressed because no_log was given
2026-02-16 17:23:50.815069 | 
2026-02-16 17:23:50.815162 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:23:50.859849 | localhost | skipping: Conditional result was False
2026-02-16 17:23:50.865883 | localhost | skipping: Conditional result was False
2026-02-16 17:23:50.877713 | 
2026-02-16 17:23:50.877909 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:23:50.919971 | localhost | skipping: Conditional result was False
2026-02-16 17:23:50.920339 | 
2026-02-16 17:23:50.923331 | localhost | skipping: Conditional result was False
2026-02-16 17:23:50.931420 | 
2026-02-16 17:23:50.931597 | LOOP [upload-logs : Upload console log and json output]