2026-05-06 03:27:03.129921 | Job console starting
2026-05-06 03:27:03.150500 | Updating git repos
2026-05-06 03:27:05.497415 | Cloning repos into workspace
2026-05-06 03:27:15.500621 | Restoring repo states
2026-05-06 03:27:15.631511 | Merging changes
2026-05-06 03:27:16.987249 | Checking out repos
2026-05-06 03:27:27.763593 | Preparing playbooks
2026-05-06 03:27:39.196803 | Running Ansible setup
2026-05-06 03:27:43.307392 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-06 03:27:43.907300 | 
2026-05-06 03:27:43.958536 | PLAY [localhost]
2026-05-06 03:27:43.969702 | 
2026-05-06 03:27:43.969858 | TASK [Gathering Facts]
2026-05-06 03:27:45.096421 | localhost | ok
2026-05-06 03:27:45.106547 | 
2026-05-06 03:27:45.106655 | TASK [Setup log path fact]
2026-05-06 03:27:45.147718 | localhost | ok
2026-05-06 03:27:45.160428 | 
2026-05-06 03:27:45.255737 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-06 03:27:45.289126 | localhost | ok
2026-05-06 03:27:45.298677 | 
2026-05-06 03:27:45.298794 | TASK [emit-job-header : Print job information]
2026-05-06 03:27:45.339913 | # Job Information
2026-05-06 03:27:45.366255 | Ansible Version: 2.16.18
2026-05-06 03:27:45.366412 | Job: magnum-cluster-api-hydrophone-v1.33.11-calico
2026-05-06 03:27:45.366534 | Pipeline: check
2026-05-06 03:27:45.366613 | Executor: 2d72f0692154
2026-05-06 03:27:45.366682 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1014
2026-05-06 03:27:45.366757 | Event ID: 538348e0-48fb-11f1-9b75-8eaa46fb0db1
2026-05-06 03:27:45.373591 | 
2026-05-06 03:27:45.373702 | LOOP [emit-job-header : Print node information]
2026-05-06 03:27:45.509795 | localhost | ok:
2026-05-06 03:27:45.510088 | localhost | # Node Information
2026-05-06 03:27:45.510135 | localhost | Inventory Hostname: controller
2026-05-06 03:27:45.510169 | localhost | Hostname: np0000172156
2026-05-06 03:27:45.510200 | localhost | Username: zuul
2026-05-06 03:27:45.510235 | localhost | Distro: Ubuntu 24.04
2026-05-06 03:27:45.510266 | localhost | Provider: yul1
2026-05-06 03:27:45.510291 | localhost | Region: ca-ymq-1
2026-05-06 03:27:45.510318 | localhost | Label: ubuntu-noble-16
2026-05-06 03:27:45.510345 | localhost | Product Name: OpenStack Nova
2026-05-06 03:27:45.510412 | localhost | Interface IP: 199.204.45.169
2026-05-06 03:27:45.524844 | 
2026-05-06 03:27:45.525010 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-06 03:27:46.037239 | localhost -> localhost | changed
2026-05-06 03:27:46.044361 | 
2026-05-06 03:27:46.044472 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-06 03:27:46.997518 | localhost -> localhost | changed
2026-05-06 03:27:47.005951 | 
2026-05-06 03:27:47.006040 | PLAY [all]
2026-05-06 03:27:47.015759 | 
2026-05-06 03:27:47.015846 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-06 03:27:47.414766 | controller -> localhost | ok
2026-05-06 03:27:47.421915 | 
2026-05-06 03:27:47.422021 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-06 03:27:47.456320 | controller | ok
2026-05-06 03:27:47.475276 | controller | included: /var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-06 03:27:47.482415 | 
2026-05-06 03:27:47.482538 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-06 03:27:49.655279 | controller -> localhost | Generating public/private rsa key pair.
2026-05-06 03:27:49.655431 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/work/172535f877ff46dbb1845933a7b7f58c_id_rsa
2026-05-06 03:27:49.655461 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/work/172535f877ff46dbb1845933a7b7f58c_id_rsa.pub
2026-05-06 03:27:49.655486 | controller -> localhost | The key fingerprint is:
2026-05-06 03:27:49.655508 | controller -> localhost | SHA256:xCXuOQx6bczFRly+TXSX6QTuYhf0JLYPc5lUeNzmS/Q zuul-build-sshkey
2026-05-06 03:27:49.655539 | controller -> localhost | The key's randomart image is:
2026-05-06 03:27:49.655561 | controller -> localhost | +---[RSA 3072]----+
2026-05-06 03:27:49.655595 | controller -> localhost | |        ..o..=o=O|
2026-05-06 03:27:49.655619 | controller -> localhost | |       o =..+.B*O|
2026-05-06 03:27:49.655640 | controller -> localhost | |      . + + .*=O.|
2026-05-06 03:27:49.655661 | controller -> localhost | |     . O +  .+*oE|
2026-05-06 03:27:49.655681 | controller -> localhost | |    . . S  o.oo..|
2026-05-06 03:27:49.655702 | controller -> localhost | |     . . .. o  . |
2026-05-06 03:27:49.655723 | controller -> localhost | |                 |
2026-05-06 03:27:49.655743 | controller -> localhost | |                 |
2026-05-06 03:27:49.655767 | controller -> localhost | |                 |
2026-05-06 03:27:49.655789 | controller -> localhost | +----[SHA256]-----+
2026-05-06 03:27:49.655842 | controller -> localhost | ok: Runtime: 0:00:01.471548
2026-05-06 03:27:49.662097 | 
2026-05-06 03:27:49.662173 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-06 03:27:49.690929 | controller | ok
2026-05-06 03:27:49.699626 | controller | included: /var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-06 03:27:49.707465 | 
2026-05-06 03:27:49.707532 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-06 03:27:49.741133 | controller | skipping: Conditional result was False
2026-05-06 03:27:49.753702 | 
2026-05-06 03:27:49.753830 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-06 03:27:50.293758 | controller | changed
2026-05-06 03:27:50.299642 | 
2026-05-06 03:27:50.299741 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-06 03:27:50.551033 | controller | ok
2026-05-06 03:27:50.579815 | 
2026-05-06 03:27:50.579915 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-06 03:27:51.168118 | controller | changed
2026-05-06 03:27:51.244760 | 
2026-05-06 03:27:51.244902 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-06 03:27:51.871920 | controller | changed
2026-05-06 03:27:51.928532 | 
2026-05-06 03:27:51.928653 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-06 03:27:51.951997 | controller | skipping: Conditional result was False
2026-05-06 03:27:51.963808 | 
2026-05-06 03:27:51.963896 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-06 03:27:52.363701 | controller -> localhost | changed
2026-05-06 03:27:52.383641 | 
2026-05-06 03:27:52.383715 | TASK [add-build-sshkey : Add back temp key]
2026-05-06 03:27:52.739907 | controller -> localhost | Identity added: /var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/work/172535f877ff46dbb1845933a7b7f58c_id_rsa (zuul-build-sshkey)
2026-05-06 03:27:52.740087 | controller -> localhost | ok: Runtime: 0:00:00.026690
2026-05-06 03:27:52.745838 | 
2026-05-06 03:27:52.745903 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-06 03:27:53.091042 | controller | ok
2026-05-06 03:27:53.132750 | 
2026-05-06 03:27:53.132876 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-06 03:27:53.158091 | controller | skipping: Conditional result was False
2026-05-06 03:27:53.172948 | 
2026-05-06 03:27:53.173068 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-06 03:27:53.523294 | controller | ok
2026-05-06 03:27:53.529829 | 
2026-05-06 03:27:53.529903 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-06 03:28:15.775688 | controller | Output suppressed because no_log was given
2026-05-06 03:28:15.785314 | 
2026-05-06 03:28:15.785374 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-06 03:28:16.015489 | controller | ok: "logs"
2026-05-06 03:28:16.015707 | controller | ok: All items complete
2026-05-06 03:28:16.015736 | 
2026-05-06 03:28:16.206098 | controller | ok: "artifacts"
2026-05-06 03:28:16.411052 | controller | ok: "docs"
2026-05-06 03:28:16.425691 | 
2026-05-06 03:28:16.425800 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-06 03:28:16.648174 | controller | changed: "logs"
2026-05-06 03:28:16.861674 | controller | changed: "artifacts"
2026-05-06 03:28:17.073622 | controller | changed: "docs"
2026-05-06 03:28:17.099751 | 
2026-05-06 03:28:17.099945 | PLAY RECAP
2026-05-06 03:28:17.100071 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-06 03:28:17.100145 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-06 03:28:17.100197 | 
2026-05-06 03:28:17.273848 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-06 03:28:17.281143 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-06 03:28:18.029179 | 
2026-05-06 03:28:18.029317 | PLAY [all]
2026-05-06 03:28:18.048286 | 
2026-05-06 03:28:18.048365 | TASK [Fix the permissions of the zuul home directory]
2026-05-06 03:28:18.432056 | controller | changed
2026-05-06 03:28:18.441283 | 
2026-05-06 03:28:18.441373 | TASK [Gather minimum local MTU]
2026-05-06 03:28:18.518436 | controller | ok
2026-05-06 03:28:18.523630 | 
2026-05-06 03:28:18.523711 | TASK [Calculate external_bridge_mtu]
2026-05-06 03:28:18.580687 | controller | ok
2026-05-06 03:28:18.589258 | 
2026-05-06 03:28:18.589343 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-06 03:28:18.614875 | controller | skipping: Conditional result was False
2026-05-06 03:28:18.624353 | 
2026-05-06 03:28:18.624460 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-06 03:28:19.182344 | controller | ok: Runtime: 0:00:00.009889
2026-05-06 03:28:19.190172 | 
2026-05-06 03:28:19.190293 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-06 03:28:19.214997 | controller | skipping: Conditional result was False
2026-05-06 03:28:19.225672 | 
2026-05-06 03:28:19.225808 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-06 03:28:19.251120 | controller | skipping: Conditional result was False
2026-05-06 03:28:19.261603 | 
2026-05-06 03:28:19.261718 | TASK [configure-swap : Setup swap file on root device]
2026-05-06 03:28:19.323657 | controller | ok
2026-05-06 03:28:19.335887 | controller | included: /var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-06 03:28:19.342225 | 
2026-05-06 03:28:19.342298 | TASK [configure-swap : Calculate required swap]
2026-05-06 03:28:19.459935 | controller | ok
2026-05-06 03:28:19.510392 | 
2026-05-06 03:28:19.510514 | TASK [configure-swap : Get root filesystem]
2026-05-06 03:28:19.761613 | controller | ext4
2026-05-06 03:28:20.049064 | controller | ok: Runtime: 0:00:00.017446
2026-05-06 03:28:20.054599 | 
2026-05-06 03:28:20.054670 | TASK [configure-swap : Save root filesystem]
2026-05-06 03:28:20.085728 | controller | ok
2026-05-06 03:28:20.092060 | 
2026-05-06 03:28:20.092130 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-06 03:28:20.122614 | controller | ok:
2026-05-06 03:28:20.122751 | controller | {
2026-05-06 03:28:20.122779 | controller |   "root_filesystem": "ext4"
2026-05-06 03:28:20.122799 | controller | }
2026-05-06 03:28:20.127787 | 
2026-05-06 03:28:20.127856 | TASK [configure-swap : Create swap backing file]
2026-05-06 03:28:30.777949 | controller | 8192+0 records in
2026-05-06 03:28:30.778066 | controller | 8192+0 records out
2026-05-06 03:28:30.778134 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.2588 s, 837 MB/s
2026-05-06 03:28:31.179047 | controller | ok: Runtime: 0:00:10.280421
2026-05-06 03:28:31.185059 | 
2026-05-06 03:28:31.185146 | TASK [configure-swap : Ensure swapfile perms]
2026-05-06 03:28:31.424597 | controller | changed
2026-05-06 03:28:31.446079 | 
2026-05-06 03:28:31.446240 | TASK [configure-swap : Make swapfile]
2026-05-06 03:28:46.416486 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-06 03:28:46.416817 | controller | no label, UUID=dd6f3ca9-571d-424a-b97b-dff39905a4de
2026-05-06 03:28:46.495374 | controller | ok: Runtime: 0:00:14.712458
2026-05-06 03:28:46.500873 | 
2026-05-06 03:28:46.500968 | TASK [configure-swap : Write swap to fstab]
2026-05-06 03:28:46.878996 | controller | changed
2026-05-06 03:28:46.887156 | 
2026-05-06 03:28:46.887244 | TASK [configure-swap : Add all swap]
2026-05-06 03:28:47.439496 | controller | ok: Runtime: 0:00:00.023625
2026-05-06 03:28:47.448061 | 
2026-05-06 03:28:47.448134 | TASK [configure-swap : Debug the swap_required variable]
2026-05-06 03:28:47.482891 | controller | ok:
2026-05-06 03:28:47.483064 | controller | {
2026-05-06 03:28:47.483100 | controller |   "swap_required": "8192"
2026-05-06 03:28:47.483128 | controller | }
2026-05-06 03:28:47.490917 | 
2026-05-06 03:28:47.491012 | TASK [configure-swap : Set swappiness]
2026-05-06 03:28:47.922410 | controller | changed
2026-05-06 03:28:47.929249 | 
2026-05-06 03:28:47.929317 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-06 03:28:47.964532 | controller | ok:
2026-05-06 03:28:47.964883 | controller | {
2026-05-06 03:28:47.965018 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-06 03:28:47.965080 | controller | }
2026-05-06 03:28:47.975491 | 
2026-05-06 03:28:47.975550 | TASK [setup-stack-user : Create stack group]
2026-05-06 03:28:48.415645 | controller | changed
2026-05-06 03:28:48.423180 | 
2026-05-06 03:28:48.423271 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-06 03:28:48.664617 | controller | changed
2026-05-06 03:28:48.672052 | 
2026-05-06 03:28:48.672127 | TASK [setup-stack-user : Create stack user]
2026-05-06 03:28:49.225908 | controller | changed
2026-05-06 03:28:49.231477 | 
2026-05-06 03:28:49.231542 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-06 03:28:49.478803 | controller | changed
2026-05-06 03:28:49.485027 | 
2026-05-06 03:28:49.485095 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-06 03:28:50.521260 | controller | changed
2026-05-06 03:28:50.529756 | 
2026-05-06 03:28:50.529842 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-06 03:28:50.817813 | controller | changed
2026-05-06 03:28:50.827681 | 
2026-05-06 03:28:50.827753 | TASK [setup-tempest-user : Create tempest group]
2026-05-06 03:28:51.124260 | controller | changed
2026-05-06 03:28:51.131611 | 
2026-05-06 03:28:51.131679 | TASK [setup-tempest-user : Create tempest user]
2026-05-06 03:28:51.512930 | controller | changed
2026-05-06 03:28:51.518727 | 
2026-05-06 03:28:51.518795 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-06 03:28:52.316356 | controller | changed
2026-05-06 03:28:52.326533 | 
2026-05-06 03:28:52.326596 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-06 03:28:52.657578 | controller | ok: Not all paths examined, check warnings for details
2026-05-06 03:28:52.668241 | 
2026-05-06 03:28:52.668308 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-06 03:28:53.573667 | controller | ok: Item: Runtime: 0:00:00.623018
2026-05-06 03:28:54.154462 | controller | ok: Item: Runtime: 0:00:00.350955
2026-05-06 03:28:55.743474 | controller | ok: Item: Runtime: 0:00:01.362592
2026-05-06 03:28:56.523913 | controller | ok: Item: Runtime: 0:00:00.547909
2026-05-06 03:28:56.957494 | controller | ok: Item: Runtime: 0:00:00.212058
2026-05-06 03:28:57.249957 | controller | ok: Item: Runtime: 0:00:00.063287
2026-05-06 03:28:57.720770 | controller | ok: Item: Runtime: 0:00:00.257523
2026-05-06 03:28:58.151817 | controller | ok: Item: Runtime: 0:00:00.211132
2026-05-06 03:29:01.446215 | controller | ok: Item: Runtime: 0:00:03.080818
2026-05-06 03:29:02.247721 | controller | ok: Item: Runtime: 0:00:00.590719
2026-05-06 03:29:02.576854 | controller | ok: Item: Runtime: 0:00:00.106470
2026-05-06 03:29:02.982891 | controller | ok: Item: Runtime: 0:00:00.183971
2026-05-06 03:29:04.397710 | controller | ok: Item: Runtime: 0:00:01.205324
2026-05-06 03:29:04.757173 | controller | ok: Item: Runtime: 0:00:00.135721
2026-05-06 03:29:05.308332 | controller | ok: Item: Runtime: 0:00:00.339379
2026-05-06 03:29:05.808647 | controller | ok: Item: Runtime: 0:00:00.288410
2026-05-06 03:29:05.836944 | 
2026-05-06 03:29:05.837135 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-06 03:29:06.049348 | controller | ok: All paths examined
2026-05-06 03:29:06.058188 | 
2026-05-06 03:29:06.058255 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-06 03:29:06.293584 | controller | ok: All paths examined
2026-05-06 03:29:06.300561 | 
2026-05-06 03:29:06.300632 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-06 03:29:06.805349 | controller | ok: Item: Runtime: 0:00:00.228509
2026-05-06 03:29:06.805748 | controller | changed: All items complete
2026-05-06 03:29:06.805777 | 
2026-05-06 03:29:07.182937 | controller | ok: Item: Runtime: 0:00:00.143699
2026-05-06 03:29:07.196638 | 
2026-05-06 03:29:07.196768 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-06 03:29:07.274941 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.277352 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.280593 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.283986 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.287327 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.290865 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.294496 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.298593 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.301568 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.304878 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.307940 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.311582 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.314840 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.318454 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.322512 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.324824 | controller | skipping: Conditional result was False
2026-05-06 03:29:07.388474 | 
2026-05-06 03:29:07.388605 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-06 03:29:10.031189 | controller | changed
2026-05-06 03:29:10.041618 | 
2026-05-06 03:29:10.041682 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-06 03:29:10.285217 | controller | changed
2026-05-06 03:29:10.297217 | 
2026-05-06 03:29:10.297296 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-06 03:29:10.564557 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-06 03:29:10.840162 | controller | ERROR
2026-05-06 03:29:10.840385 | controller | {
2026-05-06 03:29:10.840425 | controller |   "delta": "0:00:00.008514",
2026-05-06 03:29:10.840455 | controller |   "end": "2026-05-06 03:29:10.564866",
2026-05-06 03:29:10.840482 | controller |   "msg": "non-zero return code",
2026-05-06 03:29:10.840508 | controller |   "rc": 1,
2026-05-06 03:29:10.840534 | controller |   "start": "2026-05-06 03:29:10.556352"
2026-05-06 03:29:10.840558 | controller | }
2026-05-06 03:29:10.840590 | controller | ERROR: Ignoring Errors
2026-05-06 03:29:10.847447 | 
2026-05-06 03:29:10.847516 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-06 03:29:11.096481 | controller | ok
2026-05-06 03:29:11.108643 | 
2026-05-06 03:29:11.108750 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-06 03:29:11.360532 | controller | /usr/bin/journalctl
2026-05-06 03:29:11.659702 | controller | ok: Runtime: 0:00:00.009176
2026-05-06 03:29:11.667238 | 
2026-05-06 03:29:11.667322 | TASK [start-fresh-logging : Get current date]
2026-05-06 03:29:11.917666 | controller | 2026-05-06 03:29:11
2026-05-06 03:29:12.208591 | controller | ok: Runtime: 0:00:00.009484
2026-05-06 03:29:12.215531 | 
2026-05-06 03:29:12.215609 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-06 03:29:12.778353 | controller | changed
2026-05-06 03:29:12.785110 | 
2026-05-06 03:29:12.785175 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-06 03:29:12.810267 | controller | skipping: Conditional result was False
2026-05-06 03:29:12.819632 | 
2026-05-06 03:29:12.819717 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-06 03:29:13.357193 | controller | skipping: Conditional result was False
2026-05-06 03:29:13.367527 | 
2026-05-06 03:29:13.367598 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-06 03:29:13.899200 | controller | skipping: Conditional result was False
2026-05-06 03:29:13.908321 | 
2026-05-06 03:29:13.908414 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-06 03:29:13.933029 | controller | skipping: Conditional result was False
2026-05-06 03:29:13.941137 | 
2026-05-06 03:29:13.941227 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-06 03:29:14.473597 | controller | skipping: Conditional result was False
2026-05-06 03:29:14.482363 | 
2026-05-06 03:29:14.482459 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-06 03:29:15.052314 | controller | skipping: Conditional result was False
2026-05-06 03:29:15.062282 | 
2026-05-06 03:29:15.062373 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-06 03:29:15.088160 | controller | skipping: Conditional result was False
2026-05-06 03:29:15.098318 | 
2026-05-06 03:29:15.098409 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-06 03:29:15.123672 | controller | skipping: Conditional result was False
2026-05-06 03:29:15.136250 | 
2026-05-06 03:29:15.136330 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-06 03:29:15.672000 | controller | skipping: Conditional result was False
2026-05-06 03:29:15.683043 | 
2026-05-06 03:29:15.683128 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-06 03:29:16.212105 | controller | skipping: Conditional result was False
2026-05-06 03:29:16.224188 | 
2026-05-06 03:29:16.224259 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-06 03:29:16.248645 | controller | skipping: Conditional result was False
2026-05-06 03:29:16.254642 | 
2026-05-06 03:29:16.254713 | TASK [start-fresh-logging : Start rsyslog]
2026-05-06 03:29:16.277380 | controller | skipping: Conditional result was False
2026-05-06 03:29:16.294863 | 
2026-05-06 03:29:16.294934 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-06 03:29:16.725224 | controller | ok
2026-05-06 03:29:16.736672 | 
2026-05-06 03:29:16.736742 | PLAY RECAP
2026-05-06 03:29:16.736804 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-06 03:29:16.736833 | 
2026-05-06 03:29:16.914565 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-06 03:29:16.919419 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-06 03:29:17.519735 | 
2026-05-06 03:29:17.519846 | PLAY [all]
2026-05-06 03:29:17.530675 | 
2026-05-06 03:29:17.530751 | TASK [Install curl]
2026-05-06 05:27:47.380793 | PRE-RUN END RESULT_TIMED_OUT: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-06 05:27:47.408398 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-06 05:27:48.032412 | 
2026-05-06 05:27:48.032551 | PLAY [all]
2026-05-06 05:27:48.044392 | 
2026-05-06 05:27:48.044480 | LOOP [Copy individual Hydrophone result files]
2026-05-06 05:27:48.501380 | controller | ERROR: Item: e2e.log
2026-05-06 05:27:48.501855 | controller | {
2026-05-06 05:27:48.501934 | controller |   "ansible_loop_var": "item",
2026-05-06 05:27:48.501991 | controller |   "item": "e2e.log",
2026-05-06 05:27:48.502096 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-06 05:27:48.502145 | controller | }
2026-05-06 05:27:48.781673 | controller | ERROR: Item: junit_01.xml
2026-05-06 05:27:48.781907 | controller | {
2026-05-06 05:27:48.781980 | controller |   "ansible_loop_var": "item",
2026-05-06 05:27:48.782029 | controller |   "item": "junit_01.xml",
2026-05-06 05:27:48.782072 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-06 05:27:48.782113 | controller | }
2026-05-06 05:27:48.799335 | controller | ERROR: Ignoring Errors
2026-05-06 05:27:48.806817 | 
2026-05-06 05:27:48.806886 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-06 05:27:49.105265 | controller | ERROR
2026-05-06 05:27:49.105558 | controller | {
2026-05-06 05:27:49.105598 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-06 05:27:49.105628 | controller | }
2026-05-06 05:27:49.105663 | controller | ERROR: Ignoring Errors
2026-05-06 05:27:49.118280 | 
2026-05-06 05:27:49.118354 | TASK [Return built artifacts to Zuul]
2026-05-06 05:27:49.154850 | controller | ok
2026-05-06 05:27:49.160184 | 
2026-05-06 05:27:49.160246 | TASK [Get the Docker volume ID]
2026-05-06 05:27:49.700344 | controller | ERROR
2026-05-06 05:27:49.700693 | controller | {
2026-05-06 05:27:49.700723 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-06 05:27:49.700743 | controller |   "rc": 2
2026-05-06 05:27:49.700763 | controller | }
2026-05-06 05:27:49.700791 | controller | ERROR: Ignoring Errors
2026-05-06 05:27:49.705948 | 
2026-05-06 05:27:49.706017 | TASK [Copy all of the pod logs]
2026-05-06 05:27:49.742696 | controller | ERROR
2026-05-06 05:27:49.742865 | controller | {
2026-05-06 05:27:49.742893 | 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/172535f877ff46dbb1845933a7b7f58c/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-05-06 05:27:49.742915 | controller | }
2026-05-06 05:27:49.742940 | controller | ERROR: Ignoring Errors
2026-05-06 05:27:49.743997 | 
2026-05-06 05:27:49.744044 | PLAY RECAP
2026-05-06 05:27:49.744093 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-06 05:27:49.744115 | 
2026-05-06 05:27:49.890542 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-06 05:27:49.895415 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-06 05:27:50.564981 | 
2026-05-06 05:27:50.565144 | PLAY [all]
2026-05-06 05:27:50.583946 | 
2026-05-06 05:27:50.584036 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-06 05:27:50.964104 | controller | changed
2026-05-06 05:27:50.969999 | 
2026-05-06 05:27:50.970079 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-06 05:27:52.008700 | controller | ok: Runtime: 0:00:00.471146
2026-05-06 05:27:52.014460 | 
2026-05-06 05:27:52.014539 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-06 05:27:52.549872 | controller | ok: Runtime: 0:00:00.019356
2026-05-06 05:27:52.557436 | 
2026-05-06 05:27:52.557534 | TASK [export-devstack-journal : Export journal]
2026-05-06 05:27:53.132851 | controller | ok: Runtime: 0:00:00.039207
2026-05-06 05:27:53.140079 | 
2026-05-06 05:27:53.140147 | TASK [export-devstack-journal : Save journal README]
2026-05-06 05:27:53.962210 | controller | changed
2026-05-06 05:27:53.973228 | 
2026-05-06 05:27:53.973304 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-06 05:27:54.219745 | controller | changed
2026-05-06 05:27:54.226704 | 
2026-05-06 05:27:54.226791 | TASK [apache-logs-conf : Find logs]
2026-05-06 05:27:54.551718 | controller | Output suppressed because no_log was given
2026-05-06 05:27:54.557087 | 
2026-05-06 05:27:54.557156 | LOOP [apache-logs-conf : Dereference files]
2026-05-06 05:27:54.592897 | 
2026-05-06 05:27:54.593045 | LOOP [apache-logs-conf : Create hard links]
2026-05-06 05:27:54.629574 | 
2026-05-06 05:27:54.629761 | TASK [apache-logs-conf : Find logs]
2026-05-06 05:27:54.663423 | 
2026-05-06 05:27:54.663613 | LOOP [apache-logs-conf : Dereference files]
2026-05-06 05:27:54.696557 | 
2026-05-06 05:27:54.696750 | LOOP [apache-logs-conf : Create hard links]
2026-05-06 05:27:54.736500 | 
2026-05-06 05:27:54.736666 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-06 05:27:54.972461 | controller | changed
2026-05-06 05:27:54.978621 | 
2026-05-06 05:27:54.978688 | TASK [apache-logs-conf : Define config paths]
2026-05-06 05:27:55.013872 | controller | ok
2026-05-06 05:27:55.020735 | 
2026-05-06 05:27:55.020797 | TASK [apache-logs-conf : Discover configurations]
2026-05-06 05:27:55.257576 | controller | Output suppressed because no_log was given
2026-05-06 05:27:55.263068 | 
2026-05-06 05:27:55.263134 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-06 05:27:55.298513 | 
2026-05-06 05:27:55.298694 | LOOP [apache-logs-conf : Link configurations]
2026-05-06 05:27:55.328797 | 
2026-05-06 05:27:55.328932 | TASK [capture-performance-data : Generate statistics]
2026-05-06 05:27:55.961209 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-06 05:27:55.980472 | controller | Using python 3.12 to install setuptools
2026-05-06 05:27:56.020622 | controller | /usr/bin/python3.12: No module named pip
2026-05-06 05:27:56.033452 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-06 05:27:56.370145 | controller | ERROR
2026-05-06 05:27:56.370420 | controller | {
2026-05-06 05:27:56.370466 | controller |   "delta": "0:00:00.464621",
2026-05-06 05:27:56.370496 | controller |   "end": "2026-05-06 05:27:56.035046",
2026-05-06 05:27:56.370522 | controller |   "msg": "non-zero return code",
2026-05-06 05:27:56.370555 | controller |   "rc": 127,
2026-05-06 05:27:56.370581 | controller |   "start": "2026-05-06 05:27:55.570425"
2026-05-06 05:27:56.370606 | controller | }
2026-05-06 05:27:56.370640 | controller | ERROR: Ignoring Errors
2026-05-06 05:27:56.383532 | 
2026-05-06 05:27:56.383604 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-06 05:27:56.621886 | controller | changed
2026-05-06 05:27:56.629053 | 
2026-05-06 05:27:56.629124 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-06 05:28:00.225816 | controller | Output suppressed because no_log was given
2026-05-06 05:28:00.234276 | 
2026-05-06 05:28:00.234356 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-06 05:28:00.319060 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.319511 | 
2026-05-06 05:28:00.321538 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.322879 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.325619 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.328402 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.331206 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.333930 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.336846 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.339490 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.342248 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.344895 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.347578 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.350261 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.353081 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.355776 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.358508 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.361113 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.363994 | controller | skipping: Conditional result was False
2026-05-06 05:28:00.446690 | 
2026-05-06 05:28:00.446833 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-06 05:28:00.671617 | controller | ok
2026-05-06 05:28:00.678383 | 
2026-05-06 05:28:00.678456 | TASK [devstack-project-conf : Copy configuration files]
2026-05-06 05:28:01.211164 | controller | skipping: Conditional result was False
2026-05-06 05:28:01.227622 | 
2026-05-06 05:28:01.227719 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-06 05:28:01.573502 | controller | /usr/bin/python3: No module named pip
2026-05-06 05:28:02.675801 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-06 05:28:02.769501 | controller | ok: Runtime: 0:00:01.201828
2026-05-06 05:28:02.781143 | 
2026-05-06 05:28:02.781210 | LOOP [stage-output : Register sources]
2026-05-06 05:28:11.856243 | controller | Output suppressed because no_log was given
2026-05-06 05:28:11.869962 | 
2026-05-06 05:28:11.870048 | TASK [stage-output : Check sudo]
2026-05-06 05:28:12.410195 | controller | ok: Runtime: 0:00:00.020512
2026-05-06 05:28:12.416170 | 
2026-05-06 05:28:12.416235 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-06 05:28:12.519775 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.520151 | 
2026-05-06 05:28:12.521490 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.523696 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.526503 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.568250 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.568765 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.569461 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.571989 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.574515 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.576998 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.579717 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.582441 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.585111 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.587524 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.606858 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.607362 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.607835 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.608295 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.610315 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.612789 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.615369 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.617850 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.620543 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.645233 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.645577 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.729372 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.741916 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.753330 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.764232 | controller | Output suppressed because no_log was given
2026-05-06 05:28:12.777370 | 
2026-05-06 05:28:12.777438 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-06 05:28:12.844279 | controller | ok
2026-05-06 05:28:12.853529 | 
2026-05-06 05:28:12.853596 | LOOP [stage-output : Ensure target folders exist]
2026-05-06 05:28:13.112309 | controller | changed: "docs"
2026-05-06 05:28:13.308381 | controller | changed: "artifacts"
2026-05-06 05:28:13.515366 | controller | ok: "logs"
2026-05-06 05:28:13.538004 | 
2026-05-06 05:28:13.538223 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-06 05:28:13.828467 | controller | ok: Item: Runtime: 0:00:00.011484
2026-05-06 05:28:13.828722 | controller | changed: All items complete
2026-05-06 05:28:13.828751 | 
2026-05-06 05:28:14.053325 | controller | ok: Item: Runtime: 0:00:00.008108
2026-05-06 05:28:14.268047 | controller | ok: Item: Runtime: 0:00:00.009183
2026-05-06 05:28:14.483656 | controller | ok: Item: Runtime: 0:00:00.008623
2026-05-06 05:28:14.708442 | controller | ok: Item: Runtime: 0:00:00.008398
2026-05-06 05:28:14.928473 | controller | ok: Item: Runtime: 0:00:00.008388
2026-05-06 05:28:15.144757 | controller | ok: Item: Runtime: 0:00:00.006727
2026-05-06 05:28:15.366235 | controller | ok: Item: Runtime: 0:00:00.006906
2026-05-06 05:28:15.640433 | controller | ok: Item: Runtime: 0:00:00.008230
2026-05-06 05:28:15.812473 | controller | ok: Item: Runtime: 0:00:00.008194
2026-05-06 05:28:16.032154 | controller | ok: Item: Runtime: 0:00:00.007642
2026-05-06 05:28:16.250400 | controller | ok: Item: Runtime: 0:00:00.008308
2026-05-06 05:28:16.484580 | controller | ok: Item: Runtime: 0:00:00.008016
2026-05-06 05:28:16.705361 | controller | ok: Item: Runtime: 0:00:00.008008
2026-05-06 05:28:16.918900 | controller | ok: Item: Runtime: 0:00:00.008083
2026-05-06 05:28:17.144432 | controller | ok: Item: Runtime: 0:00:00.008745
2026-05-06 05:28:17.162184 | 
2026-05-06 05:28:17.162345 | TASK [stage-output : Make all log files readable]
2026-05-06 05:28:17.402345 | controller | changed
2026-05-06 05:28:17.409437 | 
2026-05-06 05:28:17.409507 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-06 05:28:17.715189 | controller | changed: Renamed files for staging.
2026-05-06 05:28:17.722380 | 
2026-05-06 05:28:17.722446 | TASK [stage-output : Discover log files for compression]
2026-05-06 05:28:17.747544 | controller | skipping: Conditional result was False
2026-05-06 05:28:17.759225 | 
2026-05-06 05:28:17.759317 | LOOP [stage-output : Archive everything from logs]
2026-05-06 05:28:17.801480 | 
2026-05-06 05:28:17.801619 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-06 05:28:18.472344 | controller | changed:
2026-05-06 05:28:18.472555 | controller | created directory /var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/work/logs/controller
2026-05-06 05:28:18.472585 | controller | cd+++++++++ logs/
2026-05-06 05:28:18.472607 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-06 05:28:18.472627 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-06 05:28:18.472647 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-06 05:28:18.472666 | controller | >f+++++++++ logs/df.txt
2026-05-06 05:28:18.472684 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-06 05:28:18.472703 | controller | >f+++++++++ logs/iptables.txt
2026-05-06 05:28:18.472721 | controller | >f+++++++++ logs/listen53.txt
2026-05-06 05:28:18.472739 | controller | >f+++++++++ logs/local_conf.txt
2026-05-06 05:28:18.472757 | controller | >f+++++++++ logs/mount.txt
2026-05-06 05:28:18.472792 | controller | >f+++++++++ logs/performance.json
2026-05-06 05:28:18.472811 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-06 05:28:18.472829 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-06 05:28:18.472847 | controller | >f+++++++++ logs/services.txt
2026-05-06 05:28:18.472871 | controller | >f+++++++++ logs/sudoers
2026-05-06 05:28:18.472891 | controller | >f+++++++++ logs/syslog.txt
2026-05-06 05:28:18.472910 | controller | cd+++++++++ logs/apache/
2026-05-06 05:28:18.472928 | controller | cd+++++++++ logs/apache_config/
2026-05-06 05:28:18.472946 | controller | cd+++++++++ logs/etc/
2026-05-06 05:28:18.472983 | controller | cd+++++++++ logs/sudoers.d/
2026-05-06 05:28:18.473002 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-06 05:28:18.473021 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-06 05:28:18.473039 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-06 05:28:18.473057 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-06 05:28:18.473075 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-06 05:28:18.485238 | 
2026-05-06 05:28:18.485304 | TASK [Check if a tempest log exits]
2026-05-06 05:28:18.724610 | controller | ok
2026-05-06 05:28:18.730020 | 
2026-05-06 05:28:18.730103 | TASK [Link post-devstack tempest.log]
2026-05-06 05:28:18.756468 | controller | skipping: Conditional result was False
2026-05-06 05:28:18.768847 | 
2026-05-06 05:28:18.768913 | TASK [Capture most recent qemu crash dump, if any]
2026-05-06 05:28:19.025384 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-06 05:28:19.310605 | controller | ERROR
2026-05-06 05:28:19.310898 | controller | {
2026-05-06 05:28:19.310938 | controller |   "delta": "0:00:00.008594",
2026-05-06 05:28:19.310967 | controller |   "end": "2026-05-06 05:28:19.026164",
2026-05-06 05:28:19.310992 | controller |   "msg": "non-zero return code",
2026-05-06 05:28:19.311017 | controller |   "rc": 127,
2026-05-06 05:28:19.311042 | controller |   "start": "2026-05-06 05:28:19.017570"
2026-05-06 05:28:19.311066 | controller | }
2026-05-06 05:28:19.311099 | controller | ERROR: Ignoring Errors
2026-05-06 05:28:19.313233 | 
2026-05-06 05:28:19.313298 | PLAY RECAP
2026-05-06 05:28:19.313364 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-06 05:28:19.313393 | 
2026-05-06 05:28:19.481817 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-06 05:28:19.489491 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-06 05:28:20.111167 | 
2026-05-06 05:28:20.111306 | PLAY [all]
2026-05-06 05:28:20.123564 | 
2026-05-06 05:28:20.123658 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-06 05:28:20.179785 | controller | skipping: Conditional result was False
2026-05-06 05:28:20.193148 | 
2026-05-06 05:28:20.193254 | TASK [fetch-output : Set log path for single node]
2026-05-06 05:28:20.239972 | controller | ok
2026-05-06 05:28:20.245624 | 
2026-05-06 05:28:20.245693 | LOOP [fetch-output : Ensure local output dirs]
2026-05-06 05:28:20.639863 | controller -> localhost | ok: "/var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/work/logs"
2026-05-06 05:28:20.855481 | controller -> localhost | changed: "/var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/work/artifacts"
2026-05-06 05:28:21.085668 | controller -> localhost | changed: "/var/lib/zuul/builds/172535f877ff46dbb1845933a7b7f58c/work/docs"
2026-05-06 05:28:21.103704 | 
2026-05-06 05:28:21.103804 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-06 05:28:21.747072 | controller | changed: .d..t...... ./
2026-05-06 05:28:21.747647 | controller | changed: All items complete
2026-05-06 05:28:21.747725 | 
2026-05-06 05:28:22.228260 | controller | changed: .d..t...... ./
2026-05-06 05:28:22.698617 | controller | changed: .d..t...... ./
2026-05-06 05:28:22.722799 | 
2026-05-06 05:28:22.722867 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-06 05:28:23.142044 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008405
2026-05-06 05:28:23.365814 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008290
2026-05-06 05:28:23.389428 | 
2026-05-06 05:28:23.389495 | PLAY [all]
2026-05-06 05:28:23.396088 | 
2026-05-06 05:28:23.396162 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-06 05:28:23.911741 | controller | changed
2026-05-06 05:28:23.924197 | 
2026-05-06 05:28:23.924269 | PLAY RECAP
2026-05-06 05:28:23.924333 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-06 05:28:23.924403 | 
2026-05-06 05:28:24.073338 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-06 05:28:24.083169 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-06 05:28:24.711925 | 
2026-05-06 05:28:24.712095 | PLAY [localhost]
2026-05-06 05:28:24.722243 | 
2026-05-06 05:28:24.722338 | TASK [Generate Zuul manifest]
2026-05-06 05:28:24.744004 | localhost | ok
2026-05-06 05:28:24.764624 | 
2026-05-06 05:28:24.764815 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-06 05:28:25.144676 | localhost | changed
2026-05-06 05:28:25.157125 | 
2026-05-06 05:28:25.157204 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-06 05:28:25.189023 | localhost | ok
2026-05-06 05:28:25.198204 | 
2026-05-06 05:28:25.198299 | TASK [Upload logs]
2026-05-06 05:28:25.216236 | localhost | ok
2026-05-06 05:28:25.327330 | 
2026-05-06 05:28:25.327477 | TASK [Set zuul-log-path fact]
2026-05-06 05:28:25.346396 | localhost | ok
2026-05-06 05:28:25.357988 | 
2026-05-06 05:28:25.358066 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-06 05:28:25.385416 | localhost | ok
2026-05-06 05:28:25.395143 | 
2026-05-06 05:28:25.395209 | TASK [upload-logs : Create log directories]
2026-05-06 05:28:25.750261 | localhost | changed
2026-05-06 05:28:25.755794 | 
2026-05-06 05:28:25.755869 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-06 05:28:26.133583 | localhost -> localhost | ok: Runtime: 0:00:00.006390
2026-05-06 05:28:26.139721 | 
2026-05-06 05:28:26.139789 | TASK [upload-logs : Upload logs to log server]
2026-05-06 05:28:26.588691 | localhost | Output suppressed because no_log was given
2026-05-06 05:28:26.593920 | 
2026-05-06 05:28:26.594024 | LOOP [upload-logs : Compress console log and json output]
2026-05-06 05:28:26.643955 | localhost | skipping: Conditional result was False
2026-05-06 05:28:26.650607 | localhost | skipping: Conditional result was False
2026-05-06 05:28:26.660357 | 
2026-05-06 05:28:26.660574 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-06 05:28:26.706657 | localhost | skipping: Conditional result was False
2026-05-06 05:28:26.707077 | 
2026-05-06 05:28:26.710777 | localhost | skipping: Conditional result was False
2026-05-06 05:28:26.727089 | 
2026-05-06 05:28:26.727286 | LOOP [upload-logs : Upload console log and json output]