2026-07-14 14:09:15.074187 | Job console starting
2026-07-14 14:09:15.343474 | Updating git repos
2026-07-14 14:09:20.084809 | Cloning repos into workspace
2026-07-14 14:09:43.408473 | Restoring repo states
2026-07-14 14:09:45.106049 | Merging changes
2026-07-14 14:09:45.922807 | Checking out repos
2026-07-14 14:09:55.659718 | Preparing playbooks
2026-07-14 14:10:08.445060 | Running Ansible setup
2026-07-14 14:10:13.734117 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-14 14:10:14.687363 | 
2026-07-14 14:10:14.687569 | PLAY [localhost]
2026-07-14 14:10:14.697777 | 
2026-07-14 14:10:14.697910 | TASK [Gathering Facts]
2026-07-14 14:10:15.794015 | localhost | ok
2026-07-14 14:10:15.803591 | 
2026-07-14 14:10:15.803699 | TASK [Setup log path fact]
2026-07-14 14:10:15.833286 | localhost | ok
2026-07-14 14:10:15.844297 | 
2026-07-14 14:10:15.844413 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-14 14:10:15.876508 | localhost | ok
2026-07-14 14:10:15.882926 | 
2026-07-14 14:10:15.883014 | TASK [emit-job-header : Print job information]
2026-07-14 14:10:15.935211 | # Job Information
2026-07-14 14:10:15.935485 | Ansible Version: 2.16.18
2026-07-14 14:10:15.935557 | Job: magnum-cluster-api-hydrophone-v1.33.12-calico
2026-07-14 14:10:15.935609 | Pipeline: check
2026-07-14 14:10:15.935655 | Executor: 2d72f0692154
2026-07-14 14:10:15.935700 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1090
2026-07-14 14:10:15.935751 | Event ID: 9e424c10-7f8c-11f1-9ff4-549dd579a878
2026-07-14 14:10:15.939996 | 
2026-07-14 14:10:15.941231 | LOOP [emit-job-header : Print node information]
2026-07-14 14:10:16.041211 | localhost | ok:
2026-07-14 14:10:16.041372 | localhost | # Node Information
2026-07-14 14:10:16.041416 | localhost | Inventory Hostname: controller
2026-07-14 14:10:16.041533 | localhost | Hostname: np0000185160
2026-07-14 14:10:16.041578 | localhost | Username: zuul
2026-07-14 14:10:16.041624 | localhost | Distro: Ubuntu 24.04
2026-07-14 14:10:16.041652 | localhost | Provider: yul1
2026-07-14 14:10:16.041676 | localhost | Region: ca-ymq-1
2026-07-14 14:10:16.041695 | localhost | Label: ubuntu-noble-16
2026-07-14 14:10:16.041714 | localhost | Product Name: OpenStack Nova
2026-07-14 14:10:16.041734 | localhost | Interface IP: 199.19.213.179
2026-07-14 14:10:16.056640 | 
2026-07-14 14:10:16.056833 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-07-14 14:10:16.602524 | localhost -> localhost | changed
2026-07-14 14:10:16.609798 | 
2026-07-14 14:10:16.609901 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-07-14 14:10:18.018675 | localhost -> localhost | changed
2026-07-14 14:10:18.025605 | 
2026-07-14 14:10:18.025670 | PLAY [all]
2026-07-14 14:10:18.036841 | 
2026-07-14 14:10:18.037146 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-07-14 14:10:18.332533 | controller -> localhost | ok
2026-07-14 14:10:18.343424 | 
2026-07-14 14:10:18.433936 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-07-14 14:10:18.474744 | controller | ok
2026-07-14 14:10:18.491093 | controller | included: /var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-07-14 14:10:18.498254 | 
2026-07-14 14:10:18.498326 | TASK [add-build-sshkey : Create Temp SSH key]
2026-07-14 14:10:20.230098 | controller -> localhost | Generating public/private rsa key pair.
2026-07-14 14:10:20.230295 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/work/d003047e3e684b3e9b73ff5fb71a8cbe_id_rsa
2026-07-14 14:10:20.230329 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/work/d003047e3e684b3e9b73ff5fb71a8cbe_id_rsa.pub
2026-07-14 14:10:20.230352 | controller -> localhost | The key fingerprint is:
2026-07-14 14:10:20.230374 | controller -> localhost | SHA256:X5pkw9+mlB5Gj5GT4ui1ToYhvan4HQyY8H/cr+hhMs4 zuul-build-sshkey
2026-07-14 14:10:20.230406 | controller -> localhost | The key's randomart image is:
2026-07-14 14:10:20.230429 | controller -> localhost | +---[RSA 3072]----+
2026-07-14 14:10:20.230457 | controller -> localhost | |                 |
2026-07-14 14:10:20.230480 | controller -> localhost | |                 |
2026-07-14 14:10:20.230500 | controller -> localhost | |  .              |
2026-07-14 14:10:20.230520 | controller -> localhost | |   o o . .   o   |
2026-07-14 14:10:20.230541 | controller -> localhost | |    + o S * B    |
2026-07-14 14:10:20.230561 | controller -> localhost | |     . = % O B   |
2026-07-14 14:10:20.230582 | controller -> localhost | |      + % X B +  |
2026-07-14 14:10:20.230603 | controller -> localhost | |     + O B * +   |
2026-07-14 14:10:20.230626 | controller -> localhost | |    ..E.=.+.+    |
2026-07-14 14:10:20.230647 | controller -> localhost | +----[SHA256]-----+
2026-07-14 14:10:20.230696 | controller -> localhost | ok: Runtime: 0:00:01.266917
2026-07-14 14:10:20.237660 | 
2026-07-14 14:10:20.237729 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-07-14 14:10:20.267149 | controller | ok
2026-07-14 14:10:20.276229 | controller | included: /var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-07-14 14:10:20.293887 | 
2026-07-14 14:10:20.294036 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-07-14 14:10:20.319003 | controller | skipping: Conditional result was False
2026-07-14 14:10:20.331477 | 
2026-07-14 14:10:20.331583 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-07-14 14:10:20.905995 | controller | changed
2026-07-14 14:10:20.913036 | 
2026-07-14 14:10:20.913239 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-07-14 14:10:21.172532 | controller | ok
2026-07-14 14:10:21.180624 | 
2026-07-14 14:10:21.180716 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-07-14 14:10:22.129048 | controller | changed
2026-07-14 14:10:22.135546 | 
2026-07-14 14:10:22.135637 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-07-14 14:10:22.868051 | controller | changed
2026-07-14 14:10:22.955322 | 
2026-07-14 14:10:22.955473 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-07-14 14:10:22.980936 | controller | skipping: Conditional result was False
2026-07-14 14:10:22.991669 | 
2026-07-14 14:10:22.991774 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-07-14 14:10:23.440013 | controller -> localhost | changed
2026-07-14 14:10:23.477666 | 
2026-07-14 14:10:23.477791 | TASK [add-build-sshkey : Add back temp key]
2026-07-14 14:10:23.779856 | controller -> localhost | Identity added: /var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/work/d003047e3e684b3e9b73ff5fb71a8cbe_id_rsa (zuul-build-sshkey)
2026-07-14 14:10:23.780031 | controller -> localhost | ok: Runtime: 0:00:00.015122
2026-07-14 14:10:23.786378 | 
2026-07-14 14:10:23.786447 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-07-14 14:10:24.106378 | controller | ok
2026-07-14 14:10:24.114107 | 
2026-07-14 14:10:24.114199 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-07-14 14:10:24.138856 | controller | skipping: Conditional result was False
2026-07-14 14:10:24.151771 | 
2026-07-14 14:10:24.151859 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-07-14 14:10:24.517068 | controller | ok
2026-07-14 14:10:24.526796 | 
2026-07-14 14:10:24.526909 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-07-14 14:11:18.691748 | controller | Output suppressed because no_log was given
2026-07-14 14:11:18.708612 | 
2026-07-14 14:11:18.708755 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-07-14 14:11:19.017162 | controller | ok: "logs"
2026-07-14 14:11:19.017563 | controller | ok: All items complete
2026-07-14 14:11:19.017602 | 
2026-07-14 14:11:19.189113 | controller | ok: "artifacts"
2026-07-14 14:11:19.378833 | controller | ok: "docs"
2026-07-14 14:11:19.399928 | 
2026-07-14 14:11:19.400111 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-07-14 14:11:19.618740 | controller | changed: "logs"
2026-07-14 14:11:19.833460 | controller | changed: "artifacts"
2026-07-14 14:11:20.057146 | controller | changed: "docs"
2026-07-14 14:11:20.075435 | 
2026-07-14 14:11:20.075563 | PLAY RECAP
2026-07-14 14:11:20.075613 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-07-14 14:11:20.075644 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-07-14 14:11:20.075753 | 
2026-07-14 14:11:20.336545 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-07-14 14:11:20.359573 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-07-14 14:11:21.395770 | 
2026-07-14 14:11:21.401329 | PLAY [all]
2026-07-14 14:11:21.445094 | 
2026-07-14 14:11:21.445368 | TASK [Fix the permissions of the zuul home directory]
2026-07-14 14:11:21.941394 | controller | changed
2026-07-14 14:11:21.950839 | 
2026-07-14 14:11:21.950997 | TASK [Gather minimum local MTU]
2026-07-14 14:11:22.064036 | controller | ok
2026-07-14 14:11:22.072876 | 
2026-07-14 14:11:22.073044 | TASK [Calculate external_bridge_mtu]
2026-07-14 14:11:22.159569 | controller | ok
2026-07-14 14:11:22.171632 | 
2026-07-14 14:11:22.171798 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-07-14 14:11:22.218914 | controller | skipping: Conditional result was False
2026-07-14 14:11:22.230858 | 
2026-07-14 14:11:22.231018 | TASK [configure-swap : Get ephemeral0 device node]
2026-07-14 14:11:22.807970 | controller | ok: Runtime: 0:00:00.009030
2026-07-14 14:11:22.818037 | 
2026-07-14 14:11:22.818130 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-07-14 14:11:22.855575 | controller | skipping: Conditional result was False
2026-07-14 14:11:22.871868 | 
2026-07-14 14:11:22.871981 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-07-14 14:11:22.907545 | controller | skipping: Conditional result was False
2026-07-14 14:11:22.919171 | 
2026-07-14 14:11:22.919318 | TASK [configure-swap : Setup swap file on root device]
2026-07-14 14:11:23.005634 | controller | ok
2026-07-14 14:11:23.021487 | controller | included: /var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-07-14 14:11:23.028430 | 
2026-07-14 14:11:23.028502 | TASK [configure-swap : Calculate required swap]
2026-07-14 14:11:23.180854 | controller | ok
2026-07-14 14:11:23.242903 | 
2026-07-14 14:11:23.243086 | TASK [configure-swap : Get root filesystem]
2026-07-14 14:11:23.506537 | controller | ext4
2026-07-14 14:11:23.785113 | controller | ok: Runtime: 0:00:00.008774
2026-07-14 14:11:23.796054 | 
2026-07-14 14:11:23.796239 | TASK [configure-swap : Save root filesystem]
2026-07-14 14:11:23.842918 | controller | ok
2026-07-14 14:11:23.855287 | 
2026-07-14 14:11:23.855445 | TASK [configure-swap : Debug the root_filesystem variable]
2026-07-14 14:11:23.903172 | controller | ok:
2026-07-14 14:11:23.903392 | controller | {
2026-07-14 14:11:23.903512 | controller |   "root_filesystem": "ext4"
2026-07-14 14:11:23.903892 | controller | }
2026-07-14 14:11:23.912612 | 
2026-07-14 14:11:23.912688 | TASK [configure-swap : Create swap backing file]
2026-07-14 14:11:33.870481 | controller | 8192+0 records in
2026-07-14 14:11:33.870604 | controller | 8192+0 records out
2026-07-14 14:11:33.870618 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.57161 s, 897 MB/s
2026-07-14 14:11:33.974738 | controller | ok: Runtime: 0:00:09.593685
2026-07-14 14:11:33.983074 | 
2026-07-14 14:11:33.983217 | TASK [configure-swap : Ensure swapfile perms]
2026-07-14 14:11:34.348435 | controller | changed
2026-07-14 14:11:34.355071 | 
2026-07-14 14:11:34.355241 | TASK [configure-swap : Make swapfile]
2026-07-14 14:11:41.846677 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-07-14 14:11:41.846837 | controller | no label, UUID=1e800271-2f0d-49ed-a29b-372512068e13
2026-07-14 14:11:41.909491 | controller | ok: Runtime: 0:00:07.241666
2026-07-14 14:11:41.917265 | 
2026-07-14 14:11:41.917414 | TASK [configure-swap : Write swap to fstab]
2026-07-14 14:11:42.368143 | controller | changed
2026-07-14 14:11:42.376127 | 
2026-07-14 14:11:42.376265 | TASK [configure-swap : Add all swap]
2026-07-14 14:11:42.932558 | controller | ok: Runtime: 0:00:00.020212
2026-07-14 14:11:42.940640 | 
2026-07-14 14:11:42.940818 | TASK [configure-swap : Debug the swap_required variable]
2026-07-14 14:11:42.976667 | controller | ok:
2026-07-14 14:11:42.976854 | controller | {
2026-07-14 14:11:42.976882 | controller |   "swap_required": "8192"
2026-07-14 14:11:42.976902 | controller | }
2026-07-14 14:11:42.983771 | 
2026-07-14 14:11:42.983883 | TASK [configure-swap : Set swappiness]
2026-07-14 14:11:43.396804 | controller | changed
2026-07-14 14:11:43.404314 | 
2026-07-14 14:11:43.404462 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-07-14 14:11:43.439307 | controller | ok:
2026-07-14 14:11:43.439516 | controller | {
2026-07-14 14:11:43.439553 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-07-14 14:11:43.439584 | controller | }
2026-07-14 14:11:43.452220 | 
2026-07-14 14:11:43.452317 | TASK [setup-stack-user : Create stack group]
2026-07-14 14:11:43.898410 | controller | changed
2026-07-14 14:11:43.906817 | 
2026-07-14 14:11:43.907733 | TASK [setup-stack-user : Create the stack user home folder]
2026-07-14 14:11:44.178732 | controller | changed
2026-07-14 14:11:44.186788 | 
2026-07-14 14:11:44.186885 | TASK [setup-stack-user : Create stack user]
2026-07-14 14:11:44.753291 | controller | changed
2026-07-14 14:11:44.760720 | 
2026-07-14 14:11:44.760817 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-07-14 14:11:45.030487 | controller | changed
2026-07-14 14:11:45.038114 | 
2026-07-14 14:11:45.056132 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-07-14 14:11:46.322013 | controller | changed
2026-07-14 14:11:46.330694 | 
2026-07-14 14:11:46.330776 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-07-14 14:11:46.650006 | controller | changed
2026-07-14 14:11:46.662278 | 
2026-07-14 14:11:46.662373 | TASK [setup-tempest-user : Create tempest group]
2026-07-14 14:11:46.952910 | controller | changed
2026-07-14 14:11:46.960527 | 
2026-07-14 14:11:46.960593 | TASK [setup-tempest-user : Create tempest user]
2026-07-14 14:11:47.337563 | controller | changed
2026-07-14 14:11:47.345035 | 
2026-07-14 14:11:47.345150 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-07-14 14:11:48.541807 | controller | changed
2026-07-14 14:11:49.864020 | 
2026-07-14 14:11:49.864229 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-07-14 14:11:50.222746 | controller | ok: Not all paths examined, check warnings for details
2026-07-14 14:11:50.234540 | 
2026-07-14 14:11:50.234659 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-07-14 14:11:50.677685 | controller | ok: Item: Runtime: 0:00:00.144811
2026-07-14 14:11:51.040313 | controller | ok: Item: Runtime: 0:00:00.119230
2026-07-14 14:11:51.478386 | controller | ok: Item: Runtime: 0:00:00.219001
2026-07-14 14:11:51.974368 | controller | ok: Item: Runtime: 0:00:00.272147
2026-07-14 14:11:52.698829 | controller | ok: Item: Runtime: 0:00:00.483922
2026-07-14 14:11:53.152033 | controller | ok: Item: Runtime: 0:00:00.197439
2026-07-14 14:11:54.590386 | controller | ok: Item: Runtime: 0:00:01.217731
2026-07-14 14:11:57.624674 | controller | ok: Item: Runtime: 0:00:02.794360
2026-07-14 14:11:58.412909 | controller | ok: Item: Runtime: 0:00:00.529644
2026-07-14 14:11:59.351646 | controller | ok: Item: Runtime: 0:00:00.063048
2026-07-14 14:11:59.362293 | controller | ok: Item: Runtime: 0:00:00.332403
2026-07-14 14:11:59.950135 | controller | ok: Item: Runtime: 0:00:00.503057
2026-07-14 14:12:00.477889 | controller | ok: Item: Runtime: 0:00:00.309513
2026-07-14 14:12:01.633519 | controller | ok: Item: Runtime: 0:00:00.922585
2026-07-14 14:12:02.223580 | controller | ok: Item: Runtime: 0:00:00.342899
2026-07-14 14:12:02.624020 | controller | ok: Item: Runtime: 0:00:00.184031
2026-07-14 14:12:02.647319 | 
2026-07-14 14:12:02.647479 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-07-14 14:12:02.867256 | controller | ok: All paths examined
2026-07-14 14:12:02.909651 | 
2026-07-14 14:12:02.909812 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-07-14 14:12:03.163193 | controller | ok: All paths examined
2026-07-14 14:12:03.170782 | 
2026-07-14 14:12:03.170883 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-07-14 14:12:03.682991 | controller | ok: Item: Runtime: 0:00:00.232174
2026-07-14 14:12:03.746883 | controller | changed: All items complete
2026-07-14 14:12:03.747169 | 
2026-07-14 14:12:04.054226 | controller | ok: Item: Runtime: 0:00:00.152698
2026-07-14 14:12:04.064987 | 
2026-07-14 14:12:04.065147 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-07-14 14:12:04.182911 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.186374 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.190129 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.194062 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.197989 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.201769 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.205640 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.210544 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.214072 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.217700 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.221341 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.225029 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.228769 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.232234 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.235993 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.239507 | controller | skipping: Conditional result was False
2026-07-14 14:12:04.320079 | 
2026-07-14 14:12:04.320237 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-07-14 14:12:06.963176 | controller | changed
2026-07-14 14:12:06.975326 | 
2026-07-14 14:12:06.975411 | TASK [setup-devstack-log-dir : Create logs directory]
2026-07-14 14:12:07.213697 | controller | changed
2026-07-14 14:12:07.224993 | 
2026-07-14 14:12:07.225111 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-07-14 14:12:07.479658 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-07-14 14:12:07.774698 | controller | ERROR
2026-07-14 14:12:07.774910 | controller | {
2026-07-14 14:12:07.774941 | controller |   "delta": "0:00:00.013019",
2026-07-14 14:12:07.774962 | controller |   "end": "2026-07-14 14:12:07.480120",
2026-07-14 14:12:07.774981 | controller |   "msg": "non-zero return code",
2026-07-14 14:12:07.774999 | controller |   "rc": 1,
2026-07-14 14:12:07.775017 | controller |   "start": "2026-07-14 14:12:07.467101"
2026-07-14 14:12:07.775035 | controller | }
2026-07-14 14:12:07.775059 | controller | ERROR: Ignoring Errors
2026-07-14 14:12:07.789671 | 
2026-07-14 14:12:07.790031 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-07-14 14:12:08.032214 | controller | ok
2026-07-14 14:12:08.042684 | 
2026-07-14 14:12:08.042751 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-07-14 14:12:08.294624 | controller | /usr/bin/journalctl
2026-07-14 14:12:08.584853 | controller | ok: Runtime: 0:00:00.005522
2026-07-14 14:12:08.591555 | 
2026-07-14 14:12:08.591643 | TASK [start-fresh-logging : Get current date]
2026-07-14 14:12:08.867591 | controller | 2026-07-14 14:12:08
2026-07-14 14:12:09.132748 | controller | ok: Runtime: 0:00:00.009864
2026-07-14 14:12:09.139442 | 
2026-07-14 14:12:09.139516 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-07-14 14:12:09.731606 | controller | changed
2026-07-14 14:12:09.740118 | 
2026-07-14 14:12:09.740229 | TASK [start-fresh-logging : Stop rsyslog]
2026-07-14 14:12:09.766017 | controller | skipping: Conditional result was False
2026-07-14 14:12:09.779601 | 
2026-07-14 14:12:09.779707 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-07-14 14:12:10.314923 | controller | skipping: Conditional result was False
2026-07-14 14:12:10.325741 | 
2026-07-14 14:12:10.325841 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-07-14 14:12:10.855580 | controller | skipping: Conditional result was False
2026-07-14 14:12:10.866147 | 
2026-07-14 14:12:10.866325 | TASK [start-fresh-logging : Recreate syslog file]
2026-07-14 14:12:10.892338 | controller | skipping: Conditional result was False
2026-07-14 14:12:10.902882 | 
2026-07-14 14:12:10.903046 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-07-14 14:12:11.435321 | controller | skipping: Conditional result was False
2026-07-14 14:12:11.442210 | 
2026-07-14 14:12:11.442280 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-07-14 14:12:12.017592 | controller | skipping: Conditional result was False
2026-07-14 14:12:12.028926 | 
2026-07-14 14:12:12.029069 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-07-14 14:12:12.054916 | controller | skipping: Conditional result was False
2026-07-14 14:12:12.066480 | 
2026-07-14 14:12:12.066571 | TASK [start-fresh-logging : Recreate kern.log file]
2026-07-14 14:12:12.092478 | controller | skipping: Conditional result was False
2026-07-14 14:12:12.103160 | 
2026-07-14 14:12:12.103255 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-07-14 14:12:12.634593 | controller | skipping: Conditional result was False
2026-07-14 14:12:12.647719 | 
2026-07-14 14:12:12.647841 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-07-14 14:12:13.182440 | controller | skipping: Conditional result was False
2026-07-14 14:12:13.192844 | 
2026-07-14 14:12:13.192985 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-07-14 14:12:13.218228 | controller | skipping: Conditional result was False
2026-07-14 14:12:13.232625 | 
2026-07-14 14:12:13.232731 | TASK [start-fresh-logging : Start rsyslog]
2026-07-14 14:12:13.271864 | controller | skipping: Conditional result was False
2026-07-14 14:12:13.297095 | 
2026-07-14 14:12:13.297318 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-07-14 14:12:13.825981 | controller | ok
2026-07-14 14:12:13.839551 | 
2026-07-14 14:12:13.839688 | PLAY RECAP
2026-07-14 14:12:13.839738 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-07-14 14:12:13.839760 | 
2026-07-14 14:12:14.005045 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-07-14 14:12:14.016832 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-07-14 14:12:15.597386 | 
2026-07-14 14:12:15.597684 | PLAY [all]
2026-07-14 14:12:15.612718 | 
2026-07-14 14:12:15.612832 | TASK [Install curl]
2026-07-14 16:10:15.264895 | PRE-RUN END RESULT_TIMED_OUT: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-07-14 16:10:15.658143 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-07-14 16:10:18.452099 | 
2026-07-14 16:10:18.452219 | PLAY [all]
2026-07-14 16:10:18.463161 | 
2026-07-14 16:10:18.463233 | LOOP [Copy individual Hydrophone result files]
2026-07-14 16:10:18.941589 | controller | ERROR: Item: e2e.log
2026-07-14 16:10:18.941911 | controller | {
2026-07-14 16:10:18.941950 | controller |   "ansible_loop_var": "item",
2026-07-14 16:10:18.941979 | controller |   "item": "e2e.log",
2026-07-14 16:10:18.942036 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-07-14 16:10:18.942061 | controller | }
2026-07-14 16:10:19.208602 | controller | ERROR: Item: junit_01.xml
2026-07-14 16:10:19.208779 | controller | {
2026-07-14 16:10:19.208851 | controller |   "ansible_loop_var": "item",
2026-07-14 16:10:19.208899 | controller |   "item": "junit_01.xml",
2026-07-14 16:10:19.208942 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-07-14 16:10:19.209017 | controller | }
2026-07-14 16:10:19.224889 | controller | ERROR: Ignoring Errors
2026-07-14 16:10:19.232189 | 
2026-07-14 16:10:19.232278 | TASK [Copy Hydrophone results tarball to output folder]
2026-07-14 16:10:19.544770 | controller | ERROR
2026-07-14 16:10:19.545233 | controller | {
2026-07-14 16:10:19.545307 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-07-14 16:10:19.545357 | controller | }
2026-07-14 16:10:19.545411 | controller | ERROR: Ignoring Errors
2026-07-14 16:10:19.560305 | 
2026-07-14 16:10:19.560390 | TASK [Return built artifacts to Zuul]
2026-07-14 16:10:20.010469 | controller | ok
2026-07-14 16:10:20.016825 | 
2026-07-14 16:10:20.016905 | TASK [Get the Docker volume ID]
2026-07-14 16:10:20.551811 | controller | ERROR
2026-07-14 16:10:20.552072 | controller | {
2026-07-14 16:10:20.552112 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-07-14 16:10:20.552141 | controller |   "rc": 2
2026-07-14 16:10:20.552167 | controller | }
2026-07-14 16:10:20.552203 | controller | ERROR: Ignoring Errors
2026-07-14 16:10:20.559048 | 
2026-07-14 16:10:20.559134 | TASK [Copy all of the pod logs]
2026-07-14 16:10:20.593985 | controller | ERROR
2026-07-14 16:10:20.594143 | controller | {
2026-07-14 16:10:20.594170 | 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/d003047e3e684b3e9b73ff5fb71a8cbe/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-07-14 16:10:20.594194 | controller | }
2026-07-14 16:10:20.594218 | controller | ERROR: Ignoring Errors
2026-07-14 16:10:20.595167 | 
2026-07-14 16:10:20.595212 | PLAY RECAP
2026-07-14 16:10:20.595252 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-07-14 16:10:20.595273 | 
2026-07-14 16:10:20.706404 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-07-14 16:10:20.829877 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-07-14 16:10:22.534482 | 
2026-07-14 16:10:22.605163 | PLAY [all]
2026-07-14 16:10:22.631288 | 
2026-07-14 16:10:22.631375 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-07-14 16:10:23.021840 | controller | changed
2026-07-14 16:10:23.027777 | 
2026-07-14 16:10:23.027857 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-07-14 16:10:24.063491 | controller | ok: Runtime: 0:00:00.465467
2026-07-14 16:10:24.071546 | 
2026-07-14 16:10:24.071641 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-07-14 16:10:24.611390 | controller | ok: Runtime: 0:00:00.017182
2026-07-14 16:10:24.616977 | 
2026-07-14 16:10:24.617045 | TASK [export-devstack-journal : Export journal]
2026-07-14 16:10:25.197841 | controller | ok: Runtime: 0:00:00.021033
2026-07-14 16:10:25.205685 | 
2026-07-14 16:10:25.205756 | TASK [export-devstack-journal : Save journal README]
2026-07-14 16:10:26.107439 | controller | changed
2026-07-14 16:10:26.120836 | 
2026-07-14 16:10:26.120908 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-07-14 16:10:26.363387 | controller | changed
2026-07-14 16:10:26.369093 | 
2026-07-14 16:10:26.369162 | TASK [apache-logs-conf : Find logs]
2026-07-14 16:10:26.684116 | controller | Output suppressed because no_log was given
2026-07-14 16:10:26.689367 | 
2026-07-14 16:10:26.689441 | LOOP [apache-logs-conf : Dereference files]
2026-07-14 16:10:26.720048 | 
2026-07-14 16:10:26.720185 | LOOP [apache-logs-conf : Create hard links]
2026-07-14 16:10:26.742280 | 
2026-07-14 16:10:26.742450 | TASK [apache-logs-conf : Find logs]
2026-07-14 16:10:26.772563 | 
2026-07-14 16:10:26.772693 | LOOP [apache-logs-conf : Dereference files]
2026-07-14 16:10:26.802917 | 
2026-07-14 16:10:26.803048 | LOOP [apache-logs-conf : Create hard links]
2026-07-14 16:10:26.837167 | 
2026-07-14 16:10:26.837310 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-07-14 16:10:27.069210 | controller | changed
2026-07-14 16:10:27.075173 | 
2026-07-14 16:10:27.075238 | TASK [apache-logs-conf : Define config paths]
2026-07-14 16:10:27.111320 | controller | ok
2026-07-14 16:10:27.119115 | 
2026-07-14 16:10:27.119204 | TASK [apache-logs-conf : Discover configurations]
2026-07-14 16:10:27.356640 | controller | Output suppressed because no_log was given
2026-07-14 16:10:27.361623 | 
2026-07-14 16:10:27.361688 | LOOP [apache-logs-conf : Dereference configurations]
2026-07-14 16:10:27.395799 | 
2026-07-14 16:10:27.395928 | LOOP [apache-logs-conf : Link configurations]
2026-07-14 16:10:27.434857 | 
2026-07-14 16:10:27.435037 | TASK [capture-performance-data : Generate statistics]
2026-07-14 16:10:28.075020 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-07-14 16:10:28.094032 | controller | Using python 3.12 to install setuptools
2026-07-14 16:10:28.132765 | controller | /usr/bin/python3.12: No module named pip
2026-07-14 16:10:28.144114 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-07-14 16:10:28.474901 | controller | ERROR
2026-07-14 16:10:28.475139 | controller | {
2026-07-14 16:10:28.475174 | controller |   "delta": "0:00:00.457907",
2026-07-14 16:10:28.475195 | controller |   "end": "2026-07-14 16:10:28.146223",
2026-07-14 16:10:28.475215 | controller |   "msg": "non-zero return code",
2026-07-14 16:10:28.475238 | controller |   "rc": 127,
2026-07-14 16:10:28.475257 | controller |   "start": "2026-07-14 16:10:27.688316"
2026-07-14 16:10:28.475276 | controller | }
2026-07-14 16:10:28.475301 | controller | ERROR: Ignoring Errors
2026-07-14 16:10:28.488595 | 
2026-07-14 16:10:28.488688 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-07-14 16:10:28.724478 | controller | changed
2026-07-14 16:10:28.731990 | 
2026-07-14 16:10:28.732058 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-07-14 16:10:32.373960 | controller | Output suppressed because no_log was given
2026-07-14 16:10:32.387306 | 
2026-07-14 16:10:32.387367 | LOOP [devstack-project-conf : Copy configuration files]
2026-07-14 16:10:32.457399 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.457703 | 
2026-07-14 16:10:32.459999 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.463384 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.466483 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.469457 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.473173 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.474366 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.477511 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.480059 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.482968 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.485627 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.488494 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.491200 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.494407 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.497471 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.919820 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.922142 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.923365 | controller | skipping: Conditional result was False
2026-07-14 16:10:32.994872 | 
2026-07-14 16:10:32.995008 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-07-14 16:10:33.230641 | controller | ok
2026-07-14 16:10:33.237932 | 
2026-07-14 16:10:33.238021 | TASK [devstack-project-conf : Copy configuration files]
2026-07-14 16:10:33.767430 | controller | skipping: Conditional result was False
2026-07-14 16:10:33.782641 | 
2026-07-14 16:10:33.782721 | TASK [capture-system-logs : Stage various logs and reports]
2026-07-14 16:10:34.107629 | controller | /usr/bin/python3: No module named pip
2026-07-14 16:10:35.222330 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-07-14 16:10:35.646493 | controller | ok: Runtime: 0:00:01.209584
2026-07-14 16:10:35.657621 | 
2026-07-14 16:10:35.657734 | LOOP [stage-output : Register sources]
2026-07-14 16:10:44.829758 | controller | Output suppressed because no_log was given
2026-07-14 16:10:44.839502 | 
2026-07-14 16:10:44.858720 | TASK [stage-output : Check sudo]
2026-07-14 16:10:45.395633 | controller | ok: Runtime: 0:00:00.019062
2026-07-14 16:10:45.402418 | 
2026-07-14 16:10:45.402489 | LOOP [stage-output : Set source and destination for files and folders]
2026-07-14 16:10:45.489769 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.490147 | 
2026-07-14 16:10:45.491935 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.493819 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.497032 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.537936 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.538412 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.539437 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.542138 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.544695 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.547171 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.549710 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.552173 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.555088 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.557590 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.577768 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.578115 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.578438 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.578754 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.579790 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.582420 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.585239 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.587941 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.590667 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.617457 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.617926 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.702269 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.714996 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.727233 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.737084 | controller | Output suppressed because no_log was given
2026-07-14 16:10:45.747385 | 
2026-07-14 16:10:45.747446 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-07-14 16:10:45.812319 | controller | ok
2026-07-14 16:10:45.821041 | 
2026-07-14 16:10:45.821109 | LOOP [stage-output : Ensure target folders exist]
2026-07-14 16:10:46.063126 | controller | changed: "docs"
2026-07-14 16:10:46.263329 | controller | changed: "artifacts"
2026-07-14 16:10:46.481962 | controller | ok: "logs"
2026-07-14 16:10:46.498910 | 
2026-07-14 16:10:46.499044 | LOOP [stage-output : Copy files and folders to staging folder]
2026-07-14 16:10:46.774151 | controller | ok: Item: Runtime: 0:00:00.005802
2026-07-14 16:10:46.774430 | controller | changed: All items complete
2026-07-14 16:10:46.774490 | 
2026-07-14 16:10:46.986531 | controller | ok: Item: Runtime: 0:00:00.004132
2026-07-14 16:10:47.196305 | controller | ok: Item: Runtime: 0:00:00.007045
2026-07-14 16:10:47.418469 | controller | ok: Item: Runtime: 0:00:00.007358
2026-07-14 16:10:47.651183 | controller | ok: Item: Runtime: 0:00:00.005567
2026-07-14 16:10:47.870676 | controller | ok: Item: Runtime: 0:00:00.007026
2026-07-14 16:10:48.080876 | controller | ok: Item: Runtime: 0:00:00.006914
2026-07-14 16:10:48.312372 | controller | ok: Item: Runtime: 0:00:00.006840
2026-07-14 16:10:48.569194 | controller | ok: Item: Runtime: 0:00:00.007543
2026-07-14 16:10:48.741498 | controller | ok: Item: Runtime: 0:00:00.007222
2026-07-14 16:10:48.962477 | controller | ok: Item: Runtime: 0:00:00.007618
2026-07-14 16:10:49.179988 | controller | ok: Item: Runtime: 0:00:00.007203
2026-07-14 16:10:49.403008 | controller | ok: Item: Runtime: 0:00:00.006718
2026-07-14 16:10:49.633693 | controller | ok: Item: Runtime: 0:00:00.007821
2026-07-14 16:10:49.845695 | controller | ok: Item: Runtime: 0:00:00.005311
2026-07-14 16:10:50.075465 | controller | ok: Item: Runtime: 0:00:00.007946
2026-07-14 16:10:50.473962 | 
2026-07-14 16:10:50.474133 | TASK [stage-output : Make all log files readable]
2026-07-14 16:10:50.716883 | controller | changed
2026-07-14 16:10:50.722793 | 
2026-07-14 16:10:50.722864 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-07-14 16:10:51.071735 | controller | changed: Renamed files for staging.
2026-07-14 16:10:51.078013 | 
2026-07-14 16:10:51.078100 | TASK [stage-output : Discover log files for compression]
2026-07-14 16:10:51.103727 | controller | skipping: Conditional result was False
2026-07-14 16:10:51.115037 | 
2026-07-14 16:10:51.115106 | LOOP [stage-output : Archive everything from logs]
2026-07-14 16:10:51.158062 | 
2026-07-14 16:10:51.158232 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-07-14 16:10:51.783983 | controller | changed:
2026-07-14 16:10:51.784207 | controller | created directory /var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/work/logs/controller
2026-07-14 16:10:51.784246 | controller | cd+++++++++ logs/
2026-07-14 16:10:51.784276 | controller | >f+++++++++ logs/deprecations_log.txt
2026-07-14 16:10:51.784303 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-07-14 16:10:51.784330 | controller | >f+++++++++ logs/devstack.journal.gz
2026-07-14 16:10:51.784356 | controller | >f+++++++++ logs/df.txt
2026-07-14 16:10:51.784382 | controller | >f+++++++++ logs/dpkg-l.txt
2026-07-14 16:10:51.784407 | controller | >f+++++++++ logs/iptables.txt
2026-07-14 16:10:51.784432 | controller | >f+++++++++ logs/listen53.txt
2026-07-14 16:10:51.784457 | controller | >f+++++++++ logs/local_conf.txt
2026-07-14 16:10:51.784482 | controller | >f+++++++++ logs/mount.txt
2026-07-14 16:10:51.784524 | controller | >f+++++++++ logs/performance.json
2026-07-14 16:10:51.784550 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-07-14 16:10:51.784574 | controller | >f+++++++++ logs/resolv_conf.txt
2026-07-14 16:10:51.784599 | controller | >f+++++++++ logs/services.txt
2026-07-14 16:10:51.784631 | controller | >f+++++++++ logs/sudoers
2026-07-14 16:10:51.784658 | controller | >f+++++++++ logs/syslog.txt
2026-07-14 16:10:51.784688 | controller | cd+++++++++ logs/apache/
2026-07-14 16:10:51.784714 | controller | cd+++++++++ logs/apache_config/
2026-07-14 16:10:51.784739 | controller | cd+++++++++ logs/etc/
2026-07-14 16:10:51.784764 | controller | cd+++++++++ logs/sudoers.d/
2026-07-14 16:10:51.784829 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-07-14 16:10:51.784857 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-07-14 16:10:51.784883 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-07-14 16:10:51.784911 | controller | >f+++++++++ logs/sudoers.d/README
2026-07-14 16:10:51.784937 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-07-14 16:10:51.799142 | 
2026-07-14 16:10:51.799205 | TASK [Check if a tempest log exits]
2026-07-14 16:10:52.040407 | controller | ok
2026-07-14 16:10:52.045983 | 
2026-07-14 16:10:52.046052 | TASK [Link post-devstack tempest.log]
2026-07-14 16:10:52.072318 | controller | skipping: Conditional result was False
2026-07-14 16:10:52.084144 | 
2026-07-14 16:10:52.084216 | TASK [Capture most recent qemu crash dump, if any]
2026-07-14 16:10:52.332418 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-07-14 16:10:52.935884 | controller | ERROR
2026-07-14 16:10:52.936151 | controller | {
2026-07-14 16:10:52.936192 | controller |   "delta": "0:00:00.008588",
2026-07-14 16:10:52.936220 | controller |   "end": "2026-07-14 16:10:52.333146",
2026-07-14 16:10:52.936247 | controller |   "msg": "non-zero return code",
2026-07-14 16:10:52.936273 | controller |   "rc": 127,
2026-07-14 16:10:52.936299 | controller |   "start": "2026-07-14 16:10:52.324558"
2026-07-14 16:10:52.936323 | controller | }
2026-07-14 16:10:52.936355 | controller | ERROR: Ignoring Errors
2026-07-14 16:10:52.938498 | 
2026-07-14 16:10:52.938566 | PLAY RECAP
2026-07-14 16:10:52.938630 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-07-14 16:10:52.938660 | 
2026-07-14 16:10:53.107930 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-07-14 16:10:53.118404 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-14 16:10:54.046560 | 
2026-07-14 16:10:54.492761 | PLAY [all]
2026-07-14 16:10:54.510537 | 
2026-07-14 16:10:54.510643 | TASK [fetch-output : Set log path for multiple nodes]
2026-07-14 16:10:54.555220 | controller | skipping: Conditional result was False
2026-07-14 16:10:54.569473 | 
2026-07-14 16:10:54.569594 | TASK [fetch-output : Set log path for single node]
2026-07-14 16:10:54.613625 | controller | ok
2026-07-14 16:10:54.620318 | 
2026-07-14 16:10:54.620395 | LOOP [fetch-output : Ensure local output dirs]
2026-07-14 16:10:55.013966 | controller -> localhost | ok: "/var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/work/logs"
2026-07-14 16:10:55.213833 | controller -> localhost | changed: "/var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/work/artifacts"
2026-07-14 16:10:55.632042 | controller -> localhost | changed: "/var/lib/zuul/builds/d003047e3e684b3e9b73ff5fb71a8cbe/work/docs"
2026-07-14 16:10:55.654203 | 
2026-07-14 16:10:55.654338 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-07-14 16:10:56.349952 | controller | changed: .d..t...... ./
2026-07-14 16:10:56.350168 | controller | changed: All items complete
2026-07-14 16:10:56.350205 | 
2026-07-14 16:10:56.791925 | controller | changed: .d..t...... ./
2026-07-14 16:10:57.228880 | controller | changed: .d..t...... ./
2026-07-14 16:10:57.245822 | 
2026-07-14 16:10:57.245966 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-07-14 16:10:57.635787 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006919
2026-07-14 16:10:57.854171 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006547
2026-07-14 16:10:57.875946 | 
2026-07-14 16:10:57.876067 | PLAY [all]
2026-07-14 16:10:57.882303 | 
2026-07-14 16:10:57.882363 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-07-14 16:10:58.353219 | controller | changed
2026-07-14 16:10:58.359119 | 
2026-07-14 16:10:58.359167 | PLAY RECAP
2026-07-14 16:10:58.359212 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-07-14 16:10:58.359233 | 
2026-07-14 16:10:58.472947 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-07-14 16:10:58.482751 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-07-14 16:10:59.172297 | 
2026-07-14 16:10:59.172429 | PLAY [localhost]
2026-07-14 16:10:59.182235 | 
2026-07-14 16:10:59.182311 | TASK [Generate Zuul manifest]
2026-07-14 16:10:59.201940 | localhost | ok
2026-07-14 16:10:59.260450 | 
2026-07-14 16:10:59.260545 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-07-14 16:10:59.629144 | localhost | changed
2026-07-14 16:10:59.639045 | 
2026-07-14 16:10:59.639118 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-07-14 16:11:00.196248 | localhost | ok
2026-07-14 16:11:00.205670 | 
2026-07-14 16:11:00.205745 | TASK [Upload logs]
2026-07-14 16:11:00.227113 | localhost | ok
2026-07-14 16:11:00.460299 | 
2026-07-14 16:11:00.460438 | TASK [Set zuul-log-path fact]
2026-07-14 16:11:00.477793 | localhost | ok
2026-07-14 16:11:00.536398 | 
2026-07-14 16:11:00.536492 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-07-14 16:11:00.564057 | localhost | ok
2026-07-14 16:11:00.571245 | 
2026-07-14 16:11:00.571311 | TASK [upload-logs : Create log directories]
2026-07-14 16:11:00.916354 | localhost | changed
2026-07-14 16:11:00.922711 | 
2026-07-14 16:11:00.922810 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-07-14 16:11:01.278600 | localhost -> localhost | ok: Runtime: 0:00:00.005262
2026-07-14 16:11:01.285254 | 
2026-07-14 16:11:01.285345 | TASK [upload-logs : Upload logs to log server]
2026-07-14 16:11:01.823543 | localhost | Output suppressed because no_log was given
2026-07-14 16:11:01.829668 | 
2026-07-14 16:11:01.829736 | LOOP [upload-logs : Compress console log and json output]
2026-07-14 16:11:01.874508 | localhost | skipping: Conditional result was False
2026-07-14 16:11:01.881689 | localhost | skipping: Conditional result was False
2026-07-14 16:11:01.895534 | 
2026-07-14 16:11:01.895768 | LOOP [upload-logs : Upload compressed console log and json output]
2026-07-14 16:11:01.936306 | localhost | skipping: Conditional result was False
2026-07-14 16:11:01.936658 | 
2026-07-14 16:11:01.940339 | localhost | skipping: Conditional result was False
2026-07-14 16:11:01.950199 | 
2026-07-14 16:11:01.950396 | LOOP [upload-logs : Upload console log and json output]