2026-05-20 01:18:23.792103 | Job console starting
2026-05-20 01:18:23.808064 | Updating git repos
2026-05-20 01:18:25.671321 | Cloning repos into workspace
2026-05-20 01:18:44.040978 | Restoring repo states
2026-05-20 01:18:44.551869 | Merging changes
2026-05-20 01:18:46.381957 | Checking out repos
2026-05-20 01:18:57.376305 | Preparing playbooks
2026-05-20 01:19:10.563474 | Running Ansible setup
2026-05-20 01:19:15.388473 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:19:16.210341 | 
2026-05-20 01:19:16.210493 | PLAY [localhost]
2026-05-20 01:19:16.219036 | 
2026-05-20 01:19:16.219128 | TASK [Gathering Facts]
2026-05-20 01:19:17.404691 | localhost | ok
2026-05-20 01:19:17.411890 | 
2026-05-20 01:19:17.411980 | TASK [Setup log path fact]
2026-05-20 01:19:17.441999 | localhost | ok
2026-05-20 01:19:17.479110 | 
2026-05-20 01:19:17.480166 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:19:17.542047 | localhost | ok
2026-05-20 01:19:17.549200 | 
2026-05-20 01:19:17.549294 | TASK [emit-job-header : Print job information]
2026-05-20 01:19:17.596205 | # Job Information
2026-05-20 01:19:17.596361 | Ansible Version: 2.16.18
2026-05-20 01:19:17.596393 | Job: magnum-cluster-api-hydrophone-v1.35.4-calico
2026-05-20 01:19:17.596416 | Pipeline: check
2026-05-20 01:19:17.596437 | Executor: 2d72f0692154
2026-05-20 01:19:17.596457 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1008
2026-05-20 01:19:17.596480 | Event ID: 4e04f870-53e9-11f1-8295-1cc955408759
2026-05-20 01:19:17.599592 | 
2026-05-20 01:19:17.599700 | LOOP [emit-job-header : Print node information]
2026-05-20 01:19:17.730842 | localhost | ok:
2026-05-20 01:19:17.731034 | localhost | # Node Information
2026-05-20 01:19:17.731063 | localhost | Inventory Hostname: controller
2026-05-20 01:19:17.731085 | localhost | Hostname: np0000175172
2026-05-20 01:19:17.731105 | localhost | Username: zuul
2026-05-20 01:19:17.731161 | localhost | Distro: Ubuntu 24.04
2026-05-20 01:19:17.731184 | localhost | Provider: yul1
2026-05-20 01:19:17.731226 | localhost | Region: ca-ymq-1
2026-05-20 01:19:17.731245 | localhost | Label: ubuntu-noble-16
2026-05-20 01:19:17.731263 | localhost | Product Name: OpenStack Nova
2026-05-20 01:19:17.731282 | localhost | Interface IP: 199.19.213.32
2026-05-20 01:19:17.742696 | 
2026-05-20 01:19:17.742829 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 01:19:18.372472 | localhost -> localhost | changed
2026-05-20 01:19:18.377612 | 
2026-05-20 01:19:18.377730 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 01:19:20.504701 | localhost -> localhost | changed
2026-05-20 01:19:20.510877 | 
2026-05-20 01:19:20.510951 | PLAY [all]
2026-05-20 01:19:20.519321 | 
2026-05-20 01:19:20.519396 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 01:19:20.818549 | controller -> localhost | ok
2026-05-20 01:19:20.825915 | 
2026-05-20 01:19:20.826012 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 01:19:20.860504 | controller | ok
2026-05-20 01:19:20.883279 | controller | included: /var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 01:19:20.890718 | 
2026-05-20 01:19:20.890788 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 01:19:22.342071 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 01:19:22.342278 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/work/33daeebf065943ec8f91d9c5e02290c9_id_rsa
2026-05-20 01:19:22.342308 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/work/33daeebf065943ec8f91d9c5e02290c9_id_rsa.pub
2026-05-20 01:19:22.342331 | controller -> localhost | The key fingerprint is:
2026-05-20 01:19:22.342352 | controller -> localhost | SHA256:V2cQ2pg0MipW6yH/X22PXLG8zvADHHXb2N6fYqHTpNE zuul-build-sshkey
2026-05-20 01:19:22.342450 | controller -> localhost | The key's randomart image is:
2026-05-20 01:19:22.342480 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 01:19:22.342509 | controller -> localhost | |      . o o o.   |
2026-05-20 01:19:22.342546 | controller -> localhost | |     . o + * . ..|
2026-05-20 01:19:22.342567 | controller -> localhost | |    + +   + o +o+|
2026-05-20 01:19:22.342595 | controller -> localhost | |   . = .   . +..o|
2026-05-20 01:19:22.342615 | controller -> localhost | |      o S . o .o.|
2026-05-20 01:19:22.342634 | controller -> localhost | |       . . . E. =|
2026-05-20 01:19:22.342654 | controller -> localhost | |        .   B.=o+|
2026-05-20 01:19:22.342673 | controller -> localhost | |         . = *+*o|
2026-05-20 01:19:22.342961 | controller -> localhost | |          . o ==o|
2026-05-20 01:19:22.342992 | controller -> localhost | +----[SHA256]-----+
2026-05-20 01:19:22.343079 | controller -> localhost | ok: Runtime: 0:00:00.411731
2026-05-20 01:19:22.359465 | 
2026-05-20 01:19:22.359866 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 01:19:22.398133 | controller | ok
2026-05-20 01:19:22.432913 | controller | included: /var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 01:19:22.492772 | 
2026-05-20 01:19:22.492894 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 01:19:22.537456 | controller | skipping: Conditional result was False
2026-05-20 01:19:22.554042 | 
2026-05-20 01:19:22.554194 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 01:19:24.613880 | controller | changed
2026-05-20 01:19:24.862124 | 
2026-05-20 01:19:24.862226 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 01:19:25.101952 | controller | ok
2026-05-20 01:19:25.108656 | 
2026-05-20 01:19:25.108735 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 01:19:25.798771 | controller | changed
2026-05-20 01:19:25.807142 | 
2026-05-20 01:19:25.807259 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 01:19:26.552815 | controller | changed
2026-05-20 01:19:26.558453 | 
2026-05-20 01:19:26.558515 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 01:19:26.571924 | controller | skipping: Conditional result was False
2026-05-20 01:19:26.583018 | 
2026-05-20 01:19:26.583173 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 01:19:27.383823 | controller -> localhost | changed
2026-05-20 01:19:27.425750 | 
2026-05-20 01:19:27.425891 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 01:19:27.976956 | controller -> localhost | Identity added: /var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/work/33daeebf065943ec8f91d9c5e02290c9_id_rsa (zuul-build-sshkey)
2026-05-20 01:19:27.977189 | controller -> localhost | ok: Runtime: 0:00:00.026023
2026-05-20 01:19:27.984718 | 
2026-05-20 01:19:27.984803 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 01:19:28.374756 | controller | ok
2026-05-20 01:19:28.383721 | 
2026-05-20 01:19:28.383853 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 01:19:28.412457 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.427364 | 
2026-05-20 01:19:28.427490 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 01:19:28.882637 | controller | ok
2026-05-20 01:19:29.047915 | 
2026-05-20 01:19:29.048009 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 01:21:18.679397 | controller | Output suppressed because no_log was given
2026-05-20 01:21:18.768180 | 
2026-05-20 01:21:18.768334 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 01:21:19.340805 | controller | ok: "logs"
2026-05-20 01:21:19.341078 | controller | ok: All items complete
2026-05-20 01:21:19.341108 | 
2026-05-20 01:21:19.464346 | controller | ok: "artifacts"
2026-05-20 01:21:19.649790 | controller | ok: "docs"
2026-05-20 01:21:19.669759 | 
2026-05-20 01:21:19.669928 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 01:21:19.905257 | controller | changed: "logs"
2026-05-20 01:21:20.099176 | controller | changed: "artifacts"
2026-05-20 01:21:20.294336 | controller | changed: "docs"
2026-05-20 01:21:20.311614 | 
2026-05-20 01:21:20.311724 | PLAY RECAP
2026-05-20 01:21:20.311772 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 01:21:20.311801 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:21:20.311823 | 
2026-05-20 01:21:20.580730 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:21:20.587328 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:21:21.497655 | 
2026-05-20 01:21:21.497788 | PLAY [all]
2026-05-20 01:21:21.581261 | 
2026-05-20 01:21:21.581420 | TASK [Fix the permissions of the zuul home directory]
2026-05-20 01:21:22.276973 | controller | changed
2026-05-20 01:21:22.284983 | 
2026-05-20 01:21:22.285058 | TASK [Gather minimum local MTU]
2026-05-20 01:21:22.361185 | controller | ok
2026-05-20 01:21:22.367307 | 
2026-05-20 01:21:22.367401 | TASK [Calculate external_bridge_mtu]
2026-05-20 01:21:22.442389 | controller | ok
2026-05-20 01:21:22.449522 | 
2026-05-20 01:21:22.449638 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-20 01:21:22.521988 | controller | skipping: Conditional result was False
2026-05-20 01:21:22.529591 | 
2026-05-20 01:21:22.529666 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-20 01:21:23.085911 | controller | ok: Runtime: 0:00:00.010128
2026-05-20 01:21:23.093675 | 
2026-05-20 01:21:23.093823 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-20 01:21:23.118243 | controller | skipping: Conditional result was False
2026-05-20 01:21:23.127735 | 
2026-05-20 01:21:23.127839 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-20 01:21:23.161459 | controller | skipping: Conditional result was False
2026-05-20 01:21:23.171660 | 
2026-05-20 01:21:23.171775 | TASK [configure-swap : Setup swap file on root device]
2026-05-20 01:21:23.226585 | controller | ok
2026-05-20 01:21:23.302080 | controller | included: /var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-20 01:21:23.316810 | 
2026-05-20 01:21:23.316901 | TASK [configure-swap : Calculate required swap]
2026-05-20 01:21:23.443130 | controller | ok
2026-05-20 01:21:23.491730 | 
2026-05-20 01:21:23.491869 | TASK [configure-swap : Get root filesystem]
2026-05-20 01:21:23.727939 | controller | ext4
2026-05-20 01:21:24.031058 | controller | ok: Runtime: 0:00:00.011121
2026-05-20 01:21:24.037974 | 
2026-05-20 01:21:24.038052 | TASK [configure-swap : Save root filesystem]
2026-05-20 01:21:24.070368 | controller | ok
2026-05-20 01:21:24.092510 | 
2026-05-20 01:21:24.092633 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-20 01:21:24.124941 | controller | ok:
2026-05-20 01:21:24.125308 | controller | {
2026-05-20 01:21:24.125340 | controller |   "root_filesystem": "ext4"
2026-05-20 01:21:24.125365 | controller | }
2026-05-20 01:21:24.130953 | 
2026-05-20 01:21:24.131027 | TASK [configure-swap : Create swap backing file]
2026-05-20 01:21:33.474174 | controller | 8192+0 records in
2026-05-20 01:21:33.474459 | controller | 8192+0 records out
2026-05-20 01:21:33.474506 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.04773 s, 949 MB/s
2026-05-20 01:21:33.739410 | controller | ok: Runtime: 0:00:09.058460
2026-05-20 01:21:33.746287 | 
2026-05-20 01:21:33.746355 | TASK [configure-swap : Ensure swapfile perms]
2026-05-20 01:21:34.017529 | controller | changed
2026-05-20 01:21:34.023804 | 
2026-05-20 01:21:34.023889 | TASK [configure-swap : Make swapfile]
2026-05-20 01:21:55.413748 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-20 01:21:55.414167 | controller | no label, UUID=5d0bcb70-613d-4fc5-a2f5-f2047dcbab37
2026-05-20 01:21:55.779577 | controller | ok: Runtime: 0:00:21.146210
2026-05-20 01:21:55.788180 | 
2026-05-20 01:21:55.788292 | TASK [configure-swap : Write swap to fstab]
2026-05-20 01:21:56.157555 | controller | changed
2026-05-20 01:21:56.166211 | 
2026-05-20 01:21:56.166333 | TASK [configure-swap : Add all swap]
2026-05-20 01:21:56.701318 | controller | ok: Runtime: 0:00:00.015855
2026-05-20 01:21:56.706992 | 
2026-05-20 01:21:56.707066 | TASK [configure-swap : Debug the swap_required variable]
2026-05-20 01:21:56.736129 | controller | ok:
2026-05-20 01:21:56.736272 | controller | {
2026-05-20 01:21:56.736297 | controller |   "swap_required": "8192"
2026-05-20 01:21:56.736317 | controller | }
2026-05-20 01:21:56.742229 | 
2026-05-20 01:21:56.742310 | TASK [configure-swap : Set swappiness]
2026-05-20 01:21:57.159064 | controller | changed
2026-05-20 01:21:57.166573 | 
2026-05-20 01:21:57.166748 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-20 01:21:57.200750 | controller | ok:
2026-05-20 01:21:57.200899 | controller | {
2026-05-20 01:21:57.200925 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-20 01:21:57.200948 | controller | }
2026-05-20 01:21:57.213006 | 
2026-05-20 01:21:57.213116 | TASK [setup-stack-user : Create stack group]
2026-05-20 01:21:57.696235 | controller | changed
2026-05-20 01:21:57.703368 | 
2026-05-20 01:21:57.703503 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-20 01:21:57.959062 | controller | changed
2026-05-20 01:21:57.966010 | 
2026-05-20 01:21:57.966074 | TASK [setup-stack-user : Create stack user]
2026-05-20 01:21:58.515764 | controller | changed
2026-05-20 01:21:58.521759 | 
2026-05-20 01:21:58.521822 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-20 01:21:58.777615 | controller | changed
2026-05-20 01:21:58.784702 | 
2026-05-20 01:21:58.784767 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-20 01:21:59.856508 | controller | changed
2026-05-20 01:21:59.866205 | 
2026-05-20 01:21:59.866301 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-20 01:22:00.143250 | controller | changed
2026-05-20 01:22:00.162405 | 
2026-05-20 01:22:00.162546 | TASK [setup-tempest-user : Create tempest group]
2026-05-20 01:22:00.456382 | controller | changed
2026-05-20 01:22:00.462641 | 
2026-05-20 01:22:00.462709 | TASK [setup-tempest-user : Create tempest user]
2026-05-20 01:22:00.869169 | controller | changed
2026-05-20 01:22:00.880094 | 
2026-05-20 01:22:00.880237 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-20 01:22:01.771610 | controller | changed
2026-05-20 01:22:01.790499 | 
2026-05-20 01:22:01.790652 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-20 01:22:02.176150 | controller | ok: Not all paths examined, check warnings for details
2026-05-20 01:22:02.191295 | 
2026-05-20 01:22:02.191402 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-20 01:22:02.760831 | controller | ok: Item: Runtime: 0:00:00.190500
2026-05-20 01:22:03.117588 | controller | ok: Item: Runtime: 0:00:00.135481
2026-05-20 01:22:03.396277 | controller | ok: Item: Runtime: 0:00:00.063910
2026-05-20 01:22:03.886913 | controller | ok: Item: Runtime: 0:00:00.265405
2026-05-20 01:22:04.253445 | controller | ok: Item: Runtime: 0:00:00.144308
2026-05-20 01:22:04.773167 | controller | ok: Item: Runtime: 0:00:00.292578
2026-05-20 01:22:05.496858 | controller | ok: Item: Runtime: 0:00:00.498377
2026-05-20 01:22:06.304664 | controller | ok: Item: Runtime: 0:00:00.593463
2026-05-20 01:22:07.129330 | controller | ok: Item: Runtime: 0:00:00.606650
2026-05-20 01:22:08.437668 | controller | ok: Item: Runtime: 0:00:01.094551
2026-05-20 01:22:08.757045 | controller | ok: Item: Runtime: 0:00:00.102478
2026-05-20 01:22:09.216934 | controller | ok: Item: Runtime: 0:00:00.223824
2026-05-20 01:22:09.787803 | controller | ok: Item: Runtime: 0:00:00.346743
2026-05-20 01:22:13.532881 | controller | ok: Item: Runtime: 0:00:03.510218
2026-05-20 01:22:14.950694 | controller | ok: Item: Runtime: 0:00:01.196435
2026-05-20 01:22:15.432266 | controller | ok: Item: Runtime: 0:00:00.273769
2026-05-20 01:22:15.463140 | 
2026-05-20 01:22:15.463301 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-20 01:22:15.702167 | controller | ok: All paths examined
2026-05-20 01:22:15.710617 | 
2026-05-20 01:22:15.710691 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-20 01:22:15.965303 | controller | ok: All paths examined
2026-05-20 01:22:15.972561 | 
2026-05-20 01:22:15.972633 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-20 01:22:16.387277 | controller | ok: Item: Runtime: 0:00:00.148705
2026-05-20 01:22:16.387526 | controller | changed: All items complete
2026-05-20 01:22:16.387556 | 
2026-05-20 01:22:16.843733 | controller | ok: Item: Runtime: 0:00:00.240680
2026-05-20 01:22:16.872343 | 
2026-05-20 01:22:16.872534 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-20 01:22:16.957730 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.960182 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.963743 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.967320 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.971320 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.974900 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.978083 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.981934 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.985465 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.988887 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.992278 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.996132 | controller | skipping: Conditional result was False
2026-05-20 01:22:16.999937 | controller | skipping: Conditional result was False
2026-05-20 01:22:17.003137 | controller | skipping: Conditional result was False
2026-05-20 01:22:17.007011 | controller | skipping: Conditional result was False
2026-05-20 01:22:17.009821 | controller | skipping: Conditional result was False
2026-05-20 01:22:17.120853 | 
2026-05-20 01:22:17.121041 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-20 01:22:19.476488 | controller | changed
2026-05-20 01:22:19.493077 | 
2026-05-20 01:22:19.493239 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-20 01:22:19.756519 | controller | changed
2026-05-20 01:22:19.772385 | 
2026-05-20 01:22:19.772608 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-20 01:22:20.052636 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-20 01:22:20.324457 | controller | ERROR
2026-05-20 01:22:20.324754 | controller | {
2026-05-20 01:22:20.324801 | controller |   "delta": "0:00:00.022584",
2026-05-20 01:22:20.324832 | controller |   "end": "2026-05-20 01:22:20.053735",
2026-05-20 01:22:20.324860 | controller |   "msg": "non-zero return code",
2026-05-20 01:22:20.324887 | controller |   "rc": 1,
2026-05-20 01:22:20.324915 | controller |   "start": "2026-05-20 01:22:20.031151"
2026-05-20 01:22:20.324941 | controller | }
2026-05-20 01:22:20.325008 | controller | ERROR: Ignoring Errors
2026-05-20 01:22:20.335943 | 
2026-05-20 01:22:20.336146 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-20 01:22:20.586760 | controller | ok
2026-05-20 01:22:20.599663 | 
2026-05-20 01:22:20.599766 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-20 01:22:20.834202 | controller | /usr/bin/journalctl
2026-05-20 01:22:21.144217 | controller | ok: Runtime: 0:00:00.008233
2026-05-20 01:22:21.153309 | 
2026-05-20 01:22:21.153409 | TASK [start-fresh-logging : Get current date]
2026-05-20 01:22:21.412864 | controller | 2026-05-20 01:22:21
2026-05-20 01:22:21.689699 | controller | ok: Runtime: 0:00:00.013233
2026-05-20 01:22:21.698207 | 
2026-05-20 01:22:21.698305 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-20 01:22:22.305641 | controller | changed
2026-05-20 01:22:22.314677 | 
2026-05-20 01:22:22.314782 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-20 01:22:22.338549 | controller | skipping: Conditional result was False
2026-05-20 01:22:22.351930 | 
2026-05-20 01:22:22.352056 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-20 01:22:22.914023 | controller | skipping: Conditional result was False
2026-05-20 01:22:22.926676 | 
2026-05-20 01:22:22.926833 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-20 01:22:23.464794 | controller | skipping: Conditional result was False
2026-05-20 01:22:23.474454 | 
2026-05-20 01:22:23.474549 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-20 01:22:23.499030 | controller | skipping: Conditional result was False
2026-05-20 01:22:23.511691 | 
2026-05-20 01:22:23.511801 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-20 01:22:24.044616 | controller | skipping: Conditional result was False
2026-05-20 01:22:24.054660 | 
2026-05-20 01:22:24.054751 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-20 01:22:24.654298 | controller | skipping: Conditional result was False
2026-05-20 01:22:24.666609 | 
2026-05-20 01:22:24.666702 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-20 01:22:24.693025 | controller | skipping: Conditional result was False
2026-05-20 01:22:24.704315 | 
2026-05-20 01:22:24.704415 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-20 01:22:24.730481 | controller | skipping: Conditional result was False
2026-05-20 01:22:24.742507 | 
2026-05-20 01:22:24.742576 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-20 01:22:25.273362 | controller | skipping: Conditional result was False
2026-05-20 01:22:25.284410 | 
2026-05-20 01:22:25.284501 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-20 01:22:25.813799 | controller | skipping: Conditional result was False
2026-05-20 01:22:25.822853 | 
2026-05-20 01:22:25.822945 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-20 01:22:25.841922 | controller | skipping: Conditional result was False
2026-05-20 01:22:25.859966 | 
2026-05-20 01:22:25.860036 | TASK [start-fresh-logging : Start rsyslog]
2026-05-20 01:22:25.875619 | controller | skipping: Conditional result was False
2026-05-20 01:22:25.905364 | 
2026-05-20 01:22:25.905599 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-20 01:22:26.433353 | controller | ok
2026-05-20 01:22:26.443374 | 
2026-05-20 01:22:26.443432 | PLAY RECAP
2026-05-20 01:22:26.443481 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-20 01:22:26.443504 | 
2026-05-20 01:22:26.574395 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:22:26.601988 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:22:27.355008 | 
2026-05-20 01:22:27.355121 | PLAY [all]
2026-05-20 01:22:27.366288 | 
2026-05-20 01:22:27.366358 | TASK [Install curl]
2026-05-20 03:19:16.950518 | PRE-RUN END RESULT_TIMED_OUT: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 03:19:17.085362 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 03:19:18.096591 | 
2026-05-20 03:19:18.191072 | PLAY [all]
2026-05-20 03:19:18.238113 | 
2026-05-20 03:19:18.238356 | LOOP [Copy individual Hydrophone result files]
2026-05-20 03:19:18.862722 | controller | ERROR: Item: e2e.log
2026-05-20 03:19:18.862976 | controller | {
2026-05-20 03:19:18.863017 | controller |   "ansible_loop_var": "item",
2026-05-20 03:19:18.863046 | controller |   "item": "e2e.log",
2026-05-20 03:19:18.863100 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-20 03:19:18.863126 | controller | }
2026-05-20 03:19:19.126679 | controller | ERROR: Item: junit_01.xml
2026-05-20 03:19:19.126827 | controller | {
2026-05-20 03:19:19.126861 | controller |   "ansible_loop_var": "item",
2026-05-20 03:19:19.126883 | controller |   "item": "junit_01.xml",
2026-05-20 03:19:19.126902 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-20 03:19:19.126920 | controller | }
2026-05-20 03:19:19.144137 | controller | ERROR: Ignoring Errors
2026-05-20 03:19:19.150366 | 
2026-05-20 03:19:19.150445 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-20 03:19:19.454801 | controller | ERROR
2026-05-20 03:19:19.455074 | controller | {
2026-05-20 03:19:19.455104 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-20 03:19:19.455128 | controller | }
2026-05-20 03:19:19.455153 | controller | ERROR: Ignoring Errors
2026-05-20 03:19:19.469244 | 
2026-05-20 03:19:19.469381 | TASK [Return built artifacts to Zuul]
2026-05-20 03:19:19.505900 | controller | ok
2026-05-20 03:19:19.513172 | 
2026-05-20 03:19:19.513238 | TASK [Get the Docker volume ID]
2026-05-20 03:19:20.054863 | controller | ERROR
2026-05-20 03:19:20.055176 | controller | {
2026-05-20 03:19:20.055211 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-20 03:19:20.055233 | controller |   "rc": 2
2026-05-20 03:19:20.055252 | controller | }
2026-05-20 03:19:20.055280 | controller | ERROR: Ignoring Errors
2026-05-20 03:19:20.060633 | 
2026-05-20 03:19:20.060700 | TASK [Copy all of the pod logs]
2026-05-20 03:19:20.096587 | controller | ERROR
2026-05-20 03:19:20.096824 | controller | {
2026-05-20 03:19:20.096853 | 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/33daeebf065943ec8f91d9c5e02290c9/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml': line 42, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-05-20 03:19:20.096877 | controller | }
2026-05-20 03:19:20.096901 | controller | ERROR: Ignoring Errors
2026-05-20 03:19:20.098141 | 
2026-05-20 03:19:20.098191 | PLAY RECAP
2026-05-20 03:19:20.098243 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-20 03:19:20.098265 | 
2026-05-20 03:19:20.293985 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 03:19:20.306562 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 03:19:20.937479 | 
2026-05-20 03:19:20.937593 | PLAY [all]
2026-05-20 03:19:20.955202 | 
2026-05-20 03:19:20.955285 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-20 03:19:21.521743 | controller | changed
2026-05-20 03:19:21.527417 | 
2026-05-20 03:19:21.527493 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-20 03:19:22.566656 | controller | ok: Runtime: 0:00:00.454896
2026-05-20 03:19:22.572886 | 
2026-05-20 03:19:22.572979 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-20 03:19:23.113425 | controller | ok: Runtime: 0:00:00.019247
2026-05-20 03:19:23.121674 | 
2026-05-20 03:19:23.121837 | TASK [export-devstack-journal : Export journal]
2026-05-20 03:19:23.706327 | controller | ok: Runtime: 0:00:00.049053
2026-05-20 03:19:23.715150 | 
2026-05-20 03:19:23.715246 | TASK [export-devstack-journal : Save journal README]
2026-05-20 03:19:24.577136 | controller | changed
2026-05-20 03:19:24.588127 | 
2026-05-20 03:19:24.588197 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-20 03:19:24.829765 | controller | changed
2026-05-20 03:19:24.835329 | 
2026-05-20 03:19:24.835393 | TASK [apache-logs-conf : Find logs]
2026-05-20 03:19:25.177623 | controller | Output suppressed because no_log was given
2026-05-20 03:19:25.182680 | 
2026-05-20 03:19:25.182744 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 03:19:25.205470 | 
2026-05-20 03:19:25.205656 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 03:19:25.229297 | 
2026-05-20 03:19:25.229469 | TASK [apache-logs-conf : Find logs]
2026-05-20 03:19:25.261761 | 
2026-05-20 03:19:25.261940 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 03:19:25.286519 | 
2026-05-20 03:19:25.286696 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 03:19:25.322688 | 
2026-05-20 03:19:25.322877 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-20 03:19:25.559636 | controller | changed
2026-05-20 03:19:25.565484 | 
2026-05-20 03:19:25.565545 | TASK [apache-logs-conf : Define config paths]
2026-05-20 03:19:25.598733 | controller | ok
2026-05-20 03:19:25.603859 | 
2026-05-20 03:19:25.603921 | TASK [apache-logs-conf : Discover configurations]
2026-05-20 03:19:25.852464 | controller | Output suppressed because no_log was given
2026-05-20 03:19:25.857531 | 
2026-05-20 03:19:25.857595 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-20 03:19:25.894169 | 
2026-05-20 03:19:25.894299 | LOOP [apache-logs-conf : Link configurations]
2026-05-20 03:19:25.935384 | 
2026-05-20 03:19:25.935515 | TASK [capture-performance-data : Generate statistics]
2026-05-20 03:19:26.561354 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-20 03:19:26.572799 | controller | Using python 3.12 to install setuptools
2026-05-20 03:19:26.608612 | controller | /usr/bin/python3.12: No module named pip
2026-05-20 03:19:26.620217 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-20 03:19:26.974876 | controller | ERROR
2026-05-20 03:19:26.975005 | controller | {
2026-05-20 03:19:26.975042 | controller |   "delta": "0:00:00.459346",
2026-05-20 03:19:26.975062 | controller |   "end": "2026-05-20 03:19:26.622461",
2026-05-20 03:19:26.975082 | controller |   "msg": "non-zero return code",
2026-05-20 03:19:26.975104 | controller |   "rc": 127,
2026-05-20 03:19:26.975123 | controller |   "start": "2026-05-20 03:19:26.163115"
2026-05-20 03:19:26.975140 | controller | }
2026-05-20 03:19:26.975165 | controller | ERROR: Ignoring Errors
2026-05-20 03:19:26.986423 | 
2026-05-20 03:19:26.986494 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-20 03:19:27.211592 | controller | changed
2026-05-20 03:19:27.220707 | 
2026-05-20 03:19:27.220796 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-20 03:19:30.830643 | controller | Output suppressed because no_log was given
2026-05-20 03:19:30.843670 | 
2026-05-20 03:19:30.843743 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-20 03:19:30.913568 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.913937 | 
2026-05-20 03:19:30.915752 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.917991 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.920851 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.923260 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.925977 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.928679 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.931544 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.934563 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.937275 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.939862 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.942252 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.944929 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.947691 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.950327 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.953160 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.955789 | controller | skipping: Conditional result was False
2026-05-20 03:19:30.958445 | controller | skipping: Conditional result was False
2026-05-20 03:19:31.022050 | 
2026-05-20 03:19:31.022198 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-20 03:19:31.259963 | controller | ok
2026-05-20 03:19:31.266700 | 
2026-05-20 03:19:31.266767 | TASK [devstack-project-conf : Copy configuration files]
2026-05-20 03:19:31.798196 | controller | skipping: Conditional result was False
2026-05-20 03:19:31.816349 | 
2026-05-20 03:19:31.816519 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-20 03:19:32.154971 | controller | /usr/bin/python3: No module named pip
2026-05-20 03:19:33.260657 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-20 03:19:33.353719 | controller | ok: Runtime: 0:00:01.204391
2026-05-20 03:19:33.363656 | 
2026-05-20 03:19:33.363720 | LOOP [stage-output : Register sources]
2026-05-20 03:19:42.505397 | controller | Output suppressed because no_log was given
2026-05-20 03:19:42.517605 | 
2026-05-20 03:19:42.517702 | TASK [stage-output : Check sudo]
2026-05-20 03:19:43.058816 | controller | ok: Runtime: 0:00:00.020807
2026-05-20 03:19:43.064751 | 
2026-05-20 03:19:43.064814 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-20 03:19:43.161873 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.162473 | 
2026-05-20 03:19:43.164663 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.165660 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.168622 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.204885 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.205308 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.207695 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.210701 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.213210 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.215730 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.218548 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.221071 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.223587 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.226163 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.245674 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.246219 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.246707 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.247190 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.249790 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.252237 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.254676 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.256785 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.259313 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.284331 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.284721 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.369254 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.377711 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.392421 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.399949 | controller | Output suppressed because no_log was given
2026-05-20 03:19:43.413359 | 
2026-05-20 03:19:43.413444 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-20 03:19:43.471063 | controller | ok
2026-05-20 03:19:43.479104 | 
2026-05-20 03:19:43.479174 | LOOP [stage-output : Ensure target folders exist]
2026-05-20 03:19:43.723577 | controller | changed: "docs"
2026-05-20 03:19:43.946864 | controller | changed: "artifacts"
2026-05-20 03:19:44.179691 | controller | ok: "logs"
2026-05-20 03:19:44.193906 | 
2026-05-20 03:19:44.194101 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-20 03:19:44.494371 | controller | ok: Item: Runtime: 0:00:00.011413
2026-05-20 03:19:44.494640 | controller | changed: All items complete
2026-05-20 03:19:44.494668 | 
2026-05-20 03:19:44.717718 | controller | ok: Item: Runtime: 0:00:00.008220
2026-05-20 03:19:44.937203 | controller | ok: Item: Runtime: 0:00:00.008613
2026-05-20 03:19:45.162146 | controller | ok: Item: Runtime: 0:00:00.009514
2026-05-20 03:19:45.384761 | controller | ok: Item: Runtime: 0:00:00.007553
2026-05-20 03:19:45.605380 | controller | ok: Item: Runtime: 0:00:00.007230
2026-05-20 03:19:45.827514 | controller | ok: Item: Runtime: 0:00:00.008142
2026-05-20 03:19:46.050053 | controller | ok: Item: Runtime: 0:00:00.008019
2026-05-20 03:19:46.319465 | controller | ok: Item: Runtime: 0:00:00.007877
2026-05-20 03:19:46.509261 | controller | ok: Item: Runtime: 0:00:00.008357
2026-05-20 03:19:46.734051 | controller | ok: Item: Runtime: 0:00:00.007565
2026-05-20 03:19:46.959607 | controller | ok: Item: Runtime: 0:00:00.004837
2026-05-20 03:19:47.181766 | controller | ok: Item: Runtime: 0:00:00.006959
2026-05-20 03:19:47.405758 | controller | ok: Item: Runtime: 0:00:00.007723
2026-05-20 03:19:47.621404 | controller | ok: Item: Runtime: 0:00:00.005878
2026-05-20 03:19:47.857461 | controller | ok: Item: Runtime: 0:00:00.007863
2026-05-20 03:19:47.879290 | 
2026-05-20 03:19:47.879408 | TASK [stage-output : Make all log files readable]
2026-05-20 03:19:48.127286 | controller | changed
2026-05-20 03:19:48.132936 | 
2026-05-20 03:19:48.133018 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-20 03:19:48.456295 | controller | changed: Renamed files for staging.
2026-05-20 03:19:48.463743 | 
2026-05-20 03:19:48.463828 | TASK [stage-output : Discover log files for compression]
2026-05-20 03:19:48.489937 | controller | skipping: Conditional result was False
2026-05-20 03:19:48.500535 | 
2026-05-20 03:19:48.500645 | LOOP [stage-output : Archive everything from logs]
2026-05-20 03:19:48.545111 | 
2026-05-20 03:19:48.545287 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-20 03:19:49.249799 | controller | changed:
2026-05-20 03:19:49.249990 | controller | created directory /var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/work/logs/controller
2026-05-20 03:19:49.250029 | controller | cd+++++++++ logs/
2026-05-20 03:19:49.250060 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-20 03:19:49.250088 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-20 03:19:49.250114 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-20 03:19:49.250140 | controller | >f+++++++++ logs/df.txt
2026-05-20 03:19:49.250166 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-20 03:19:49.250191 | controller | >f+++++++++ logs/iptables.txt
2026-05-20 03:19:49.250216 | controller | >f+++++++++ logs/listen53.txt
2026-05-20 03:19:49.250242 | controller | >f+++++++++ logs/local_conf.txt
2026-05-20 03:19:49.250267 | controller | >f+++++++++ logs/mount.txt
2026-05-20 03:19:49.250307 | controller | >f+++++++++ logs/performance.json
2026-05-20 03:19:49.250333 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-20 03:19:49.250359 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-20 03:19:49.250384 | controller | >f+++++++++ logs/services.txt
2026-05-20 03:19:49.250416 | controller | >f+++++++++ logs/sudoers
2026-05-20 03:19:49.250442 | controller | >f+++++++++ logs/syslog.txt
2026-05-20 03:19:49.250469 | controller | cd+++++++++ logs/apache/
2026-05-20 03:19:49.250494 | controller | cd+++++++++ logs/apache_config/
2026-05-20 03:19:49.250531 | controller | cd+++++++++ logs/etc/
2026-05-20 03:19:49.250561 | controller | cd+++++++++ logs/sudoers.d/
2026-05-20 03:19:49.250587 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-20 03:19:49.250613 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-20 03:19:49.250638 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-20 03:19:49.250663 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-20 03:19:49.250688 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-20 03:19:49.262606 | 
2026-05-20 03:19:49.262668 | TASK [Check if a tempest log exits]
2026-05-20 03:19:49.510947 | controller | ok
2026-05-20 03:19:49.515941 | 
2026-05-20 03:19:49.516000 | TASK [Link post-devstack tempest.log]
2026-05-20 03:19:49.541050 | controller | skipping: Conditional result was False
2026-05-20 03:19:49.551883 | 
2026-05-20 03:19:49.551970 | TASK [Capture most recent qemu crash dump, if any]
2026-05-20 03:19:49.801708 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-20 03:19:50.095116 | controller | ERROR
2026-05-20 03:19:50.095319 | controller | {
2026-05-20 03:19:50.095358 | controller |   "delta": "0:00:00.008231",
2026-05-20 03:19:50.095387 | controller |   "end": "2026-05-20 03:19:49.802370",
2026-05-20 03:19:50.095412 | controller |   "msg": "non-zero return code",
2026-05-20 03:19:50.095437 | controller |   "rc": 127,
2026-05-20 03:19:50.095461 | controller |   "start": "2026-05-20 03:19:49.794139"
2026-05-20 03:19:50.095485 | controller | }
2026-05-20 03:19:50.095516 | controller | ERROR: Ignoring Errors
2026-05-20 03:19:50.097268 | 
2026-05-20 03:19:50.097327 | PLAY RECAP
2026-05-20 03:19:50.097385 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-20 03:19:50.097414 | 
2026-05-20 03:19:50.224756 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 03:19:50.233515 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 03:19:50.940501 | 
2026-05-20 03:19:50.940734 | PLAY [all]
2026-05-20 03:19:50.955892 | 
2026-05-20 03:19:50.956044 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 03:19:51.001902 | controller | skipping: Conditional result was False
2026-05-20 03:19:51.014094 | 
2026-05-20 03:19:51.014261 | TASK [fetch-output : Set log path for single node]
2026-05-20 03:19:51.061081 | controller | ok
2026-05-20 03:19:51.069127 | 
2026-05-20 03:19:51.069235 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 03:19:51.462026 | controller -> localhost | ok: "/var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/work/logs"
2026-05-20 03:19:51.668051 | controller -> localhost | changed: "/var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/work/artifacts"
2026-05-20 03:19:51.888375 | controller -> localhost | changed: "/var/lib/zuul/builds/33daeebf065943ec8f91d9c5e02290c9/work/docs"
2026-05-20 03:19:51.910701 | 
2026-05-20 03:19:51.910946 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 03:19:52.570840 | controller | changed: .d..t...... ./
2026-05-20 03:19:52.571104 | controller | changed: All items complete
2026-05-20 03:19:52.571144 | 
2026-05-20 03:19:53.107431 | controller | changed: .d..t...... ./
2026-05-20 03:19:53.585827 | controller | changed: .d..t...... ./
2026-05-20 03:19:53.613574 | 
2026-05-20 03:19:53.613775 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 03:19:54.024486 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006809
2026-05-20 03:19:54.238989 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007631
2026-05-20 03:19:54.262195 | 
2026-05-20 03:19:54.262349 | PLAY [all]
2026-05-20 03:19:54.269258 | 
2026-05-20 03:19:54.269332 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 03:19:54.930348 | controller | changed
2026-05-20 03:19:54.938685 | 
2026-05-20 03:19:54.938761 | PLAY RECAP
2026-05-20 03:19:54.938834 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 03:19:54.938867 | 
2026-05-20 03:19:55.107853 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 03:19:55.118388 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 03:19:55.685439 | 
2026-05-20 03:19:55.685543 | PLAY [localhost]
2026-05-20 03:19:55.695027 | 
2026-05-20 03:19:55.695097 | TASK [Generate Zuul manifest]
2026-05-20 03:19:55.717138 | localhost | ok
2026-05-20 03:19:55.735082 | 
2026-05-20 03:19:55.735162 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 03:19:56.092150 | localhost | changed
2026-05-20 03:19:56.101911 | 
2026-05-20 03:19:56.101982 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 03:19:56.131838 | localhost | ok
2026-05-20 03:19:56.141358 | 
2026-05-20 03:19:56.141449 | TASK [Upload logs]
2026-05-20 03:19:56.161309 | localhost | ok
2026-05-20 03:19:56.260744 | 
2026-05-20 03:19:56.260855 | TASK [Set zuul-log-path fact]
2026-05-20 03:19:56.282503 | localhost | ok
2026-05-20 03:19:56.296693 | 
2026-05-20 03:19:56.296758 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 03:19:56.329377 | localhost | ok
2026-05-20 03:19:56.339234 | 
2026-05-20 03:19:56.339300 | TASK [upload-logs : Create log directories]
2026-05-20 03:19:56.706313 | localhost | changed
2026-05-20 03:19:56.711738 | 
2026-05-20 03:19:56.711814 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 03:19:57.089547 | localhost -> localhost | ok: Runtime: 0:00:00.006624
2026-05-20 03:19:57.096330 | 
2026-05-20 03:19:57.096394 | TASK [upload-logs : Upload logs to log server]
2026-05-20 03:19:57.533283 | localhost | Output suppressed because no_log was given
2026-05-20 03:19:57.538346 | 
2026-05-20 03:19:57.538437 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 03:19:57.586040 | localhost | skipping: Conditional result was False
2026-05-20 03:19:57.592221 | localhost | skipping: Conditional result was False
2026-05-20 03:19:57.605184 | 
2026-05-20 03:19:57.605336 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 03:19:57.651216 | localhost | skipping: Conditional result was False
2026-05-20 03:19:57.651762 | 
2026-05-20 03:19:57.655106 | localhost | skipping: Conditional result was False
2026-05-20 03:19:57.670624 | 
2026-05-20 03:19:57.670775 | LOOP [upload-logs : Upload console log and json output]