2026-09-20 17:24:19.927604 | Job console starting
2026-09-20 17:24:19.945355 | Updating git repos
2026-09-20 17:24:23.645027 | Cloning repos into workspace
2026-09-20 17:24:30.534693 | Restoring repo states
2026-09-20 17:24:33.767288 | Merging changes
2026-09-20 17:24:34.458320 | Checking out repos
2026-09-20 17:24:39.728190 | Preparing playbooks
2026-09-20 17:24:42.625673 | Running Ansible setup
2026-09-20 17:24:47.583440 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-20 17:24:48.152779 | 
2026-09-20 17:24:48.152899 | PLAY [localhost]
2026-09-20 17:24:48.159427 | 
2026-09-20 17:24:48.159493 | TASK [Gathering Facts]
2026-09-20 17:24:49.995716 | localhost | ok
2026-09-20 17:24:50.003848 | 
2026-09-20 17:24:50.003944 | TASK [Setup log path fact]
2026-09-20 17:24:50.032690 | localhost | ok
2026-09-20 17:24:50.044824 | 
2026-09-20 17:24:50.044907 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-20 17:24:50.083757 | localhost | ok
2026-09-20 17:24:50.094828 | 
2026-09-20 17:24:50.094956 | TASK [emit-job-header : Print job information]
2026-09-20 17:24:50.144584 | # Job Information
2026-09-20 17:24:50.144780 | Ansible Version: 2.16.18
2026-09-20 17:24:50.144843 | Job: magnum-cluster-api-hydrophone-v1.35.5-cilium
2026-09-20 17:24:50.144886 | Pipeline: check
2026-09-20 17:24:50.144924 | Executor: 2d72f0692154
2026-09-20 17:24:50.144962 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1109
2026-09-20 17:24:50.145005 | Event ID: 7ede01f0-b517-11f1-8ec6-1b6e61d2c42d
2026-09-20 17:24:50.150105 | 
2026-09-20 17:24:50.150250 | LOOP [emit-job-header : Print node information]
2026-09-20 17:24:50.256332 | localhost | ok:
2026-09-20 17:24:50.256899 | localhost | # Node Information
2026-09-20 17:24:50.256989 | localhost | Inventory Hostname: controller
2026-09-20 17:24:50.257037 | localhost | Hostname: np0000193574
2026-09-20 17:24:50.257075 | localhost | Username: zuul
2026-09-20 17:24:50.257119 | localhost | Distro: Ubuntu 24.04
2026-09-20 17:24:50.257184 | localhost | Provider: yul1
2026-09-20 17:24:50.257225 | localhost | Region: ca-ymq-1
2026-09-20 17:24:50.257261 | localhost | Label: ubuntu-noble-16
2026-09-20 17:24:50.257296 | localhost | Product Name: OpenStack Nova
2026-09-20 17:24:50.257330 | localhost | Interface IP: 162.253.55.22
2026-09-20 17:24:50.277606 | 
2026-09-20 17:24:50.277786 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-09-20 17:24:50.726330 | localhost -> localhost | changed
2026-09-20 17:24:50.731420 | 
2026-09-20 17:24:50.731484 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-09-20 17:24:51.588962 | localhost -> localhost | changed
2026-09-20 17:24:51.608183 | 
2026-09-20 17:24:51.608335 | PLAY [all]
2026-09-20 17:24:51.623491 | 
2026-09-20 17:24:51.623573 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-09-20 17:24:51.855492 | controller -> localhost | ok
2026-09-20 17:24:51.873839 | 
2026-09-20 17:24:51.874037 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-09-20 17:24:51.911431 | controller | ok
2026-09-20 17:24:51.936058 | controller | included: /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-09-20 17:24:51.944474 | 
2026-09-20 17:24:51.944600 | TASK [add-build-sshkey : Create Temp SSH key]
2026-09-20 17:24:54.490644 | controller -> localhost | Generating public/private rsa key pair.
2026-09-20 17:24:54.490894 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/work/ce850aa518f1420eb80a101fb554f299_id_rsa
2026-09-20 17:24:54.490962 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/work/ce850aa518f1420eb80a101fb554f299_id_rsa.pub
2026-09-20 17:24:54.491022 | controller -> localhost | The key fingerprint is:
2026-09-20 17:24:54.491083 | controller -> localhost | SHA256:Xuh5ZU6fxX0E48vxl7molZzHWkNOvl1iJBzUzdz4O8w zuul-build-sshkey
2026-09-20 17:24:54.491149 | controller -> localhost | The key's randomart image is:
2026-09-20 17:24:54.491213 | controller -> localhost | +---[RSA 3072]----+
2026-09-20 17:24:54.491263 | controller -> localhost | |            ..o=.|
2026-09-20 17:24:54.491305 | controller -> localhost | |           . .oo=|
2026-09-20 17:24:54.491355 | controller -> localhost | |            . o..|
2026-09-20 17:24:54.491408 | controller -> localhost | |         . . o *=|
2026-09-20 17:24:54.491467 | controller -> localhost | |        S . * *=O|
2026-09-20 17:24:54.491523 | controller -> localhost | |       o o =.+XE+|
2026-09-20 17:24:54.491565 | controller -> localhost | |        + . .*=Oo|
2026-09-20 17:24:54.491602 | controller -> localhost | |         .  o.+.=|
2026-09-20 17:24:54.491644 | controller -> localhost | |           . . ..|
2026-09-20 17:24:54.491682 | controller -> localhost | +----[SHA256]-----+
2026-09-20 17:24:54.491767 | controller -> localhost | ok: Runtime: 0:00:02.127364
2026-09-20 17:24:54.504350 | 
2026-09-20 17:24:54.504433 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-09-20 17:24:54.531597 | controller | ok
2026-09-20 17:24:54.546172 | controller | included: /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-09-20 17:24:54.558230 | 
2026-09-20 17:24:54.558311 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-09-20 17:24:54.581386 | controller | skipping: Conditional result was False
2026-09-20 17:24:54.589291 | 
2026-09-20 17:24:54.589380 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-09-20 17:24:55.133949 | controller | changed
2026-09-20 17:24:55.146072 | 
2026-09-20 17:24:55.146225 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-09-20 17:24:55.372818 | controller | ok
2026-09-20 17:24:55.382931 | 
2026-09-20 17:24:55.383064 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-09-20 17:24:55.983806 | controller | changed
2026-09-20 17:24:55.995311 | 
2026-09-20 17:24:55.995439 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-09-20 17:24:56.670712 | controller | changed
2026-09-20 17:24:56.677465 | 
2026-09-20 17:24:56.677543 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-09-20 17:24:56.700681 | controller | skipping: Conditional result was False
2026-09-20 17:24:56.725285 | 
2026-09-20 17:24:56.725575 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-09-20 17:24:57.065881 | controller -> localhost | changed
2026-09-20 17:24:57.077410 | 
2026-09-20 17:24:57.077480 | TASK [add-build-sshkey : Add back temp key]
2026-09-20 17:24:57.388990 | controller -> localhost | Identity added: /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/work/ce850aa518f1420eb80a101fb554f299_id_rsa (zuul-build-sshkey)
2026-09-20 17:24:57.389351 | controller -> localhost | ok: Runtime: 0:00:00.016168
2026-09-20 17:24:57.399947 | 
2026-09-20 17:24:57.400069 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-09-20 17:24:57.732559 | controller | ok
2026-09-20 17:24:57.743086 | 
2026-09-20 17:24:57.743254 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-09-20 17:24:57.780181 | controller | skipping: Conditional result was False
2026-09-20 17:24:57.804834 | 
2026-09-20 17:24:57.805041 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-09-20 17:24:58.166547 | controller | ok
2026-09-20 17:24:58.179177 | 
2026-09-20 17:24:58.179338 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-09-20 17:25:23.848418 | controller | Output suppressed because no_log was given
2026-09-20 17:25:23.866480 | 
2026-09-20 17:25:23.866561 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-09-20 17:25:24.142002 | controller | ok: "logs"
2026-09-20 17:25:24.142427 | controller | ok: All items complete
2026-09-20 17:25:24.142485 | 
2026-09-20 17:25:24.339136 | controller | ok: "artifacts"
2026-09-20 17:25:24.556034 | controller | ok: "docs"
2026-09-20 17:25:24.570565 | 
2026-09-20 17:25:24.570690 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-09-20 17:25:24.821316 | controller | changed: "logs"
2026-09-20 17:25:25.029055 | controller | changed: "artifacts"
2026-09-20 17:25:25.243822 | controller | changed: "docs"
2026-09-20 17:25:25.263599 | 
2026-09-20 17:25:25.263692 | PLAY RECAP
2026-09-20 17:25:25.263776 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-09-20 17:25:25.263829 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-09-20 17:25:25.263869 | 
2026-09-20 17:25:25.396552 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-09-20 17:25:25.405411 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-09-20 17:25:26.020175 | 
2026-09-20 17:25:26.020282 | PLAY [all]
2026-09-20 17:25:26.035053 | 
2026-09-20 17:25:26.035125 | TASK [Fix the permissions of the zuul home directory]
2026-09-20 17:25:26.443679 | controller | changed
2026-09-20 17:25:26.453832 | 
2026-09-20 17:25:26.453971 | TASK [Gather minimum local MTU]
2026-09-20 17:25:26.538966 | controller | ok
2026-09-20 17:25:26.548690 | 
2026-09-20 17:25:26.548818 | TASK [Calculate external_bridge_mtu]
2026-09-20 17:25:26.612405 | controller | ok
2026-09-20 17:25:26.620337 | 
2026-09-20 17:25:26.620426 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-09-20 17:25:26.655627 | controller | skipping: Conditional result was False
2026-09-20 17:25:26.663771 | 
2026-09-20 17:25:26.663836 | TASK [configure-swap : Get ephemeral0 device node]
2026-09-20 17:25:27.197228 | controller | ok: Runtime: 0:00:00.010542
2026-09-20 17:25:27.207280 | 
2026-09-20 17:25:27.207408 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-09-20 17:25:27.241992 | controller | skipping: Conditional result was False
2026-09-20 17:25:27.253252 | 
2026-09-20 17:25:27.253377 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-09-20 17:25:27.287656 | controller | skipping: Conditional result was False
2026-09-20 17:25:27.297798 | 
2026-09-20 17:25:27.297920 | TASK [configure-swap : Setup swap file on root device]
2026-09-20 17:25:27.371379 | controller | ok
2026-09-20 17:25:27.389600 | controller | included: /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-09-20 17:25:27.401858 | 
2026-09-20 17:25:27.401940 | TASK [configure-swap : Calculate required swap]
2026-09-20 17:25:27.511291 | controller | ok
2026-09-20 17:25:27.562575 | 
2026-09-20 17:25:27.562699 | TASK [configure-swap : Get root filesystem]
2026-09-20 17:25:27.809662 | controller | ext4
2026-09-20 17:25:28.098368 | controller | ok: Runtime: 0:00:00.011077
2026-09-20 17:25:28.108221 | 
2026-09-20 17:25:28.108335 | TASK [configure-swap : Save root filesystem]
2026-09-20 17:25:28.142302 | controller | ok
2026-09-20 17:25:28.153107 | 
2026-09-20 17:25:28.153244 | TASK [configure-swap : Debug the root_filesystem variable]
2026-09-20 17:25:28.186910 | controller | ok:
2026-09-20 17:25:28.187088 | controller | {
2026-09-20 17:25:28.187138 | controller |   "root_filesystem": "ext4"
2026-09-20 17:25:28.187202 | controller | }
2026-09-20 17:25:28.196202 | 
2026-09-20 17:25:28.196318 | TASK [configure-swap : Create swap backing file]
2026-09-20 17:25:38.315043 | controller | 8192+0 records in
2026-09-20 17:25:38.315918 | controller | 8192+0 records out
2026-09-20 17:25:38.315952 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.79868 s, 877 MB/s
2026-09-20 17:25:38.748196 | controller | ok: Runtime: 0:00:09.814097
2026-09-20 17:25:38.759295 | 
2026-09-20 17:25:38.759425 | TASK [configure-swap : Ensure swapfile perms]
2026-09-20 17:25:39.024302 | controller | changed
2026-09-20 17:25:39.034850 | 
2026-09-20 17:25:39.035001 | TASK [configure-swap : Make swapfile]
2026-09-20 17:26:26.192291 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-09-20 17:26:26.192521 | controller | no label, UUID=87370764-7b38-4103-824b-c4b4d48271f9
2026-09-20 17:26:26.606737 | controller | ok: Runtime: 0:00:46.818172
2026-09-20 17:26:26.612654 | 
2026-09-20 17:26:26.612716 | TASK [configure-swap : Write swap to fstab]
2026-09-20 17:26:27.087322 | controller | changed
2026-09-20 17:26:27.098764 | 
2026-09-20 17:26:27.098839 | TASK [configure-swap : Add all swap]
2026-09-20 17:26:27.653105 | controller | ok: Runtime: 0:00:00.020158
2026-09-20 17:26:27.663295 | 
2026-09-20 17:26:27.663421 | TASK [configure-swap : Debug the swap_required variable]
2026-09-20 17:26:27.696913 | controller | ok:
2026-09-20 17:26:27.697098 | controller | {
2026-09-20 17:26:27.697147 | controller |   "swap_required": "8192"
2026-09-20 17:26:27.697209 | controller | }
2026-09-20 17:26:27.708555 | 
2026-09-20 17:26:27.708678 | TASK [configure-swap : Set swappiness]
2026-09-20 17:26:28.106489 | controller | changed
2026-09-20 17:26:28.116041 | 
2026-09-20 17:26:28.116203 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-09-20 17:26:28.149914 | controller | ok:
2026-09-20 17:26:28.150155 | controller | {
2026-09-20 17:26:28.150225 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-09-20 17:26:28.150271 | controller | }
2026-09-20 17:26:28.169295 | 
2026-09-20 17:26:28.169466 | TASK [setup-stack-user : Create stack group]
2026-09-20 17:26:28.631358 | controller | changed
2026-09-20 17:26:28.642026 | 
2026-09-20 17:26:28.642159 | TASK [setup-stack-user : Create the stack user home folder]
2026-09-20 17:26:28.909412 | controller | changed
2026-09-20 17:26:28.920105 | 
2026-09-20 17:26:28.920279 | TASK [setup-stack-user : Create stack user]
2026-09-20 17:26:29.497257 | controller | changed
2026-09-20 17:26:29.508005 | 
2026-09-20 17:26:29.508201 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-09-20 17:26:29.776184 | controller | changed
2026-09-20 17:26:29.787489 | 
2026-09-20 17:26:29.787613 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-09-20 17:26:30.889736 | controller | changed
2026-09-20 17:26:30.897256 | 
2026-09-20 17:26:30.897338 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-09-20 17:26:31.201913 | controller | changed
2026-09-20 17:26:31.213149 | 
2026-09-20 17:26:31.213228 | TASK [setup-tempest-user : Create tempest group]
2026-09-20 17:26:31.499375 | controller | changed
2026-09-20 17:26:31.509404 | 
2026-09-20 17:26:31.509566 | TASK [setup-tempest-user : Create tempest user]
2026-09-20 17:26:31.879916 | controller | changed
2026-09-20 17:26:31.889994 | 
2026-09-20 17:26:31.890122 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-09-20 17:26:32.720792 | controller | changed
2026-09-20 17:26:32.740694 | 
2026-09-20 17:26:32.740833 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-09-20 17:26:33.092282 | controller | ok: Not all paths examined, check warnings for details
2026-09-20 17:26:33.112362 | 
2026-09-20 17:26:33.112532 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-09-20 17:26:34.185661 | controller | ok: Item: Runtime: 0:00:00.719710
2026-09-20 17:26:35.679604 | controller | ok: Item: Runtime: 0:00:01.292750
2026-09-20 17:26:36.539001 | controller | ok: Item: Runtime: 0:00:00.613303
2026-09-20 17:26:36.985986 | controller | ok: Item: Runtime: 0:00:00.205990
2026-09-20 17:26:37.493148 | controller | ok: Item: Runtime: 0:00:00.244704
2026-09-20 17:26:38.418227 | controller | ok: Item: Runtime: 0:00:00.669345
2026-09-20 17:26:39.001789 | controller | ok: Item: Runtime: 0:00:00.347194
2026-09-20 17:26:39.547695 | controller | ok: Item: Runtime: 0:00:00.298516
2026-09-20 17:26:39.860577 | controller | ok: Item: Runtime: 0:00:00.058368
2026-09-20 17:26:40.577248 | controller | ok: Item: Runtime: 0:00:00.468537
2026-09-20 17:26:41.040358 | controller | ok: Item: Runtime: 0:00:00.211827
2026-09-20 17:26:41.585764 | controller | ok: Item: Runtime: 0:00:00.345114
2026-09-20 17:26:42.133938 | controller | ok: Item: Runtime: 0:00:00.295192
2026-09-20 17:26:42.563254 | controller | ok: Item: Runtime: 0:00:00.173675
2026-09-20 17:26:45.901312 | controller | ok: Item: Runtime: 0:00:03.086019
2026-09-20 17:26:47.264209 | controller | ok: Item: Runtime: 0:00:01.105042
2026-09-20 17:26:47.313035 | 
2026-09-20 17:26:47.313230 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-09-20 17:26:47.559580 | controller | ok: All paths examined
2026-09-20 17:26:47.571275 | 
2026-09-20 17:26:47.571436 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-09-20 17:26:47.850991 | controller | ok: All paths examined
2026-09-20 17:26:47.866271 | 
2026-09-20 17:26:47.866415 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-09-20 17:26:48.467936 | controller | ok: Item: Runtime: 0:00:00.258758
2026-09-20 17:26:48.468279 | controller | changed: All items complete
2026-09-20 17:26:48.468335 | 
2026-09-20 17:26:48.891716 | controller | ok: Item: Runtime: 0:00:00.196541
2026-09-20 17:26:48.907214 | 
2026-09-20 17:26:48.907367 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-09-20 17:26:49.003114 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.005294 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.008318 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.011415 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.014468 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.017618 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.020681 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.024075 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.026703 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.029671 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.032649 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.035749 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.038701 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.041627 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.044681 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.047609 | controller | skipping: Conditional result was False
2026-09-20 17:26:49.116020 | 
2026-09-20 17:26:49.116132 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-09-20 17:26:51.841855 | controller | changed
2026-09-20 17:26:51.861589 | 
2026-09-20 17:26:51.861708 | TASK [setup-devstack-log-dir : Create logs directory]
2026-09-20 17:26:52.118415 | controller | changed
2026-09-20 17:26:52.137336 | 
2026-09-20 17:26:52.137509 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-09-20 17:26:52.451936 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-09-20 17:26:52.686144 | controller | ERROR
2026-09-20 17:26:52.686420 | controller | {
2026-09-20 17:26:52.686475 | controller |   "delta": "0:00:00.030297",
2026-09-20 17:26:52.686514 | controller |   "end": "2026-09-20 17:26:52.452533",
2026-09-20 17:26:52.686549 | controller |   "msg": "non-zero return code",
2026-09-20 17:26:52.686584 | controller |   "rc": 1,
2026-09-20 17:26:52.686618 | controller |   "start": "2026-09-20 17:26:52.422236"
2026-09-20 17:26:52.686650 | controller | }
2026-09-20 17:26:52.686693 | controller | ERROR: Ignoring Errors
2026-09-20 17:26:52.697081 | 
2026-09-20 17:26:52.697218 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-09-20 17:26:52.963445 | controller | ok
2026-09-20 17:26:52.982061 | 
2026-09-20 17:26:52.982194 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-09-20 17:26:53.256451 | controller | /usr/bin/journalctl
2026-09-20 17:26:53.532136 | controller | ok: Runtime: 0:00:00.006569
2026-09-20 17:26:53.543832 | 
2026-09-20 17:26:53.543954 | TASK [start-fresh-logging : Get current date]
2026-09-20 17:26:53.848019 | controller | 2026-09-20 17:26:53
2026-09-20 17:26:54.093885 | controller | ok: Runtime: 0:00:00.018781
2026-09-20 17:26:54.104346 | 
2026-09-20 17:26:54.104471 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-09-20 17:26:54.713039 | controller | changed
2026-09-20 17:26:54.725612 | 
2026-09-20 17:26:54.725732 | TASK [start-fresh-logging : Stop rsyslog]
2026-09-20 17:26:54.751663 | controller | skipping: Conditional result was False
2026-09-20 17:26:54.762816 | 
2026-09-20 17:26:54.762941 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-09-20 17:26:55.302197 | controller | skipping: Conditional result was False
2026-09-20 17:26:55.313210 | 
2026-09-20 17:26:55.313333 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-09-20 17:26:55.857932 | controller | skipping: Conditional result was False
2026-09-20 17:26:55.869026 | 
2026-09-20 17:26:55.869147 | TASK [start-fresh-logging : Recreate syslog file]
2026-09-20 17:26:55.883443 | controller | skipping: Conditional result was False
2026-09-20 17:26:55.894117 | 
2026-09-20 17:26:55.894254 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-09-20 17:26:56.438380 | controller | skipping: Conditional result was False
2026-09-20 17:26:56.450860 | 
2026-09-20 17:26:56.451003 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-09-20 17:26:57.026385 | controller | skipping: Conditional result was False
2026-09-20 17:26:57.032291 | 
2026-09-20 17:26:57.032352 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-09-20 17:26:57.056321 | controller | skipping: Conditional result was False
2026-09-20 17:26:57.063319 | 
2026-09-20 17:26:57.063402 | TASK [start-fresh-logging : Recreate kern.log file]
2026-09-20 17:26:57.086743 | controller | skipping: Conditional result was False
2026-09-20 17:26:57.099146 | 
2026-09-20 17:26:57.099301 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-09-20 17:26:57.634602 | controller | skipping: Conditional result was False
2026-09-20 17:26:57.646776 | 
2026-09-20 17:26:57.646902 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-09-20 17:26:58.189017 | controller | skipping: Conditional result was False
2026-09-20 17:26:58.200252 | 
2026-09-20 17:26:58.200375 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-09-20 17:26:58.224785 | controller | skipping: Conditional result was False
2026-09-20 17:26:58.235319 | 
2026-09-20 17:26:58.235445 | TASK [start-fresh-logging : Start rsyslog]
2026-09-20 17:26:58.259550 | controller | skipping: Conditional result was False
2026-09-20 17:26:58.281389 | 
2026-09-20 17:26:58.281521 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-09-20 17:26:58.745151 | controller | ok
2026-09-20 17:26:58.761113 | 
2026-09-20 17:26:58.761225 | PLAY RECAP
2026-09-20 17:26:58.761306 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-09-20 17:26:58.761349 | 
2026-09-20 17:26:58.902217 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-09-20 17:26:58.911230 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-09-20 17:26:59.461079 | 
2026-09-20 17:26:59.461436 | PLAY [all]
2026-09-20 17:26:59.471244 | 
2026-09-20 17:26:59.471311 | TASK [Install curl]
2026-09-20 17:27:04.796456 | controller | ok
2026-09-20 17:27:04.807091 | 
2026-09-20 17:27:04.807275 | TASK [ensure-rust : Use rustup]
2026-09-20 17:27:04.844875 | controller | ok
2026-09-20 17:27:04.861294 | controller | included: /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-09-20 17:27:04.872281 | 
2026-09-20 17:27:04.872418 | TASK [ensure-rust : Install Rust]
2026-09-20 17:27:05.600373 | controller | info: downloading installer
2026-09-20 17:27:06.304800 | controller | info: profile set to default
2026-09-20 17:27:06.304858 | controller | info: default host tuple is x86_64-unknown-linux-gnu
2026-09-20 17:27:06.306871 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-09-20 17:27:37.327213 | controller | error: could not download file from 'https://static.rust-lang.org/dist/channel-rust-stable.toml.sha256' to '/opt/rust/tmp/r9x9l5e6mwx55eho_file': error downloading file: error sending request for url (https://static.rust-lang.org/dist/channel-rust-stable.toml.sha256): client error (Connect): Connection timed out (os error 110)
2026-09-20 17:27:37.432732 | controller | ERROR
2026-09-20 17:27:37.433013 | controller | {
2026-09-20 17:27:37.433073 | controller |   "delta": "0:00:32.084323",
2026-09-20 17:27:37.433117 | controller |   "end": "2026-09-20 17:27:37.354146",
2026-09-20 17:27:37.433155 | controller |   "msg": "non-zero return code",
2026-09-20 17:27:37.433220 | controller |   "rc": 1,
2026-09-20 17:27:37.433262 | controller |   "start": "2026-09-20 17:27:05.269823"
2026-09-20 17:27:37.433298 | controller | }
failure
2026-09-20 17:27:37.435808 | 
2026-09-20 17:27:37.435899 | PLAY RECAP
2026-09-20 17:27:37.435975 | controller | ok: 2 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-09-20 17:27:37.436016 | 
2026-09-20 17:27:37.565051 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-09-20 17:27:37.572111 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-09-20 17:27:38.132668 | 
2026-09-20 17:27:38.132784 | PLAY [all]
2026-09-20 17:27:38.142349 | 
2026-09-20 17:27:38.142418 | LOOP [Copy individual Hydrophone result files]
2026-09-20 17:27:38.572402 | controller | ERROR: Item: e2e.log
2026-09-20 17:27:38.573271 | controller | {
2026-09-20 17:27:38.573360 | controller |   "ansible_loop_var": "item",
2026-09-20 17:27:38.573418 | controller |   "item": "e2e.log",
2026-09-20 17:27:38.573524 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-09-20 17:27:38.573572 | controller | }
2026-09-20 17:27:38.858591 | controller | ERROR: Item: junit_01.xml
2026-09-20 17:27:38.858743 | controller | {
2026-09-20 17:27:38.858819 | controller |   "ansible_loop_var": "item",
2026-09-20 17:27:38.858872 | controller |   "item": "junit_01.xml",
2026-09-20 17:27:38.858916 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-09-20 17:27:38.858963 | controller | }
2026-09-20 17:27:38.869248 | controller | ERROR: Ignoring Errors
2026-09-20 17:27:38.878543 | 
2026-09-20 17:27:38.878662 | TASK [Copy Hydrophone results tarball to output folder]
2026-09-20 17:27:39.195754 | controller | ERROR
2026-09-20 17:27:39.196102 | controller | {
2026-09-20 17:27:39.196195 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-09-20 17:27:39.196244 | controller | }
2026-09-20 17:27:39.196291 | controller | ERROR: Ignoring Errors
2026-09-20 17:27:39.225577 | 
2026-09-20 17:27:39.225711 | TASK [Return built artifacts to Zuul]
2026-09-20 17:27:39.260750 | controller | ok
2026-09-20 17:27:39.270661 | 
2026-09-20 17:27:39.270787 | TASK [Get the Docker volume ID]
2026-09-20 17:27:39.813038 | controller | ERROR
2026-09-20 17:27:39.813364 | controller | {
2026-09-20 17:27:39.813427 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-09-20 17:27:39.813470 | controller |   "rc": 2
2026-09-20 17:27:39.813509 | controller | }
2026-09-20 17:27:39.813562 | controller | ERROR: Ignoring Errors
2026-09-20 17:27:39.823006 | 
2026-09-20 17:27:39.823152 | TASK [Copy all of the pod logs]
2026-09-20 17:27:39.857475 | controller | ERROR
2026-09-20 17:27:39.857729 | controller | {
2026-09-20 17:27:39.857786 | 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/ce850aa518f1420eb80a101fb554f299/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-09-20 17:27:39.857833 | controller | }
2026-09-20 17:27:39.857880 | controller | ERROR: Ignoring Errors
2026-09-20 17:27:39.859192 | 
2026-09-20 17:27:39.859282 | PLAY RECAP
2026-09-20 17:27:39.859361 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-09-20 17:27:39.859402 | 
2026-09-20 17:27:39.981382 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-09-20 17:27:39.987472 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-09-20 17:27:40.556666 | 
2026-09-20 17:27:40.556776 | PLAY [all]
2026-09-20 17:27:40.572566 | 
2026-09-20 17:27:40.572639 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-09-20 17:27:40.981807 | controller | changed
2026-09-20 17:27:40.992411 | 
2026-09-20 17:27:40.992595 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-09-20 17:27:42.037635 | controller | ok: Runtime: 0:00:00.534891
2026-09-20 17:27:42.044597 | 
2026-09-20 17:27:42.044710 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-09-20 17:27:42.581073 | controller | ok: Runtime: 0:00:00.018799
2026-09-20 17:27:42.588060 | 
2026-09-20 17:27:42.588129 | TASK [export-devstack-journal : Export journal]
2026-09-20 17:27:43.163757 | controller | ok: Runtime: 0:00:00.025445
2026-09-20 17:27:43.168896 | 
2026-09-20 17:27:43.168961 | TASK [export-devstack-journal : Save journal README]
2026-09-20 17:27:44.054723 | controller | changed
2026-09-20 17:27:44.068757 | 
2026-09-20 17:27:44.068833 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-09-20 17:27:44.348700 | controller | changed
2026-09-20 17:27:44.360217 | 
2026-09-20 17:27:44.360373 | TASK [apache-logs-conf : Find logs]
2026-09-20 17:27:44.715480 | controller | Output suppressed because no_log was given
2026-09-20 17:27:44.732467 | 
2026-09-20 17:27:44.732685 | LOOP [apache-logs-conf : Dereference files]
2026-09-20 17:27:44.776018 | 
2026-09-20 17:27:44.776525 | LOOP [apache-logs-conf : Create hard links]
2026-09-20 17:27:44.809849 | 
2026-09-20 17:27:44.810154 | TASK [apache-logs-conf : Find logs]
2026-09-20 17:27:44.835311 | 
2026-09-20 17:27:44.835610 | LOOP [apache-logs-conf : Dereference files]
2026-09-20 17:27:44.864191 | 
2026-09-20 17:27:44.864306 | LOOP [apache-logs-conf : Create hard links]
2026-09-20 17:27:44.897511 | 
2026-09-20 17:27:44.897944 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-09-20 17:27:45.147707 | controller | changed
2026-09-20 17:27:45.152954 | 
2026-09-20 17:27:45.153016 | TASK [apache-logs-conf : Define config paths]
2026-09-20 17:27:45.181664 | controller | ok
2026-09-20 17:27:45.187408 | 
2026-09-20 17:27:45.187466 | TASK [apache-logs-conf : Discover configurations]
2026-09-20 17:27:45.437585 | controller | Output suppressed because no_log was given
2026-09-20 17:27:45.447323 | 
2026-09-20 17:27:45.447453 | LOOP [apache-logs-conf : Dereference configurations]
2026-09-20 17:27:45.481086 | 
2026-09-20 17:27:45.481315 | LOOP [apache-logs-conf : Link configurations]
2026-09-20 17:27:45.525952 | 
2026-09-20 17:27:45.526209 | TASK [capture-performance-data : Generate statistics]
2026-09-20 17:27:46.214448 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-09-20 17:27:46.233251 | controller | Using python 3.12 to install setuptools
2026-09-20 17:27:46.285497 | controller | /usr/bin/python3.12: No module named pip
2026-09-20 17:27:46.299779 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-09-20 17:27:46.570656 | controller | ERROR
2026-09-20 17:27:46.570880 | controller | {
2026-09-20 17:27:46.570944 | controller |   "delta": "0:00:00.507233",
2026-09-20 17:27:46.570985 | controller |   "end": "2026-09-20 17:27:46.301873",
2026-09-20 17:27:46.571022 | controller |   "msg": "non-zero return code",
2026-09-20 17:27:46.571063 | controller |   "rc": 127,
2026-09-20 17:27:46.571098 | controller |   "start": "2026-09-20 17:27:45.794640"
2026-09-20 17:27:46.571132 | controller | }
2026-09-20 17:27:46.571196 | controller | ERROR: Ignoring Errors
2026-09-20 17:27:46.592651 | 
2026-09-20 17:27:46.592903 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-09-20 17:27:46.859225 | controller | changed
2026-09-20 17:27:46.870978 | 
2026-09-20 17:27:46.871105 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-09-20 17:27:50.773572 | controller | Output suppressed because no_log was given
2026-09-20 17:27:50.787722 | 
2026-09-20 17:27:50.788573 | LOOP [devstack-project-conf : Copy configuration files]
2026-09-20 17:27:50.878306 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.878705 | 
2026-09-20 17:27:50.880369 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.881909 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.884299 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.886680 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.889004 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.891403 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.893938 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.896275 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.898566 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.900870 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.903154 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.905536 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.907980 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.910236 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.912549 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.914811 | controller | skipping: Conditional result was False
2026-09-20 17:27:50.917144 | controller | skipping: Conditional result was False
2026-09-20 17:27:51.015959 | 
2026-09-20 17:27:51.016266 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-09-20 17:27:51.232856 | controller | ok
2026-09-20 17:27:51.243304 | 
2026-09-20 17:27:51.243436 | TASK [devstack-project-conf : Copy configuration files]
2026-09-20 17:27:51.784962 | controller | skipping: Conditional result was False
2026-09-20 17:27:51.806390 | 
2026-09-20 17:27:51.806546 | TASK [capture-system-logs : Stage various logs and reports]
2026-09-20 17:27:52.217070 | controller | /usr/bin/python3: No module named pip
2026-09-20 17:27:53.497837 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-09-20 17:27:53.852932 | controller | ok: Runtime: 0:00:01.406493
2026-09-20 17:27:53.865253 | 
2026-09-20 17:27:53.865340 | LOOP [stage-output : Register sources]
2026-09-20 17:28:03.707425 | controller | Output suppressed because no_log was given
2026-09-20 17:28:03.720958 | 
2026-09-20 17:28:03.721078 | TASK [stage-output : Check sudo]
2026-09-20 17:28:04.272226 | controller | ok: Runtime: 0:00:00.015502
2026-09-20 17:28:04.282653 | 
2026-09-20 17:28:04.282776 | LOOP [stage-output : Set source and destination for files and folders]
2026-09-20 17:28:04.390289 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.390681 | 
2026-09-20 17:28:04.391951 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.392396 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.400881 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.428887 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.429550 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.429974 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.430450 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.432154 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.434425 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.436611 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.438750 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.440918 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.443132 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.457147 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.457617 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.458114 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.460141 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.462431 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.464603 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.466738 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.468939 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.471067 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.492479 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.492956 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.564740 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.573183 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.583757 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.594545 | controller | Output suppressed because no_log was given
2026-09-20 17:28:04.610102 | 
2026-09-20 17:28:04.610242 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-09-20 17:28:04.694771 | controller | ok
2026-09-20 17:28:04.707531 | 
2026-09-20 17:28:04.707659 | LOOP [stage-output : Ensure target folders exist]
2026-09-20 17:28:04.973527 | controller | changed: "docs"
2026-09-20 17:28:05.230096 | controller | changed: "artifacts"
2026-09-20 17:28:05.457252 | controller | ok: "logs"
2026-09-20 17:28:05.475543 | 
2026-09-20 17:28:05.475709 | LOOP [stage-output : Copy files and folders to staging folder]
2026-09-20 17:28:05.763572 | controller | ok: Item: Runtime: 0:00:00.007923
2026-09-20 17:28:05.763844 | controller | changed: All items complete
2026-09-20 17:28:05.763870 | 
2026-09-20 17:28:06.038973 | controller | ok: Item: Runtime: 0:00:00.008950
2026-09-20 17:28:06.288023 | controller | ok: Item: Runtime: 0:00:00.007722
2026-09-20 17:28:06.534321 | controller | ok: Item: Runtime: 0:00:00.007984
2026-09-20 17:28:06.785471 | controller | ok: Item: Runtime: 0:00:00.008375
2026-09-20 17:28:07.010248 | controller | ok: Item: Runtime: 0:00:00.008531
2026-09-20 17:28:07.264677 | controller | ok: Item: Runtime: 0:00:00.008337
2026-09-20 17:28:07.522186 | controller | ok: Item: Runtime: 0:00:00.009586
2026-09-20 17:28:07.814986 | controller | ok: Item: Runtime: 0:00:00.007668
2026-09-20 17:28:08.039521 | controller | ok: Item: Runtime: 0:00:00.007499
2026-09-20 17:28:08.298698 | controller | ok: Item: Runtime: 0:00:00.009287
2026-09-20 17:28:08.535346 | controller | ok: Item: Runtime: 0:00:00.009649
2026-09-20 17:28:08.798429 | controller | ok: Item: Runtime: 0:00:00.008061
2026-09-20 17:28:09.050779 | controller | ok: Item: Runtime: 0:00:00.007991
2026-09-20 17:28:09.302618 | controller | ok: Item: Runtime: 0:00:00.007162
2026-09-20 17:28:09.551794 | controller | ok: Item: Runtime: 0:00:00.008722
2026-09-20 17:28:09.577806 | 
2026-09-20 17:28:09.577973 | TASK [stage-output : Make all log files readable]
2026-09-20 17:28:09.854903 | controller | changed
2026-09-20 17:28:09.866088 | 
2026-09-20 17:28:09.866227 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-09-20 17:28:10.215264 | controller | changed: Renamed files for staging.
2026-09-20 17:28:10.225540 | 
2026-09-20 17:28:10.225658 | TASK [stage-output : Discover log files for compression]
2026-09-20 17:28:10.251289 | controller | skipping: Conditional result was False
2026-09-20 17:28:10.265440 | 
2026-09-20 17:28:10.265720 | LOOP [stage-output : Archive everything from logs]
2026-09-20 17:28:10.314402 | 
2026-09-20 17:28:10.314664 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-09-20 17:28:11.050712 | controller | changed:
2026-09-20 17:28:11.050963 | controller | created directory /var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/work/logs/controller
2026-09-20 17:28:11.051016 | controller | cd+++++++++ logs/
2026-09-20 17:28:11.051056 | controller | >f+++++++++ logs/deprecations_log.txt
2026-09-20 17:28:11.051093 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-09-20 17:28:11.051130 | controller | >f+++++++++ logs/devstack.journal.gz
2026-09-20 17:28:11.051186 | controller | >f+++++++++ logs/df.txt
2026-09-20 17:28:11.051226 | controller | >f+++++++++ logs/dpkg-l.txt
2026-09-20 17:28:11.051261 | controller | >f+++++++++ logs/iptables.txt
2026-09-20 17:28:11.051296 | controller | >f+++++++++ logs/listen53.txt
2026-09-20 17:28:11.051333 | controller | >f+++++++++ logs/local_conf.txt
2026-09-20 17:28:11.051366 | controller | >f+++++++++ logs/mount.txt
2026-09-20 17:28:11.051400 | controller | >f+++++++++ logs/performance.json
2026-09-20 17:28:11.051433 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-09-20 17:28:11.051466 | controller | >f+++++++++ logs/resolv_conf.txt
2026-09-20 17:28:11.051499 | controller | >f+++++++++ logs/services.txt
2026-09-20 17:28:11.051540 | controller | >f+++++++++ logs/sudoers
2026-09-20 17:28:11.051575 | controller | >f+++++++++ logs/syslog.txt
2026-09-20 17:28:11.051619 | controller | cd+++++++++ logs/apache/
2026-09-20 17:28:11.051671 | controller | cd+++++++++ logs/apache_config/
2026-09-20 17:28:11.051711 | controller | cd+++++++++ logs/etc/
2026-09-20 17:28:11.051749 | controller | cd+++++++++ logs/sudoers.d/
2026-09-20 17:28:11.051783 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-09-20 17:28:11.051817 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-09-20 17:28:11.051853 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-09-20 17:28:11.051888 | controller | >f+++++++++ logs/sudoers.d/README
2026-09-20 17:28:11.051922 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-09-20 17:28:11.076628 | 
2026-09-20 17:28:11.076756 | TASK [Check if a tempest log exits]
2026-09-20 17:28:11.376429 | controller | ok
2026-09-20 17:28:11.386026 | 
2026-09-20 17:28:11.386149 | TASK [Link post-devstack tempest.log]
2026-09-20 17:28:11.410369 | controller | skipping: Conditional result was False
2026-09-20 17:28:11.421986 | 
2026-09-20 17:28:11.422119 | TASK [Capture most recent qemu crash dump, if any]
2026-09-20 17:28:11.740169 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-09-20 17:28:11.973845 | controller | ERROR
2026-09-20 17:28:11.974104 | controller | {
2026-09-20 17:28:11.974160 | controller |   "delta": "0:00:00.008354",
2026-09-20 17:28:11.974222 | controller |   "end": "2026-09-20 17:28:11.740861",
2026-09-20 17:28:11.974259 | controller |   "msg": "non-zero return code",
2026-09-20 17:28:11.974294 | controller |   "rc": 127,
2026-09-20 17:28:11.974327 | controller |   "start": "2026-09-20 17:28:11.732507"
2026-09-20 17:28:11.974361 | controller | }
2026-09-20 17:28:11.974404 | controller | ERROR: Ignoring Errors
2026-09-20 17:28:11.985268 | 
2026-09-20 17:28:11.985396 | TASK [Collect Guru Meditation Reports from OpenStack services]
2026-09-20 17:28:12.536608 | controller | ERROR
2026-09-20 17:28:12.536934 | controller | {
2026-09-20 17:28:12.536996 | controller |   "delta": "0:00:00.093136",
2026-09-20 17:28:12.537053 | controller |   "end": "2026-09-20 17:28:12.369614",
2026-09-20 17:28:12.537114 | controller |   "msg": "non-zero return code",
2026-09-20 17:28:12.537196 | controller |   "rc": -12,
2026-09-20 17:28:12.537237 | controller |   "start": "2026-09-20 17:28:12.276478"
2026-09-20 17:28:12.537272 | controller | }
2026-09-20 17:28:12.537318 | controller | ERROR: Ignoring Errors
2026-09-20 17:28:12.539843 | 
2026-09-20 17:28:12.540226 | PLAY RECAP
2026-09-20 17:28:12.540317 | controller | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-09-20 17:28:12.540361 | 
2026-09-20 17:28:12.689598 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-09-20 17:28:12.696637 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-20 17:28:13.311904 | 
2026-09-20 17:28:13.312060 | PLAY [all]
2026-09-20 17:28:13.322919 | 
2026-09-20 17:28:13.322987 | TASK [fetch-output : Set log path for multiple nodes]
2026-09-20 17:28:13.377092 | controller | skipping: Conditional result was False
2026-09-20 17:28:13.383344 | 
2026-09-20 17:28:13.383418 | TASK [fetch-output : Set log path for single node]
2026-09-20 17:28:13.412412 | controller | ok
2026-09-20 17:28:13.419327 | 
2026-09-20 17:28:13.419411 | LOOP [fetch-output : Ensure local output dirs]
2026-09-20 17:28:13.892756 | controller -> localhost | ok: "/var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/work/logs"
2026-09-20 17:28:14.128245 | controller -> localhost | changed: "/var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/work/artifacts"
2026-09-20 17:28:14.354034 | controller -> localhost | changed: "/var/lib/zuul/builds/ce850aa518f1420eb80a101fb554f299/work/docs"
2026-09-20 17:28:14.371006 | 
2026-09-20 17:28:14.371247 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-09-20 17:28:15.072298 | controller | changed: .d..t...... ./
2026-09-20 17:28:15.072698 | controller | changed: All items complete
2026-09-20 17:28:15.072759 | 
2026-09-20 17:28:15.587301 | controller | changed: .d..t...... ./
2026-09-20 17:28:16.079969 | controller | changed: .d..t...... ./
2026-09-20 17:28:16.103595 | 
2026-09-20 17:28:16.103790 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-09-20 17:28:16.545267 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.010154
2026-09-20 17:28:16.800761 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007258
2026-09-20 17:28:16.822661 | 
2026-09-20 17:28:16.822850 | PLAY [all]
2026-09-20 17:28:16.835118 | 
2026-09-20 17:28:16.835271 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-09-20 17:28:17.327062 | controller | changed
2026-09-20 17:28:17.337965 | 
2026-09-20 17:28:17.338056 | PLAY RECAP
2026-09-20 17:28:17.338133 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-09-20 17:28:17.338199 | 
2026-09-20 17:28:17.449730 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-09-20 17:28:17.458953 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-09-20 17:28:17.982489 | 
2026-09-20 17:28:17.982594 | PLAY [localhost]
2026-09-20 17:28:17.991205 | 
2026-09-20 17:28:17.991271 | TASK [Generate Zuul manifest]
2026-09-20 17:28:18.007376 | localhost | ok
2026-09-20 17:28:18.021720 | 
2026-09-20 17:28:18.021797 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-09-20 17:28:18.382203 | localhost | changed
2026-09-20 17:28:18.390685 | 
2026-09-20 17:28:18.390752 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-09-20 17:28:18.426191 | localhost | ok
2026-09-20 17:28:18.432207 | 
2026-09-20 17:28:18.432266 | TASK [Upload logs]
2026-09-20 17:28:18.448388 | localhost | ok
2026-09-20 17:28:18.556119 | 
2026-09-20 17:28:18.556305 | TASK [Set zuul-log-path fact]
2026-09-20 17:28:18.573290 | localhost | ok
2026-09-20 17:28:18.582564 | 
2026-09-20 17:28:18.582624 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-09-20 17:28:18.608395 | localhost | ok
2026-09-20 17:28:18.615182 | 
2026-09-20 17:28:18.615244 | TASK [upload-logs : Create log directories]
2026-09-20 17:28:18.980083 | localhost | changed
2026-09-20 17:28:18.988720 | 
2026-09-20 17:28:18.988863 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-09-20 17:28:19.343608 | localhost -> localhost | ok: Runtime: 0:00:00.004467
2026-09-20 17:28:19.352441 | 
2026-09-20 17:28:19.352622 | TASK [upload-logs : Upload logs to log server]
2026-09-20 17:28:19.820360 | localhost | Output suppressed because no_log was given
2026-09-20 17:28:19.827009 | 
2026-09-20 17:28:19.827132 | LOOP [upload-logs : Compress console log and json output]
2026-09-20 17:28:19.890337 | localhost | skipping: Conditional result was False
2026-09-20 17:28:19.899555 | localhost | skipping: Conditional result was False
2026-09-20 17:28:19.909911 | 
2026-09-20 17:28:19.910070 | LOOP [upload-logs : Upload compressed console log and json output]
2026-09-20 17:28:19.968945 | localhost | skipping: Conditional result was False
2026-09-20 17:28:19.969272 | 
2026-09-20 17:28:19.974288 | localhost | skipping: Conditional result was False
2026-09-20 17:28:19.982803 | 
2026-09-20 17:28:19.982957 | LOOP [upload-logs : Upload console log and json output]