2026-08-26 20:11:09.504756 | Job console starting
2026-08-26 20:11:09.551352 | Updating git repos
2026-08-26 20:11:13.944804 | Cloning repos into workspace
2026-08-26 20:11:26.627711 | Restoring repo states
2026-08-26 20:11:28.780748 | Merging changes
2026-08-26 20:11:31.088105 | Checking out repos
2026-08-26 20:11:45.378551 | Preparing playbooks
2026-08-26 20:11:57.178339 | Running Ansible setup
2026-08-26 20:12:01.610690 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:12:02.290569 | 
2026-08-26 20:12:02.394982 | PLAY [localhost]
2026-08-26 20:12:02.413776 | 
2026-08-26 20:12:02.413898 | TASK [Gathering Facts]
2026-08-26 20:12:03.508135 | localhost | ok
2026-08-26 20:12:03.532592 | 
2026-08-26 20:12:03.532989 | TASK [Setup log path fact]
2026-08-26 20:12:03.568640 | localhost | ok
2026-08-26 20:12:03.582168 | 
2026-08-26 20:12:03.582336 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:12:03.619038 | localhost | ok
2026-08-26 20:12:03.633495 | 
2026-08-26 20:12:03.633630 | TASK [emit-job-header : Print job information]
2026-08-26 20:12:03.690353 | # Job Information
2026-08-26 20:12:03.690741 | Ansible Version: 2.16.18
2026-08-26 20:12:03.690829 | Job: magnum-cluster-api-hydrophone-v1.33.12-calico
2026-08-26 20:12:03.690867 | Pipeline: check
2026-08-26 20:12:03.690949 | Executor: 2d72f0692154
2026-08-26 20:12:03.691048 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1118
2026-08-26 20:12:03.691126 | Event ID: 404f3400-a189-11f1-8f6d-3337ed446285
2026-08-26 20:12:03.695859 | 
2026-08-26 20:12:03.695972 | LOOP [emit-job-header : Print node information]
2026-08-26 20:12:03.803281 | localhost | ok:
2026-08-26 20:12:03.803517 | localhost | # Node Information
2026-08-26 20:12:03.803579 | localhost | Inventory Hostname: controller
2026-08-26 20:12:03.803619 | localhost | Hostname: np0000191213
2026-08-26 20:12:03.803657 | localhost | Username: zuul
2026-08-26 20:12:03.803705 | localhost | Distro: Ubuntu 24.04
2026-08-26 20:12:03.803747 | localhost | Provider: yul1
2026-08-26 20:12:03.803778 | localhost | Region: ca-ymq-1
2026-08-26 20:12:03.803807 | localhost | Label: ubuntu-noble-16
2026-08-26 20:12:03.803836 | localhost | Product Name: OpenStack Nova
2026-08-26 20:12:03.803873 | localhost | Interface IP: 162.253.55.242
2026-08-26 20:12:03.833227 | 
2026-08-26 20:12:03.833459 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-26 20:12:04.279348 | localhost -> localhost | changed
2026-08-26 20:12:04.285426 | 
2026-08-26 20:12:04.285543 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-26 20:12:05.373688 | localhost -> localhost | changed
2026-08-26 20:12:05.383992 | 
2026-08-26 20:12:05.384071 | PLAY [all]
2026-08-26 20:12:05.393928 | 
2026-08-26 20:12:05.393998 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-26 20:12:05.643765 | controller -> localhost | ok
2026-08-26 20:12:05.650332 | 
2026-08-26 20:12:05.650411 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-26 20:12:05.684552 | controller | ok
2026-08-26 20:12:05.705683 | controller | included: /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-26 20:12:05.712223 | 
2026-08-26 20:12:05.712289 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-26 20:12:07.459492 | controller -> localhost | Generating public/private rsa key pair.
2026-08-26 20:12:07.459801 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/work/5cef041ef5ea4365ac6e259825cf32ee_id_rsa
2026-08-26 20:12:07.459850 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/work/5cef041ef5ea4365ac6e259825cf32ee_id_rsa.pub
2026-08-26 20:12:07.459885 | controller -> localhost | The key fingerprint is:
2026-08-26 20:12:07.459917 | controller -> localhost | SHA256:wmXq8bX2hIxyw8dssd1+Ias41FfTptPSpsxVHo1WSzY zuul-build-sshkey
2026-08-26 20:12:07.459965 | controller -> localhost | The key's randomart image is:
2026-08-26 20:12:07.459998 | controller -> localhost | +---[RSA 3072]----+
2026-08-26 20:12:07.460037 | controller -> localhost | |                 |
2026-08-26 20:12:07.460071 | controller -> localhost | |               E.|
2026-08-26 20:12:07.460103 | controller -> localhost | |        o     oo=|
2026-08-26 20:12:07.460134 | controller -> localhost | |     . +      o==|
2026-08-26 20:12:07.460164 | controller -> localhost | |      = S +  ..*+|
2026-08-26 20:12:07.460194 | controller -> localhost | |     . = B B ++.*|
2026-08-26 20:12:07.460224 | controller -> localhost | |      o B @ +o+*.|
2026-08-26 20:12:07.460254 | controller -> localhost | |       o *.o o+ .|
2026-08-26 20:12:07.460288 | controller -> localhost | |         ...o .. |
2026-08-26 20:12:07.460318 | controller -> localhost | +----[SHA256]-----+
2026-08-26 20:12:07.460406 | controller -> localhost | ok: Runtime: 0:00:01.315165
2026-08-26 20:12:07.471456 | 
2026-08-26 20:12:07.471595 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-26 20:12:07.507042 | controller | ok
2026-08-26 20:12:07.524384 | controller | included: /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-26 20:12:07.541985 | 
2026-08-26 20:12:07.542138 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-26 20:12:07.565533 | controller | skipping: Conditional result was False
2026-08-26 20:12:07.577788 | 
2026-08-26 20:12:07.577854 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-26 20:12:08.171042 | controller | changed
2026-08-26 20:12:08.179349 | 
2026-08-26 20:12:08.179474 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-26 20:12:08.415680 | controller | ok
2026-08-26 20:12:08.424074 | 
2026-08-26 20:12:08.424170 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-26 20:12:09.064389 | controller | changed
2026-08-26 20:12:09.072325 | 
2026-08-26 20:12:09.072394 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-26 20:12:09.780048 | controller | changed
2026-08-26 20:12:09.786013 | 
2026-08-26 20:12:09.786080 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-26 20:12:09.810632 | controller | skipping: Conditional result was False
2026-08-26 20:12:09.817691 | 
2026-08-26 20:12:09.817769 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-26 20:12:10.258786 | controller -> localhost | changed
2026-08-26 20:12:10.277276 | 
2026-08-26 20:12:10.277431 | TASK [add-build-sshkey : Add back temp key]
2026-08-26 20:12:10.628996 | controller -> localhost | Identity added: /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/work/5cef041ef5ea4365ac6e259825cf32ee_id_rsa (zuul-build-sshkey)
2026-08-26 20:12:10.629254 | controller -> localhost | ok: Runtime: 0:00:00.030856
2026-08-26 20:12:10.636938 | 
2026-08-26 20:12:10.637089 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-26 20:12:10.965380 | controller | ok
2026-08-26 20:12:10.971023 | 
2026-08-26 20:12:10.971102 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-26 20:12:10.996153 | controller | skipping: Conditional result was False
2026-08-26 20:12:11.016268 | 
2026-08-26 20:12:11.016419 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-26 20:12:11.367609 | controller | ok
2026-08-26 20:12:11.373992 | 
2026-08-26 20:12:11.374070 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-26 20:13:12.835161 | controller | Output suppressed because no_log was given
2026-08-26 20:13:12.849767 | 
2026-08-26 20:13:12.849836 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-26 20:13:13.351077 | controller | ok: "logs"
2026-08-26 20:13:13.351305 | controller | ok: All items complete
2026-08-26 20:13:13.351334 | 
2026-08-26 20:13:13.574392 | controller | ok: "artifacts"
2026-08-26 20:13:13.807602 | controller | ok: "docs"
2026-08-26 20:13:13.836590 | 
2026-08-26 20:13:13.836707 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-26 20:13:14.066878 | controller | changed: "logs"
2026-08-26 20:13:14.257105 | controller | changed: "artifacts"
2026-08-26 20:13:14.469614 | controller | changed: "docs"
2026-08-26 20:13:14.482685 | 
2026-08-26 20:13:14.482876 | PLAY RECAP
2026-08-26 20:13:14.482936 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-26 20:13:14.482967 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:13:14.482990 | 
2026-08-26 20:13:14.652429 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-08-26 20:13:14.657602 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:13:15.350211 | 
2026-08-26 20:13:15.350357 | PLAY [all]
2026-08-26 20:13:15.368331 | 
2026-08-26 20:13:15.368411 | TASK [Fix the permissions of the zuul home directory]
2026-08-26 20:13:16.043496 | controller | changed
2026-08-26 20:13:16.049286 | 
2026-08-26 20:13:16.049369 | TASK [Gather minimum local MTU]
2026-08-26 20:13:16.143815 | controller | ok
2026-08-26 20:13:16.153024 | 
2026-08-26 20:13:16.153098 | TASK [Calculate external_bridge_mtu]
2026-08-26 20:13:16.203990 | controller | ok
2026-08-26 20:13:16.210536 | 
2026-08-26 20:13:16.210610 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-08-26 20:13:16.242138 | controller | skipping: Conditional result was False
2026-08-26 20:13:16.253005 | 
2026-08-26 20:13:16.253101 | TASK [configure-swap : Get ephemeral0 device node]
2026-08-26 20:13:16.797304 | controller | ok: Runtime: 0:00:00.011292
2026-08-26 20:13:16.804910 | 
2026-08-26 20:13:16.805027 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-08-26 20:13:16.850310 | controller | skipping: Conditional result was False
2026-08-26 20:13:16.856830 | 
2026-08-26 20:13:16.856901 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-08-26 20:13:16.880998 | controller | skipping: Conditional result was False
2026-08-26 20:13:16.886832 | 
2026-08-26 20:13:16.886902 | TASK [configure-swap : Setup swap file on root device]
2026-08-26 20:13:16.939994 | controller | ok
2026-08-26 20:13:16.950437 | controller | included: /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-08-26 20:13:16.963546 | 
2026-08-26 20:13:16.963718 | TASK [configure-swap : Calculate required swap]
2026-08-26 20:13:17.080982 | controller | ok
2026-08-26 20:13:17.161478 | 
2026-08-26 20:13:17.161678 | TASK [configure-swap : Get root filesystem]
2026-08-26 20:13:17.443506 | controller | ext4
2026-08-26 20:13:17.707078 | controller | ok: Runtime: 0:00:00.033156
2026-08-26 20:13:17.715206 | 
2026-08-26 20:13:17.715301 | TASK [configure-swap : Save root filesystem]
2026-08-26 20:13:17.751384 | controller | ok
2026-08-26 20:13:17.760474 | 
2026-08-26 20:13:17.760565 | TASK [configure-swap : Debug the root_filesystem variable]
2026-08-26 20:13:17.795096 | controller | ok:
2026-08-26 20:13:17.795246 | controller | {
2026-08-26 20:13:17.795281 | controller |   "root_filesystem": "ext4"
2026-08-26 20:13:17.795308 | controller | }
2026-08-26 20:13:17.802163 | 
2026-08-26 20:13:17.802249 | TASK [configure-swap : Create swap backing file]
2026-08-26 20:13:27.328175 | controller | 8192+0 records in
2026-08-26 20:13:27.328299 | controller | 8192+0 records out
2026-08-26 20:13:27.328318 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.19119 s, 935 MB/s
2026-08-26 20:13:27.397657 | controller | ok: Runtime: 0:00:09.200433
2026-08-26 20:13:27.403994 | 
2026-08-26 20:13:27.409250 | TASK [configure-swap : Ensure swapfile perms]
2026-08-26 20:13:27.674274 | controller | changed
2026-08-26 20:13:27.685929 | 
2026-08-26 20:13:27.686019 | TASK [configure-swap : Make swapfile]
2026-08-26 20:14:00.185352 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-08-26 20:14:00.185499 | controller | no label, UUID=33e53421-a3e1-4455-9e5d-2cfaa2a3600a
2026-08-26 20:14:00.255900 | controller | ok: Runtime: 0:00:32.197048
2026-08-26 20:14:00.261503 | 
2026-08-26 20:14:00.261568 | TASK [configure-swap : Write swap to fstab]
2026-08-26 20:14:00.675381 | controller | changed
2026-08-26 20:14:00.682762 | 
2026-08-26 20:14:00.682864 | TASK [configure-swap : Add all swap]
2026-08-26 20:14:01.252370 | controller | ok: Runtime: 0:00:00.016807
2026-08-26 20:14:01.259005 | 
2026-08-26 20:14:01.259073 | TASK [configure-swap : Debug the swap_required variable]
2026-08-26 20:14:01.291769 | controller | ok:
2026-08-26 20:14:01.291918 | controller | {
2026-08-26 20:14:01.291944 | controller |   "swap_required": "8192"
2026-08-26 20:14:01.291964 | controller | }
2026-08-26 20:14:01.301442 | 
2026-08-26 20:14:01.301582 | TASK [configure-swap : Set swappiness]
2026-08-26 20:14:01.737616 | controller | changed
2026-08-26 20:14:01.743166 | 
2026-08-26 20:14:01.743239 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-08-26 20:14:01.778150 | controller | ok:
2026-08-26 20:14:01.778303 | controller | {
2026-08-26 20:14:01.778338 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-08-26 20:14:01.778369 | controller | }
2026-08-26 20:14:01.791968 | 
2026-08-26 20:14:01.792031 | TASK [setup-stack-user : Create stack group]
2026-08-26 20:14:02.299296 | controller | changed
2026-08-26 20:14:02.308675 | 
2026-08-26 20:14:02.308743 | TASK [setup-stack-user : Create the stack user home folder]
2026-08-26 20:14:02.575316 | controller | changed
2026-08-26 20:14:02.583282 | 
2026-08-26 20:14:02.583352 | TASK [setup-stack-user : Create stack user]
2026-08-26 20:14:03.180667 | controller | changed
2026-08-26 20:14:03.244426 | 
2026-08-26 20:14:03.244529 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-08-26 20:14:03.538802 | controller | changed
2026-08-26 20:14:03.548393 | 
2026-08-26 20:14:03.548489 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-08-26 20:14:04.772263 | controller | changed
2026-08-26 20:14:04.780879 | 
2026-08-26 20:14:04.780950 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-08-26 20:14:05.105778 | controller | changed
2026-08-26 20:14:05.156057 | 
2026-08-26 20:14:05.156135 | TASK [setup-tempest-user : Create tempest group]
2026-08-26 20:14:05.437014 | controller | changed
2026-08-26 20:14:05.447943 | 
2026-08-26 20:14:05.448020 | TASK [setup-tempest-user : Create tempest user]
2026-08-26 20:14:05.828029 | controller | changed
2026-08-26 20:14:05.835643 | 
2026-08-26 20:14:05.835736 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-08-26 20:14:06.683211 | controller | changed
2026-08-26 20:14:06.696499 | 
2026-08-26 20:14:06.696581 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-08-26 20:14:07.064071 | controller | ok: Not all paths examined, check warnings for details
2026-08-26 20:14:07.074693 | 
2026-08-26 20:14:07.074761 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-08-26 20:14:08.330906 | controller | ok: Item: Runtime: 0:00:00.955658
2026-08-26 20:14:08.986903 | controller | ok: Item: Runtime: 0:00:00.424369
2026-08-26 20:14:09.803034 | controller | ok: Item: Runtime: 0:00:00.580985
2026-08-26 20:14:10.434433 | controller | ok: Item: Runtime: 0:00:00.375244
2026-08-26 20:14:10.813952 | controller | ok: Item: Runtime: 0:00:00.152916
2026-08-26 20:14:11.123873 | controller | ok: Item: Runtime: 0:00:00.064738
2026-08-26 20:14:12.579268 | controller | ok: Item: Runtime: 0:00:01.205404
2026-08-26 20:14:15.760861 | controller | ok: Item: Runtime: 0:00:02.917244
2026-08-26 20:14:16.260653 | controller | ok: Item: Runtime: 0:00:00.254177
2026-08-26 20:14:16.790663 | controller | ok: Item: Runtime: 0:00:00.292749
2026-08-26 20:14:17.268368 | controller | ok: Item: Runtime: 0:00:00.258945
2026-08-26 20:14:17.755725 | controller | ok: Item: Runtime: 0:00:00.235895
2026-08-26 20:14:18.613970 | controller | ok: Item: Runtime: 0:00:00.612276
2026-08-26 20:14:19.458053 | controller | ok: Item: Runtime: 0:00:00.564599
2026-08-26 20:14:20.117474 | controller | ok: Item: Runtime: 0:00:00.410904
2026-08-26 20:14:20.543268 | controller | ok: Item: Runtime: 0:00:00.199747
2026-08-26 20:14:20.573632 | 
2026-08-26 20:14:20.573774 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-08-26 20:14:20.810604 | controller | ok: All paths examined
2026-08-26 20:14:20.817250 | 
2026-08-26 20:14:20.817329 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-08-26 20:14:21.061551 | controller | ok: All paths examined
2026-08-26 20:14:21.072190 | 
2026-08-26 20:14:21.072293 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-08-26 20:14:21.562590 | controller | ok: Item: Runtime: 0:00:00.186655
2026-08-26 20:14:21.562868 | controller | changed: All items complete
2026-08-26 20:14:21.562899 | 
2026-08-26 20:14:22.331976 | controller | ok: Item: Runtime: 0:00:00.258880
2026-08-26 20:14:22.347269 | 
2026-08-26 20:14:22.347421 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-08-26 20:14:22.458225 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.460469 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.464173 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.467970 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.471729 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.475861 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.479340 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.483401 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.486695 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.490337 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.493881 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.497835 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.501394 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.504920 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.508459 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.512157 | controller | skipping: Conditional result was False
2026-08-26 20:14:22.579566 | 
2026-08-26 20:14:22.579690 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-08-26 20:14:25.363266 | controller | changed
2026-08-26 20:14:25.383648 | 
2026-08-26 20:14:25.384041 | TASK [setup-devstack-log-dir : Create logs directory]
2026-08-26 20:14:25.660624 | controller | changed
2026-08-26 20:14:25.676157 | 
2026-08-26 20:14:25.676307 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-08-26 20:14:25.964090 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-08-26 20:14:26.225351 | controller | ERROR
2026-08-26 20:14:26.232606 | controller | {
2026-08-26 20:14:26.232666 | controller |   "delta": "0:00:00.009906",
2026-08-26 20:14:26.232690 | controller |   "end": "2026-08-26 20:14:25.964637",
2026-08-26 20:14:26.232710 | controller |   "msg": "non-zero return code",
2026-08-26 20:14:26.232728 | controller |   "rc": 1,
2026-08-26 20:14:26.232747 | controller |   "start": "2026-08-26 20:14:25.954731"
2026-08-26 20:14:26.232764 | controller | }
2026-08-26 20:14:26.232790 | controller | ERROR: Ignoring Errors
2026-08-26 20:14:26.244475 | 
2026-08-26 20:14:26.244565 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-08-26 20:14:26.514400 | controller | ok
2026-08-26 20:14:26.530998 | 
2026-08-26 20:14:26.531138 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-08-26 20:14:26.861505 | controller | /usr/bin/journalctl
2026-08-26 20:14:27.111318 | controller | ok: Runtime: 0:00:00.010901
2026-08-26 20:14:27.121074 | 
2026-08-26 20:14:27.121168 | TASK [start-fresh-logging : Get current date]
2026-08-26 20:14:27.436440 | controller | 2026-08-26 20:14:27
2026-08-26 20:14:28.217876 | controller | ok: Runtime: 0:00:00.014513
2026-08-26 20:14:28.228515 | 
2026-08-26 20:14:28.228646 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-08-26 20:14:28.876761 | controller | changed
2026-08-26 20:14:28.884441 | 
2026-08-26 20:14:28.884533 | TASK [start-fresh-logging : Stop rsyslog]
2026-08-26 20:14:28.920994 | controller | skipping: Conditional result was False
2026-08-26 20:14:28.928101 | 
2026-08-26 20:14:28.928203 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-08-26 20:14:29.495783 | controller | skipping: Conditional result was False
2026-08-26 20:14:29.504507 | 
2026-08-26 20:14:29.504622 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-08-26 20:14:30.041013 | controller | skipping: Conditional result was False
2026-08-26 20:14:30.054378 | 
2026-08-26 20:14:30.054465 | TASK [start-fresh-logging : Recreate syslog file]
2026-08-26 20:14:30.072814 | controller | skipping: Conditional result was False
2026-08-26 20:14:30.087901 | 
2026-08-26 20:14:30.087983 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-08-26 20:14:30.629920 | controller | skipping: Conditional result was False
2026-08-26 20:14:30.638327 | 
2026-08-26 20:14:30.638420 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-08-26 20:14:31.310128 | controller | skipping: Conditional result was False
2026-08-26 20:14:31.320570 | 
2026-08-26 20:14:31.320672 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-08-26 20:14:31.366158 | controller | skipping: Conditional result was False
2026-08-26 20:14:31.375470 | 
2026-08-26 20:14:31.375619 | TASK [start-fresh-logging : Recreate kern.log file]
2026-08-26 20:14:31.409115 | controller | skipping: Conditional result was False
2026-08-26 20:14:31.417334 | 
2026-08-26 20:14:31.417423 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-08-26 20:14:31.952607 | controller | skipping: Conditional result was False
2026-08-26 20:14:31.960664 | 
2026-08-26 20:14:31.960752 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-08-26 20:14:32.499287 | controller | skipping: Conditional result was False
2026-08-26 20:14:32.508624 | 
2026-08-26 20:14:32.508745 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-08-26 20:14:32.534173 | controller | skipping: Conditional result was False
2026-08-26 20:14:32.543046 | 
2026-08-26 20:14:32.543157 | TASK [start-fresh-logging : Start rsyslog]
2026-08-26 20:14:32.581259 | controller | skipping: Conditional result was False
2026-08-26 20:14:32.604884 | 
2026-08-26 20:14:32.604988 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-08-26 20:14:33.293327 | controller | ok
2026-08-26 20:14:33.309459 | 
2026-08-26 20:14:33.309544 | PLAY RECAP
2026-08-26 20:14:33.309780 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-08-26 20:14:33.309814 | 
2026-08-26 20:14:33.569570 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-26 20:14:33.577003 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:14:34.362198 | 
2026-08-26 20:14:34.362886 | PLAY [all]
2026-08-26 20:14:34.378186 | 
2026-08-26 20:14:34.378343 | TASK [Install curl]
2026-08-26 20:14:51.035105 | controller | ok
2026-08-26 20:14:51.041422 | 
2026-08-26 20:14:51.041602 | TASK [ensure-rust : Use rustup]
2026-08-26 20:14:51.075788 | controller | ok
2026-08-26 20:14:51.086159 | controller | included: /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-08-26 20:14:51.094357 | 
2026-08-26 20:14:51.094816 | TASK [ensure-rust : Install Rust]
2026-08-26 20:15:15.559444 | controller | curl: (6) Could not resolve host: sh.rustup.rs
2026-08-26 20:15:15.671391 | controller | ERROR
2026-08-26 20:15:15.671743 | controller | {
2026-08-26 20:15:15.671799 | controller |   "delta": "0:00:24.042698",
2026-08-26 20:15:15.671839 | controller |   "end": "2026-08-26 20:15:15.570076",
2026-08-26 20:15:15.671929 | controller |   "msg": "non-zero return code",
2026-08-26 20:15:15.671976 | controller |   "rc": 6,
2026-08-26 20:15:15.672017 | controller |   "start": "2026-08-26 20:14:51.527378"
2026-08-26 20:15:15.672048 | controller | }
failure
2026-08-26 20:15:15.674991 | 
2026-08-26 20:15:15.675068 | PLAY RECAP
2026-08-26 20:15:15.675201 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-26 20:15:15.675244 | 
2026-08-26 20:15:15.908180 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-08-26 20:15:15.917165 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:15:16.635464 | 
2026-08-26 20:15:16.635612 | PLAY [all]
2026-08-26 20:15:16.648082 | 
2026-08-26 20:15:16.648155 | LOOP [Copy individual Hydrophone result files]
2026-08-26 20:15:17.364637 | controller | ERROR: Item: e2e.log
2026-08-26 20:15:17.365007 | controller | {
2026-08-26 20:15:17.365056 | controller |   "ansible_loop_var": "item",
2026-08-26 20:15:17.365087 | controller |   "item": "e2e.log",
2026-08-26 20:15:17.365145 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-08-26 20:15:17.365170 | controller | }
2026-08-26 20:15:17.646175 | controller | ERROR: Item: junit_01.xml
2026-08-26 20:15:17.733041 | controller | {
2026-08-26 20:15:17.733151 | controller |   "ansible_loop_var": "item",
2026-08-26 20:15:17.733204 | controller |   "item": "junit_01.xml",
2026-08-26 20:15:17.733245 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-08-26 20:15:17.733286 | controller | }
2026-08-26 20:15:17.751955 | controller | ERROR: Ignoring Errors
2026-08-26 20:15:17.757557 | 
2026-08-26 20:15:17.757630 | TASK [Copy Hydrophone results tarball to output folder]
2026-08-26 20:15:18.075958 | controller | ERROR
2026-08-26 20:15:18.076330 | controller | {
2026-08-26 20:15:18.076397 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-08-26 20:15:18.076445 | controller | }
2026-08-26 20:15:18.076546 | controller | ERROR: Ignoring Errors
2026-08-26 20:15:18.088503 | 
2026-08-26 20:15:18.088583 | TASK [Return built artifacts to Zuul]
2026-08-26 20:15:18.125583 | controller | ok
2026-08-26 20:15:18.130907 | 
2026-08-26 20:15:18.130973 | TASK [Get the Docker volume ID]
2026-08-26 20:15:18.674856 | controller | ERROR
2026-08-26 20:15:18.675086 | controller | {
2026-08-26 20:15:18.675117 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-08-26 20:15:18.675139 | controller |   "rc": 2
2026-08-26 20:15:18.675159 | controller | }
2026-08-26 20:15:18.675187 | controller | ERROR: Ignoring Errors
2026-08-26 20:15:18.680358 | 
2026-08-26 20:15:18.680435 | TASK [Copy all of the pod logs]
2026-08-26 20:15:18.720491 | controller | ERROR
2026-08-26 20:15:18.720773 | controller | {
2026-08-26 20:15:18.720816 | 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/5cef041ef5ea4365ac6e259825cf32ee/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:15:18.720864 | controller | }
2026-08-26 20:15:18.720978 | controller | ERROR: Ignoring Errors
2026-08-26 20:15:18.722228 | 
2026-08-26 20:15:18.722293 | PLAY RECAP
2026-08-26 20:15:18.722354 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-08-26 20:15:18.722385 | 
2026-08-26 20:15:18.885024 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-08-26 20:15:18.894609 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:15:19.667256 | 
2026-08-26 20:15:19.667415 | PLAY [all]
2026-08-26 20:15:19.689707 | 
2026-08-26 20:15:19.689848 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-08-26 20:15:20.358612 | controller | changed
2026-08-26 20:15:20.367838 | 
2026-08-26 20:15:20.367921 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-08-26 20:15:21.412823 | controller | ok: Runtime: 0:00:00.507256
2026-08-26 20:15:21.420803 | 
2026-08-26 20:15:21.420884 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-08-26 20:15:21.964259 | controller | ok: Runtime: 0:00:00.019730
2026-08-26 20:15:21.977867 | 
2026-08-26 20:15:21.977976 | TASK [export-devstack-journal : Export journal]
2026-08-26 20:15:22.554129 | controller | ok: Runtime: 0:00:00.023998
2026-08-26 20:15:22.665658 | 
2026-08-26 20:15:22.665782 | TASK [export-devstack-journal : Save journal README]
2026-08-26 20:15:23.714771 | controller | changed
2026-08-26 20:15:23.974432 | 
2026-08-26 20:15:23.974562 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-08-26 20:15:24.649115 | controller | changed
2026-08-26 20:15:24.655951 | 
2026-08-26 20:15:24.656027 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:15:25.020498 | controller | Output suppressed because no_log was given
2026-08-26 20:15:25.028439 | 
2026-08-26 20:15:25.028516 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:15:25.054508 | 
2026-08-26 20:15:25.054627 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:15:25.080780 | 
2026-08-26 20:15:25.080922 | TASK [apache-logs-conf : Find logs]
2026-08-26 20:15:25.118265 | 
2026-08-26 20:15:25.118609 | LOOP [apache-logs-conf : Dereference files]
2026-08-26 20:15:25.156413 | 
2026-08-26 20:15:25.156563 | LOOP [apache-logs-conf : Create hard links]
2026-08-26 20:15:25.212006 | 
2026-08-26 20:15:25.212232 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-08-26 20:15:25.501645 | controller | changed
2026-08-26 20:15:25.525157 | 
2026-08-26 20:15:25.525237 | TASK [apache-logs-conf : Define config paths]
2026-08-26 20:15:25.561362 | controller | ok
2026-08-26 20:15:25.567022 | 
2026-08-26 20:15:25.567119 | TASK [apache-logs-conf : Discover configurations]
2026-08-26 20:15:25.827478 | controller | Output suppressed because no_log was given
2026-08-26 20:15:25.834910 | 
2026-08-26 20:15:25.834983 | LOOP [apache-logs-conf : Dereference configurations]
2026-08-26 20:15:25.869505 | 
2026-08-26 20:15:25.869668 | LOOP [apache-logs-conf : Link configurations]
2026-08-26 20:15:25.902064 | 
2026-08-26 20:15:25.902221 | TASK [capture-performance-data : Generate statistics]
2026-08-26 20:15:26.622668 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-08-26 20:15:26.642107 | controller | Using python 3.12 to install setuptools
2026-08-26 20:15:26.695382 | controller | /usr/bin/python3.12: No module named pip
2026-08-26 20:15:26.709879 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-08-26 20:15:26.948811 | controller | ERROR
2026-08-26 20:15:26.949023 | controller | {
2026-08-26 20:15:26.949059 | controller |   "delta": "0:00:00.528063",
2026-08-26 20:15:26.949081 | controller |   "end": "2026-08-26 20:15:26.712016",
2026-08-26 20:15:26.949100 | controller |   "msg": "non-zero return code",
2026-08-26 20:15:26.949124 | controller |   "rc": 127,
2026-08-26 20:15:26.949157 | controller |   "start": "2026-08-26 20:15:26.183953"
2026-08-26 20:15:26.949176 | controller | }
2026-08-26 20:15:26.949201 | controller | ERROR: Ignoring Errors
2026-08-26 20:15:26.960998 | 
2026-08-26 20:15:26.961072 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-08-26 20:15:27.229829 | controller | changed
2026-08-26 20:15:27.420155 | 
2026-08-26 20:15:27.420328 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-08-26 20:15:31.354632 | controller | Output suppressed because no_log was given
2026-08-26 20:15:31.362538 | 
2026-08-26 20:15:31.362603 | LOOP [devstack-project-conf : Copy configuration files]
2026-08-26 20:15:31.449390 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.449829 | 
2026-08-26 20:15:31.451859 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.453637 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.456490 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.458632 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.461464 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.464414 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.467809 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.470520 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.473122 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.475755 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.478570 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.481370 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.484219 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.486924 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.489609 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.492527 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.495161 | controller | skipping: Conditional result was False
2026-08-26 20:15:31.564951 | 
2026-08-26 20:15:31.565137 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-08-26 20:15:31.814014 | controller | ok
2026-08-26 20:15:31.821112 | 
2026-08-26 20:15:31.821256 | TASK [devstack-project-conf : Copy configuration files]
2026-08-26 20:15:32.352992 | controller | skipping: Conditional result was False
2026-08-26 20:15:32.369544 | 
2026-08-26 20:15:32.369622 | TASK [capture-system-logs : Stage various logs and reports]
2026-08-26 20:15:32.784646 | controller | /usr/bin/python3: No module named pip
2026-08-26 20:15:33.904284 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-08-26 20:15:34.407645 | controller | ok: Runtime: 0:00:01.245233
2026-08-26 20:15:34.418260 | 
2026-08-26 20:15:34.418324 | LOOP [stage-output : Register sources]
2026-08-26 20:15:44.241371 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.253223 | 
2026-08-26 20:15:44.253320 | TASK [stage-output : Check sudo]
2026-08-26 20:15:44.801261 | controller | ok: Runtime: 0:00:00.024441
2026-08-26 20:15:44.807170 | 
2026-08-26 20:15:44.807237 | LOOP [stage-output : Set source and destination for files and folders]
2026-08-26 20:15:44.917321 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.917556 | 
2026-08-26 20:15:44.923792 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.927987 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.932811 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.974447 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.974868 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.976214 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.979592 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.982130 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.984783 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.987485 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.990161 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.992887 | controller | Output suppressed because no_log was given
2026-08-26 20:15:44.995600 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.012844 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.013402 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.013885 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.016359 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.019013 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.021545 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.024058 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.026975 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.029489 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.070080 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.070453 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.166868 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.180604 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.197894 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.204704 | controller | Output suppressed because no_log was given
2026-08-26 20:15:45.221850 | 
2026-08-26 20:15:45.222165 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-08-26 20:15:45.299808 | controller | ok
2026-08-26 20:15:45.309845 | 
2026-08-26 20:15:45.309917 | LOOP [stage-output : Ensure target folders exist]
2026-08-26 20:15:45.578975 | controller | changed: "docs"
2026-08-26 20:15:45.851898 | controller | changed: "artifacts"
2026-08-26 20:15:46.095715 | controller | ok: "logs"
2026-08-26 20:15:46.109299 | 
2026-08-26 20:15:46.109651 | LOOP [stage-output : Copy files and folders to staging folder]
2026-08-26 20:15:46.408566 | controller | ok: Item: Runtime: 0:00:00.009438
2026-08-26 20:15:46.408832 | controller | changed: All items complete
2026-08-26 20:15:46.408873 | 
2026-08-26 20:15:46.640453 | controller | ok: Item: Runtime: 0:00:00.008717
2026-08-26 20:15:46.874995 | controller | ok: Item: Runtime: 0:00:00.007966
2026-08-26 20:15:47.127436 | controller | ok: Item: Runtime: 0:00:00.009241
2026-08-26 20:15:47.363486 | controller | ok: Item: Runtime: 0:00:00.007740
2026-08-26 20:15:47.622287 | controller | ok: Item: Runtime: 0:00:00.010138
2026-08-26 20:15:47.868437 | controller | ok: Item: Runtime: 0:00:00.007126
2026-08-26 20:15:48.121813 | controller | ok: Item: Runtime: 0:00:00.009324
2026-08-26 20:15:48.432754 | controller | ok: Item: Runtime: 0:00:00.009482
2026-08-26 20:15:48.626609 | controller | ok: Item: Runtime: 0:00:00.007667
2026-08-26 20:15:48.853517 | controller | ok: Item: Runtime: 0:00:00.007680
2026-08-26 20:15:49.103136 | controller | ok: Item: Runtime: 0:00:00.010174
2026-08-26 20:15:49.349416 | controller | ok: Item: Runtime: 0:00:00.006146
2026-08-26 20:15:49.597077 | controller | ok: Item: Runtime: 0:00:00.007738
2026-08-26 20:15:49.857068 | controller | ok: Item: Runtime: 0:00:00.009710
2026-08-26 20:15:50.096131 | controller | ok: Item: Runtime: 0:00:00.010008
2026-08-26 20:15:50.118249 | 
2026-08-26 20:15:50.118383 | TASK [stage-output : Make all log files readable]
2026-08-26 20:15:50.368073 | controller | changed
2026-08-26 20:15:50.377878 | 
2026-08-26 20:15:50.377969 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-08-26 20:15:50.769033 | controller | changed: Renamed files for staging.
2026-08-26 20:15:50.776625 | 
2026-08-26 20:15:50.776708 | TASK [stage-output : Discover log files for compression]
2026-08-26 20:15:50.801956 | controller | skipping: Conditional result was False
2026-08-26 20:15:50.817677 | 
2026-08-26 20:15:50.817760 | LOOP [stage-output : Archive everything from logs]
2026-08-26 20:15:50.861856 | 
2026-08-26 20:15:50.862242 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-08-26 20:15:51.657274 | controller | changed:
2026-08-26 20:15:51.657430 | controller | created directory /var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/work/logs/controller
2026-08-26 20:15:51.657458 | controller | cd+++++++++ logs/
2026-08-26 20:15:51.657480 | controller | >f+++++++++ logs/deprecations_log.txt
2026-08-26 20:15:51.657500 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-08-26 20:15:51.657519 | controller | >f+++++++++ logs/devstack.journal.gz
2026-08-26 20:15:51.657539 | controller | >f+++++++++ logs/df.txt
2026-08-26 20:15:51.657558 | controller | >f+++++++++ logs/dpkg-l.txt
2026-08-26 20:15:51.657577 | controller | >f+++++++++ logs/iptables.txt
2026-08-26 20:15:51.657596 | controller | >f+++++++++ logs/listen53.txt
2026-08-26 20:15:51.657614 | controller | >f+++++++++ logs/local_conf.txt
2026-08-26 20:15:51.657633 | controller | >f+++++++++ logs/mount.txt
2026-08-26 20:15:51.657651 | controller | >f+++++++++ logs/performance.json
2026-08-26 20:15:51.657673 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-08-26 20:15:51.657701 | controller | >f+++++++++ logs/resolv_conf.txt
2026-08-26 20:15:51.657726 | controller | >f+++++++++ logs/services.txt
2026-08-26 20:15:51.657786 | controller | >f+++++++++ logs/sudoers
2026-08-26 20:15:51.658058 | controller | >f+++++++++ logs/syslog.txt
2026-08-26 20:15:51.658090 | controller | cd+++++++++ logs/apache/
2026-08-26 20:15:51.658112 | controller | cd+++++++++ logs/apache_config/
2026-08-26 20:15:51.658132 | controller | cd+++++++++ logs/etc/
2026-08-26 20:15:51.658151 | controller | cd+++++++++ logs/sudoers.d/
2026-08-26 20:15:51.658170 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-08-26 20:15:51.658189 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-08-26 20:15:51.658208 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-08-26 20:15:51.658226 | controller | >f+++++++++ logs/sudoers.d/README
2026-08-26 20:15:51.658245 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-08-26 20:15:51.673028 | 
2026-08-26 20:15:51.673091 | TASK [Check if a tempest log exits]
2026-08-26 20:15:51.949817 | controller | ok
2026-08-26 20:15:51.955514 | 
2026-08-26 20:15:51.955579 | TASK [Link post-devstack tempest.log]
2026-08-26 20:15:51.981642 | controller | skipping: Conditional result was False
2026-08-26 20:15:51.995099 | 
2026-08-26 20:15:51.995204 | TASK [Capture most recent qemu crash dump, if any]
2026-08-26 20:15:52.277335 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-08-26 20:15:52.542811 | controller | ERROR
2026-08-26 20:15:52.543069 | controller | {
2026-08-26 20:15:52.543114 | controller |   "delta": "0:00:00.008097",
2026-08-26 20:15:52.543144 | controller |   "end": "2026-08-26 20:15:52.278028",
2026-08-26 20:15:52.543170 | controller |   "msg": "non-zero return code",
2026-08-26 20:15:52.543195 | controller |   "rc": 127,
2026-08-26 20:15:52.543219 | controller |   "start": "2026-08-26 20:15:52.269931"
2026-08-26 20:15:52.543243 | controller | }
2026-08-26 20:15:52.543276 | controller | ERROR: Ignoring Errors
2026-08-26 20:15:52.551455 | 
2026-08-26 20:15:52.551532 | TASK [Collect Guru Meditation Reports from OpenStack services]
2026-08-26 20:15:53.092513 | controller | ERROR
2026-08-26 20:15:53.092810 | controller | {
2026-08-26 20:15:53.092854 | controller |   "delta": "0:00:00.101698",
2026-08-26 20:15:53.092883 | controller |   "end": "2026-08-26 20:15:52.924111",
2026-08-26 20:15:53.092909 | controller |   "msg": "non-zero return code",
2026-08-26 20:15:53.092934 | controller |   "rc": -12,
2026-08-26 20:15:53.093029 | controller |   "start": "2026-08-26 20:15:52.822413"
2026-08-26 20:15:53.093093 | controller | }
2026-08-26 20:15:53.093131 | controller | ERROR: Ignoring Errors
2026-08-26 20:15:53.095259 | 
2026-08-26 20:15:53.095316 | PLAY RECAP
2026-08-26 20:15:53.095379 | controller | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-08-26 20:15:53.095410 | 
2026-08-26 20:15:53.345977 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-26 20:15:53.369656 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:15:54.054502 | 
2026-08-26 20:15:54.054676 | PLAY [all]
2026-08-26 20:15:54.068477 | 
2026-08-26 20:15:54.068561 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-26 20:15:54.125850 | controller | skipping: Conditional result was False
2026-08-26 20:15:54.139003 | 
2026-08-26 20:15:54.139089 | TASK [fetch-output : Set log path for single node]
2026-08-26 20:15:54.183953 | controller | ok
2026-08-26 20:15:54.191636 | 
2026-08-26 20:15:54.191750 | LOOP [fetch-output : Ensure local output dirs]
2026-08-26 20:15:54.597027 | controller -> localhost | ok: "/var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/work/logs"
2026-08-26 20:15:54.817144 | controller -> localhost | changed: "/var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/work/artifacts"
2026-08-26 20:15:55.073709 | controller -> localhost | changed: "/var/lib/zuul/builds/5cef041ef5ea4365ac6e259825cf32ee/work/docs"
2026-08-26 20:15:55.090205 | 
2026-08-26 20:15:55.090411 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-26 20:15:55.915023 | controller | changed: .d..t...... ./
2026-08-26 20:15:55.915241 | controller | changed: All items complete
2026-08-26 20:15:55.915275 | 
2026-08-26 20:15:56.507254 | controller | changed: .d..t...... ./
2026-08-26 20:15:57.076260 | controller | changed: .d..t...... ./
2026-08-26 20:15:57.089754 | 
2026-08-26 20:15:57.089838 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-26 20:15:57.549121 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009229
2026-08-26 20:15:57.870636 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.092055
2026-08-26 20:15:57.891853 | 
2026-08-26 20:15:57.892078 | PLAY [all]
2026-08-26 20:15:57.899415 | 
2026-08-26 20:15:57.899483 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-26 20:15:58.716636 | controller | changed
2026-08-26 20:15:58.724385 | 
2026-08-26 20:15:58.724453 | PLAY RECAP
2026-08-26 20:15:58.724506 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-26 20:15:58.724528 | 
2026-08-26 20:15:58.921189 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-08-26 20:15:58.967706 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-08-26 20:15:59.715909 | 
2026-08-26 20:15:59.716091 | PLAY [localhost]
2026-08-26 20:15:59.732985 | 
2026-08-26 20:15:59.900043 | TASK [Generate Zuul manifest]
2026-08-26 20:15:59.934263 | localhost | ok
2026-08-26 20:15:59.990789 | 
2026-08-26 20:15:59.990979 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-26 20:16:00.459935 | localhost | changed
2026-08-26 20:16:00.506258 | 
2026-08-26 20:16:00.507123 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-26 20:16:00.567087 | localhost | ok
2026-08-26 20:16:00.578688 | 
2026-08-26 20:16:00.579563 | TASK [Upload logs]
2026-08-26 20:16:00.616833 | localhost | ok
2026-08-26 20:16:00.732977 | 
2026-08-26 20:16:00.733104 | TASK [Set zuul-log-path fact]
2026-08-26 20:16:00.754783 | localhost | ok
2026-08-26 20:16:00.768247 | 
2026-08-26 20:16:00.768340 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-26 20:16:00.810367 | localhost | ok
2026-08-26 20:16:00.820702 | 
2026-08-26 20:16:00.820819 | TASK [upload-logs : Create log directories]
2026-08-26 20:16:01.570482 | localhost | changed
2026-08-26 20:16:01.579985 | 
2026-08-26 20:16:01.580102 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-26 20:16:02.020780 | localhost -> localhost | ok: Runtime: 0:00:00.004211
2026-08-26 20:16:02.029310 | 
2026-08-26 20:16:02.029380 | TASK [upload-logs : Upload logs to log server]
2026-08-26 20:16:02.593387 | localhost | Output suppressed because no_log was given
2026-08-26 20:16:02.601168 | 
2026-08-26 20:16:02.601306 | LOOP [upload-logs : Compress console log and json output]
2026-08-26 20:16:02.651186 | localhost | skipping: Conditional result was False
2026-08-26 20:16:02.658170 | localhost | skipping: Conditional result was False
2026-08-26 20:16:02.675851 | 
2026-08-26 20:16:02.676069 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-26 20:16:02.720729 | localhost | skipping: Conditional result was False
2026-08-26 20:16:02.721091 | 
2026-08-26 20:16:02.725111 | localhost | skipping: Conditional result was False
2026-08-26 20:16:02.740784 | 
2026-08-26 20:16:02.740999 | LOOP [upload-logs : Upload console log and json output]