2026-08-26 20:16:34.061498 | Job console starting
2026-08-26 20:16:34.072654 | Updating git repos
2026-08-26 20:16:36.412030 | Cloning repos into workspace
2026-08-26 20:16:59.970567 | Restoring repo states
2026-08-26 20:17:02.608428 | Merging changes
2026-08-26 20:17:03.778446 | Checking out repos
2026-08-26 20:17:15.428378 | Preparing playbooks
2026-08-26 20:17:28.038056 | Running Ansible setup
2026-08-26 20:17:33.268803 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:17:34.167399 | 
2026-08-26 20:17:34.167524 | PLAY [localhost]
2026-08-26 20:17:34.175134 | 
2026-08-26 20:17:34.175203 | TASK [Gathering Facts]
2026-08-26 20:17:35.429282 | localhost | ok
2026-08-26 20:17:35.440161 | 
2026-08-26 20:17:35.440289 | TASK [Setup log path fact]
2026-08-26 20:17:35.464519 | localhost | ok
2026-08-26 20:17:35.477940 | 
2026-08-26 20:17:35.478270 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:17:35.510382 | localhost | ok
2026-08-26 20:17:35.518944 | 
2026-08-26 20:17:35.519020 | TASK [emit-job-header : Print job information]
2026-08-26 20:17:35.562006 | # Job Information
2026-08-26 20:17:35.562259 | Ansible Version: 2.16.18
2026-08-26 20:17:35.562328 | Job: magnum-cluster-api-hydrophone-v1.33.12-calico
2026-08-26 20:17:35.562378 | Pipeline: check
2026-08-26 20:17:35.562422 | Executor: 2d72f0692154
2026-08-26 20:17:35.562465 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1127
2026-08-26 20:17:35.562514 | Event ID: 57f65930-a189-11f1-9dc6-7e9366e8c4f0
2026-08-26 20:17:35.567561 | 
2026-08-26 20:17:35.567638 | LOOP [emit-job-header : Print node information]
2026-08-26 20:17:35.678528 | localhost | ok:
2026-08-26 20:17:35.678732 | localhost | # Node Information
2026-08-26 20:17:35.678772 | localhost | Inventory Hostname: controller
2026-08-26 20:17:35.678803 | localhost | Hostname: np0000191237
2026-08-26 20:17:35.678831 | localhost | Username: zuul
2026-08-26 20:17:35.678861 | localhost | Distro: Ubuntu 24.04
2026-08-26 20:17:35.678889 | localhost | Provider: yul1
2026-08-26 20:17:35.678916 | localhost | Region: ca-ymq-1
2026-08-26 20:17:35.678942 | localhost | Label: ubuntu-noble-16
2026-08-26 20:17:35.678968 | localhost | Product Name: OpenStack Nova
2026-08-26 20:17:35.678993 | localhost | Interface IP: 162.253.55.136
2026-08-26 20:17:35.696663 | 
2026-08-26 20:17:35.696760 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 20:17:36.133397 | localhost -> localhost | changed
2026-08-26 20:17:36.145910 | 
2026-08-26 20:17:36.146032 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 20:17:37.132614 | localhost -> localhost | changed
2026-08-26 20:17:37.147919 | 
2026-08-26 20:17:37.147987 | PLAY [all]
2026-08-26 20:17:37.156639 | 
2026-08-26 20:17:37.156708 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 20:17:37.424904 | controller -> localhost | ok
2026-08-26 20:17:37.436480 | 
2026-08-26 20:17:37.436552 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 20:17:37.480862 | controller | ok
2026-08-26 20:17:37.495497 | controller | included: /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 20:17:37.611334 | 
2026-08-26 20:17:37.611437 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 20:17:40.283668 | controller -> localhost | Generating public/private rsa key pair.
2026-08-26 20:17:40.283866 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/work/8cffc7c10c58432b99b9f6997c6c3d61_id_rsa
2026-08-26 20:17:40.283897 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/work/8cffc7c10c58432b99b9f6997c6c3d61_id_rsa.pub
2026-08-26 20:17:40.283920 | controller -> localhost | The key fingerprint is:
2026-08-26 20:17:40.283942 | controller -> localhost | SHA256:R3aENxJmypEOriMDJXeq736leSkxzGBpypj+K/qwGN0 zuul-build-sshkey
2026-08-26 20:17:40.283981 | controller -> localhost | The key's randomart image is:
2026-08-26 20:17:40.284003 | controller -> localhost | +---[RSA 3072]----+
2026-08-26 20:17:40.284029 | controller -> localhost | |        ..+o.    |
2026-08-26 20:17:40.284052 | controller -> localhost | |. o . ...=o.o    |
2026-08-26 20:17:40.284073 | controller -> localhost | | + + . oo oo..   |
2026-08-26 20:17:40.284093 | controller -> localhost | |. *   . .o .     |
2026-08-26 20:17:40.284113 | controller -> localhost | |oB + .  S .      |
2026-08-26 20:17:40.284133 | controller -> localhost | |=oo.B .  .       |
2026-08-26 20:17:40.284153 | controller -> localhost | |+..oEB .         |
2026-08-26 20:17:40.284174 | controller -> localhost | |.*. = o          |
2026-08-26 20:17:40.284196 | controller -> localhost | |=+*+.o           |
2026-08-26 20:17:40.284217 | controller -> localhost | +----[SHA256]-----+
2026-08-26 20:17:40.284281 | controller -> localhost | ok: Runtime: 0:00:02.188439
2026-08-26 20:17:40.291206 | 
2026-08-26 20:17:40.291280 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 20:17:40.327466 | controller | ok
2026-08-26 20:17:40.339693 | controller | included: /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 20:17:40.348517 | 
2026-08-26 20:17:40.348582 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 20:17:40.374781 | controller | skipping: Conditional result was False
2026-08-26 20:17:40.388645 | 
2026-08-26 20:17:40.388776 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 20:17:40.969183 | controller | changed
2026-08-26 20:17:40.975223 | 
2026-08-26 20:17:40.975286 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 20:17:41.205446 | controller | ok
2026-08-26 20:17:41.211403 | 
2026-08-26 20:17:41.211471 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 20:17:41.873221 | controller | changed
2026-08-26 20:17:41.884382 | 
2026-08-26 20:17:41.884457 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 20:17:42.620113 | controller | changed
2026-08-26 20:17:42.630307 | 
2026-08-26 20:17:42.630457 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 20:17:42.657240 | controller | skipping: Conditional result was False
2026-08-26 20:17:42.668754 | 
2026-08-26 20:17:42.668891 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 20:17:43.102568 | controller -> localhost | changed
2026-08-26 20:17:43.117903 | 
2026-08-26 20:17:43.117982 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 20:17:43.605330 | controller -> localhost | Identity added: /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/work/8cffc7c10c58432b99b9f6997c6c3d61_id_rsa (zuul-build-sshkey)
2026-08-26 20:17:43.605586 | controller -> localhost | ok: Runtime: 0:00:00.014488
2026-08-26 20:17:43.612821 | 
2026-08-26 20:17:43.612891 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 20:17:43.963003 | controller | ok
2026-08-26 20:17:43.973859 | 
2026-08-26 20:17:43.973995 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 20:17:44.003567 | controller | skipping: Conditional result was False
2026-08-26 20:17:44.028690 | 
2026-08-26 20:17:44.028865 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 20:17:44.422498 | controller | ok
2026-08-26 20:17:44.428908 | 
2026-08-26 20:17:44.429010 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 20:20:50.838776 | controller | Output suppressed because no_log was given
2026-08-26 20:20:51.195851 | 
2026-08-26 20:20:51.195997 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 20:20:51.694235 | controller | ok: "logs"
2026-08-26 20:20:51.694499 | controller | ok: All items complete
2026-08-26 20:20:51.694527 | 
2026-08-26 20:20:51.924144 | controller | ok: "artifacts"
2026-08-26 20:20:52.132058 | controller | ok: "docs"
2026-08-26 20:20:52.145366 | 
2026-08-26 20:20:52.145533 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 20:20:52.377059 | controller | changed: "logs"
2026-08-26 20:20:52.569274 | controller | changed: "artifacts"
2026-08-26 20:20:52.783800 | controller | changed: "docs"
2026-08-26 20:20:52.799769 | 
2026-08-26 20:20:52.799982 | PLAY RECAP
2026-08-26 20:20:52.800043 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 20:20:52.800074 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:20:52.800101 | 
2026-08-26 20:20:53.490461 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:20:53.732356 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:20:55.579778 | 
2026-08-26 20:20:55.580034 | PLAY [all]
2026-08-26 20:20:55.703224 | 
2026-08-26 20:20:55.704276 | TASK [Fix the permissions of the zuul home directory]
2026-08-26 20:20:56.457880 | controller | changed
2026-08-26 20:20:56.472339 | 
2026-08-26 20:20:56.472947 | TASK [Gather minimum local MTU]
2026-08-26 20:20:56.629497 | controller | ok
2026-08-26 20:20:56.635132 | 
2026-08-26 20:20:56.635231 | TASK [Calculate external_bridge_mtu]
2026-08-26 20:20:56.720904 | controller | ok
2026-08-26 20:20:56.735047 | 
2026-08-26 20:20:56.735147 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-08-26 20:20:56.856059 | controller | skipping: Conditional result was False
2026-08-26 20:20:56.975569 | 
2026-08-26 20:20:56.975683 | TASK [configure-swap : Get ephemeral0 device node]
2026-08-26 20:20:57.571432 | controller | ok: Runtime: 0:00:00.006844
2026-08-26 20:20:57.579010 | 
2026-08-26 20:20:57.580193 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-08-26 20:20:57.633095 | controller | skipping: Conditional result was False
2026-08-26 20:20:57.655485 | 
2026-08-26 20:20:57.655809 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-08-26 20:20:57.733671 | controller | skipping: Conditional result was False
2026-08-26 20:20:57.743425 | 
2026-08-26 20:20:57.743570 | TASK [configure-swap : Setup swap file on root device]
2026-08-26 20:20:57.845868 | controller | ok
2026-08-26 20:20:57.879923 | controller | included: /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-08-26 20:20:57.927961 | 
2026-08-26 20:20:57.928157 | TASK [configure-swap : Calculate required swap]
2026-08-26 20:20:58.212249 | controller | ok
2026-08-26 20:20:58.487189 | 
2026-08-26 20:20:58.487339 | TASK [configure-swap : Get root filesystem]
2026-08-26 20:20:58.900210 | controller | ext4
2026-08-26 20:20:59.154826 | controller | ok: Runtime: 0:00:00.011577
2026-08-26 20:20:59.161453 | 
2026-08-26 20:20:59.161530 | TASK [configure-swap : Save root filesystem]
2026-08-26 20:20:59.207933 | controller | ok
2026-08-26 20:20:59.214825 | 
2026-08-26 20:20:59.214910 | TASK [configure-swap : Debug the root_filesystem variable]
2026-08-26 20:20:59.251150 | controller | ok:
2026-08-26 20:20:59.251367 | controller | {
2026-08-26 20:20:59.251477 | controller |   "root_filesystem": "ext4"
2026-08-26 20:20:59.251502 | controller | }
2026-08-26 20:20:59.257837 | 
2026-08-26 20:20:59.257913 | TASK [configure-swap : Create swap backing file]
2026-08-26 20:21:08.571441 | controller | 8192+0 records in
2026-08-26 20:21:08.571782 | controller | 8192+0 records out
2026-08-26 20:21:08.571810 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 8.99795 s, 955 MB/s
2026-08-26 20:21:08.812149 | controller | ok: Runtime: 0:00:09.007748
2026-08-26 20:21:08.818319 | 
2026-08-26 20:21:08.818388 | TASK [configure-swap : Ensure swapfile perms]
2026-08-26 20:21:09.114921 | controller | changed
2026-08-26 20:21:09.125764 | 
2026-08-26 20:21:09.125845 | TASK [configure-swap : Make swapfile]
2026-08-26 20:21:16.796470 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-08-26 20:21:16.796611 | controller | no label, UUID=c7e90583-0cd2-4f9a-aee7-a714bd27171c
2026-08-26 20:21:17.191518 | controller | ok: Runtime: 0:00:07.390821
2026-08-26 20:21:17.248256 | 
2026-08-26 20:21:17.248653 | TASK [configure-swap : Write swap to fstab]
2026-08-26 20:21:18.007134 | controller | changed
2026-08-26 20:21:18.175603 | 
2026-08-26 20:21:18.175778 | TASK [configure-swap : Add all swap]
2026-08-26 20:21:18.722135 | controller | ok: Runtime: 0:00:00.016789
2026-08-26 20:21:18.836757 | 
2026-08-26 20:21:18.836887 | TASK [configure-swap : Debug the swap_required variable]
2026-08-26 20:21:18.878903 | controller | ok:
2026-08-26 20:21:18.879090 | controller | {
2026-08-26 20:21:18.879119 | controller |   "swap_required": "8192"
2026-08-26 20:21:18.879141 | controller | }
2026-08-26 20:21:18.885435 | 
2026-08-26 20:21:18.943381 | TASK [configure-swap : Set swappiness]
2026-08-26 20:21:19.475442 | controller | changed
2026-08-26 20:21:19.482477 | 
2026-08-26 20:21:19.482554 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-08-26 20:21:19.513708 | controller | ok:
2026-08-26 20:21:19.513834 | controller | {
2026-08-26 20:21:19.513858 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-08-26 20:21:19.513882 | controller | }
2026-08-26 20:21:19.524710 | 
2026-08-26 20:21:19.524775 | TASK [setup-stack-user : Create stack group]
2026-08-26 20:21:20.156335 | controller | changed
2026-08-26 20:21:20.161967 | 
2026-08-26 20:21:20.162035 | TASK [setup-stack-user : Create the stack user home folder]
2026-08-26 20:21:20.439026 | controller | changed
2026-08-26 20:21:20.446563 | 
2026-08-26 20:21:20.446635 | TASK [setup-stack-user : Create stack user]
2026-08-26 20:21:21.404176 | controller | changed
2026-08-26 20:21:21.515040 | 
2026-08-26 20:21:21.515278 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-08-26 20:21:21.781275 | controller | changed
2026-08-26 20:21:21.819095 | 
2026-08-26 20:21:21.819283 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-08-26 20:21:23.542600 | controller | changed
2026-08-26 20:21:23.549764 | 
2026-08-26 20:21:23.549837 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-08-26 20:21:23.885250 | controller | changed
2026-08-26 20:21:24.143871 | 
2026-08-26 20:21:24.144053 | TASK [setup-tempest-user : Create tempest group]
2026-08-26 20:21:24.656648 | controller | changed
2026-08-26 20:21:24.662276 | 
2026-08-26 20:21:24.662345 | TASK [setup-tempest-user : Create tempest user]
2026-08-26 20:21:25.508678 | controller | changed
2026-08-26 20:21:25.522981 | 
2026-08-26 20:21:25.563555 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-08-26 20:21:26.462344 | controller | changed
2026-08-26 20:21:26.481531 | 
2026-08-26 20:21:26.481678 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-08-26 20:21:26.832003 | controller | ok: Not all paths examined, check warnings for details
2026-08-26 20:21:26.857164 | 
2026-08-26 20:21:26.857395 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-08-26 20:21:28.136577 | controller | ok: Item: Runtime: 0:00:00.950206
2026-08-26 20:21:28.827552 | controller | ok: Item: Runtime: 0:00:00.445937
2026-08-26 20:21:29.637370 | controller | ok: Item: Runtime: 0:00:00.550166
2026-08-26 20:21:30.241191 | controller | ok: Item: Runtime: 0:00:00.366255
2026-08-26 20:21:30.650900 | controller | ok: Item: Runtime: 0:00:00.172763
2026-08-26 20:21:30.989126 | controller | ok: Item: Runtime: 0:00:00.063417
2026-08-26 20:21:32.420941 | controller | ok: Item: Runtime: 0:00:01.180394
2026-08-26 20:21:35.572560 | controller | ok: Item: Runtime: 0:00:02.913022
2026-08-26 20:21:36.780130 | controller | ok: Item: Runtime: 0:00:00.251266
2026-08-26 20:21:37.339088 | controller | ok: Item: Runtime: 0:00:00.314922
2026-08-26 20:21:37.875492 | controller | ok: Item: Runtime: 0:00:00.265592
2026-08-26 20:21:38.370521 | controller | ok: Item: Runtime: 0:00:00.239866
2026-08-26 20:21:39.313583 | controller | ok: Item: Runtime: 0:00:00.630085
2026-08-26 20:21:40.122512 | controller | ok: Item: Runtime: 0:00:00.551124
2026-08-26 20:21:40.862004 | controller | ok: Item: Runtime: 0:00:00.469218
2026-08-26 20:21:41.302583 | controller | ok: Item: Runtime: 0:00:00.185270
2026-08-26 20:21:41.338100 | 
2026-08-26 20:21:41.338272 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-08-26 20:21:41.579597 | controller | ok: All paths examined
2026-08-26 20:21:41.706219 | 
2026-08-26 20:21:41.706418 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-08-26 20:21:42.027008 | controller | ok: All paths examined
2026-08-26 20:21:42.037256 | 
2026-08-26 20:21:42.040227 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-08-26 20:21:42.590853 | controller | ok: Item: Runtime: 0:00:00.205874
2026-08-26 20:21:42.591087 | controller | changed: All items complete
2026-08-26 20:21:42.591116 | 
2026-08-26 20:21:43.119428 | controller | ok: Item: Runtime: 0:00:00.270837
2026-08-26 20:21:43.141956 | 
2026-08-26 20:21:43.142307 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-08-26 20:21:43.237928 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.240104 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.243553 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.246798 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.250329 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.253952 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.257721 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.261371 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.264471 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.268057 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.271540 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.275185 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.280934 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.282774 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.287293 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.289941 | controller | skipping: Conditional result was False
2026-08-26 20:21:43.393454 | 
2026-08-26 20:21:43.393675 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-08-26 20:21:46.250792 | controller | changed
2026-08-26 20:21:46.262632 | 
2026-08-26 20:21:46.262741 | TASK [setup-devstack-log-dir : Create logs directory]
2026-08-26 20:21:46.553086 | controller | changed
2026-08-26 20:21:46.564664 | 
2026-08-26 20:21:46.564747 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-08-26 20:21:46.855838 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-08-26 20:21:47.224923 | controller | ERROR
2026-08-26 20:21:47.225143 | controller | {
2026-08-26 20:21:47.225189 | controller |   "delta": "0:00:00.010584",
2026-08-26 20:21:47.225218 | controller |   "end": "2026-08-26 20:21:46.856527",
2026-08-26 20:21:47.225245 | controller |   "msg": "non-zero return code",
2026-08-26 20:21:47.225271 | controller |   "rc": 1,
2026-08-26 20:21:47.225297 | controller |   "start": "2026-08-26 20:21:46.845943"
2026-08-26 20:21:47.225323 | controller | }
2026-08-26 20:21:47.225356 | controller | ERROR: Ignoring Errors
2026-08-26 20:21:47.232765 | 
2026-08-26 20:21:47.232835 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-08-26 20:21:47.510279 | controller | ok
2026-08-26 20:21:47.524767 | 
2026-08-26 20:21:47.524868 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-08-26 20:21:47.784544 | controller | /usr/bin/journalctl
2026-08-26 20:21:48.076501 | controller | ok: Runtime: 0:00:00.007863
2026-08-26 20:21:48.083009 | 
2026-08-26 20:21:48.083079 | TASK [start-fresh-logging : Get current date]
2026-08-26 20:21:48.373088 | controller | 2026-08-26 20:21:48
2026-08-26 20:21:48.622852 | controller | ok: Runtime: 0:00:00.010472
2026-08-26 20:21:48.629189 | 
2026-08-26 20:21:48.629257 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-08-26 20:21:49.340711 | controller | changed
2026-08-26 20:21:49.347917 | 
2026-08-26 20:21:49.347986 | TASK [start-fresh-logging : Stop rsyslog]
2026-08-26 20:21:49.384246 | controller | skipping: Conditional result was False
2026-08-26 20:21:49.396927 | 
2026-08-26 20:21:49.397260 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-08-26 20:21:49.936985 | controller | skipping: Conditional result was False
2026-08-26 20:21:49.949690 | 
2026-08-26 20:21:49.949867 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-08-26 20:21:50.487430 | controller | skipping: Conditional result was False
2026-08-26 20:21:50.494584 | 
2026-08-26 20:21:50.494660 | TASK [start-fresh-logging : Recreate syslog file]
2026-08-26 20:21:50.531475 | controller | skipping: Conditional result was False
2026-08-26 20:21:50.539280 | 
2026-08-26 20:21:50.539432 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-08-26 20:21:52.102916 | controller | skipping: Conditional result was False
2026-08-26 20:21:52.110132 | 
2026-08-26 20:21:52.110203 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-08-26 20:21:52.723458 | controller | skipping: Conditional result was False
2026-08-26 20:21:52.954037 | 
2026-08-26 20:21:52.954151 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-08-26 20:21:52.980094 | controller | skipping: Conditional result was False
2026-08-26 20:21:52.987587 | 
2026-08-26 20:21:52.987658 | TASK [start-fresh-logging : Recreate kern.log file]
2026-08-26 20:21:53.023318 | controller | skipping: Conditional result was False
2026-08-26 20:21:53.031677 | 
2026-08-26 20:21:53.031752 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-08-26 20:21:53.567021 | controller | skipping: Conditional result was False
2026-08-26 20:21:53.573975 | 
2026-08-26 20:21:53.574288 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-08-26 20:21:54.115361 | controller | skipping: Conditional result was False
2026-08-26 20:21:54.122411 | 
2026-08-26 20:21:54.122500 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-08-26 20:21:54.188479 | controller | skipping: Conditional result was False
2026-08-26 20:21:54.196249 | 
2026-08-26 20:21:54.196327 | TASK [start-fresh-logging : Start rsyslog]
2026-08-26 20:21:54.233957 | controller | skipping: Conditional result was False
2026-08-26 20:21:54.247324 | 
2026-08-26 20:21:54.247403 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-08-26 20:21:54.892592 | controller | ok
2026-08-26 20:21:54.915458 | 
2026-08-26 20:21:54.915564 | PLAY RECAP
2026-08-26 20:21:54.915615 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-08-26 20:21:54.915638 | 
2026-08-26 20:21:55.268238 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:21:55.313596 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:21:56.545949 | 
2026-08-26 20:21:56.546171 | PLAY [all]
2026-08-26 20:21:56.591296 | 
2026-08-26 20:21:56.591500 | TASK [Install curl]
2026-08-26 20:22:08.184186 | controller | ok
2026-08-26 20:22:08.193613 | 
2026-08-26 20:22:08.193740 | TASK [ensure-rust : Use rustup]
2026-08-26 20:22:08.234281 | controller | ok
2026-08-26 20:22:08.267904 | controller | included: /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-08-26 20:22:08.276913 | 
2026-08-26 20:22:08.277021 | TASK [ensure-rust : Install Rust]
2026-08-26 20:22:11.409863 | controller | info: downloading installer
2026-08-26 20:22:30.255696 | controller | warn: curl: (6) Could not resolve host: static.rust-lang.org
2026-08-26 20:22:30.281466 | controller | warn: It looks like you have an existing rustup settings file at:
2026-08-26 20:22:30.281514 | controller | warn: /opt/rust/settings.toml
2026-08-26 20:22:30.281534 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-08-26 20:22:30.281546 | controller | warn: instead of the one inferred from the default host triple.
2026-08-26 20:22:30.329975 | controller | info: profile set to default
2026-08-26 20:22:30.330048 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-08-26 20:22:30.332116 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-08-26 20:22:51.256512 | controller | error: could not download file from 'https://static.rust-lang.org/dist/channel-rust-stable.toml' to '/opt/rust/tmp/6__gpf1un_eg9oh__file.toml': error downloading file: error sending request for url (https://static.rust-lang.org/dist/channel-rust-stable.toml): client error (Connect): dns error: failed to lookup address information: Temporary failure in name resolution
2026-08-26 20:22:52.037825 | controller | ERROR
2026-08-26 20:22:52.038069 | controller | {
2026-08-26 20:22:52.038168 | controller |   "delta": "0:00:42.369735",
2026-08-26 20:22:52.038266 | controller |   "end": "2026-08-26 20:22:51.285956",
2026-08-26 20:22:52.038348 | controller |   "msg": "non-zero return code",
2026-08-26 20:22:52.038376 | controller |   "rc": 1,
2026-08-26 20:22:52.038406 | controller |   "start": "2026-08-26 20:22:08.916221"
2026-08-26 20:22:52.038434 | controller | }
failure
2026-08-26 20:22:52.040799 | 
2026-08-26 20:22:52.040948 | PLAY RECAP
2026-08-26 20:22:52.041068 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:22:52.041096 | 
2026-08-26 20:22:52.220729 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:22:52.905168 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:22:53.863458 | 
2026-08-26 20:22:53.863594 | PLAY [all]
2026-08-26 20:22:53.956736 | 
2026-08-26 20:22:53.956904 | LOOP [Copy individual Hydrophone result files]
2026-08-26 20:22:55.082320 | controller | ERROR: Item: e2e.log
2026-08-26 20:22:55.082715 | controller | {
2026-08-26 20:22:55.082771 | controller |   "ansible_loop_var": "item",
2026-08-26 20:22:55.082808 | controller |   "item": "e2e.log",
2026-08-26 20:22:55.082867 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-08-26 20:22:55.082894 | controller | }
2026-08-26 20:22:55.390621 | controller | ERROR: Item: junit_01.xml
2026-08-26 20:22:55.402871 | controller | {
2026-08-26 20:22:55.403042 | controller |   "ansible_loop_var": "item",
2026-08-26 20:22:55.403080 | controller |   "item": "junit_01.xml",
2026-08-26 20:22:55.403107 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-08-26 20:22:55.403134 | controller | }
2026-08-26 20:22:55.418481 | controller | ERROR: Ignoring Errors
2026-08-26 20:22:55.425183 | 
2026-08-26 20:22:55.425253 | TASK [Copy Hydrophone results tarball to output folder]
2026-08-26 20:22:55.763572 | controller | ERROR
2026-08-26 20:22:55.763805 | controller | {
2026-08-26 20:22:55.763836 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-08-26 20:22:55.763859 | controller | }
2026-08-26 20:22:55.763884 | controller | ERROR: Ignoring Errors
2026-08-26 20:22:55.789905 | 
2026-08-26 20:22:55.790087 | TASK [Return built artifacts to Zuul]
2026-08-26 20:22:55.825225 | controller | ok
2026-08-26 20:22:55.831239 | 
2026-08-26 20:22:55.831306 | TASK [Get the Docker volume ID]
2026-08-26 20:22:56.375028 | controller | ERROR
2026-08-26 20:22:56.375248 | controller | {
2026-08-26 20:22:56.375278 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-08-26 20:22:56.375300 | controller |   "rc": 2
2026-08-26 20:22:56.375320 | controller | }
2026-08-26 20:22:56.375348 | controller | ERROR: Ignoring Errors
2026-08-26 20:22:56.380921 | 
2026-08-26 20:22:56.380995 | TASK [Copy all of the pod logs]
2026-08-26 20:22:56.417283 | controller | ERROR
2026-08-26 20:22:56.417502 | controller | {
2026-08-26 20:22:56.417555 | 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/8cffc7c10c58432b99b9f6997c6c3d61/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:22:56.417583 | controller | }
2026-08-26 20:22:56.417797 | controller | ERROR: Ignoring Errors
2026-08-26 20:22:56.419161 | 
2026-08-26 20:22:56.419209 | PLAY RECAP
2026-08-26 20:22:56.419257 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-08-26 20:22:56.419280 | 
2026-08-26 20:22:56.629853 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:22:56.637886 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:22:57.309208 | 
2026-08-26 20:22:57.309341 | PLAY [all]
2026-08-26 20:22:57.328524 | 
2026-08-26 20:22:57.328608 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-08-26 20:22:57.966211 | controller | changed
2026-08-26 20:22:57.972400 | 
2026-08-26 20:22:57.972486 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-08-26 20:22:59.518967 | controller | ok: Runtime: 0:00:00.627394
2026-08-26 20:22:59.525503 | 
2026-08-26 20:22:59.525576 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-08-26 20:23:00.065556 | controller | ok: Runtime: 0:00:00.020236
2026-08-26 20:23:00.072955 | 
2026-08-26 20:23:00.073052 | TASK [export-devstack-journal : Export journal]
2026-08-26 20:23:00.653083 | controller | ok: Runtime: 0:00:00.024760
2026-08-26 20:23:00.660560 | 
2026-08-26 20:23:00.660640 | TASK [export-devstack-journal : Save journal README]
2026-08-26 20:23:01.671104 | controller | changed
2026-08-26 20:23:01.684700 | 
2026-08-26 20:23:01.684812 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-08-26 20:23:01.964742 | controller | changed
2026-08-26 20:23:01.970278 | 
2026-08-26 20:23:01.970353 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:23:02.344888 | controller | Output suppressed because no_log was given
2026-08-26 20:23:02.368239 | 
2026-08-26 20:23:02.368534 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:23:02.407528 | 
2026-08-26 20:23:02.407682 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:23:02.443922 | 
2026-08-26 20:23:02.444051 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:23:02.480123 | 
2026-08-26 20:23:02.480259 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:23:02.515155 | 
2026-08-26 20:23:02.515323 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:23:02.554948 | 
2026-08-26 20:23:02.555085 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-08-26 20:23:02.823456 | controller | changed
2026-08-26 20:23:02.829712 | 
2026-08-26 20:23:02.829781 | TASK [apache-logs-conf : Define config paths]
2026-08-26 20:23:02.865086 | controller | ok
2026-08-26 20:23:02.871860 | 
2026-08-26 20:23:02.871928 | TASK [apache-logs-conf : Discover configurations]
2026-08-26 20:23:03.112593 | controller | Output suppressed because no_log was given
2026-08-26 20:23:03.118276 | 
2026-08-26 20:23:03.118359 | LOOP [apache-logs-conf : Dereference configurations]
2026-08-26 20:23:03.155660 | 
2026-08-26 20:23:03.155882 | LOOP [apache-logs-conf : Link configurations]
2026-08-26 20:23:03.193857 | 
2026-08-26 20:23:03.194053 | TASK [capture-performance-data : Generate statistics]
2026-08-26 20:23:03.911935 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-08-26 20:23:03.932516 | controller | Using python 3.12 to install setuptools
2026-08-26 20:23:03.990575 | controller | /usr/bin/python3.12: No module named pip
2026-08-26 20:23:04.007010 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-08-26 20:23:04.237820 | controller | ERROR
2026-08-26 20:23:04.238077 | controller | {
2026-08-26 20:23:04.238124 | controller |   "delta": "0:00:00.542305",
2026-08-26 20:23:04.238154 | controller |   "end": "2026-08-26 20:23:04.009434",
2026-08-26 20:23:04.238181 | controller |   "msg": "non-zero return code",
2026-08-26 20:23:04.238212 | controller |   "rc": 127,
2026-08-26 20:23:04.238239 | controller |   "start": "2026-08-26 20:23:03.467129"
2026-08-26 20:23:04.238265 | controller | }
2026-08-26 20:23:04.238299 | controller | ERROR: Ignoring Errors
2026-08-26 20:23:04.250758 | 
2026-08-26 20:23:04.250832 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-08-26 20:23:04.549213 | controller | changed
2026-08-26 20:23:04.557576 | 
2026-08-26 20:23:04.557646 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-08-26 20:23:08.737318 | controller | Output suppressed because no_log was given
2026-08-26 20:23:08.745449 | 
2026-08-26 20:23:08.745527 | LOOP [devstack-project-conf : Copy configuration files]
2026-08-26 20:23:08.849492 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.849901 | 
2026-08-26 20:23:08.857465 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.864804 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.866669 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.868453 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.871152 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.873843 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.876842 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.879682 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.882593 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.885430 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.888208 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.890934 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.893861 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.896559 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.899314 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.902197 | controller | skipping: Conditional result was False
2026-08-26 20:23:08.917180 | controller | skipping: Conditional result was False
2026-08-26 20:23:09.006292 | 
2026-08-26 20:23:09.006435 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-08-26 20:23:09.958715 | controller | ok
2026-08-26 20:23:09.964661 | 
2026-08-26 20:23:09.964733 | TASK [devstack-project-conf : Copy configuration files]
2026-08-26 20:23:10.506582 | controller | skipping: Conditional result was False
2026-08-26 20:23:10.520846 | 
2026-08-26 20:23:10.520937 | TASK [capture-system-logs : Stage various logs and reports]
2026-08-26 20:23:10.972244 | controller | /usr/bin/python3: No module named pip
2026-08-26 20:23:12.285308 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-08-26 20:23:12.565135 | controller | ok: Runtime: 0:00:01.494253
2026-08-26 20:23:12.578117 | 
2026-08-26 20:23:12.578292 | LOOP [stage-output : Register sources]
2026-08-26 20:23:22.530601 | controller | Output suppressed because no_log was given
2026-08-26 20:23:22.545037 | 
2026-08-26 20:23:22.545133 | TASK [stage-output : Check sudo]
2026-08-26 20:23:23.089369 | controller | ok: Runtime: 0:00:00.025677
2026-08-26 20:23:23.095799 | 
2026-08-26 20:23:23.095869 | LOOP [stage-output : Set source and destination for files and folders]
2026-08-26 20:23:23.202295 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.202761 | 
2026-08-26 20:23:23.204543 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.205634 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.208491 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.252344 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.252726 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.253083 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.256380 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.258480 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.261776 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.264129 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.267658 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.271030 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.271687 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.293248 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.293752 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.294212 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.294669 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.295329 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.297761 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.300313 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.302820 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.305728 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.331745 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.332105 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.425036 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.438659 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.446380 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.457308 | controller | Output suppressed because no_log was given
2026-08-26 20:23:23.471827 | 
2026-08-26 20:23:23.471915 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-08-26 20:23:23.543170 | controller | ok
2026-08-26 20:23:23.552237 | 
2026-08-26 20:23:23.552317 | LOOP [stage-output : Ensure target folders exist]
2026-08-26 20:23:23.816430 | controller | changed: "docs"
2026-08-26 20:23:24.066420 | controller | changed: "artifacts"
2026-08-26 20:23:24.317949 | controller | ok: "logs"
2026-08-26 20:23:24.331592 | 
2026-08-26 20:23:24.331773 | LOOP [stage-output : Copy files and folders to staging folder]
2026-08-26 20:23:24.637040 | controller | ok: Item: Runtime: 0:00:00.009663
2026-08-26 20:23:24.637300 | controller | changed: All items complete
2026-08-26 20:23:24.637329 | 
2026-08-26 20:23:24.898486 | controller | ok: Item: Runtime: 0:00:00.009197
2026-08-26 20:23:25.137151 | controller | ok: Item: Runtime: 0:00:00.007934
2026-08-26 20:23:25.350266 | controller | ok: Item: Runtime: 0:00:00.005503
2026-08-26 20:23:25.619869 | controller | ok: Item: Runtime: 0:00:00.008475
2026-08-26 20:23:25.873189 | controller | ok: Item: Runtime: 0:00:00.006277
2026-08-26 20:23:26.085584 | controller | ok: Item: Runtime: 0:00:00.007944
2026-08-26 20:23:26.338617 | controller | ok: Item: Runtime: 0:00:00.007291
2026-08-26 20:23:26.683170 | controller | ok: Item: Runtime: 0:00:00.005585
2026-08-26 20:23:26.850554 | controller | ok: Item: Runtime: 0:00:00.007731
2026-08-26 20:23:27.092732 | controller | ok: Item: Runtime: 0:00:00.005372
2026-08-26 20:23:27.335172 | controller | ok: Item: Runtime: 0:00:00.004529
2026-08-26 20:23:27.572400 | controller | ok: Item: Runtime: 0:00:00.008404
2026-08-26 20:23:27.814725 | controller | ok: Item: Runtime: 0:00:00.004533
2026-08-26 20:23:28.052622 | controller | ok: Item: Runtime: 0:00:00.008158
2026-08-26 20:23:28.274587 | controller | ok: Item: Runtime: 0:00:00.008941
2026-08-26 20:23:28.303898 | 
2026-08-26 20:23:28.306445 | TASK [stage-output : Make all log files readable]
2026-08-26 20:23:28.571466 | controller | changed
2026-08-26 20:23:28.577891 | 
2026-08-26 20:23:28.577966 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-08-26 20:23:28.927840 | controller | changed: Renamed files for staging.
2026-08-26 20:23:28.933655 | 
2026-08-26 20:23:28.933751 | TASK [stage-output : Discover log files for compression]
2026-08-26 20:23:28.959029 | controller | skipping: Conditional result was False
2026-08-26 20:23:28.971374 | 
2026-08-26 20:23:28.971446 | LOOP [stage-output : Archive everything from logs]
2026-08-26 20:23:29.012721 | 
2026-08-26 20:23:29.012860 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-08-26 20:23:29.813167 | controller | changed:
2026-08-26 20:23:29.813335 | controller | created directory /var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/work/logs/controller
2026-08-26 20:23:29.813365 | controller | cd+++++++++ logs/
2026-08-26 20:23:29.813385 | controller | >f+++++++++ logs/deprecations_log.txt
2026-08-26 20:23:29.813405 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-08-26 20:23:29.813424 | controller | >f+++++++++ logs/devstack.journal.gz
2026-08-26 20:23:29.813443 | controller | >f+++++++++ logs/df.txt
2026-08-26 20:23:29.813461 | controller | >f+++++++++ logs/dpkg-l.txt
2026-08-26 20:23:29.813479 | controller | >f+++++++++ logs/iptables.txt
2026-08-26 20:23:29.813496 | controller | >f+++++++++ logs/listen53.txt
2026-08-26 20:23:29.813514 | controller | >f+++++++++ logs/local_conf.txt
2026-08-26 20:23:29.813532 | controller | >f+++++++++ logs/mount.txt
2026-08-26 20:23:29.813550 | controller | >f+++++++++ logs/performance.json
2026-08-26 20:23:29.813567 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-08-26 20:23:29.813585 | controller | >f+++++++++ logs/resolv_conf.txt
2026-08-26 20:23:29.813602 | controller | >f+++++++++ logs/services.txt
2026-08-26 20:23:29.813622 | controller | >f+++++++++ logs/sudoers
2026-08-26 20:23:29.813640 | controller | >f+++++++++ logs/syslog.txt
2026-08-26 20:23:29.813658 | controller | cd+++++++++ logs/apache/
2026-08-26 20:23:29.813676 | controller | cd+++++++++ logs/apache_config/
2026-08-26 20:23:29.813693 | controller | cd+++++++++ logs/etc/
2026-08-26 20:23:29.813711 | controller | cd+++++++++ logs/sudoers.d/
2026-08-26 20:23:29.813729 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-08-26 20:23:29.813747 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-08-26 20:23:29.813765 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-08-26 20:23:29.813783 | controller | >f+++++++++ logs/sudoers.d/README
2026-08-26 20:23:29.813800 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-08-26 20:23:29.830521 | 
2026-08-26 20:23:29.830593 | TASK [Check if a tempest log exits]
2026-08-26 20:23:30.132472 | controller | ok
2026-08-26 20:23:30.138951 | 
2026-08-26 20:23:30.139016 | TASK [Link post-devstack tempest.log]
2026-08-26 20:23:30.164518 | controller | skipping: Conditional result was False
2026-08-26 20:23:30.176607 | 
2026-08-26 20:23:30.176776 | TASK [Capture most recent qemu crash dump, if any]
2026-08-26 20:23:30.447431 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-08-26 20:23:30.727681 | controller | ERROR
2026-08-26 20:23:30.727891 | controller | {
2026-08-26 20:23:30.727921 | controller |   "delta": "0:00:00.007516",
2026-08-26 20:23:30.727942 | controller |   "end": "2026-08-26 20:23:30.448052",
2026-08-26 20:23:30.727961 | controller |   "msg": "non-zero return code",
2026-08-26 20:23:30.727979 | controller |   "rc": 127,
2026-08-26 20:23:30.727998 | controller |   "start": "2026-08-26 20:23:30.440536"
2026-08-26 20:23:30.728015 | controller | }
2026-08-26 20:23:30.728038 | controller | ERROR: Ignoring Errors
2026-08-26 20:23:30.733905 | 
2026-08-26 20:23:30.733984 | TASK [Collect Guru Meditation Reports from OpenStack services]
2026-08-26 20:23:31.292469 | controller | ERROR
2026-08-26 20:23:31.292689 | controller | {
2026-08-26 20:23:31.292719 | controller |   "delta": "0:00:00.086843",
2026-08-26 20:23:31.292740 | controller |   "end": "2026-08-26 20:23:31.123888",
2026-08-26 20:23:31.292759 | controller |   "msg": "non-zero return code",
2026-08-26 20:23:31.292777 | controller |   "rc": -12,
2026-08-26 20:23:31.292795 | controller |   "start": "2026-08-26 20:23:31.037045"
2026-08-26 20:23:31.292812 | controller | }
2026-08-26 20:23:31.292835 | controller | ERROR: Ignoring Errors
2026-08-26 20:23:31.294293 | 
2026-08-26 20:23:31.294339 | PLAY RECAP
2026-08-26 20:23:31.294385 | controller | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-08-26 20:23:31.294407 | 
2026-08-26 20:23:31.481270 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:23:31.491200 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:23:32.128752 | 
2026-08-26 20:23:32.128880 | PLAY [all]
2026-08-26 20:23:32.140976 | 
2026-08-26 20:23:32.141078 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-26 20:23:32.187489 | controller | skipping: Conditional result was False
2026-08-26 20:23:32.202221 | 
2026-08-26 20:23:32.202302 | TASK [fetch-output : Set log path for single node]
2026-08-26 20:23:32.248431 | controller | ok
2026-08-26 20:23:32.255056 | 
2026-08-26 20:23:32.255126 | LOOP [fetch-output : Ensure local output dirs]
2026-08-26 20:23:32.654868 | controller -> localhost | ok: "/var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/work/logs"
2026-08-26 20:23:32.882962 | controller -> localhost | changed: "/var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/work/artifacts"
2026-08-26 20:23:33.090880 | controller -> localhost | changed: "/var/lib/zuul/builds/8cffc7c10c58432b99b9f6997c6c3d61/work/docs"
2026-08-26 20:23:33.102576 | 
2026-08-26 20:23:33.102754 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-26 20:23:33.817511 | controller | changed: .d..t...... ./
2026-08-26 20:23:33.817736 | controller | changed: All items complete
2026-08-26 20:23:33.817763 | 
2026-08-26 20:23:34.379342 | controller | changed: .d..t...... ./
2026-08-26 20:23:34.878245 | controller | changed: .d..t...... ./
2026-08-26 20:23:34.897790 | 
2026-08-26 20:23:34.897929 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-26 20:23:35.363139 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.011695
2026-08-26 20:23:35.582401 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005887
2026-08-26 20:23:35.600135 | 
2026-08-26 20:23:35.600276 | PLAY [all]
2026-08-26 20:23:35.606860 | 
2026-08-26 20:23:35.606928 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-26 20:23:36.336728 | controller | changed
2026-08-26 20:23:36.345902 | 
2026-08-26 20:23:36.345991 | PLAY RECAP
2026-08-26 20:23:36.346101 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 20:23:36.346136 | 
2026-08-26 20:23:36.531801 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:23:36.538587 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-26 20:23:37.176346 | 
2026-08-26 20:23:37.176488 | PLAY [localhost]
2026-08-26 20:23:37.187118 | 
2026-08-26 20:23:37.187198 | TASK [Generate Zuul manifest]
2026-08-26 20:23:37.208976 | localhost | ok
2026-08-26 20:23:37.231420 | 
2026-08-26 20:23:37.231541 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-26 20:23:37.645991 | localhost | changed
2026-08-26 20:23:37.660547 | 
2026-08-26 20:23:37.660651 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-26 20:23:37.692664 | localhost | ok
2026-08-26 20:23:37.703366 | 
2026-08-26 20:23:37.703489 | TASK [Upload logs]
2026-08-26 20:23:37.738279 | localhost | ok
2026-08-26 20:23:37.879239 | 
2026-08-26 20:23:37.879394 | TASK [Set zuul-log-path fact]
2026-08-26 20:23:37.902596 | localhost | ok
2026-08-26 20:23:37.916307 | 
2026-08-26 20:23:37.916375 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:23:37.949196 | localhost | ok
2026-08-26 20:23:37.958092 | 
2026-08-26 20:23:37.958162 | TASK [upload-logs : Create log directories]
2026-08-26 20:23:38.347414 | localhost | changed
2026-08-26 20:23:38.352888 | 
2026-08-26 20:23:38.352979 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-26 20:23:38.737239 | localhost -> localhost | ok: Runtime: 0:00:00.005747
2026-08-26 20:23:38.743973 | 
2026-08-26 20:23:38.744046 | TASK [upload-logs : Upload logs to log server]
2026-08-26 20:23:39.208658 | localhost | Output suppressed because no_log was given
2026-08-26 20:23:39.212590 | 
2026-08-26 20:23:39.212658 | LOOP [upload-logs : Compress console log and json output]
2026-08-26 20:23:39.264453 | localhost | skipping: Conditional result was False
2026-08-26 20:23:39.269137 | localhost | skipping: Conditional result was False
2026-08-26 20:23:39.283554 | 
2026-08-26 20:23:39.284081 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-26 20:23:39.326288 | localhost | skipping: Conditional result was False
2026-08-26 20:23:39.329837 | 
2026-08-26 20:23:39.331266 | localhost | skipping: Conditional result was False
2026-08-26 20:23:39.342807 | 
2026-08-26 20:23:39.342950 | LOOP [upload-logs : Upload console log and json output]