2026-08-26 20:21:02.627405 | Job console starting
2026-08-26 20:21:02.642054 | Updating git repos
2026-08-26 20:21:04.031446 | Cloning repos into workspace
2026-08-26 20:21:18.369636 | Restoring repo states
2026-08-26 20:21:20.105973 | Merging changes
2026-08-26 20:21:22.501742 | Checking out repos
2026-08-26 20:21:31.961504 | Preparing playbooks
2026-08-26 20:21:38.021920 | Running Ansible setup
2026-08-26 20:21:43.510945 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:21:44.293638 | 
2026-08-26 20:21:44.293788 | PLAY [localhost]
2026-08-26 20:21:44.302054 | 
2026-08-26 20:21:44.302132 | TASK [Gathering Facts]
2026-08-26 20:21:45.911116 | localhost | ok
2026-08-26 20:21:45.919446 | 
2026-08-26 20:21:45.919609 | TASK [Setup log path fact]
2026-08-26 20:21:45.962733 | localhost | ok
2026-08-26 20:21:45.976837 | 
2026-08-26 20:21:45.976981 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:21:46.018581 | localhost | ok
2026-08-26 20:21:46.026170 | 
2026-08-26 20:21:46.026244 | TASK [emit-job-header : Print job information]
2026-08-26 20:21:46.069888 | # Job Information
2026-08-26 20:21:46.070128 | Ansible Version: 2.16.18
2026-08-26 20:21:46.070255 | Job: magnum-cluster-api-hydrophone-v1.36.1-calico
2026-08-26 20:21:46.070312 | Pipeline: check
2026-08-26 20:21:46.070363 | Executor: 2d72f0692154
2026-08-26 20:21:46.070411 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1114
2026-08-26 20:21:46.070619 | Event ID: 6347d700-a189-11f1-89fe-66c77f18b121
2026-08-26 20:21:46.075000 | 
2026-08-26 20:21:46.075069 | LOOP [emit-job-header : Print node information]
2026-08-26 20:21:46.199776 | localhost | ok:
2026-08-26 20:21:46.199937 | localhost | # Node Information
2026-08-26 20:21:46.199966 | localhost | Inventory Hostname: controller
2026-08-26 20:21:46.199988 | localhost | Hostname: np0000191259
2026-08-26 20:21:46.200008 | localhost | Username: zuul
2026-08-26 20:21:46.200030 | localhost | Distro: Ubuntu 24.04
2026-08-26 20:21:46.200050 | localhost | Provider: yul1
2026-08-26 20:21:46.200070 | localhost | Region: ca-ymq-1
2026-08-26 20:21:46.200088 | localhost | Label: ubuntu-noble-16
2026-08-26 20:21:46.200107 | localhost | Product Name: OpenStack Nova
2026-08-26 20:21:46.200127 | localhost | Interface IP: 162.253.55.143
2026-08-26 20:21:46.212153 | 
2026-08-26 20:21:46.212583 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 20:21:46.664425 | localhost -> localhost | changed
2026-08-26 20:21:46.670277 | 
2026-08-26 20:21:46.670351 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 20:21:47.752593 | localhost -> localhost | changed
2026-08-26 20:21:47.760496 | 
2026-08-26 20:21:47.760613 | PLAY [all]
2026-08-26 20:21:47.770605 | 
2026-08-26 20:21:47.770698 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 20:21:48.022927 | controller -> localhost | ok
2026-08-26 20:21:48.032770 | 
2026-08-26 20:21:48.032878 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 20:21:48.078023 | controller | ok
2026-08-26 20:21:48.093048 | controller | included: /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 20:21:48.101580 | 
2026-08-26 20:21:48.101681 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 20:21:49.381515 | controller -> localhost | Generating public/private rsa key pair.
2026-08-26 20:21:49.381721 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/work/ee7e5d7abd594f62be6ec6081694b7f9_id_rsa
2026-08-26 20:21:49.381754 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/work/ee7e5d7abd594f62be6ec6081694b7f9_id_rsa.pub
2026-08-26 20:21:49.381779 | controller -> localhost | The key fingerprint is:
2026-08-26 20:21:49.381803 | controller -> localhost | SHA256:UlR7TJ7zs0QWg8Q323xRYwkOnDH47EDPIxKrTb5IKBY zuul-build-sshkey
2026-08-26 20:21:49.381838 | controller -> localhost | The key's randomart image is:
2026-08-26 20:21:49.381861 | controller -> localhost | +---[RSA 3072]----+
2026-08-26 20:21:49.381890 | controller -> localhost | |        ..+*=o+o+|
2026-08-26 20:21:49.381912 | controller -> localhost | |       o o B*.+=.|
2026-08-26 20:21:49.381933 | controller -> localhost | |        = * *oo=.|
2026-08-26 20:21:49.381953 | controller -> localhost | | E     = o B =. +|
2026-08-26 20:21:49.381973 | controller -> localhost | |  . . * S + . + .|
2026-08-26 20:21:49.381992 | controller -> localhost | | o . o +   . . o |
2026-08-26 20:21:49.382012 | controller -> localhost | |. . . . .     .  |
2026-08-26 20:21:49.382031 | controller -> localhost | |     . .         |
2026-08-26 20:21:49.382053 | controller -> localhost | |                 |
2026-08-26 20:21:49.382074 | controller -> localhost | +----[SHA256]-----+
2026-08-26 20:21:49.382124 | controller -> localhost | ok: Runtime: 0:00:00.743637
2026-08-26 20:21:49.390288 | 
2026-08-26 20:21:49.390372 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 20:21:49.425815 | controller | ok
2026-08-26 20:21:49.435887 | controller | included: /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 20:21:49.450213 | 
2026-08-26 20:21:49.450366 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 20:21:49.486506 | controller | skipping: Conditional result was False
2026-08-26 20:21:49.496536 | 
2026-08-26 20:21:49.496608 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 20:21:50.108347 | controller | changed
2026-08-26 20:21:50.113831 | 
2026-08-26 20:21:50.113904 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 20:21:50.365443 | controller | ok
2026-08-26 20:21:50.507916 | 
2026-08-26 20:21:50.508049 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 20:21:51.233352 | controller | changed
2026-08-26 20:21:51.540480 | 
2026-08-26 20:21:51.540614 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 20:21:52.329560 | controller | changed
2026-08-26 20:21:52.335309 | 
2026-08-26 20:21:52.335375 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 20:21:52.382256 | controller | skipping: Conditional result was False
2026-08-26 20:21:52.392922 | 
2026-08-26 20:21:52.393127 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 20:21:52.963533 | controller -> localhost | changed
2026-08-26 20:21:52.977990 | 
2026-08-26 20:21:52.978134 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 20:21:53.313130 | controller -> localhost | Identity added: /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/work/ee7e5d7abd594f62be6ec6081694b7f9_id_rsa (zuul-build-sshkey)
2026-08-26 20:21:53.313331 | controller -> localhost | ok: Runtime: 0:00:00.014567
2026-08-26 20:21:53.321392 | 
2026-08-26 20:21:53.321477 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 20:21:53.673441 | controller | ok
2026-08-26 20:21:53.679712 | 
2026-08-26 20:21:53.679783 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 20:21:53.716059 | controller | skipping: Conditional result was False
2026-08-26 20:21:53.941688 | 
2026-08-26 20:21:53.941865 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 20:21:54.503526 | controller | ok
2026-08-26 20:21:54.511916 | 
2026-08-26 20:21:54.512039 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 20:22:47.171608 | controller | Output suppressed because no_log was given
2026-08-26 20:22:47.193610 | 
2026-08-26 20:22:47.193712 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 20:22:47.482211 | controller | ok: "logs"
2026-08-26 20:22:47.482609 | controller | ok: All items complete
2026-08-26 20:22:47.482653 | 
2026-08-26 20:22:47.974693 | controller | ok: "artifacts"
2026-08-26 20:22:48.677930 | controller | ok: "docs"
2026-08-26 20:22:48.693262 | 
2026-08-26 20:22:48.693412 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 20:22:48.941936 | controller | changed: "logs"
2026-08-26 20:22:49.131590 | controller | changed: "artifacts"
2026-08-26 20:22:49.359811 | controller | changed: "docs"
2026-08-26 20:22:49.379876 | 
2026-08-26 20:22:49.391971 | PLAY RECAP
2026-08-26 20:22:49.392201 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 20:22:49.392247 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:22:49.392278 | 
2026-08-26 20:22:50.117902 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:22:50.136421 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:22:53.441420 | 
2026-08-26 20:22:53.441561 | PLAY [all]
2026-08-26 20:22:53.917568 | 
2026-08-26 20:22:53.917789 | TASK [Fix the permissions of the zuul home directory]
2026-08-26 20:22:54.506388 | controller | changed
2026-08-26 20:22:54.521093 | 
2026-08-26 20:22:54.521355 | TASK [Gather minimum local MTU]
2026-08-26 20:22:54.629173 | controller | ok
2026-08-26 20:22:54.642711 | 
2026-08-26 20:22:54.642785 | TASK [Calculate external_bridge_mtu]
2026-08-26 20:22:54.699257 | controller | ok
2026-08-26 20:22:54.710752 | 
2026-08-26 20:22:54.711630 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-08-26 20:22:54.764499 | controller | skipping: Conditional result was False
2026-08-26 20:22:54.776791 | 
2026-08-26 20:22:54.776982 | TASK [configure-swap : Get ephemeral0 device node]
2026-08-26 20:22:55.327992 | controller | ok: Runtime: 0:00:00.010317
2026-08-26 20:22:55.335171 | 
2026-08-26 20:22:55.335242 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-08-26 20:22:55.361731 | controller | skipping: Conditional result was False
2026-08-26 20:22:55.372801 | 
2026-08-26 20:22:55.372955 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-08-26 20:22:55.410451 | controller | skipping: Conditional result was False
2026-08-26 20:22:55.418959 | 
2026-08-26 20:22:55.419033 | TASK [configure-swap : Setup swap file on root device]
2026-08-26 20:22:55.470550 | controller | ok
2026-08-26 20:22:55.482382 | controller | included: /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-08-26 20:22:55.488627 | 
2026-08-26 20:22:55.488688 | TASK [configure-swap : Calculate required swap]
2026-08-26 20:22:55.613371 | controller | ok
2026-08-26 20:22:55.709533 | 
2026-08-26 20:22:55.709686 | TASK [configure-swap : Get root filesystem]
2026-08-26 20:22:55.982785 | controller | ext4
2026-08-26 20:22:56.262564 | controller | ok: Runtime: 0:00:00.013410
2026-08-26 20:22:56.268343 | 
2026-08-26 20:22:56.268411 | TASK [configure-swap : Save root filesystem]
2026-08-26 20:22:56.300302 | controller | ok
2026-08-26 20:22:56.306724 | 
2026-08-26 20:22:56.306788 | TASK [configure-swap : Debug the root_filesystem variable]
2026-08-26 20:22:56.339249 | controller | ok:
2026-08-26 20:22:56.346694 | controller | {
2026-08-26 20:22:56.346728 | controller |   "root_filesystem": "ext4"
2026-08-26 20:22:56.346750 | controller | }
2026-08-26 20:22:56.352821 | 
2026-08-26 20:22:56.352910 | TASK [configure-swap : Create swap backing file]
2026-08-26 20:23:06.291852 | controller | 8192+0 records in
2026-08-26 20:23:06.292053 | controller | 8192+0 records out
2026-08-26 20:23:06.292076 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.63939 s, 891 MB/s
2026-08-26 20:23:06.401664 | controller | ok: Runtime: 0:00:09.649123
2026-08-26 20:23:06.409107 | 
2026-08-26 20:23:06.409171 | TASK [configure-swap : Ensure swapfile perms]
2026-08-26 20:23:06.654795 | controller | changed
2026-08-26 20:23:06.660342 | 
2026-08-26 20:23:06.660420 | TASK [configure-swap : Make swapfile]
2026-08-26 20:23:28.755211 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-08-26 20:23:28.755506 | controller | no label, UUID=90c99f82-57b3-4204-83a6-fd3ce1b32717
2026-08-26 20:23:29.218234 | controller | ok: Runtime: 0:00:21.811899
2026-08-26 20:23:29.225668 | 
2026-08-26 20:23:29.225734 | TASK [configure-swap : Write swap to fstab]
2026-08-26 20:23:29.660641 | controller | changed
2026-08-26 20:23:29.666131 | 
2026-08-26 20:23:29.666203 | TASK [configure-swap : Add all swap]
2026-08-26 20:23:30.207113 | controller | ok: Runtime: 0:00:00.041198
2026-08-26 20:23:30.212796 | 
2026-08-26 20:23:30.212858 | TASK [configure-swap : Debug the swap_required variable]
2026-08-26 20:23:30.247705 | controller | ok:
2026-08-26 20:23:30.247810 | controller | {
2026-08-26 20:23:30.247835 | controller |   "swap_required": "8192"
2026-08-26 20:23:30.247855 | controller | }
2026-08-26 20:23:30.255974 | 
2026-08-26 20:23:30.256039 | TASK [configure-swap : Set swappiness]
2026-08-26 20:23:30.705337 | controller | changed
2026-08-26 20:23:30.710915 | 
2026-08-26 20:23:30.710984 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-08-26 20:23:30.756138 | controller | ok:
2026-08-26 20:23:30.756293 | controller | {
2026-08-26 20:23:30.756330 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-08-26 20:23:30.756362 | controller | }
2026-08-26 20:23:30.768742 | 
2026-08-26 20:23:30.768805 | TASK [setup-stack-user : Create stack group]
2026-08-26 20:23:31.279891 | controller | changed
2026-08-26 20:23:31.287496 | 
2026-08-26 20:23:31.287564 | TASK [setup-stack-user : Create the stack user home folder]
2026-08-26 20:23:31.587761 | controller | changed
2026-08-26 20:23:31.594334 | 
2026-08-26 20:23:31.594401 | TASK [setup-stack-user : Create stack user]
2026-08-26 20:23:32.206853 | controller | changed
2026-08-26 20:23:32.212808 | 
2026-08-26 20:23:32.212929 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-08-26 20:23:32.480566 | controller | changed
2026-08-26 20:23:32.488272 | 
2026-08-26 20:23:32.488340 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-08-26 20:23:33.631901 | controller | changed
2026-08-26 20:23:33.652351 | 
2026-08-26 20:23:33.652545 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-08-26 20:23:33.953638 | controller | changed
2026-08-26 20:23:33.972217 | 
2026-08-26 20:23:33.972437 | TASK [setup-tempest-user : Create tempest group]
2026-08-26 20:23:34.253223 | controller | changed
2026-08-26 20:23:34.259793 | 
2026-08-26 20:23:34.259859 | TASK [setup-tempest-user : Create tempest user]
2026-08-26 20:23:34.665598 | controller | changed
2026-08-26 20:23:34.671028 | 
2026-08-26 20:23:34.671093 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-08-26 20:23:35.475119 | controller | changed
2026-08-26 20:23:35.486283 | 
2026-08-26 20:23:35.487238 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-08-26 20:23:35.840463 | controller | ok: Not all paths examined, check warnings for details
2026-08-26 20:23:35.852127 | 
2026-08-26 20:23:35.852193 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-08-26 20:23:37.061904 | controller | ok: Item: Runtime: 0:00:00.897772
2026-08-26 20:23:37.742803 | controller | ok: Item: Runtime: 0:00:00.412317
2026-08-26 20:23:38.532277 | controller | ok: Item: Runtime: 0:00:00.557688
2026-08-26 20:23:39.155443 | controller | ok: Item: Runtime: 0:00:00.367877
2026-08-26 20:23:39.588045 | controller | ok: Item: Runtime: 0:00:00.170692
2026-08-26 20:23:40.611926 | controller | ok: Item: Runtime: 0:00:00.062109
2026-08-26 20:23:42.228026 | controller | ok: Item: Runtime: 0:00:01.348291
2026-08-26 20:23:45.800416 | controller | ok: Item: Runtime: 0:00:03.313226
2026-08-26 20:23:46.308625 | controller | ok: Item: Runtime: 0:00:00.242990
2026-08-26 20:23:46.928324 | controller | ok: Item: Runtime: 0:00:00.329204
2026-08-26 20:23:47.444198 | controller | ok: Item: Runtime: 0:00:00.248657
2026-08-26 20:23:47.972815 | controller | ok: Item: Runtime: 0:00:00.255988
2026-08-26 20:23:48.872823 | controller | ok: Item: Runtime: 0:00:00.645676
2026-08-26 20:23:49.766423 | controller | ok: Item: Runtime: 0:00:00.622508
2026-08-26 20:23:50.479612 | controller | ok: Item: Runtime: 0:00:00.455629
2026-08-26 20:23:50.917523 | controller | ok: Item: Runtime: 0:00:00.197168
2026-08-26 20:23:50.948408 | 
2026-08-26 20:23:50.974270 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-08-26 20:23:51.218453 | controller | ok: All paths examined
2026-08-26 20:23:51.225236 | 
2026-08-26 20:23:51.225307 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-08-26 20:23:51.473776 | controller | ok: All paths examined
2026-08-26 20:23:51.491030 | 
2026-08-26 20:23:51.491325 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-08-26 20:23:52.019535 | controller | ok: Item: Runtime: 0:00:00.212068
2026-08-26 20:23:52.019774 | controller | changed: All items complete
2026-08-26 20:23:52.019804 | 
2026-08-26 20:23:52.539796 | controller | ok: Item: Runtime: 0:00:00.267704
2026-08-26 20:23:52.559834 | 
2026-08-26 20:23:52.560004 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-08-26 20:23:52.650270 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.652621 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.656325 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.659542 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.663102 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.666796 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.670230 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.673859 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.677821 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.681263 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.684545 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.687968 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.691338 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.694864 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.698485 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.701821 | controller | skipping: Conditional result was False
2026-08-26 20:23:52.774174 | 
2026-08-26 20:23:52.774308 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-08-26 20:23:55.698021 | controller | changed
2026-08-26 20:23:55.710083 | 
2026-08-26 20:23:55.710154 | TASK [setup-devstack-log-dir : Create logs directory]
2026-08-26 20:23:55.979072 | controller | changed
2026-08-26 20:23:55.989556 | 
2026-08-26 20:23:55.989625 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-08-26 20:23:56.261560 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-08-26 20:23:56.540366 | controller | ERROR
2026-08-26 20:23:56.540591 | controller | {
2026-08-26 20:23:56.540622 | controller |   "delta": "0:00:00.009986",
2026-08-26 20:23:56.540645 | controller |   "end": "2026-08-26 20:23:56.262222",
2026-08-26 20:23:56.540666 | controller |   "msg": "non-zero return code",
2026-08-26 20:23:56.540685 | controller |   "rc": 1,
2026-08-26 20:23:56.540705 | controller |   "start": "2026-08-26 20:23:56.252236"
2026-08-26 20:23:56.540727 | controller | }
2026-08-26 20:23:56.540753 | controller | ERROR: Ignoring Errors
2026-08-26 20:23:56.547131 | 
2026-08-26 20:23:56.547202 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-08-26 20:23:56.823886 | controller | ok
2026-08-26 20:23:56.835645 | 
2026-08-26 20:23:56.835714 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-08-26 20:23:57.092241 | controller | /usr/bin/journalctl
2026-08-26 20:23:57.376648 | controller | ok: Runtime: 0:00:00.008888
2026-08-26 20:23:57.383511 | 
2026-08-26 20:23:57.383577 | TASK [start-fresh-logging : Get current date]
2026-08-26 20:23:57.683644 | controller | 2026-08-26 20:23:57
2026-08-26 20:23:57.923648 | controller | ok: Runtime: 0:00:00.009500
2026-08-26 20:23:57.932404 | 
2026-08-26 20:23:57.932476 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-08-26 20:23:58.553028 | controller | changed
2026-08-26 20:23:58.562036 | 
2026-08-26 20:23:58.562101 | TASK [start-fresh-logging : Stop rsyslog]
2026-08-26 20:23:58.591483 | controller | skipping: Conditional result was False
2026-08-26 20:23:58.609300 | 
2026-08-26 20:23:58.609368 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-08-26 20:23:59.142724 | controller | skipping: Conditional result was False
2026-08-26 20:23:59.151812 | 
2026-08-26 20:23:59.151906 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-08-26 20:23:59.688304 | controller | skipping: Conditional result was False
2026-08-26 20:23:59.702549 | 
2026-08-26 20:23:59.702638 | TASK [start-fresh-logging : Recreate syslog file]
2026-08-26 20:23:59.718479 | controller | skipping: Conditional result was False
2026-08-26 20:23:59.726704 | 
2026-08-26 20:23:59.726770 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-08-26 20:24:00.263088 | controller | skipping: Conditional result was False
2026-08-26 20:24:00.275209 | 
2026-08-26 20:24:00.275302 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-08-26 20:24:00.852772 | controller | skipping: Conditional result was False
2026-08-26 20:24:00.864030 | 
2026-08-26 20:24:00.864125 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-08-26 20:24:00.889344 | controller | skipping: Conditional result was False
2026-08-26 20:24:00.900776 | 
2026-08-26 20:24:00.900867 | TASK [start-fresh-logging : Recreate kern.log file]
2026-08-26 20:24:00.926208 | controller | skipping: Conditional result was False
2026-08-26 20:24:00.938247 | 
2026-08-26 20:24:01.028004 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-08-26 20:24:01.572286 | controller | skipping: Conditional result was False
2026-08-26 20:24:01.580901 | 
2026-08-26 20:24:01.581018 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-08-26 20:24:02.117821 | controller | skipping: Conditional result was False
2026-08-26 20:24:02.124861 | 
2026-08-26 20:24:02.124932 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-08-26 20:24:02.149599 | controller | skipping: Conditional result was False
2026-08-26 20:24:02.159214 | 
2026-08-26 20:24:02.159304 | TASK [start-fresh-logging : Start rsyslog]
2026-08-26 20:24:02.184001 | controller | skipping: Conditional result was False
2026-08-26 20:24:02.203494 | 
2026-08-26 20:24:02.203606 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-08-26 20:24:02.711698 | controller | ok
2026-08-26 20:24:02.720876 | 
2026-08-26 20:24:02.720936 | PLAY RECAP
2026-08-26 20:24:02.721020 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-08-26 20:24:02.721046 | 
2026-08-26 20:24:02.946600 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:24:02.953082 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:24:03.594812 | 
2026-08-26 20:24:03.594936 | PLAY [all]
2026-08-26 20:24:03.606641 | 
2026-08-26 20:24:03.606719 | TASK [Install curl]
2026-08-26 20:24:30.031073 | controller | ok
2026-08-26 20:24:30.040479 | 
2026-08-26 20:24:30.040609 | TASK [ensure-rust : Use rustup]
2026-08-26 20:24:30.076867 | controller | ok
2026-08-26 20:24:30.085249 | controller | included: /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-08-26 20:24:30.090992 | 
2026-08-26 20:24:30.091065 | TASK [ensure-rust : Install Rust]
2026-08-26 20:24:35.701692 | controller | curl: (6) Could not resolve host: sh.rustup.rs
2026-08-26 20:24:36.138950 | controller | ERROR
2026-08-26 20:24:36.139216 | controller | {
2026-08-26 20:24:36.139258 | controller |   "delta": "0:00:05.208597",
2026-08-26 20:24:36.139287 | controller |   "end": "2026-08-26 20:24:35.709255",
2026-08-26 20:24:36.139314 | controller |   "msg": "non-zero return code",
2026-08-26 20:24:36.139339 | controller |   "rc": 6,
2026-08-26 20:24:36.139369 | controller |   "start": "2026-08-26 20:24:30.500658"
2026-08-26 20:24:36.139397 | controller | }
failure
2026-08-26 20:24:36.141359 | 
2026-08-26 20:24:36.141421 | PLAY RECAP
2026-08-26 20:24:36.141480 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:24:36.141508 | 
2026-08-26 20:24:36.262146 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:24:36.274776 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:24:36.877275 | 
2026-08-26 20:24:36.877401 | PLAY [all]
2026-08-26 20:24:36.888854 | 
2026-08-26 20:24:36.888935 | LOOP [Copy individual Hydrophone result files]
2026-08-26 20:24:37.345739 | controller | ERROR: Item: e2e.log
2026-08-26 20:24:37.345995 | controller | {
2026-08-26 20:24:37.346034 | controller |   "ansible_loop_var": "item",
2026-08-26 20:24:37.346063 | controller |   "item": "e2e.log",
2026-08-26 20:24:37.346117 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-08-26 20:24:37.346141 | controller | }
2026-08-26 20:24:37.647353 | controller | ERROR: Item: junit_01.xml
2026-08-26 20:24:37.647578 | controller | {
2026-08-26 20:24:37.647650 | controller |   "ansible_loop_var": "item",
2026-08-26 20:24:37.647698 | controller |   "item": "junit_01.xml",
2026-08-26 20:24:37.647740 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-08-26 20:24:37.647781 | controller | }
2026-08-26 20:24:37.659948 | controller | ERROR: Ignoring Errors
2026-08-26 20:24:37.666265 | 
2026-08-26 20:24:37.666331 | TASK [Copy Hydrophone results tarball to output folder]
2026-08-26 20:24:37.985067 | controller | ERROR
2026-08-26 20:24:37.985368 | controller | {
2026-08-26 20:24:37.985408 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-08-26 20:24:37.985440 | controller | }
2026-08-26 20:24:37.985474 | controller | ERROR: Ignoring Errors
2026-08-26 20:24:37.999372 | 
2026-08-26 20:24:37.999449 | TASK [Return built artifacts to Zuul]
2026-08-26 20:24:38.098424 | controller | ok
2026-08-26 20:24:38.104904 | 
2026-08-26 20:24:38.105021 | TASK [Get the Docker volume ID]
2026-08-26 20:24:38.645656 | controller | ERROR
2026-08-26 20:24:38.645945 | controller | {
2026-08-26 20:24:38.645984 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-08-26 20:24:38.646015 | controller |   "rc": 2
2026-08-26 20:24:38.646041 | controller | }
2026-08-26 20:24:38.646078 | controller | ERROR: Ignoring Errors
2026-08-26 20:24:38.653111 | 
2026-08-26 20:24:38.653204 | TASK [Copy all of the pod logs]
2026-08-26 20:24:38.690033 | controller | ERROR
2026-08-26 20:24:38.690229 | controller | {
2026-08-26 20:24:38.690269 | 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/ee7e5d7abd594f62be6ec6081694b7f9/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml': line 42, 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-08-26 20:24:38.690314 | controller | }
2026-08-26 20:24:38.690350 | controller | ERROR: Ignoring Errors
2026-08-26 20:24:38.691576 | 
2026-08-26 20:24:38.691637 | PLAY RECAP
2026-08-26 20:24:38.691697 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-08-26 20:24:38.691726 | 
2026-08-26 20:24:38.839152 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:24:38.853814 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:24:40.031039 | 
2026-08-26 20:24:40.031186 | PLAY [all]
2026-08-26 20:24:40.051281 | 
2026-08-26 20:24:40.051367 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-08-26 20:24:40.480866 | controller | changed
2026-08-26 20:24:40.489068 | 
2026-08-26 20:24:40.489187 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-08-26 20:24:41.532425 | controller | ok: Runtime: 0:00:00.517092
2026-08-26 20:24:41.540362 | 
2026-08-26 20:24:41.540459 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-08-26 20:24:42.077968 | controller | ok: Runtime: 0:00:00.022081
2026-08-26 20:24:42.085785 | 
2026-08-26 20:24:42.085913 | TASK [export-devstack-journal : Export journal]
2026-08-26 20:24:42.695937 | controller | ok: Runtime: 0:00:00.022192
2026-08-26 20:24:42.704656 | 
2026-08-26 20:24:42.704736 | TASK [export-devstack-journal : Save journal README]
2026-08-26 20:24:43.723267 | controller | changed
2026-08-26 20:24:43.737100 | 
2026-08-26 20:24:43.737255 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-08-26 20:24:44.232509 | controller | changed
2026-08-26 20:24:44.240252 | 
2026-08-26 20:24:44.240344 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:24:44.611501 | controller | Output suppressed because no_log was given
2026-08-26 20:24:44.618774 | 
2026-08-26 20:24:44.618851 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:24:44.647101 | 
2026-08-26 20:24:44.647346 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:24:44.684423 | 
2026-08-26 20:24:44.684616 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:24:44.721757 | 
2026-08-26 20:24:44.721977 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:24:44.758722 | 
2026-08-26 20:24:44.758890 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:24:44.799380 | 
2026-08-26 20:24:44.799612 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-08-26 20:24:45.056521 | controller | changed
2026-08-26 20:24:45.064445 | 
2026-08-26 20:24:45.064510 | TASK [apache-logs-conf : Define config paths]
2026-08-26 20:24:45.100119 | controller | ok
2026-08-26 20:24:45.105404 | 
2026-08-26 20:24:45.105469 | TASK [apache-logs-conf : Discover configurations]
2026-08-26 20:24:45.363731 | controller | Output suppressed because no_log was given
2026-08-26 20:24:45.369033 | 
2026-08-26 20:24:45.369109 | LOOP [apache-logs-conf : Dereference configurations]
2026-08-26 20:24:45.407318 | 
2026-08-26 20:24:45.407474 | LOOP [apache-logs-conf : Link configurations]
2026-08-26 20:24:45.439297 | 
2026-08-26 20:24:45.439420 | TASK [capture-performance-data : Generate statistics]
2026-08-26 20:24:46.415610 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-08-26 20:24:46.436602 | controller | Using python 3.12 to install setuptools
2026-08-26 20:24:46.489739 | controller | /usr/bin/python3.12: No module named pip
2026-08-26 20:24:46.505275 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-08-26 20:24:46.981989 | controller | ERROR
2026-08-26 20:24:46.982248 | controller | {
2026-08-26 20:24:46.982302 | controller |   "delta": "0:00:00.555069",
2026-08-26 20:24:46.982336 | controller |   "end": "2026-08-26 20:24:46.508005",
2026-08-26 20:24:46.982363 | controller |   "msg": "non-zero return code",
2026-08-26 20:24:46.982398 | controller |   "rc": 127,
2026-08-26 20:24:46.982449 | controller |   "start": "2026-08-26 20:24:45.952936"
2026-08-26 20:24:46.982486 | controller | }
2026-08-26 20:24:46.982530 | controller | ERROR: Ignoring Errors
2026-08-26 20:24:46.999769 | 
2026-08-26 20:24:46.999898 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-08-26 20:24:47.267991 | controller | changed
2026-08-26 20:24:47.276530 | 
2026-08-26 20:24:47.276597 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-08-26 20:24:51.312827 | controller | Output suppressed because no_log was given
2026-08-26 20:24:51.327390 | 
2026-08-26 20:24:51.327464 | LOOP [devstack-project-conf : Copy configuration files]
2026-08-26 20:24:51.419934 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.420326 | 
2026-08-26 20:24:51.422551 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.425294 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.429188 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.432923 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.436511 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.440225 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.445614 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.448257 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.450949 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.452686 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.455990 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.458580 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.461973 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.465174 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.467962 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.470800 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.473586 | controller | skipping: Conditional result was False
2026-08-26 20:24:51.552173 | 
2026-08-26 20:24:51.552301 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-08-26 20:24:51.813208 | controller | ok
2026-08-26 20:24:51.820568 | 
2026-08-26 20:24:51.820654 | TASK [devstack-project-conf : Copy configuration files]
2026-08-26 20:24:52.354990 | controller | skipping: Conditional result was False
2026-08-26 20:24:52.370469 | 
2026-08-26 20:24:52.370557 | TASK [capture-system-logs : Stage various logs and reports]
2026-08-26 20:24:52.762177 | controller | /usr/bin/python3: No module named pip
2026-08-26 20:24:53.973203 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-08-26 20:24:54.413308 | controller | ok: Runtime: 0:00:01.333433
2026-08-26 20:24:54.425600 | 
2026-08-26 20:24:54.425726 | LOOP [stage-output : Register sources]
2026-08-26 20:25:05.415123 | controller | Output suppressed because no_log was given
2026-08-26 20:25:05.428620 | 
2026-08-26 20:25:05.428709 | TASK [stage-output : Check sudo]
2026-08-26 20:25:05.970705 | controller | ok: Runtime: 0:00:00.030340
2026-08-26 20:25:05.978391 | 
2026-08-26 20:25:05.978455 | LOOP [stage-output : Set source and destination for files and folders]
2026-08-26 20:25:06.077851 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.078320 | 
2026-08-26 20:25:06.080092 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.081455 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.084230 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.119800 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.120169 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.122706 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.125416 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.128031 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.130508 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.133026 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.135591 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.138299 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.140776 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.157315 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.157670 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.158108 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.160256 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.162885 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.165443 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.168020 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.170732 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.173282 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.204197 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.204854 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.305276 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.320047 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.333541 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.340846 | controller | Output suppressed because no_log was given
2026-08-26 20:25:06.354992 | 
2026-08-26 20:25:06.355065 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-08-26 20:25:06.430235 | controller | ok
2026-08-26 20:25:06.438569 | 
2026-08-26 20:25:06.438634 | LOOP [stage-output : Ensure target folders exist]
2026-08-26 20:25:06.713605 | controller | changed: "docs"
2026-08-26 20:25:06.960897 | controller | changed: "artifacts"
2026-08-26 20:25:07.214253 | controller | ok: "logs"
2026-08-26 20:25:07.227437 | 
2026-08-26 20:25:07.227627 | LOOP [stage-output : Copy files and folders to staging folder]
2026-08-26 20:25:07.536533 | controller | ok: Item: Runtime: 0:00:00.010054
2026-08-26 20:25:07.536778 | controller | changed: All items complete
2026-08-26 20:25:07.536806 | 
2026-08-26 20:25:07.823458 | controller | ok: Item: Runtime: 0:00:00.010131
2026-08-26 20:25:08.081341 | controller | ok: Item: Runtime: 0:00:00.009327
2026-08-26 20:25:08.351987 | controller | ok: Item: Runtime: 0:00:00.009631
2026-08-26 20:25:08.601005 | controller | ok: Item: Runtime: 0:00:00.006498
2026-08-26 20:25:08.865091 | controller | ok: Item: Runtime: 0:00:00.007688
2026-08-26 20:25:09.113863 | controller | ok: Item: Runtime: 0:00:00.008923
2026-08-26 20:25:09.341069 | controller | ok: Item: Runtime: 0:00:00.010694
2026-08-26 20:25:09.647010 | controller | ok: Item: Runtime: 0:00:00.007789
2026-08-26 20:25:09.834301 | controller | ok: Item: Runtime: 0:00:00.007367
2026-08-26 20:25:10.077456 | controller | ok: Item: Runtime: 0:00:00.007161
2026-08-26 20:25:10.345019 | controller | ok: Item: Runtime: 0:00:00.008175
2026-08-26 20:25:10.582676 | controller | ok: Item: Runtime: 0:00:00.007607
2026-08-26 20:25:10.804297 | controller | ok: Item: Runtime: 0:00:00.007702
2026-08-26 20:25:11.041740 | controller | ok: Item: Runtime: 0:00:00.007573
2026-08-26 20:25:11.289317 | controller | ok: Item: Runtime: 0:00:00.006804
2026-08-26 20:25:11.310426 | 
2026-08-26 20:25:11.310655 | TASK [stage-output : Make all log files readable]
2026-08-26 20:25:11.559159 | controller | changed
2026-08-26 20:25:11.565461 | 
2026-08-26 20:25:11.565530 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-08-26 20:25:11.910543 | controller | changed: Renamed files for staging.
2026-08-26 20:25:11.916740 | 
2026-08-26 20:25:11.916824 | TASK [stage-output : Discover log files for compression]
2026-08-26 20:25:11.941872 | controller | skipping: Conditional result was False
2026-08-26 20:25:11.953209 | 
2026-08-26 20:25:11.953303 | LOOP [stage-output : Archive everything from logs]
2026-08-26 20:25:11.994780 | 
2026-08-26 20:25:11.994907 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-08-26 20:25:12.726671 | controller | changed:
2026-08-26 20:25:12.726929 | controller | created directory /var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/work/logs/controller
2026-08-26 20:25:12.726978 | controller | cd+++++++++ logs/
2026-08-26 20:25:12.727009 | controller | >f+++++++++ logs/deprecations_log.txt
2026-08-26 20:25:12.727037 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-08-26 20:25:12.727064 | controller | >f+++++++++ logs/devstack.journal.gz
2026-08-26 20:25:12.727091 | controller | >f+++++++++ logs/df.txt
2026-08-26 20:25:12.727118 | controller | >f+++++++++ logs/dpkg-l.txt
2026-08-26 20:25:12.727170 | controller | >f+++++++++ logs/iptables.txt
2026-08-26 20:25:12.727202 | controller | >f+++++++++ logs/listen53.txt
2026-08-26 20:25:12.727229 | controller | >f+++++++++ logs/local_conf.txt
2026-08-26 20:25:12.727254 | controller | >f+++++++++ logs/mount.txt
2026-08-26 20:25:12.727280 | controller | >f+++++++++ logs/performance.json
2026-08-26 20:25:12.727306 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-08-26 20:25:12.727377 | controller | >f+++++++++ logs/resolv_conf.txt
2026-08-26 20:25:12.727405 | controller | >f+++++++++ logs/services.txt
2026-08-26 20:25:12.727437 | controller | >f+++++++++ logs/sudoers
2026-08-26 20:25:12.727466 | controller | >f+++++++++ logs/syslog.txt
2026-08-26 20:25:12.727492 | controller | cd+++++++++ logs/apache/
2026-08-26 20:25:12.727518 | controller | cd+++++++++ logs/apache_config/
2026-08-26 20:25:12.727570 | controller | cd+++++++++ logs/etc/
2026-08-26 20:25:12.727601 | controller | cd+++++++++ logs/sudoers.d/
2026-08-26 20:25:12.727627 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-08-26 20:25:12.727653 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-08-26 20:25:12.727679 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-08-26 20:25:12.727740 | controller | >f+++++++++ logs/sudoers.d/README
2026-08-26 20:25:12.727778 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-08-26 20:25:12.741560 | 
2026-08-26 20:25:12.741626 | TASK [Check if a tempest log exits]
2026-08-26 20:25:13.924275 | controller | ok
2026-08-26 20:25:13.929681 | 
2026-08-26 20:25:13.929748 | TASK [Link post-devstack tempest.log]
2026-08-26 20:25:13.955815 | controller | skipping: Conditional result was False
2026-08-26 20:25:13.964838 | 
2026-08-26 20:25:13.964928 | TASK [Capture most recent qemu crash dump, if any]
2026-08-26 20:25:14.249485 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-08-26 20:25:14.510411 | controller | ERROR
2026-08-26 20:25:14.510683 | controller | {
2026-08-26 20:25:14.510712 | controller |   "delta": "0:00:00.009024",
2026-08-26 20:25:14.510733 | controller |   "end": "2026-08-26 20:25:14.250141",
2026-08-26 20:25:14.510752 | controller |   "msg": "non-zero return code",
2026-08-26 20:25:14.510770 | controller |   "rc": 127,
2026-08-26 20:25:14.510790 | controller |   "start": "2026-08-26 20:25:14.241117"
2026-08-26 20:25:14.510809 | controller | }
2026-08-26 20:25:14.510833 | controller | ERROR: Ignoring Errors
2026-08-26 20:25:14.516944 | 
2026-08-26 20:25:14.517042 | TASK [Collect Guru Meditation Reports from OpenStack services]
2026-08-26 20:25:15.058972 | controller | ERROR
2026-08-26 20:25:15.059285 | controller | {
2026-08-26 20:25:15.059325 | controller |   "delta": "0:00:00.107200",
2026-08-26 20:25:15.059354 | controller |   "end": "2026-08-26 20:25:14.896045",
2026-08-26 20:25:15.059379 | controller |   "msg": "non-zero return code",
2026-08-26 20:25:15.059404 | controller |   "rc": -12,
2026-08-26 20:25:15.059428 | controller |   "start": "2026-08-26 20:25:14.788845"
2026-08-26 20:25:15.059464 | controller | }
2026-08-26 20:25:15.059500 | controller | ERROR: Ignoring Errors
2026-08-26 20:25:15.061579 | 
2026-08-26 20:25:15.061651 | PLAY RECAP
2026-08-26 20:25:15.061722 | controller | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-08-26 20:25:15.061753 | 
2026-08-26 20:25:15.282054 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:25:15.535364 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:25:17.872415 | 
2026-08-26 20:25:17.872567 | PLAY [all]
2026-08-26 20:25:17.886411 | 
2026-08-26 20:25:17.886494 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-26 20:25:17.942603 | controller | skipping: Conditional result was False
2026-08-26 20:25:17.953332 | 
2026-08-26 20:25:17.953409 | TASK [fetch-output : Set log path for single node]
2026-08-26 20:25:18.002269 | controller | ok
2026-08-26 20:25:18.008841 | 
2026-08-26 20:25:18.008911 | LOOP [fetch-output : Ensure local output dirs]
2026-08-26 20:25:18.745660 | controller -> localhost | ok: "/var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/work/logs"
2026-08-26 20:25:18.993504 | controller -> localhost | changed: "/var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/work/artifacts"
2026-08-26 20:25:19.340198 | controller -> localhost | changed: "/var/lib/zuul/builds/ee7e5d7abd594f62be6ec6081694b7f9/work/docs"
2026-08-26 20:25:19.364859 | 
2026-08-26 20:25:19.365126 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-26 20:25:20.108507 | controller | changed: .d..t...... ./
2026-08-26 20:25:20.108776 | controller | changed: All items complete
2026-08-26 20:25:20.108805 | 
2026-08-26 20:25:20.628757 | controller | changed: .d..t...... ./
2026-08-26 20:25:21.142081 | controller | changed: .d..t...... ./
2026-08-26 20:25:21.166374 | 
2026-08-26 20:25:21.166536 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-26 20:25:21.764210 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.033419
2026-08-26 20:25:22.061107 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007737
2026-08-26 20:25:22.076829 | 
2026-08-26 20:25:22.076960 | PLAY [all]
2026-08-26 20:25:22.089000 | 
2026-08-26 20:25:22.089128 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-26 20:25:22.594277 | controller | changed
2026-08-26 20:25:22.603800 | 
2026-08-26 20:25:22.603860 | PLAY RECAP
2026-08-26 20:25:22.603913 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 20:25:22.603934 | 
2026-08-26 20:25:22.760897 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:25:22.774865 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-26 20:25:24.331096 | 
2026-08-26 20:25:24.331256 | PLAY [localhost]
2026-08-26 20:25:24.366638 | 
2026-08-26 20:25:24.366743 | TASK [Generate Zuul manifest]
2026-08-26 20:25:24.393471 | localhost | ok
2026-08-26 20:25:24.599052 | 
2026-08-26 20:25:24.599258 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-26 20:25:25.062016 | localhost | changed
2026-08-26 20:25:25.074033 | 
2026-08-26 20:25:25.074108 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-26 20:25:25.194736 | localhost | ok
2026-08-26 20:25:25.202833 | 
2026-08-26 20:25:25.202904 | TASK [Upload logs]
2026-08-26 20:25:25.223100 | localhost | ok
2026-08-26 20:25:25.496281 | 
2026-08-26 20:25:25.496431 | TASK [Set zuul-log-path fact]
2026-08-26 20:25:25.516682 | localhost | ok
2026-08-26 20:25:25.539581 | 
2026-08-26 20:25:25.539651 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:25:25.570222 | localhost | ok
2026-08-26 20:25:25.580547 | 
2026-08-26 20:25:25.580612 | TASK [upload-logs : Create log directories]
2026-08-26 20:25:26.028363 | localhost | changed
2026-08-26 20:25:26.037289 | 
2026-08-26 20:25:26.037403 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-26 20:25:26.403086 | localhost -> localhost | ok: Runtime: 0:00:00.005980
2026-08-26 20:25:26.409900 | 
2026-08-26 20:25:26.409965 | TASK [upload-logs : Upload logs to log server]
2026-08-26 20:25:26.914283 | localhost | Output suppressed because no_log was given
2026-08-26 20:25:26.919572 | 
2026-08-26 20:25:26.919647 | LOOP [upload-logs : Compress console log and json output]
2026-08-26 20:25:26.970447 | localhost | skipping: Conditional result was False
2026-08-26 20:25:26.981831 | localhost | skipping: Conditional result was False
2026-08-26 20:25:26.995783 | 
2026-08-26 20:25:26.995935 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-26 20:25:27.046089 | localhost | skipping: Conditional result was False
2026-08-26 20:25:27.046390 | 
2026-08-26 20:25:27.051078 | localhost | skipping: Conditional result was False
2026-08-26 20:25:27.061667 | 
2026-08-26 20:25:27.061784 | LOOP [upload-logs : Upload console log and json output]