2026-02-16 17:25:16.951645 | Job console starting
2026-02-16 17:25:17.015047 | Updating git repos
2026-02-16 17:25:18.496146 | Cloning repos into workspace
2026-02-16 17:25:34.329041 | Restoring repo states
2026-02-16 17:25:37.094935 | Merging changes
2026-02-16 17:25:37.957821 | Checking out repos
2026-02-16 17:25:46.471350 | Preparing playbooks
2026-02-16 17:25:49.602325 | Running Ansible setup
2026-02-16 17:25:53.488153 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:25:54.104925 | 
2026-02-16 17:25:54.105105 | PLAY [localhost]
2026-02-16 17:25:54.113211 | 
2026-02-16 17:25:54.113315 | TASK [Gathering Facts]
2026-02-16 17:25:54.985719 | localhost | ok
2026-02-16 17:25:54.994951 | 
2026-02-16 17:25:54.995094 | TASK [Setup log path fact]
2026-02-16 17:25:55.015083 | localhost | ok
2026-02-16 17:25:55.026442 | 
2026-02-16 17:25:55.026580 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:25:55.056568 | localhost | ok
2026-02-16 17:25:55.064866 | 
2026-02-16 17:25:55.064952 | TASK [emit-job-header : Print job information]
2026-02-16 17:25:55.108098 | # Job Information
2026-02-16 17:25:55.108289 | Ansible Version: 2.16.15
2026-02-16 17:25:55.108334 | Job: magnum-cluster-api-sonobuoy-v1.33.8-cilium
2026-02-16 17:25:55.108365 | Pipeline: check
2026-02-16 17:25:55.108393 | Executor: 3a2793d2bd32
2026-02-16 17:25:55.108420 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:25:55.108451 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:25:55.112346 | 
2026-02-16 17:25:55.112435 | LOOP [emit-job-header : Print node information]
2026-02-16 17:25:55.212755 | localhost | ok:
2026-02-16 17:25:55.213110 | localhost | # Node Information
2026-02-16 17:25:55.213176 | localhost | Inventory Hostname: controller
2026-02-16 17:25:55.213223 | localhost | Hostname: np0000155595
2026-02-16 17:25:55.213267 | localhost | Username: zuul
2026-02-16 17:25:55.213316 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:25:55.213359 | localhost | Provider: yul1
2026-02-16 17:25:55.213402 | localhost | Region: ca-ymq-1
2026-02-16 17:25:55.213443 | localhost | Label: ubuntu-noble-16
2026-02-16 17:25:55.213483 | localhost | Product Name: OpenStack Nova
2026-02-16 17:25:55.213523 | localhost | Interface IP: 199.204.45.244
2026-02-16 17:25:55.230067 | 
2026-02-16 17:25:55.230193 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:25:55.631766 | localhost -> localhost | changed
2026-02-16 17:25:55.640905 | 
2026-02-16 17:25:55.641029 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:25:56.468138 | localhost -> localhost | changed
2026-02-16 17:25:56.480252 | 
2026-02-16 17:25:56.480364 | PLAY [all]
2026-02-16 17:25:56.490631 | 
2026-02-16 17:25:56.490709 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:25:56.710791 | controller -> localhost | ok
2026-02-16 17:25:56.720996 | 
2026-02-16 17:25:56.721100 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:25:56.754246 | controller | ok
2026-02-16 17:25:56.771814 | controller | included: /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:25:56.779488 | 
2026-02-16 17:25:56.779560 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:25:57.815436 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:25:57.815649 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/work/35d048fa07ec42829b1055c6a7aafe8f_id_rsa
2026-02-16 17:25:57.815729 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/work/35d048fa07ec42829b1055c6a7aafe8f_id_rsa.pub
2026-02-16 17:25:57.815757 | controller -> localhost | The key fingerprint is:
2026-02-16 17:25:57.815821 | controller -> localhost | SHA256:fRD6wgMUJ/nrQVFk/HNg6FY3nWl9T/J7DOQsBeV6MbA zuul-build-sshkey
2026-02-16 17:25:57.815871 | controller -> localhost | The key's randomart image is:
2026-02-16 17:25:57.815895 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:25:57.815932 | controller -> localhost | |      +o.+=.oo..+|
2026-02-16 17:25:57.815955 | controller -> localhost | |     ..o.oo.++*+=|
2026-02-16 17:25:57.815974 | controller -> localhost | |      ...o.+EB==o|
2026-02-16 17:25:57.816001 | controller -> localhost | |       oooo.+.=oo|
2026-02-16 17:25:57.816023 | controller -> localhost | |       .Soo o+.o.|
2026-02-16 17:25:57.816043 | controller -> localhost | |        oo . . .o|
2026-02-16 17:25:57.816061 | controller -> localhost | |       . .      .|
2026-02-16 17:25:57.816088 | controller -> localhost | |        .        |
2026-02-16 17:25:57.816112 | controller -> localhost | |                 |
2026-02-16 17:25:57.816132 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:25:57.816189 | controller -> localhost | ok: Runtime: 0:00:00.628001
2026-02-16 17:25:57.822690 | 
2026-02-16 17:25:57.822757 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:25:57.859316 | controller | ok
2026-02-16 17:25:57.870337 | controller | included: /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:25:57.879266 | 
2026-02-16 17:25:57.879375 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:25:57.905311 | controller | skipping: Conditional result was False
2026-02-16 17:25:57.916339 | 
2026-02-16 17:25:57.916433 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:25:58.458503 | controller | changed
2026-02-16 17:25:58.465798 | 
2026-02-16 17:25:58.465874 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:25:58.693281 | controller | ok
2026-02-16 17:25:58.700335 | 
2026-02-16 17:25:58.700405 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:25:59.281801 | controller | changed
2026-02-16 17:25:59.289665 | 
2026-02-16 17:25:59.289729 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:25:59.831027 | controller | changed
2026-02-16 17:25:59.838838 | 
2026-02-16 17:25:59.838906 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:25:59.864711 | controller | skipping: Conditional result was False
2026-02-16 17:25:59.874199 | 
2026-02-16 17:25:59.874292 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:26:00.234621 | controller -> localhost | changed
2026-02-16 17:26:00.251298 | 
2026-02-16 17:26:00.251496 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:26:00.559407 | controller -> localhost | Identity added: /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/work/35d048fa07ec42829b1055c6a7aafe8f_id_rsa (zuul-build-sshkey)
2026-02-16 17:26:00.559672 | controller -> localhost | ok: Runtime: 0:00:00.014744
2026-02-16 17:26:00.600939 | 
2026-02-16 17:26:00.601121 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:26:00.898191 | controller | ok
2026-02-16 17:26:00.903925 | 
2026-02-16 17:26:00.903992 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:26:00.929901 | controller | skipping: Conditional result was False
2026-02-16 17:26:00.944634 | 
2026-02-16 17:26:00.944697 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:26:01.277419 | controller | ok
2026-02-16 17:26:01.285044 | 
2026-02-16 17:26:01.285111 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:26:21.076088 | controller | Output suppressed because no_log was given
2026-02-16 17:26:21.088142 | 
2026-02-16 17:26:21.088210 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:26:21.321867 | controller | ok: "logs"
2026-02-16 17:26:21.322140 | controller | ok: All items complete
2026-02-16 17:26:21.322168 | 
2026-02-16 17:26:21.506086 | controller | ok: "artifacts"
2026-02-16 17:26:21.701524 | controller | ok: "docs"
2026-02-16 17:26:21.715962 | 
2026-02-16 17:26:21.716133 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:26:21.950073 | controller | changed: "logs"
2026-02-16 17:26:22.125529 | controller | changed: "artifacts"
2026-02-16 17:26:22.347814 | controller | changed: "docs"
2026-02-16 17:26:22.370906 | 
2026-02-16 17:26:22.371030 | PLAY RECAP
2026-02-16 17:26:22.371078 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:26:22.371107 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:26:22.371125 | 
2026-02-16 17:26:22.552701 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:26:22.557774 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:26:23.208070 | 
2026-02-16 17:26:23.208208 | PLAY [all]
2026-02-16 17:26:23.225440 | 
2026-02-16 17:26:23.225513 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:26:23.644390 | controller | changed
2026-02-16 17:26:23.652285 | 
2026-02-16 17:26:23.652375 | TASK [Gather minimum local MTU]
2026-02-16 17:26:23.734574 | controller | ok
2026-02-16 17:26:23.740063 | 
2026-02-16 17:26:23.740129 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:26:23.798434 | controller | ok
2026-02-16 17:26:23.806021 | 
2026-02-16 17:26:23.806136 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:26:23.841053 | controller | skipping: Conditional result was False
2026-02-16 17:26:23.849526 | 
2026-02-16 17:26:23.849602 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:26:24.388883 | controller | ok: Runtime: 0:00:00.009540
2026-02-16 17:26:24.397013 | 
2026-02-16 17:26:24.397105 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:26:24.423551 | controller | skipping: Conditional result was False
2026-02-16 17:26:24.432658 | 
2026-02-16 17:26:24.432749 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:26:24.458094 | controller | skipping: Conditional result was False
2026-02-16 17:26:24.468963 | 
2026-02-16 17:26:24.469057 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:26:24.526503 | controller | ok
2026-02-16 17:26:24.536553 | controller | included: /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:26:24.542569 | 
2026-02-16 17:26:24.542632 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:26:24.594964 | controller | ok
2026-02-16 17:26:24.602486 | 
2026-02-16 17:26:24.602570 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:26:24.899269 | controller | ext4
2026-02-16 17:26:25.174980 | controller | ok: Runtime: 0:00:00.011494
2026-02-16 17:26:25.180554 | 
2026-02-16 17:26:25.180619 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:26:25.215909 | controller | ok
2026-02-16 17:26:25.223548 | 
2026-02-16 17:26:25.223630 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:26:25.259970 | controller | ok:
2026-02-16 17:26:25.260205 | controller | {
2026-02-16 17:26:25.260262 | controller |   "root_filesystem": "ext4"
2026-02-16 17:26:25.260306 | controller | }
2026-02-16 17:26:25.268456 | 
2026-02-16 17:26:25.268518 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:26:35.665547 | controller | 8192+0 records in
2026-02-16 17:26:35.665979 | controller | 8192+0 records out
2026-02-16 17:26:35.666001 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.1383 s, 847 MB/s
2026-02-16 17:26:35.844311 | controller | ok: Runtime: 0:00:10.149404
2026-02-16 17:26:35.853799 | 
2026-02-16 17:26:35.853903 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:26:36.107047 | controller | changed
2026-02-16 17:26:36.116766 | 
2026-02-16 17:26:36.116872 | TASK [configure-swap : Make swapfile]
2026-02-16 17:27:10.967281 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:27:10.967492 | controller | no label, UUID=ae08c663-ae4e-456b-9828-dc9059692ee2
2026-02-16 17:27:11.369454 | controller | ok: Runtime: 0:00:34.607923
2026-02-16 17:27:11.376271 | 
2026-02-16 17:27:11.376338 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:27:11.781952 | controller | changed
2026-02-16 17:27:11.788773 | 
2026-02-16 17:27:11.788839 | TASK [configure-swap : Add all swap]
2026-02-16 17:27:12.330042 | controller | ok: Runtime: 0:00:00.025846
2026-02-16 17:27:12.337490 | 
2026-02-16 17:27:12.337560 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:27:12.372202 | controller | ok:
2026-02-16 17:27:12.372374 | controller | {
2026-02-16 17:27:12.372410 | controller |   "swap_required": "8192"
2026-02-16 17:27:12.372438 | controller | }
2026-02-16 17:27:12.378396 | 
2026-02-16 17:27:12.378462 | TASK [configure-swap : Set swappiness]
2026-02-16 17:27:12.781153 | controller | changed
2026-02-16 17:27:12.788872 | 
2026-02-16 17:27:12.788941 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:27:12.819931 | controller | ok:
2026-02-16 17:27:12.820051 | controller | {
2026-02-16 17:27:12.820076 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:27:12.820102 | controller | }
2026-02-16 17:27:12.829559 | 
2026-02-16 17:27:12.829622 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:27:13.270646 | controller | changed
2026-02-16 17:27:13.279393 | 
2026-02-16 17:27:13.279466 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:27:13.518995 | controller | changed
2026-02-16 17:27:13.524676 | 
2026-02-16 17:27:13.524742 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:27:15.060357 | controller | changed
2026-02-16 17:27:15.066113 | 
2026-02-16 17:27:15.066179 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:27:15.316018 | controller | changed
2026-02-16 17:27:15.322257 | 
2026-02-16 17:27:15.322323 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:27:16.400022 | controller | changed
2026-02-16 17:27:16.406280 | 
2026-02-16 17:27:16.406347 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:27:16.642224 | controller | changed
2026-02-16 17:27:16.653617 | 
2026-02-16 17:27:16.653680 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:27:16.941540 | controller | changed
2026-02-16 17:27:16.948521 | 
2026-02-16 17:27:16.948587 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:27:17.316166 | controller | changed
2026-02-16 17:27:17.361145 | 
2026-02-16 17:27:17.361311 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:27:18.135640 | controller | changed
2026-02-16 17:27:18.151980 | 
2026-02-16 17:27:18.152157 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:27:18.474666 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:27:18.486849 | 
2026-02-16 17:27:18.486918 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:27:18.960102 | controller | ok: Item: Runtime: 0:00:00.187132
2026-02-16 17:27:19.518584 | controller | ok: Item: Runtime: 0:00:00.338726
2026-02-16 17:27:20.278806 | controller | ok: Item: Runtime: 0:00:00.534556
2026-02-16 17:27:20.571804 | controller | ok: Item: Runtime: 0:00:00.062535
2026-02-16 17:27:21.068644 | controller | ok: Item: Runtime: 0:00:00.265290
2026-02-16 17:27:22.599847 | controller | ok: Item: Runtime: 0:00:01.310978
2026-02-16 17:27:25.913339 | controller | ok: Item: Runtime: 0:00:03.079803
2026-02-16 17:27:26.240368 | controller | ok: Item: Runtime: 0:00:00.098400
2026-02-16 17:27:26.655385 | controller | ok: Item: Runtime: 0:00:00.183850
2026-02-16 17:27:26.997639 | controller | ok: Item: Runtime: 0:00:00.120013
2026-02-16 17:27:27.776177 | controller | ok: Item: Runtime: 0:00:00.551224
2026-02-16 17:27:28.477377 | controller | ok: Item: Runtime: 0:00:00.468367
2026-02-16 17:27:28.948402 | controller | ok: Item: Runtime: 0:00:00.228933
2026-02-16 17:27:29.380142 | controller | ok: Item: Runtime: 0:00:00.216768
2026-02-16 17:27:29.978324 | controller | ok: Item: Runtime: 0:00:00.371130
2026-02-16 17:27:31.375100 | controller | ok: Item: Runtime: 0:00:01.163563
2026-02-16 17:27:31.407273 | 
2026-02-16 17:27:31.407443 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:27:31.638070 | controller | ok: All paths examined
2026-02-16 17:27:31.648106 | 
2026-02-16 17:27:31.648178 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:27:31.889125 | controller | ok: All paths examined
2026-02-16 17:27:31.895611 | 
2026-02-16 17:27:31.895684 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:27:32.322430 | controller | ok: Item: Runtime: 0:00:00.153181
2026-02-16 17:27:32.322714 | controller | changed: All items complete
2026-02-16 17:27:32.322758 | 
2026-02-16 17:27:32.739620 | controller | ok: Item: Runtime: 0:00:00.193937
2026-02-16 17:27:32.748387 | 
2026-02-16 17:27:32.748457 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:27:32.791655 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.794014 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.796948 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.800294 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.803420 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.806530 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.809527 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.812645 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.815861 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.818935 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.822171 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.825308 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.828058 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.830852 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.833870 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.837171 | controller | skipping: Conditional result was False
2026-02-16 17:27:32.869517 | 
2026-02-16 17:27:32.869692 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:27:35.191942 | controller | changed
2026-02-16 17:27:35.202182 | 
2026-02-16 17:27:35.202243 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:27:35.450367 | controller | changed
2026-02-16 17:27:35.544910 | 
2026-02-16 17:27:35.545076 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:27:35.804521 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:27:36.085876 | controller | ERROR
2026-02-16 17:27:36.086253 | controller | {
2026-02-16 17:27:36.086322 | controller |   "delta": "0:00:00.011069",
2026-02-16 17:27:36.086368 | controller |   "end": "2026-02-16 17:27:35.805135",
2026-02-16 17:27:36.086408 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:36.086449 | controller |   "rc": 1,
2026-02-16 17:27:36.086488 | controller |   "start": "2026-02-16 17:27:35.794066"
2026-02-16 17:27:36.086525 | controller | }
2026-02-16 17:27:36.086578 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:36.094548 | 
2026-02-16 17:27:36.094617 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:27:36.342373 | controller | ok
2026-02-16 17:27:36.356291 | 
2026-02-16 17:27:36.356355 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:27:36.592724 | controller | /usr/bin/journalctl
2026-02-16 17:27:36.897526 | controller | ok: Runtime: 0:00:00.007284
2026-02-16 17:27:36.903253 | 
2026-02-16 17:27:36.903323 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:27:37.154399 | controller | 2026-02-16 17:27:37
2026-02-16 17:27:37.532490 | controller | ok: Runtime: 0:00:00.010217
2026-02-16 17:27:37.538054 | 
2026-02-16 17:27:37.538116 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:27:38.112326 | controller | changed
2026-02-16 17:27:38.120947 | 
2026-02-16 17:27:38.121048 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:27:38.147148 | controller | skipping: Conditional result was False
2026-02-16 17:27:38.156881 | 
2026-02-16 17:27:38.156985 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:27:38.689230 | controller | skipping: Conditional result was False
2026-02-16 17:27:38.696283 | 
2026-02-16 17:27:38.696351 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:27:39.229520 | controller | skipping: Conditional result was False
2026-02-16 17:27:39.241952 | 
2026-02-16 17:27:39.242327 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:27:39.268428 | controller | skipping: Conditional result was False
2026-02-16 17:27:39.279882 | 
2026-02-16 17:27:39.279951 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:27:39.815943 | controller | skipping: Conditional result was False
2026-02-16 17:27:39.827356 | 
2026-02-16 17:27:39.827453 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:27:40.358876 | controller | skipping: Conditional result was False
2026-02-16 17:27:40.369463 | 
2026-02-16 17:27:40.369565 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:27:40.394931 | controller | skipping: Conditional result was False
2026-02-16 17:27:40.406294 | 
2026-02-16 17:27:40.406376 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:27:40.423462 | controller | skipping: Conditional result was False
2026-02-16 17:27:40.433270 | 
2026-02-16 17:27:40.433412 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:27:41.015422 | controller | skipping: Conditional result was False
2026-02-16 17:27:41.026593 | 
2026-02-16 17:27:41.026712 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:27:41.561799 | controller | skipping: Conditional result was False
2026-02-16 17:27:41.573172 | 
2026-02-16 17:27:41.573300 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:27:41.598270 | controller | skipping: Conditional result was False
2026-02-16 17:27:41.608979 | 
2026-02-16 17:27:41.609048 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:27:41.634727 | controller | skipping: Conditional result was False
2026-02-16 17:27:41.652894 | 
2026-02-16 17:27:41.653007 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:27:42.052100 | controller | ok
2026-02-16 17:27:42.062189 | 
2026-02-16 17:27:42.062248 | PLAY RECAP
2026-02-16 17:27:42.062297 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:27:42.062319 | 
2026-02-16 17:27:42.293741 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:27:42.303747 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:27:42.901699 | 
2026-02-16 17:27:42.902207 | PLAY [all]
2026-02-16 17:27:42.923231 | 
2026-02-16 17:27:42.923779 | TASK [ensure-rust : Use rustup]
2026-02-16 17:27:42.947137 | controller | ok
2026-02-16 17:27:42.958851 | controller | included: /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:27:42.965883 | 
2026-02-16 17:27:42.965976 | TASK [ensure-rust : Install Rust]
2026-02-16 17:27:43.366670 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:27:43.505715 | controller | ERROR
2026-02-16 17:27:43.505981 | controller | {
2026-02-16 17:27:43.506022 | controller |   "delta": "0:00:00.012066",
2026-02-16 17:27:43.506054 | controller |   "end": "2026-02-16 17:27:43.369805",
2026-02-16 17:27:43.506083 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:43.506110 | controller |   "rc": 127,
2026-02-16 17:27:43.506140 | controller |   "start": "2026-02-16 17:27:43.357739"
2026-02-16 17:27:43.506173 | controller | }
failure
2026-02-16 17:27:43.508070 | 
2026-02-16 17:27:43.508134 | PLAY RECAP
2026-02-16 17:27:43.508198 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:27:43.508229 | 
2026-02-16 17:27:43.628268 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:27:43.639166 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:27:44.232939 | 
2026-02-16 17:27:44.233110 | PLAY [all]
2026-02-16 17:27:44.249834 | 
2026-02-16 17:27:44.249920 | TASK [Return built artifacts to Zuul]
2026-02-16 17:27:44.284042 | controller | ok
2026-02-16 17:27:44.296987 | 
2026-02-16 17:27:44.297308 | TASK [Get the Docker volume ID]
2026-02-16 17:27:44.841094 | controller | ERROR
2026-02-16 17:27:44.841402 | controller | {
2026-02-16 17:27:44.841469 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:27:44.841499 | controller |   "rc": 2
2026-02-16 17:27:44.841519 | controller | }
2026-02-16 17:27:44.841545 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:44.848221 | 
2026-02-16 17:27:44.848287 | TASK [Copy all of the pod logs]
2026-02-16 17:27:44.882758 | controller | ERROR
2026-02-16 17:27:44.882961 | controller | {
2026-02-16 17:27:44.883002 | 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/35d048fa07ec42829b1055c6a7aafe8f/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:27:44.883079 | controller | }
2026-02-16 17:27:44.883155 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:44.884251 | 
2026-02-16 17:27:44.884317 | PLAY RECAP
2026-02-16 17:27:44.884377 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:27:44.884407 | 
2026-02-16 17:27:45.020481 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:27:45.025968 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:27:45.645565 | 
2026-02-16 17:27:45.645691 | PLAY [all]
2026-02-16 17:27:45.663621 | 
2026-02-16 17:27:45.663699 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:27:46.054002 | controller | changed
2026-02-16 17:27:46.061026 | 
2026-02-16 17:27:46.061106 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:27:47.098416 | controller | ok: Runtime: 0:00:00.464075
2026-02-16 17:27:47.106664 | 
2026-02-16 17:27:47.106773 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:27:47.649426 | controller | ok: Runtime: 0:00:00.019971
2026-02-16 17:27:47.655382 | 
2026-02-16 17:27:47.655465 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:27:48.193099 | controller | ok: Runtime: 0:00:00.019569
2026-02-16 17:27:48.199093 | 
2026-02-16 17:27:48.199159 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:27:49.106071 | controller | changed
2026-02-16 17:27:49.154109 | 
2026-02-16 17:27:49.154264 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:27:49.396565 | controller | changed
2026-02-16 17:27:49.404060 | 
2026-02-16 17:27:49.404129 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:27:49.729088 | controller | Output suppressed because no_log was given
2026-02-16 17:27:49.734545 | 
2026-02-16 17:27:49.734615 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:27:49.770464 | 
2026-02-16 17:27:49.770650 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:27:49.797228 | 
2026-02-16 17:27:49.797412 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:27:49.819898 | 
2026-02-16 17:27:49.820347 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:27:49.855574 | 
2026-02-16 17:27:49.855741 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:27:49.890220 | 
2026-02-16 17:27:49.890447 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:27:50.131461 | controller | changed
2026-02-16 17:27:50.139683 | 
2026-02-16 17:27:50.139753 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:27:50.174716 | controller | ok
2026-02-16 17:27:50.182352 | 
2026-02-16 17:27:50.182417 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:27:50.429648 | controller | Output suppressed because no_log was given
2026-02-16 17:27:50.437823 | 
2026-02-16 17:27:50.437885 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:27:50.477354 | 
2026-02-16 17:27:50.477607 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:27:50.508593 | 
2026-02-16 17:27:50.508756 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:27:51.151965 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:27:51.170246 | controller | Using python 3.12 to install setuptools
2026-02-16 17:27:51.209196 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:27:51.219744 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:27:51.555225 | controller | ERROR
2026-02-16 17:27:51.555461 | controller | {
2026-02-16 17:27:51.555496 | controller |   "delta": "0:00:00.470586",
2026-02-16 17:27:51.555517 | controller |   "end": "2026-02-16 17:27:51.222159",
2026-02-16 17:27:51.555536 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:51.555557 | controller |   "rc": 127,
2026-02-16 17:27:51.555575 | controller |   "start": "2026-02-16 17:27:50.751573"
2026-02-16 17:27:51.555592 | controller | }
2026-02-16 17:27:51.555617 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:51.567886 | 
2026-02-16 17:27:51.567968 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:27:51.816157 | controller | changed
2026-02-16 17:27:51.823129 | 
2026-02-16 17:27:51.823245 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:27:55.568569 | controller | Output suppressed because no_log was given
2026-02-16 17:27:55.580709 | 
2026-02-16 17:27:55.580799 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:27:55.613127 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.613624 | 
2026-02-16 17:27:55.615519 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.617112 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.618833 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.620997 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.623270 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.625575 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.628084 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.630566 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.632822 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.635315 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.637594 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.639838 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.642061 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.644375 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.646682 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.648907 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.652080 | controller | skipping: Conditional result was False
2026-02-16 17:27:55.683523 | 
2026-02-16 17:27:55.683651 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:27:55.921130 | controller | ok
2026-02-16 17:27:55.928154 | 
2026-02-16 17:27:55.928219 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:27:56.509381 | controller | skipping: Conditional result was False
2026-02-16 17:27:56.526541 | 
2026-02-16 17:27:56.526622 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:27:56.789485 | controller | sudo: iptables-save: command not found
2026-02-16 17:27:56.829592 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:27:57.968104 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:27:58.066538 | controller | ok: Runtime: 0:00:01.202978
2026-02-16 17:27:58.078355 | 
2026-02-16 17:27:58.078429 | LOOP [stage-output : Register sources]
2026-02-16 17:28:07.962342 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.037635 | 
2026-02-16 17:28:08.037805 | TASK [stage-output : Check sudo]
2026-02-16 17:28:08.583377 | controller | ok: Runtime: 0:00:00.022097
2026-02-16 17:28:08.590198 | 
2026-02-16 17:28:08.590277 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:28:08.686538 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.687377 | 
2026-02-16 17:28:08.689256 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.690411 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.693096 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.727929 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.728332 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.728688 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.730593 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.732815 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.734935 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.737106 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.739229 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.741273 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.743399 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.761766 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.762305 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.762885 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.763415 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.763912 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.764931 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.767118 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.769218 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.771275 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.795651 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.796151 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.868543 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.879530 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.890618 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.902845 | controller | Output suppressed because no_log was given
2026-02-16 17:28:08.917161 | 
2026-02-16 17:28:08.917263 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:28:08.983300 | controller | ok
2026-02-16 17:28:09.016755 | 
2026-02-16 17:28:09.016858 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:28:09.286219 | controller | changed: "docs"
2026-02-16 17:28:09.493578 | controller | changed: "artifacts"
2026-02-16 17:28:09.725727 | controller | ok: "logs"
2026-02-16 17:28:09.738458 | 
2026-02-16 17:28:09.738567 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:28:10.016794 | controller | ok: Item: Runtime: 0:00:00.010414
2026-02-16 17:28:10.017079 | controller | changed: All items complete
2026-02-16 17:28:10.017110 | 
2026-02-16 17:28:10.252435 | controller | ok: Item: Runtime: 0:00:00.008217
2026-02-16 17:28:10.480466 | controller | ok: Item: Runtime: 0:00:00.008772
2026-02-16 17:28:10.708686 | controller | ok: Item: Runtime: 0:00:00.008419
2026-02-16 17:28:10.941209 | controller | ok: Item: Runtime: 0:00:00.009179
2026-02-16 17:28:11.178372 | controller | ok: Item: Runtime: 0:00:00.008748
2026-02-16 17:28:11.404006 | controller | ok: Item: Runtime: 0:00:00.008449
2026-02-16 17:28:11.635248 | controller | ok: Item: Runtime: 0:00:00.009206
2026-02-16 17:28:11.880803 | controller | ok: Item: Runtime: 0:00:00.007788
2026-02-16 17:28:12.097527 | controller | ok: Item: Runtime: 0:00:00.007256
2026-02-16 17:28:12.329715 | controller | ok: Item: Runtime: 0:00:00.008697
2026-02-16 17:28:12.626525 | controller | ok: Item: Runtime: 0:00:00.010082
2026-02-16 17:28:12.828492 | controller | ok: Item: Runtime: 0:00:00.008524
2026-02-16 17:28:13.060633 | controller | ok: Item: Runtime: 0:00:00.005748
2026-02-16 17:28:13.291530 | controller | ok: Item: Runtime: 0:00:00.008282
2026-02-16 17:28:13.513142 | controller | ok: Item: Runtime: 0:00:00.007600
2026-02-16 17:28:13.605151 | 
2026-02-16 17:28:13.605337 | TASK [stage-output : Make all log files readable]
2026-02-16 17:28:13.864670 | controller | changed
2026-02-16 17:28:13.872419 | 
2026-02-16 17:28:13.872487 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:28:14.214583 | controller | changed: Renamed files for staging.
2026-02-16 17:28:14.220050 | 
2026-02-16 17:28:14.220117 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:28:14.245989 | controller | skipping: Conditional result was False
2026-02-16 17:28:14.258295 | 
2026-02-16 17:28:14.258367 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:28:14.307421 | 
2026-02-16 17:28:14.307639 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:28:14.947751 | controller | changed:
2026-02-16 17:28:14.947961 | controller | created directory /var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/work/logs/controller
2026-02-16 17:28:14.948001 | controller | cd+++++++++ logs/
2026-02-16 17:28:14.948031 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:28:14.948060 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:28:14.948086 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:28:14.948113 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:28:14.948139 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:28:14.948165 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:28:14.948190 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:28:14.948216 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:28:14.948241 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:28:14.948267 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:28:14.948292 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:28:14.948317 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:28:14.948348 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:28:14.948388 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:28:14.948415 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:28:14.948441 | controller | cd+++++++++ logs/apache/
2026-02-16 17:28:14.948466 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:28:14.948491 | controller | cd+++++++++ logs/etc/
2026-02-16 17:28:14.948516 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:28:14.948541 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:28:14.948566 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:28:14.948591 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:28:14.948616 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:28:14.948641 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:28:14.961144 | 
2026-02-16 17:28:14.961215 | TASK [Check if a tempest log exits]
2026-02-16 17:28:15.212315 | controller | ok
2026-02-16 17:28:15.219269 | 
2026-02-16 17:28:15.219337 | TASK [Link post-devstack tempest.log]
2026-02-16 17:28:15.244730 | controller | skipping: Conditional result was False
2026-02-16 17:28:15.257285 | 
2026-02-16 17:28:15.257353 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:28:15.506656 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:28:15.802254 | controller | ERROR
2026-02-16 17:28:15.802509 | controller | {
2026-02-16 17:28:15.802554 | controller |   "delta": "0:00:00.007947",
2026-02-16 17:28:15.802582 | controller |   "end": "2026-02-16 17:28:15.507236",
2026-02-16 17:28:15.802607 | controller |   "msg": "non-zero return code",
2026-02-16 17:28:15.802632 | controller |   "rc": 127,
2026-02-16 17:28:15.802657 | controller |   "start": "2026-02-16 17:28:15.499289"
2026-02-16 17:28:15.802682 | controller | }
2026-02-16 17:28:15.802714 | controller | ERROR: Ignoring Errors
2026-02-16 17:28:15.804749 | 
2026-02-16 17:28:15.804811 | PLAY RECAP
2026-02-16 17:28:15.804873 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:28:15.804902 | 
2026-02-16 17:28:15.942229 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:28:15.952867 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:28:16.562747 | 
2026-02-16 17:28:16.562890 | PLAY [all]
2026-02-16 17:28:16.574751 | 
2026-02-16 17:28:16.574827 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:28:16.620370 | controller | skipping: Conditional result was False
2026-02-16 17:28:16.630707 | 
2026-02-16 17:28:16.630783 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:28:16.672280 | controller | ok
2026-02-16 17:28:16.679963 | 
2026-02-16 17:28:16.680032 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:28:17.093101 | controller -> localhost | ok: "/var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/work/logs"
2026-02-16 17:28:17.307638 | controller -> localhost | changed: "/var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/work/artifacts"
2026-02-16 17:28:17.523558 | controller -> localhost | changed: "/var/lib/zuul/builds/35d048fa07ec42829b1055c6a7aafe8f/work/docs"
2026-02-16 17:28:17.537882 | 
2026-02-16 17:28:17.538043 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:28:18.215293 | controller | changed: .d..t...... ./
2026-02-16 17:28:18.215597 | controller | changed: All items complete
2026-02-16 17:28:18.215635 | 
2026-02-16 17:28:18.721419 | controller | changed: .d..t...... ./
2026-02-16 17:28:19.193157 | controller | changed: .d..t...... ./
2026-02-16 17:28:19.205827 | 
2026-02-16 17:28:19.205916 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:28:19.638610 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008064
2026-02-16 17:28:19.871119 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008073
2026-02-16 17:28:19.879331 | 
2026-02-16 17:28:19.879408 | PLAY [all]
2026-02-16 17:28:19.886721 | 
2026-02-16 17:28:19.886783 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:28:20.358508 | controller | changed
2026-02-16 17:28:20.365212 | 
2026-02-16 17:28:20.365267 | PLAY RECAP
2026-02-16 17:28:20.365318 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:28:20.365340 | 
2026-02-16 17:28:20.523137 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:28:20.534200 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:28:21.130278 | 
2026-02-16 17:28:21.130423 | PLAY [localhost]
2026-02-16 17:28:21.141056 | 
2026-02-16 17:28:21.141129 | TASK [Generate Zuul manifest]
2026-02-16 17:28:21.163073 | localhost | ok
2026-02-16 17:28:21.179199 | 
2026-02-16 17:28:21.179279 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:28:21.546339 | localhost | changed
2026-02-16 17:28:21.559252 | 
2026-02-16 17:28:21.559338 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:28:21.743337 | localhost | ok
2026-02-16 17:28:21.752039 | 
2026-02-16 17:28:21.752105 | TASK [Upload logs]
2026-02-16 17:28:21.784186 | localhost | ok
2026-02-16 17:28:21.861277 | 
2026-02-16 17:28:21.861474 | TASK [Set zuul-log-path fact]
2026-02-16 17:28:21.883601 | localhost | ok
2026-02-16 17:28:21.896767 | 
2026-02-16 17:28:21.896837 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:28:21.928343 | localhost | ok
2026-02-16 17:28:21.936787 | 
2026-02-16 17:28:21.936852 | TASK [upload-logs : Create log directories]
2026-02-16 17:28:22.354287 | localhost | changed
2026-02-16 17:28:22.360982 | 
2026-02-16 17:28:22.361060 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:28:22.816794 | localhost -> localhost | ok: Runtime: 0:00:00.004937
2026-02-16 17:28:22.821809 | 
2026-02-16 17:28:22.821877 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:28:23.260072 | localhost | Output suppressed because no_log was given
2026-02-16 17:28:23.264019 | 
2026-02-16 17:28:23.264086 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:28:23.309652 | localhost | skipping: Conditional result was False
2026-02-16 17:28:23.315627 | localhost | skipping: Conditional result was False
2026-02-16 17:28:23.327700 | 
2026-02-16 17:28:23.327841 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:28:23.371880 | localhost | skipping: Conditional result was False
2026-02-16 17:28:23.372398 | 
2026-02-16 17:28:23.374831 | localhost | skipping: Conditional result was False
2026-02-16 17:28:23.392606 | 
2026-02-16 17:28:23.392757 | LOOP [upload-logs : Upload console log and json output]