2026-08-26 20:05:45.005524 | Job console starting
2026-08-26 20:05:45.021055 | Updating git repos
2026-08-26 20:05:48.643870 | Cloning repos into workspace
2026-08-26 20:05:59.167510 | Restoring repo states
2026-08-26 20:06:00.193458 | Merging changes
2026-08-26 20:06:01.194622 | Checking out repos
2026-08-26 20:06:21.818492 | Preparing playbooks
2026-08-26 20:06:33.606449 | Running Ansible setup
2026-08-26 20:06:38.445917 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:06:39.118703 | 
2026-08-26 20:06:39.139601 | PLAY [localhost]
2026-08-26 20:06:39.153276 | 
2026-08-26 20:06:39.153345 | TASK [Gathering Facts]
2026-08-26 20:06:40.027866 | localhost | ok
2026-08-26 20:06:40.035424 | 
2026-08-26 20:06:40.035500 | TASK [Setup log path fact]
2026-08-26 20:06:40.056053 | localhost | ok
2026-08-26 20:06:40.067370 | 
2026-08-26 20:06:40.067455 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:06:40.107625 | localhost | ok
2026-08-26 20:06:40.115168 | 
2026-08-26 20:06:40.115278 | TASK [emit-job-header : Print job information]
2026-08-26 20:06:40.156906 | # Job Information
2026-08-26 20:06:40.157189 | Ansible Version: 2.16.18
2026-08-26 20:06:40.157252 | Job: magnum-cluster-api-hydrophone-v1.33.12-cilium
2026-08-26 20:06:40.157282 | Pipeline: check
2026-08-26 20:06:40.157310 | Executor: 2d72f0692154
2026-08-26 20:06:40.157337 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1109
2026-08-26 20:06:40.157367 | Event ID: 08ed7fd0-a189-11f1-9bf1-6917bfd2854f
2026-08-26 20:06:40.161238 | 
2026-08-26 20:06:40.161329 | LOOP [emit-job-header : Print node information]
2026-08-26 20:06:40.265714 | localhost | ok:
2026-08-26 20:06:40.265931 | localhost | # Node Information
2026-08-26 20:06:40.265970 | localhost | Inventory Hostname: controller
2026-08-26 20:06:40.266001 | localhost | Hostname: np0000191190
2026-08-26 20:06:40.266028 | localhost | Username: zuul
2026-08-26 20:06:40.266060 | localhost | Distro: Ubuntu 24.04
2026-08-26 20:06:40.266087 | localhost | Provider: yul1
2026-08-26 20:06:40.266113 | localhost | Region: ca-ymq-1
2026-08-26 20:06:40.266138 | localhost | Label: ubuntu-noble-16
2026-08-26 20:06:40.266163 | localhost | Product Name: OpenStack Nova
2026-08-26 20:06:40.266188 | localhost | Interface IP: 162.253.55.109
2026-08-26 20:06:40.272885 | 
2026-08-26 20:06:40.273021 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 20:06:40.647084 | localhost -> localhost | changed
2026-08-26 20:06:40.656523 | 
2026-08-26 20:06:40.656603 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 20:06:41.505412 | localhost -> localhost | changed
2026-08-26 20:06:41.512615 | 
2026-08-26 20:06:41.512676 | PLAY [all]
2026-08-26 20:06:41.521496 | 
2026-08-26 20:06:41.521563 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 20:06:41.760030 | controller -> localhost | ok
2026-08-26 20:06:41.772285 | 
2026-08-26 20:06:41.772388 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 20:06:41.810484 | controller | ok
2026-08-26 20:06:41.827029 | controller | included: /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 20:06:41.833313 | 
2026-08-26 20:06:41.833410 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 20:06:42.910755 | controller -> localhost | Generating public/private rsa key pair.
2026-08-26 20:06:43.031684 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/work/8ad99e1ff85a49938d1f4dd8d5cd507c_id_rsa
2026-08-26 20:06:43.031926 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/work/8ad99e1ff85a49938d1f4dd8d5cd507c_id_rsa.pub
2026-08-26 20:06:43.032047 | controller -> localhost | The key fingerprint is:
2026-08-26 20:06:43.032133 | controller -> localhost | SHA256:DYI/XYsbLq4jtJCjo73aXyDFiYadqNeCY4fo1p0bByc zuul-build-sshkey
2026-08-26 20:06:43.032251 | controller -> localhost | The key's randomart image is:
2026-08-26 20:06:43.032306 | controller -> localhost | +---[RSA 3072]----+
2026-08-26 20:06:43.032415 | controller -> localhost | |                 |
2026-08-26 20:06:43.032524 | controller -> localhost | | + + o           |
2026-08-26 20:06:43.032583 | controller -> localhost | |o = = . . .      |
2026-08-26 20:06:43.032662 | controller -> localhost | |oo.o . o = .     |
2026-08-26 20:06:43.032809 | controller -> localhost | |=++.oEo.S o      |
2026-08-26 20:06:43.032880 | controller -> localhost | |*o+o..=o o       |
2026-08-26 20:06:43.032986 | controller -> localhost | |.* o +o.o        |
2026-08-26 20:06:43.033118 | controller -> localhost | |+oo .o+.         |
2026-08-26 20:06:43.033181 | controller -> localhost | |+o++o+.          |
2026-08-26 20:06:43.033262 | controller -> localhost | +----[SHA256]-----+
2026-08-26 20:06:43.033495 | controller -> localhost | ok: Runtime: 0:00:00.452331
2026-08-26 20:06:43.047375 | 
2026-08-26 20:06:43.047466 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 20:06:43.084261 | controller | ok
2026-08-26 20:06:43.093725 | controller | included: /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 20:06:43.101591 | 
2026-08-26 20:06:43.101668 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 20:06:43.126324 | controller | skipping: Conditional result was False
2026-08-26 20:06:43.133874 | 
2026-08-26 20:06:43.134094 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 20:06:43.687075 | controller | changed
2026-08-26 20:06:43.697150 | 
2026-08-26 20:06:43.697248 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 20:06:43.953464 | controller | ok
2026-08-26 20:06:43.969951 | 
2026-08-26 20:06:43.970238 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 20:06:44.575812 | controller | changed
2026-08-26 20:06:44.584533 | 
2026-08-26 20:06:44.587297 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 20:06:45.230777 | controller | changed
2026-08-26 20:06:45.247829 | 
2026-08-26 20:06:45.248082 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 20:06:45.275174 | controller | skipping: Conditional result was False
2026-08-26 20:06:45.291956 | 
2026-08-26 20:06:45.320647 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 20:06:45.641280 | controller -> localhost | changed
2026-08-26 20:06:45.655055 | 
2026-08-26 20:06:45.655150 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 20:06:45.911416 | controller -> localhost | Identity added: /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/work/8ad99e1ff85a49938d1f4dd8d5cd507c_id_rsa (zuul-build-sshkey)
2026-08-26 20:06:45.911639 | controller -> localhost | ok: Runtime: 0:00:00.010185
2026-08-26 20:06:45.917472 | 
2026-08-26 20:06:45.917544 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 20:06:46.245290 | controller | ok
2026-08-26 20:06:46.250882 | 
2026-08-26 20:06:46.250960 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 20:06:46.287064 | controller | skipping: Conditional result was False
2026-08-26 20:06:46.305808 | 
2026-08-26 20:06:46.305916 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 20:06:46.657149 | controller | ok
2026-08-26 20:06:46.663892 | 
2026-08-26 20:06:46.663959 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 20:07:37.348401 | controller | Output suppressed because no_log was given
2026-08-26 20:07:37.359040 | 
2026-08-26 20:07:37.359110 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 20:07:37.612005 | controller | ok: "logs"
2026-08-26 20:07:37.612318 | controller | ok: All items complete
2026-08-26 20:07:37.612347 | 
2026-08-26 20:07:37.789209 | controller | ok: "artifacts"
2026-08-26 20:07:37.992645 | controller | ok: "docs"
2026-08-26 20:07:38.011964 | 
2026-08-26 20:07:38.012110 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 20:07:38.258605 | controller | changed: "logs"
2026-08-26 20:07:38.451253 | controller | changed: "artifacts"
2026-08-26 20:07:38.632804 | controller | changed: "docs"
2026-08-26 20:07:38.654481 | 
2026-08-26 20:07:38.654618 | PLAY RECAP
2026-08-26 20:07:38.654675 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 20:07:38.654707 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:07:38.654730 | 
2026-08-26 20:07:38.790721 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:07:38.795991 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:07:39.425128 | 
2026-08-26 20:07:39.425439 | PLAY [all]
2026-08-26 20:07:39.442200 | 
2026-08-26 20:07:39.442274 | TASK [Fix the permissions of the zuul home directory]
2026-08-26 20:07:39.822201 | controller | changed
2026-08-26 20:07:39.827767 | 
2026-08-26 20:07:39.827856 | TASK [Gather minimum local MTU]
2026-08-26 20:07:39.902549 | controller | ok
2026-08-26 20:07:39.914631 | 
2026-08-26 20:07:39.914793 | TASK [Calculate external_bridge_mtu]
2026-08-26 20:07:39.968382 | controller | ok
2026-08-26 20:07:39.976374 | 
2026-08-26 20:07:39.976451 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-08-26 20:07:40.021403 | controller | skipping: Conditional result was False
2026-08-26 20:07:40.032699 | 
2026-08-26 20:07:40.032792 | TASK [configure-swap : Get ephemeral0 device node]
2026-08-26 20:07:40.592062 | controller | ok: Runtime: 0:00:00.010923
2026-08-26 20:07:40.598421 | 
2026-08-26 20:07:40.598497 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-08-26 20:07:40.633397 | controller | skipping: Conditional result was False
2026-08-26 20:07:40.647454 | 
2026-08-26 20:07:40.647663 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-08-26 20:07:40.674093 | controller | skipping: Conditional result was False
2026-08-26 20:07:40.684746 | 
2026-08-26 20:07:40.684855 | TASK [configure-swap : Setup swap file on root device]
2026-08-26 20:07:40.740925 | controller | ok
2026-08-26 20:07:40.751389 | controller | included: /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-08-26 20:07:40.757682 | 
2026-08-26 20:07:40.757752 | TASK [configure-swap : Calculate required swap]
2026-08-26 20:07:40.856399 | controller | ok
2026-08-26 20:07:40.897142 | 
2026-08-26 20:07:40.897256 | TASK [configure-swap : Get root filesystem]
2026-08-26 20:07:41.153640 | controller | ext4
2026-08-26 20:07:41.434855 | controller | ok: Runtime: 0:00:00.012058
2026-08-26 20:07:41.440827 | 
2026-08-26 20:07:41.440896 | TASK [configure-swap : Save root filesystem]
2026-08-26 20:07:41.474465 | controller | ok
2026-08-26 20:07:41.480548 | 
2026-08-26 20:07:41.480610 | TASK [configure-swap : Debug the root_filesystem variable]
2026-08-26 20:07:41.513897 | controller | ok:
2026-08-26 20:07:41.514438 | controller | {
2026-08-26 20:07:41.514497 | controller |   "root_filesystem": "ext4"
2026-08-26 20:07:41.514527 | controller | }
2026-08-26 20:07:41.521510 | 
2026-08-26 20:07:41.521573 | TASK [configure-swap : Create swap backing file]
2026-08-26 20:07:51.945025 | controller | 8192+0 records in
2026-08-26 20:07:51.945163 | controller | 8192+0 records out
2026-08-26 20:07:51.945173 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.1396 s, 847 MB/s
2026-08-26 20:07:52.066759 | controller | ok: Runtime: 0:00:10.149681
2026-08-26 20:07:52.074001 | 
2026-08-26 20:07:52.074067 | TASK [configure-swap : Ensure swapfile perms]
2026-08-26 20:07:52.348379 | controller | changed
2026-08-26 20:07:52.354601 | 
2026-08-26 20:07:52.354694 | TASK [configure-swap : Make swapfile]
2026-08-26 20:08:14.378274 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-08-26 20:08:14.378527 | controller | no label, UUID=f98e7bbf-b5cf-48ee-b2d7-786b1e43ea7a
2026-08-26 20:08:14.440361 | controller | ok: Runtime: 0:00:21.750183
2026-08-26 20:08:14.447111 | 
2026-08-26 20:08:14.447176 | TASK [configure-swap : Write swap to fstab]
2026-08-26 20:08:14.934770 | controller | changed
2026-08-26 20:08:14.942360 | 
2026-08-26 20:08:14.942449 | TASK [configure-swap : Add all swap]
2026-08-26 20:08:15.489319 | controller | ok: Runtime: 0:00:00.017550
2026-08-26 20:08:15.497681 | 
2026-08-26 20:08:15.497751 | TASK [configure-swap : Debug the swap_required variable]
2026-08-26 20:08:15.529778 | controller | ok:
2026-08-26 20:08:15.529929 | controller | {
2026-08-26 20:08:15.529967 | controller |   "swap_required": "8192"
2026-08-26 20:08:15.529996 | controller | }
2026-08-26 20:08:15.540426 | 
2026-08-26 20:08:15.540490 | TASK [configure-swap : Set swappiness]
2026-08-26 20:08:15.956069 | controller | changed
2026-08-26 20:08:15.962838 | 
2026-08-26 20:08:15.962908 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-08-26 20:08:15.994584 | controller | ok:
2026-08-26 20:08:15.994738 | controller | {
2026-08-26 20:08:15.994775 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-08-26 20:08:15.994809 | controller | }
2026-08-26 20:08:16.006585 | 
2026-08-26 20:08:16.006653 | TASK [setup-stack-user : Create stack group]
2026-08-26 20:08:16.472476 | controller | changed
2026-08-26 20:08:16.478681 | 
2026-08-26 20:08:16.478755 | TASK [setup-stack-user : Create the stack user home folder]
2026-08-26 20:08:16.739120 | controller | changed
2026-08-26 20:08:16.746540 | 
2026-08-26 20:08:16.746620 | TASK [setup-stack-user : Create stack user]
2026-08-26 20:08:17.338549 | controller | changed
2026-08-26 20:08:17.344508 | 
2026-08-26 20:08:17.344590 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-08-26 20:08:17.624330 | controller | changed
2026-08-26 20:08:17.631010 | 
2026-08-26 20:08:17.631085 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-08-26 20:08:18.766257 | controller | changed
2026-08-26 20:08:18.774686 | 
2026-08-26 20:08:18.774757 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-08-26 20:08:19.073511 | controller | changed
2026-08-26 20:08:19.083981 | 
2026-08-26 20:08:19.084114 | TASK [setup-tempest-user : Create tempest group]
2026-08-26 20:08:19.415278 | controller | changed
2026-08-26 20:08:19.421883 | 
2026-08-26 20:08:19.421947 | TASK [setup-tempest-user : Create tempest user]
2026-08-26 20:08:19.820150 | controller | changed
2026-08-26 20:08:19.829346 | 
2026-08-26 20:08:19.829417 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-08-26 20:08:20.663756 | controller | changed
2026-08-26 20:08:20.682709 | 
2026-08-26 20:08:20.682916 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-08-26 20:08:21.042436 | controller | ok: Not all paths examined, check warnings for details
2026-08-26 20:08:21.054299 | 
2026-08-26 20:08:21.054361 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-08-26 20:08:22.511045 | controller | ok: Item: Runtime: 0:00:01.122022
2026-08-26 20:08:23.178286 | controller | ok: Item: Runtime: 0:00:00.413223
2026-08-26 20:08:23.938209 | controller | ok: Item: Runtime: 0:00:00.549909
2026-08-26 20:08:24.531679 | controller | ok: Item: Runtime: 0:00:00.350389
2026-08-26 20:08:24.960237 | controller | ok: Item: Runtime: 0:00:00.184175
2026-08-26 20:08:25.269573 | controller | ok: Item: Runtime: 0:00:00.064481
2026-08-26 20:08:26.749539 | controller | ok: Item: Runtime: 0:00:01.229138
2026-08-26 20:08:30.213569 | controller | ok: Item: Runtime: 0:00:03.206131
2026-08-26 20:08:30.720230 | controller | ok: Item: Runtime: 0:00:00.256097
2026-08-26 20:08:31.262816 | controller | ok: Item: Runtime: 0:00:00.306827
2026-08-26 20:08:31.783729 | controller | ok: Item: Runtime: 0:00:00.266347
2026-08-26 20:08:32.262935 | controller | ok: Item: Runtime: 0:00:00.230243
2026-08-26 20:08:33.145515 | controller | ok: Item: Runtime: 0:00:00.610584
2026-08-26 20:08:33.925830 | controller | ok: Item: Runtime: 0:00:00.566416
2026-08-26 20:08:34.600709 | controller | ok: Item: Runtime: 0:00:00.421656
2026-08-26 20:08:35.018649 | controller | ok: Item: Runtime: 0:00:00.162353
2026-08-26 20:08:35.047381 | 
2026-08-26 20:08:35.047550 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-08-26 20:08:35.288380 | controller | ok: All paths examined
2026-08-26 20:08:35.298779 | 
2026-08-26 20:08:35.298981 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-08-26 20:08:35.560150 | controller | ok: All paths examined
2026-08-26 20:08:35.572663 | 
2026-08-26 20:08:35.572734 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-08-26 20:08:36.078347 | controller | ok: Item: Runtime: 0:00:00.205477
2026-08-26 20:08:36.078548 | controller | changed: All items complete
2026-08-26 20:08:36.078576 | 
2026-08-26 20:08:36.547664 | controller | ok: Item: Runtime: 0:00:00.237018
2026-08-26 20:08:36.556434 | 
2026-08-26 20:08:36.556644 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-08-26 20:08:36.640401 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.648661 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.654767 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.658649 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.662029 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.665920 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.670432 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.674404 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.677994 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.681357 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.684528 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.688237 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.692109 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.696328 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.700009 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.703659 | controller | skipping: Conditional result was False
2026-08-26 20:08:36.773974 | 
2026-08-26 20:08:36.774085 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-08-26 20:08:39.518662 | controller | changed
2026-08-26 20:08:39.542566 | 
2026-08-26 20:08:39.542732 | TASK [setup-devstack-log-dir : Create logs directory]
2026-08-26 20:08:39.779126 | controller | changed
2026-08-26 20:08:39.791783 | 
2026-08-26 20:08:39.791858 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-08-26 20:08:40.065574 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-08-26 20:08:40.344319 | controller | ERROR
2026-08-26 20:08:40.344502 | controller | {
2026-08-26 20:08:40.344532 | controller |   "delta": "0:00:00.010492",
2026-08-26 20:08:40.344553 | controller |   "end": "2026-08-26 20:08:40.066153",
2026-08-26 20:08:40.344572 | controller |   "msg": "non-zero return code",
2026-08-26 20:08:40.344590 | controller |   "rc": 1,
2026-08-26 20:08:40.344608 | controller |   "start": "2026-08-26 20:08:40.055661"
2026-08-26 20:08:40.344628 | controller | }
2026-08-26 20:08:40.344663 | controller | ERROR: Ignoring Errors
2026-08-26 20:08:40.352190 | 
2026-08-26 20:08:40.352262 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-08-26 20:08:40.612282 | controller | ok
2026-08-26 20:08:40.622877 | 
2026-08-26 20:08:40.622959 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-08-26 20:08:40.870827 | controller | /usr/bin/journalctl
2026-08-26 20:08:41.607854 | controller | ok: Runtime: 0:00:00.006871
2026-08-26 20:08:41.614967 | 
2026-08-26 20:08:41.615049 | TASK [start-fresh-logging : Get current date]
2026-08-26 20:08:41.894422 | controller | 2026-08-26 20:08:41
2026-08-26 20:08:42.162326 | controller | ok: Runtime: 0:00:00.009718
2026-08-26 20:08:42.172321 | 
2026-08-26 20:08:42.172400 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-08-26 20:08:42.845053 | controller | changed
2026-08-26 20:08:42.858751 | 
2026-08-26 20:08:42.858851 | TASK [start-fresh-logging : Stop rsyslog]
2026-08-26 20:08:42.888885 | controller | skipping: Conditional result was False
2026-08-26 20:08:42.903316 | 
2026-08-26 20:08:42.903436 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-08-26 20:08:43.446195 | controller | skipping: Conditional result was False
2026-08-26 20:08:43.452729 | 
2026-08-26 20:08:43.452801 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-08-26 20:08:43.986582 | controller | skipping: Conditional result was False
2026-08-26 20:08:43.999448 | 
2026-08-26 20:08:43.999561 | TASK [start-fresh-logging : Recreate syslog file]
2026-08-26 20:08:44.026393 | controller | skipping: Conditional result was False
2026-08-26 20:08:44.040339 | 
2026-08-26 20:08:44.040596 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-08-26 20:08:44.583693 | controller | skipping: Conditional result was False
2026-08-26 20:08:44.595462 | 
2026-08-26 20:08:44.595572 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-08-26 20:08:45.211850 | controller | skipping: Conditional result was False
2026-08-26 20:08:45.226596 | 
2026-08-26 20:08:45.226753 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-08-26 20:08:45.254269 | controller | skipping: Conditional result was False
2026-08-26 20:08:45.268261 | 
2026-08-26 20:08:45.268367 | TASK [start-fresh-logging : Recreate kern.log file]
2026-08-26 20:08:45.296602 | controller | skipping: Conditional result was False
2026-08-26 20:08:45.311717 | 
2026-08-26 20:08:45.311905 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-08-26 20:08:45.862982 | controller | skipping: Conditional result was False
2026-08-26 20:08:45.873178 | 
2026-08-26 20:08:45.873285 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-08-26 20:08:46.419639 | controller | skipping: Conditional result was False
2026-08-26 20:08:46.427368 | 
2026-08-26 20:08:46.427446 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-08-26 20:08:46.452818 | controller | skipping: Conditional result was False
2026-08-26 20:08:46.459304 | 
2026-08-26 20:08:46.459399 | TASK [start-fresh-logging : Start rsyslog]
2026-08-26 20:08:46.482740 | controller | skipping: Conditional result was False
2026-08-26 20:08:46.499013 | 
2026-08-26 20:08:46.499115 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-08-26 20:08:47.139715 | controller | ok
2026-08-26 20:08:47.149472 | 
2026-08-26 20:08:47.149527 | PLAY RECAP
2026-08-26 20:08:47.149575 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-08-26 20:08:47.149597 | 
2026-08-26 20:08:47.297787 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:08:47.318395 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:08:48.089747 | 
2026-08-26 20:08:48.089870 | PLAY [all]
2026-08-26 20:08:48.101829 | 
2026-08-26 20:08:48.101920 | TASK [Install curl]
2026-08-26 20:08:55.773089 | controller | ok
2026-08-26 20:08:55.780592 | 
2026-08-26 20:08:55.780703 | TASK [ensure-rust : Use rustup]
2026-08-26 20:08:55.815585 | controller | ok
2026-08-26 20:08:55.825437 | controller | included: /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-08-26 20:08:55.831701 | 
2026-08-26 20:08:55.831766 | TASK [ensure-rust : Install Rust]
2026-08-26 20:08:56.842644 | controller | info: downloading installer
2026-08-26 20:09:00.104458 | controller | warn: It looks like you have an existing rustup settings file at:
2026-08-26 20:09:00.104541 | controller | warn: /opt/rust/settings.toml
2026-08-26 20:09:00.104553 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-08-26 20:09:00.104569 | controller | warn: instead of the one inferred from the default host triple.
2026-08-26 20:09:00.333270 | controller | info: profile set to default
2026-08-26 20:09:00.333315 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-08-26 20:09:00.335521 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-08-26 20:09:18.005439 | controller | error: could not download file from 'https://static.rust-lang.org/dist/channel-rust-stable.toml' to '/opt/rust/tmp/v6x5jig17bh7_nm5_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:09:18.424398 | controller | ERROR
2026-08-26 20:09:18.424656 | controller | {
2026-08-26 20:09:18.424698 | controller |   "delta": "0:00:21.664917",
2026-08-26 20:09:18.424728 | controller |   "end": "2026-08-26 20:09:18.032994",
2026-08-26 20:09:18.424756 | controller |   "msg": "non-zero return code",
2026-08-26 20:09:18.424782 | controller |   "rc": 1,
2026-08-26 20:09:18.424812 | controller |   "start": "2026-08-26 20:08:56.368077"
2026-08-26 20:09:18.424839 | controller | }
failure
2026-08-26 20:09:18.427498 | 
2026-08-26 20:09:18.427566 | PLAY RECAP
2026-08-26 20:09:18.427626 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:09:18.427657 | 
2026-08-26 20:09:18.631342 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:09:18.666394 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:09:19.291493 | 
2026-08-26 20:09:19.291642 | PLAY [all]
2026-08-26 20:09:19.303064 | 
2026-08-26 20:09:19.303153 | LOOP [Copy individual Hydrophone result files]
2026-08-26 20:09:19.794637 | controller | ERROR: Item: e2e.log
2026-08-26 20:09:19.795328 | controller | {
2026-08-26 20:09:19.795392 | controller |   "ansible_loop_var": "item",
2026-08-26 20:09:19.795501 | controller |   "item": "e2e.log",
2026-08-26 20:09:19.795576 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-08-26 20:09:19.795605 | controller | }
2026-08-26 20:09:20.084947 | controller | ERROR: Item: junit_01.xml
2026-08-26 20:09:20.085184 | controller | {
2026-08-26 20:09:20.085231 | controller |   "ansible_loop_var": "item",
2026-08-26 20:09:20.085262 | controller |   "item": "junit_01.xml",
2026-08-26 20:09:20.085288 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-08-26 20:09:20.085314 | controller | }
2026-08-26 20:09:20.098805 | controller | ERROR: Ignoring Errors
2026-08-26 20:09:20.104224 | 
2026-08-26 20:09:20.104297 | TASK [Copy Hydrophone results tarball to output folder]
2026-08-26 20:09:20.438872 | controller | ERROR
2026-08-26 20:09:20.439148 | controller | {
2026-08-26 20:09:20.439179 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-08-26 20:09:20.439203 | controller | }
2026-08-26 20:09:20.439229 | controller | ERROR: Ignoring Errors
2026-08-26 20:09:20.458468 | 
2026-08-26 20:09:20.458560 | TASK [Return built artifacts to Zuul]
2026-08-26 20:09:20.490106 | controller | ok
2026-08-26 20:09:20.495678 | 
2026-08-26 20:09:20.495752 | TASK [Get the Docker volume ID]
2026-08-26 20:09:21.057531 | controller | ERROR
2026-08-26 20:09:21.057756 | controller | {
2026-08-26 20:09:21.057786 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-08-26 20:09:21.057808 | controller |   "rc": 2
2026-08-26 20:09:21.057828 | controller | }
2026-08-26 20:09:21.057854 | controller | ERROR: Ignoring Errors
2026-08-26 20:09:21.067209 | 
2026-08-26 20:09:21.067295 | TASK [Copy all of the pod logs]
2026-08-26 20:09:21.110131 | controller | ERROR
2026-08-26 20:09:21.110279 | controller | {
2026-08-26 20:09:21.110319 | 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/8ad99e1ff85a49938d1f4dd8d5cd507c/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:09:21.110344 | controller | }
2026-08-26 20:09:21.110369 | controller | ERROR: Ignoring Errors
2026-08-26 20:09:21.111326 | 
2026-08-26 20:09:21.111370 | PLAY RECAP
2026-08-26 20:09:21.111415 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-08-26 20:09:21.111447 | 
2026-08-26 20:09:21.222729 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:09:21.230319 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:09:21.915432 | 
2026-08-26 20:09:21.915584 | PLAY [all]
2026-08-26 20:09:21.934790 | 
2026-08-26 20:09:21.934885 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-08-26 20:09:22.351162 | controller | changed
2026-08-26 20:09:22.357460 | 
2026-08-26 20:09:22.357568 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-08-26 20:09:23.399143 | controller | ok: Runtime: 0:00:00.451630
2026-08-26 20:09:23.406557 | 
2026-08-26 20:09:23.406653 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-08-26 20:09:23.947112 | controller | ok: Runtime: 0:00:00.020533
2026-08-26 20:09:23.954970 | 
2026-08-26 20:09:23.955076 | TASK [export-devstack-journal : Export journal]
2026-08-26 20:09:24.528582 | controller | ok: Runtime: 0:00:00.022859
2026-08-26 20:09:24.539341 | 
2026-08-26 20:09:24.539428 | TASK [export-devstack-journal : Save journal README]
2026-08-26 20:09:25.404843 | controller | changed
2026-08-26 20:09:25.425163 | 
2026-08-26 20:09:25.425472 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-08-26 20:09:25.677594 | controller | changed
2026-08-26 20:09:25.687150 | 
2026-08-26 20:09:25.687262 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:09:26.033616 | controller | Output suppressed because no_log was given
2026-08-26 20:09:26.041380 | 
2026-08-26 20:09:26.041461 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:09:26.082152 | 
2026-08-26 20:09:26.082342 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:09:26.140594 | 
2026-08-26 20:09:26.140841 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:09:26.181374 | 
2026-08-26 20:09:26.181622 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:09:26.217619 | 
2026-08-26 20:09:26.217780 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:09:26.254645 | 
2026-08-26 20:09:26.254851 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-08-26 20:09:26.531783 | controller | changed
2026-08-26 20:09:26.541026 | 
2026-08-26 20:09:26.541130 | TASK [apache-logs-conf : Define config paths]
2026-08-26 20:09:26.582177 | controller | ok
2026-08-26 20:09:26.590781 | 
2026-08-26 20:09:26.590939 | TASK [apache-logs-conf : Discover configurations]
2026-08-26 20:09:26.853950 | controller | Output suppressed because no_log was given
2026-08-26 20:09:26.861348 | 
2026-08-26 20:09:26.861428 | LOOP [apache-logs-conf : Dereference configurations]
2026-08-26 20:09:26.886046 | 
2026-08-26 20:09:26.886206 | LOOP [apache-logs-conf : Link configurations]
2026-08-26 20:09:26.925063 | 
2026-08-26 20:09:26.925282 | TASK [capture-performance-data : Generate statistics]
2026-08-26 20:09:27.604617 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-08-26 20:09:27.624312 | controller | Using python 3.12 to install setuptools
2026-08-26 20:09:27.675690 | controller | /usr/bin/python3.12: No module named pip
2026-08-26 20:09:27.690240 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-08-26 20:09:27.970325 | controller | ERROR
2026-08-26 20:09:27.970547 | controller | {
2026-08-26 20:09:27.970585 | controller |   "delta": "0:00:00.501887",
2026-08-26 20:09:27.970607 | controller |   "end": "2026-08-26 20:09:27.692619",
2026-08-26 20:09:27.970626 | controller |   "msg": "non-zero return code",
2026-08-26 20:09:27.970651 | controller |   "rc": 127,
2026-08-26 20:09:27.970671 | controller |   "start": "2026-08-26 20:09:27.190732"
2026-08-26 20:09:27.970690 | controller | }
2026-08-26 20:09:27.970715 | controller | ERROR: Ignoring Errors
2026-08-26 20:09:27.981992 | 
2026-08-26 20:09:27.982062 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-08-26 20:09:28.220017 | controller | changed
2026-08-26 20:09:28.229576 | 
2026-08-26 20:09:28.229644 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-08-26 20:09:32.233704 | controller | Output suppressed because no_log was given
2026-08-26 20:09:32.246980 | 
2026-08-26 20:09:32.247049 | LOOP [devstack-project-conf : Copy configuration files]
2026-08-26 20:09:32.344622 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.499838 | 
2026-08-26 20:09:32.503110 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.504673 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.505950 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.507038 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.508151 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.509383 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.510657 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.511879 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.513290 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.514341 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.515344 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.516254 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.517519 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.518274 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.519016 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.519762 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.520664 | controller | skipping: Conditional result was False
2026-08-26 20:09:32.611420 | 
2026-08-26 20:09:32.611571 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-08-26 20:09:32.877032 | controller | ok
2026-08-26 20:09:32.883997 | 
2026-08-26 20:09:32.884071 | TASK [devstack-project-conf : Copy configuration files]
2026-08-26 20:09:33.434073 | controller | skipping: Conditional result was False
2026-08-26 20:09:33.449129 | 
2026-08-26 20:09:33.449236 | TASK [capture-system-logs : Stage various logs and reports]
2026-08-26 20:09:33.831550 | controller | /usr/bin/python3: No module named pip
2026-08-26 20:09:35.034173 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-08-26 20:09:35.486266 | controller | ok: Runtime: 0:00:01.330751
2026-08-26 20:09:35.499709 | 
2026-08-26 20:09:35.499788 | LOOP [stage-output : Register sources]
2026-08-26 20:09:46.239370 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.251982 | 
2026-08-26 20:09:46.252115 | TASK [stage-output : Check sudo]
2026-08-26 20:09:46.803979 | controller | ok: Runtime: 0:00:00.023959
2026-08-26 20:09:46.814059 | 
2026-08-26 20:09:46.814163 | LOOP [stage-output : Set source and destination for files and folders]
2026-08-26 20:09:46.912550 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.912917 | 
2026-08-26 20:09:46.916545 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.919660 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.922866 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.975414 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.976027 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.976635 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.979937 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.982629 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.985873 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.988399 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.991201 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.993764 | controller | Output suppressed because no_log was given
2026-08-26 20:09:46.996426 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.016258 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.016610 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.016936 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.017293 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.020375 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.021919 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.024432 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.027033 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.029548 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.057928 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.058262 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.141083 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.153360 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.165935 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.176009 | controller | Output suppressed because no_log was given
2026-08-26 20:09:47.187885 | 
2026-08-26 20:09:47.187969 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-08-26 20:09:47.252454 | controller | ok
2026-08-26 20:09:47.263782 | 
2026-08-26 20:09:47.263925 | LOOP [stage-output : Ensure target folders exist]
2026-08-26 20:09:47.544087 | controller | changed: "docs"
2026-08-26 20:09:47.779324 | controller | changed: "artifacts"
2026-08-26 20:09:47.991419 | controller | ok: "logs"
2026-08-26 20:09:48.011014 | 
2026-08-26 20:09:48.011165 | LOOP [stage-output : Copy files and folders to staging folder]
2026-08-26 20:09:48.295807 | controller | ok: Item: Runtime: 0:00:00.010160
2026-08-26 20:09:48.296101 | controller | changed: All items complete
2026-08-26 20:09:48.296137 | 
2026-08-26 20:09:48.542243 | controller | ok: Item: Runtime: 0:00:00.006855
2026-08-26 20:09:48.755705 | controller | ok: Item: Runtime: 0:00:00.009033
2026-08-26 20:09:48.976308 | controller | ok: Item: Runtime: 0:00:00.008924
2026-08-26 20:09:49.192373 | controller | ok: Item: Runtime: 0:00:00.009769
2026-08-26 20:09:49.434480 | controller | ok: Item: Runtime: 0:00:00.008840
2026-08-26 20:09:49.680575 | controller | ok: Item: Runtime: 0:00:00.008632
2026-08-26 20:09:49.925784 | controller | ok: Item: Runtime: 0:00:00.006179
2026-08-26 20:09:50.215932 | controller | ok: Item: Runtime: 0:00:00.009404
2026-08-26 20:09:50.427661 | controller | ok: Item: Runtime: 0:00:00.008141
2026-08-26 20:09:50.667370 | controller | ok: Item: Runtime: 0:00:00.007190
2026-08-26 20:09:50.906917 | controller | ok: Item: Runtime: 0:00:00.008349
2026-08-26 20:09:51.124185 | controller | ok: Item: Runtime: 0:00:00.008943
2026-08-26 20:09:51.361988 | controller | ok: Item: Runtime: 0:00:00.008262
2026-08-26 20:09:51.610223 | controller | ok: Item: Runtime: 0:00:00.008312
2026-08-26 20:09:51.848508 | controller | ok: Item: Runtime: 0:00:00.008171
2026-08-26 20:09:51.870044 | 
2026-08-26 20:09:51.870180 | TASK [stage-output : Make all log files readable]
2026-08-26 20:09:52.140560 | controller | changed
2026-08-26 20:09:52.149444 | 
2026-08-26 20:09:52.149537 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-08-26 20:09:52.521496 | controller | changed: Renamed files for staging.
2026-08-26 20:09:52.531851 | 
2026-08-26 20:09:52.531971 | TASK [stage-output : Discover log files for compression]
2026-08-26 20:09:52.558478 | controller | skipping: Conditional result was False
2026-08-26 20:09:52.572063 | 
2026-08-26 20:09:52.572163 | LOOP [stage-output : Archive everything from logs]
2026-08-26 20:09:52.617855 | 
2026-08-26 20:09:52.618027 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-08-26 20:09:53.329718 | controller | changed:
2026-08-26 20:09:53.330003 | controller | created directory /var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/work/logs/controller
2026-08-26 20:09:53.330045 | controller | cd+++++++++ logs/
2026-08-26 20:09:53.330069 | controller | >f+++++++++ logs/deprecations_log.txt
2026-08-26 20:09:53.330114 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-08-26 20:09:53.330135 | controller | >f+++++++++ logs/devstack.journal.gz
2026-08-26 20:09:53.330154 | controller | >f+++++++++ logs/df.txt
2026-08-26 20:09:53.330173 | controller | >f+++++++++ logs/dpkg-l.txt
2026-08-26 20:09:53.330192 | controller | >f+++++++++ logs/iptables.txt
2026-08-26 20:09:53.330210 | controller | >f+++++++++ logs/listen53.txt
2026-08-26 20:09:53.330228 | controller | >f+++++++++ logs/local_conf.txt
2026-08-26 20:09:53.330246 | controller | >f+++++++++ logs/mount.txt
2026-08-26 20:09:53.330264 | controller | >f+++++++++ logs/performance.json
2026-08-26 20:09:53.330283 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-08-26 20:09:53.330300 | controller | >f+++++++++ logs/resolv_conf.txt
2026-08-26 20:09:53.330318 | controller | >f+++++++++ logs/services.txt
2026-08-26 20:09:53.330341 | controller | >f+++++++++ logs/sudoers
2026-08-26 20:09:53.330360 | controller | >f+++++++++ logs/syslog.txt
2026-08-26 20:09:53.330378 | controller | cd+++++++++ logs/apache/
2026-08-26 20:09:53.330397 | controller | cd+++++++++ logs/apache_config/
2026-08-26 20:09:53.330415 | controller | cd+++++++++ logs/etc/
2026-08-26 20:09:53.330433 | controller | cd+++++++++ logs/sudoers.d/
2026-08-26 20:09:53.330451 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-08-26 20:09:53.330469 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-08-26 20:09:53.330487 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-08-26 20:09:53.330505 | controller | >f+++++++++ logs/sudoers.d/README
2026-08-26 20:09:53.330523 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-08-26 20:09:53.348151 | 
2026-08-26 20:09:53.348220 | TASK [Check if a tempest log exits]
2026-08-26 20:09:53.615072 | controller | ok
2026-08-26 20:09:53.623830 | 
2026-08-26 20:09:53.623897 | TASK [Link post-devstack tempest.log]
2026-08-26 20:09:53.648879 | controller | skipping: Conditional result was False
2026-08-26 20:09:53.661631 | 
2026-08-26 20:09:53.661757 | TASK [Capture most recent qemu crash dump, if any]
2026-08-26 20:09:53.937652 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-08-26 20:09:54.221594 | controller | ERROR
2026-08-26 20:09:54.221838 | controller | {
2026-08-26 20:09:54.221921 | controller |   "delta": "0:00:00.007824",
2026-08-26 20:09:54.221974 | controller |   "end": "2026-08-26 20:09:53.938238",
2026-08-26 20:09:54.222021 | controller |   "msg": "non-zero return code",
2026-08-26 20:09:54.222066 | controller |   "rc": 127,
2026-08-26 20:09:54.222111 | controller |   "start": "2026-08-26 20:09:53.930414"
2026-08-26 20:09:54.222154 | controller | }
2026-08-26 20:09:54.222209 | controller | ERROR: Ignoring Errors
2026-08-26 20:09:54.227981 | 
2026-08-26 20:09:54.228048 | TASK [Collect Guru Meditation Reports from OpenStack services]
2026-08-26 20:09:54.766910 | controller | ERROR
2026-08-26 20:09:54.767842 | controller | {
2026-08-26 20:09:54.767882 | controller |   "delta": "0:00:00.080014",
2026-08-26 20:09:54.767905 | controller |   "end": "2026-08-26 20:09:54.567437",
2026-08-26 20:09:54.767924 | controller |   "msg": "non-zero return code",
2026-08-26 20:09:54.767942 | controller |   "rc": -12,
2026-08-26 20:09:54.767959 | controller |   "start": "2026-08-26 20:09:54.487423"
2026-08-26 20:09:54.767977 | controller | }
2026-08-26 20:09:54.768000 | controller | ERROR: Ignoring Errors
2026-08-26 20:09:54.770093 | 
2026-08-26 20:09:54.770161 | PLAY RECAP
2026-08-26 20:09:54.770209 | controller | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-08-26 20:09:54.770232 | 
2026-08-26 20:09:54.909603 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:09:54.917271 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:09:55.665607 | 
2026-08-26 20:09:55.665729 | PLAY [all]
2026-08-26 20:09:55.677342 | 
2026-08-26 20:09:55.677431 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-26 20:09:55.722598 | controller | skipping: Conditional result was False
2026-08-26 20:09:55.729436 | 
2026-08-26 20:09:55.729534 | TASK [fetch-output : Set log path for single node]
2026-08-26 20:09:55.786024 | controller | ok
2026-08-26 20:09:55.791571 | 
2026-08-26 20:09:55.791644 | LOOP [fetch-output : Ensure local output dirs]
2026-08-26 20:09:56.203028 | controller -> localhost | ok: "/var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/work/logs"
2026-08-26 20:09:56.406218 | controller -> localhost | changed: "/var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/work/artifacts"
2026-08-26 20:09:56.620873 | controller -> localhost | changed: "/var/lib/zuul/builds/8ad99e1ff85a49938d1f4dd8d5cd507c/work/docs"
2026-08-26 20:09:56.643306 | 
2026-08-26 20:09:56.643398 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-26 20:09:57.349243 | controller | changed: .d..t...... ./
2026-08-26 20:09:57.349427 | controller | changed: All items complete
2026-08-26 20:09:57.349454 | 
2026-08-26 20:09:57.874011 | controller | changed: .d..t...... ./
2026-08-26 20:09:58.353720 | controller | changed: .d..t...... ./
2026-08-26 20:09:58.382066 | 
2026-08-26 20:09:58.382188 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-26 20:09:58.822971 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.021643
2026-08-26 20:09:59.055576 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009664
2026-08-26 20:09:59.069911 | 
2026-08-26 20:09:59.070039 | PLAY [all]
2026-08-26 20:09:59.076617 | 
2026-08-26 20:09:59.076683 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-26 20:09:59.559274 | controller | changed
2026-08-26 20:09:59.569299 | 
2026-08-26 20:09:59.569572 | PLAY RECAP
2026-08-26 20:09:59.569620 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 20:09:59.569643 | 
2026-08-26 20:09:59.694205 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:09:59.707464 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-26 20:10:00.401164 | 
2026-08-26 20:10:00.401272 | PLAY [localhost]
2026-08-26 20:10:00.410934 | 
2026-08-26 20:10:00.411006 | TASK [Generate Zuul manifest]
2026-08-26 20:10:00.431068 | localhost | ok
2026-08-26 20:10:00.531167 | 
2026-08-26 20:10:00.531386 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-26 20:10:00.986845 | localhost | changed
2026-08-26 20:10:00.998592 | 
2026-08-26 20:10:00.998718 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-26 20:10:01.203555 | localhost | ok
2026-08-26 20:10:01.210893 | 
2026-08-26 20:10:01.210959 | TASK [Upload logs]
2026-08-26 20:10:01.235251 | localhost | ok
2026-08-26 20:10:01.524025 | 
2026-08-26 20:10:01.544633 | TASK [Set zuul-log-path fact]
2026-08-26 20:10:01.568151 | localhost | ok
2026-08-26 20:10:01.595626 | 
2026-08-26 20:10:01.595700 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:10:01.627594 | localhost | ok
2026-08-26 20:10:01.641026 | 
2026-08-26 20:10:01.641156 | TASK [upload-logs : Create log directories]
2026-08-26 20:10:02.002560 | localhost | changed
2026-08-26 20:10:02.007882 | 
2026-08-26 20:10:02.007960 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-26 20:10:02.372660 | localhost -> localhost | ok: Runtime: 0:00:00.004582
2026-08-26 20:10:02.379005 | 
2026-08-26 20:10:02.379074 | TASK [upload-logs : Upload logs to log server]
2026-08-26 20:10:02.835802 | localhost | Output suppressed because no_log was given
2026-08-26 20:10:02.841797 | 
2026-08-26 20:10:02.841870 | LOOP [upload-logs : Compress console log and json output]
2026-08-26 20:10:02.885170 | localhost | skipping: Conditional result was False
2026-08-26 20:10:02.892281 | localhost | skipping: Conditional result was False
2026-08-26 20:10:02.904946 | 
2026-08-26 20:10:02.905097 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-26 20:10:02.945069 | localhost | skipping: Conditional result was False
2026-08-26 20:10:02.945430 | 
2026-08-26 20:10:02.949134 | localhost | skipping: Conditional result was False
2026-08-26 20:10:02.959836 | 
2026-08-26 20:10:02.959985 | LOOP [upload-logs : Upload console log and json output]