2026-02-16 17:27:53.003616 | Job console starting
2026-02-16 17:27:53.012084 | Updating git repos
2026-02-16 17:27:57.512404 | Cloning repos into workspace
2026-02-16 17:28:03.003543 | Restoring repo states
2026-02-16 17:28:04.202863 | Merging changes
2026-02-16 17:28:04.615929 | Checking out repos
2026-02-16 17:28:08.218951 | Preparing playbooks
2026-02-16 17:28:10.727652 | Running Ansible setup
2026-02-16 17:28:14.470592 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:28:15.064501 | 
2026-02-16 17:28:15.213139 | PLAY [localhost]
2026-02-16 17:28:15.226694 | 
2026-02-16 17:28:15.226815 | TASK [Gathering Facts]
2026-02-16 17:28:16.101738 | localhost | ok
2026-02-16 17:28:16.108797 | 
2026-02-16 17:28:16.108874 | TASK [Setup log path fact]
2026-02-16 17:28:16.130091 | localhost | ok
2026-02-16 17:28:16.140780 | 
2026-02-16 17:28:16.140850 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:28:16.168602 | localhost | ok
2026-02-16 17:28:16.174372 | 
2026-02-16 17:28:16.174438 | TASK [emit-job-header : Print job information]
2026-02-16 17:28:16.213750 | # Job Information
2026-02-16 17:28:16.213880 | Ansible Version: 2.16.15
2026-02-16 17:28:16.213910 | Job: magnum-cluster-api-sonobuoy-v1.34.4-cilium
2026-02-16 17:28:16.213932 | Pipeline: check
2026-02-16 17:28:16.213956 | Executor: 3a2793d2bd32
2026-02-16 17:28:16.213981 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:28:16.214010 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:28:16.217055 | 
2026-02-16 17:28:16.217124 | LOOP [emit-job-header : Print node information]
2026-02-16 17:28:16.320769 | localhost | ok:
2026-02-16 17:28:16.321152 | localhost | # Node Information
2026-02-16 17:28:16.321217 | localhost | Inventory Hostname: controller
2026-02-16 17:28:16.321264 | localhost | Hostname: np0000155603
2026-02-16 17:28:16.321306 | localhost | Username: zuul
2026-02-16 17:28:16.321353 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:28:16.321396 | localhost | Provider: yul1
2026-02-16 17:28:16.321438 | localhost | Region: ca-ymq-1
2026-02-16 17:28:16.321479 | localhost | Label: ubuntu-noble-16
2026-02-16 17:28:16.321519 | localhost | Product Name: OpenStack Nova
2026-02-16 17:28:16.321559 | localhost | Interface IP: 199.204.45.109
2026-02-16 17:28:16.334018 | 
2026-02-16 17:28:16.334244 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:28:16.755486 | localhost -> localhost | changed
2026-02-16 17:28:16.763457 | 
2026-02-16 17:28:16.763560 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:28:17.590446 | localhost -> localhost | changed
2026-02-16 17:28:17.600062 | 
2026-02-16 17:28:17.600139 | PLAY [all]
2026-02-16 17:28:17.609378 | 
2026-02-16 17:28:17.609446 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:28:17.847115 | controller -> localhost | ok
2026-02-16 17:28:17.857209 | 
2026-02-16 17:28:17.857303 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:28:17.892411 | controller | ok
2026-02-16 17:28:17.907654 | controller | included: /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:28:17.913762 | 
2026-02-16 17:28:17.913821 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:28:18.636321 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:28:18.636580 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/work/b4a1af18d13f49b1b1bc94538a1fd4b5_id_rsa
2026-02-16 17:28:18.636620 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/work/b4a1af18d13f49b1b1bc94538a1fd4b5_id_rsa.pub
2026-02-16 17:28:18.636650 | controller -> localhost | The key fingerprint is:
2026-02-16 17:28:18.636679 | controller -> localhost | SHA256:lOrBfLJgZBAFJUNT5/Te1T4LUTkDC6X0FVkU2KXncjY zuul-build-sshkey
2026-02-16 17:28:18.636730 | controller -> localhost | The key's randomart image is:
2026-02-16 17:28:18.636759 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:28:18.636793 | controller -> localhost | | .O*+ o   o.o.*B*|
2026-02-16 17:28:18.636823 | controller -> localhost | |   = + . o + *=o |
2026-02-16 17:28:18.636851 | controller -> localhost | |    o . + . = oo.|
2026-02-16 17:28:18.636878 | controller -> localhost | |   o o + . . o o |
2026-02-16 17:28:18.636905 | controller -> localhost | |    o * S . . +E+|
2026-02-16 17:28:18.636932 | controller -> localhost | |   . o =     . *.|
2026-02-16 17:28:18.636979 | controller -> localhost | |      o       .  |
2026-02-16 17:28:18.637011 | controller -> localhost | |                 |
2026-02-16 17:28:18.637043 | controller -> localhost | |                 |
2026-02-16 17:28:18.637071 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:28:18.637137 | controller -> localhost | ok: Runtime: 0:00:00.322841
2026-02-16 17:28:18.643931 | 
2026-02-16 17:28:18.644008 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:28:18.683879 | controller | ok
2026-02-16 17:28:18.698304 | controller | included: /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:28:18.712658 | 
2026-02-16 17:28:18.712840 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:28:18.738658 | controller | skipping: Conditional result was False
2026-02-16 17:28:18.748951 | 
2026-02-16 17:28:18.749093 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:28:19.339101 | controller | changed
2026-02-16 17:28:19.346982 | 
2026-02-16 17:28:19.347043 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:28:19.572838 | controller | ok
2026-02-16 17:28:19.580541 | 
2026-02-16 17:28:19.580606 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:28:20.170530 | controller | changed
2026-02-16 17:28:20.176411 | 
2026-02-16 17:28:20.176477 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:28:20.740493 | controller | changed
2026-02-16 17:28:20.749349 | 
2026-02-16 17:28:20.749434 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:28:20.774797 | controller | skipping: Conditional result was False
2026-02-16 17:28:20.781264 | 
2026-02-16 17:28:20.781342 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:28:21.145875 | controller -> localhost | changed
2026-02-16 17:28:21.162201 | 
2026-02-16 17:28:21.162271 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:28:21.483514 | controller -> localhost | Identity added: /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/work/b4a1af18d13f49b1b1bc94538a1fd4b5_id_rsa (zuul-build-sshkey)
2026-02-16 17:28:21.483734 | controller -> localhost | ok: Runtime: 0:00:00.013510
2026-02-16 17:28:21.530432 | 
2026-02-16 17:28:21.530570 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:28:21.841233 | controller | ok
2026-02-16 17:28:21.846895 | 
2026-02-16 17:28:21.846965 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:28:21.872654 | controller | skipping: Conditional result was False
2026-02-16 17:28:21.889469 | 
2026-02-16 17:28:21.889537 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:28:22.209192 | controller | ok
2026-02-16 17:28:22.215667 | 
2026-02-16 17:28:22.215731 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:28:42.313558 | controller | Output suppressed because no_log was given
2026-02-16 17:28:42.325660 | 
2026-02-16 17:28:42.325734 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:28:42.586443 | controller | ok: "logs"
2026-02-16 17:28:42.586710 | controller | ok: All items complete
2026-02-16 17:28:42.586740 | 
2026-02-16 17:28:42.763196 | controller | ok: "artifacts"
2026-02-16 17:28:42.952290 | controller | ok: "docs"
2026-02-16 17:28:42.975869 | 
2026-02-16 17:28:42.976097 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:28:43.205000 | controller | changed: "logs"
2026-02-16 17:28:43.397494 | controller | changed: "artifacts"
2026-02-16 17:28:43.622917 | controller | changed: "docs"
2026-02-16 17:28:43.643829 | 
2026-02-16 17:28:43.644054 | PLAY RECAP
2026-02-16 17:28:43.644141 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:28:43.644182 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:28:43.644214 | 
2026-02-16 17:28:43.882605 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:28:43.890713 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:28:44.512795 | 
2026-02-16 17:28:44.512984 | PLAY [all]
2026-02-16 17:28:44.533714 | 
2026-02-16 17:28:44.533861 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:28:44.883178 | controller | changed
2026-02-16 17:28:44.890182 | 
2026-02-16 17:28:44.890256 | TASK [Gather minimum local MTU]
2026-02-16 17:28:44.956966 | controller | ok
2026-02-16 17:28:44.962089 | 
2026-02-16 17:28:44.962152 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:28:45.017402 | controller | ok
2026-02-16 17:28:45.024815 | 
2026-02-16 17:28:45.024911 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:28:45.051715 | controller | skipping: Conditional result was False
2026-02-16 17:28:45.064166 | 
2026-02-16 17:28:45.064283 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:28:45.607530 | controller | ok: Runtime: 0:00:00.009634
2026-02-16 17:28:45.615804 | 
2026-02-16 17:28:45.615901 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:28:45.652239 | controller | skipping: Conditional result was False
2026-02-16 17:28:45.662638 | 
2026-02-16 17:28:45.662740 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:28:45.688329 | controller | skipping: Conditional result was False
2026-02-16 17:28:45.700048 | 
2026-02-16 17:28:45.700155 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:28:45.757644 | controller | ok
2026-02-16 17:28:45.770199 | controller | included: /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:28:45.776385 | 
2026-02-16 17:28:45.776446 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:28:45.831313 | controller | ok
2026-02-16 17:28:45.839066 | 
2026-02-16 17:28:45.839157 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:28:46.110569 | controller | ext4
2026-02-16 17:28:46.421679 | controller | ok: Runtime: 0:00:00.012053
2026-02-16 17:28:46.428192 | 
2026-02-16 17:28:46.428295 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:28:46.458667 | controller | ok
2026-02-16 17:28:46.464400 | 
2026-02-16 17:28:46.464469 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:28:46.508894 | controller | ok:
2026-02-16 17:28:46.509083 | controller | {
2026-02-16 17:28:46.509109 | controller |   "root_filesystem": "ext4"
2026-02-16 17:28:46.509128 | controller | }
2026-02-16 17:28:46.515348 | 
2026-02-16 17:28:46.515409 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:28:56.849351 | controller | 8192+0 records in
2026-02-16 17:28:56.849550 | controller | 8192+0 records out
2026-02-16 17:28:56.849571 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.0485 s, 855 MB/s
2026-02-16 17:28:57.093280 | controller | ok: Runtime: 0:00:10.058193
2026-02-16 17:28:57.101974 | 
2026-02-16 17:28:57.110454 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:28:57.370079 | controller | changed
2026-02-16 17:28:57.379994 | 
2026-02-16 17:28:57.380785 | TASK [configure-swap : Make swapfile]
2026-02-16 17:29:09.521940 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:29:09.522132 | controller | no label, UUID=38b82713-ef22-4cce-bd57-0707c00ec08c
2026-02-16 17:29:09.994043 | controller | ok: Runtime: 0:00:11.884127
2026-02-16 17:29:09.999606 | 
2026-02-16 17:29:09.999670 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:29:10.550175 | controller | changed
2026-02-16 17:29:10.556913 | 
2026-02-16 17:29:10.557000 | TASK [configure-swap : Add all swap]
2026-02-16 17:29:11.098876 | controller | ok: Runtime: 0:00:00.020230
2026-02-16 17:29:11.371861 | 
2026-02-16 17:29:11.388628 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:29:11.421866 | controller | ok:
2026-02-16 17:29:11.422020 | controller | {
2026-02-16 17:29:11.422054 | controller |   "swap_required": "8192"
2026-02-16 17:29:11.422081 | controller | }
2026-02-16 17:29:11.428002 | 
2026-02-16 17:29:11.428096 | TASK [configure-swap : Set swappiness]
2026-02-16 17:29:11.848613 | controller | changed
2026-02-16 17:29:11.855513 | 
2026-02-16 17:29:11.855599 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:29:11.894134 | controller | ok:
2026-02-16 17:29:11.894321 | controller | {
2026-02-16 17:29:11.894346 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:29:11.894373 | controller | }
2026-02-16 17:29:11.904521 | 
2026-02-16 17:29:11.904599 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:29:12.328318 | controller | changed
2026-02-16 17:29:12.335833 | 
2026-02-16 17:29:12.335895 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:29:12.575319 | controller | changed
2026-02-16 17:29:12.582724 | 
2026-02-16 17:29:12.582796 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:29:13.114092 | controller | changed
2026-02-16 17:29:13.119726 | 
2026-02-16 17:29:13.119790 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:29:13.379698 | controller | changed
2026-02-16 17:29:13.387135 | 
2026-02-16 17:29:13.387210 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:29:14.519692 | controller | changed
2026-02-16 17:29:14.525309 | 
2026-02-16 17:29:14.525372 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:29:14.783813 | controller | changed
2026-02-16 17:29:15.057065 | 
2026-02-16 17:29:15.057182 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:29:15.354859 | controller | changed
2026-02-16 17:29:15.361304 | 
2026-02-16 17:29:15.361417 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:29:15.731791 | controller | changed
2026-02-16 17:29:15.783169 | 
2026-02-16 17:29:15.783326 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:29:16.557752 | controller | changed
2026-02-16 17:29:16.571506 | 
2026-02-16 17:29:16.571577 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:29:16.900847 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:29:16.912479 | 
2026-02-16 17:29:16.912571 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:29:17.396625 | controller | ok: Item: Runtime: 0:00:00.187347
2026-02-16 17:29:17.991945 | controller | ok: Item: Runtime: 0:00:00.362218
2026-02-16 17:29:18.764656 | controller | ok: Item: Runtime: 0:00:00.544200
2026-02-16 17:29:19.041091 | controller | ok: Item: Runtime: 0:00:00.065081
2026-02-16 17:29:19.535621 | controller | ok: Item: Runtime: 0:00:00.263564
2026-02-16 17:29:21.054859 | controller | ok: Item: Runtime: 0:00:01.300040
2026-02-16 17:29:24.384188 | controller | ok: Item: Runtime: 0:00:03.101222
2026-02-16 17:29:24.787544 | controller | ok: Item: Runtime: 0:00:00.103426
2026-02-16 17:29:25.115611 | controller | ok: Item: Runtime: 0:00:00.187510
2026-02-16 17:29:25.455614 | controller | ok: Item: Runtime: 0:00:00.121826
2026-02-16 17:29:26.224294 | controller | ok: Item: Runtime: 0:00:00.535736
2026-02-16 17:29:27.025704 | controller | ok: Item: Runtime: 0:00:00.572873
2026-02-16 17:29:27.488777 | controller | ok: Item: Runtime: 0:00:00.241447
2026-02-16 17:29:27.925321 | controller | ok: Item: Runtime: 0:00:00.219954
2026-02-16 17:29:28.528348 | controller | ok: Item: Runtime: 0:00:00.383757
2026-02-16 17:29:29.913226 | controller | ok: Item: Runtime: 0:00:01.161312
2026-02-16 17:29:29.934603 | 
2026-02-16 17:29:29.934768 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:29:30.159478 | controller | ok: All paths examined
2026-02-16 17:29:30.168113 | 
2026-02-16 17:29:30.168197 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:29:30.418196 | controller | ok: All paths examined
2026-02-16 17:29:30.429884 | 
2026-02-16 17:29:30.429976 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:29:30.868183 | controller | ok: Item: Runtime: 0:00:00.153601
2026-02-16 17:29:30.868440 | controller | changed: All items complete
2026-02-16 17:29:30.868470 | 
2026-02-16 17:29:31.272153 | controller | ok: Item: Runtime: 0:00:00.183446
2026-02-16 17:29:31.287140 | 
2026-02-16 17:29:31.287214 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:29:31.326901 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.329011 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.332112 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.335206 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.338328 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.341459 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.344442 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.347621 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.350818 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.353765 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.356713 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.359660 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.362708 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.365977 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.368886 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.371756 | controller | skipping: Conditional result was False
2026-02-16 17:29:31.392734 | 
2026-02-16 17:29:31.392804 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:29:33.760263 | controller | changed
2026-02-16 17:29:33.774639 | 
2026-02-16 17:29:33.774749 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:29:34.042573 | controller | changed
2026-02-16 17:29:34.091738 | 
2026-02-16 17:29:34.091880 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:29:34.343451 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:29:34.632434 | controller | ERROR
2026-02-16 17:29:34.632640 | controller | {
2026-02-16 17:29:34.632671 | controller |   "delta": "0:00:00.010015",
2026-02-16 17:29:34.632693 | controller |   "end": "2026-02-16 17:29:34.343844",
2026-02-16 17:29:34.632714 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:34.632733 | controller |   "rc": 1,
2026-02-16 17:29:34.632751 | controller |   "start": "2026-02-16 17:29:34.333829"
2026-02-16 17:29:34.632768 | controller | }
2026-02-16 17:29:34.632790 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:34.638783 | 
2026-02-16 17:29:34.638890 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:29:34.895690 | controller | ok
2026-02-16 17:29:34.910084 | 
2026-02-16 17:29:34.910259 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:29:35.149661 | controller | /usr/bin/journalctl
2026-02-16 17:29:35.448696 | controller | ok: Runtime: 0:00:00.007210
2026-02-16 17:29:35.456099 | 
2026-02-16 17:29:35.456162 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:29:35.724213 | controller | 2026-02-16 17:29:35
2026-02-16 17:29:35.995194 | controller | ok: Runtime: 0:00:00.012256
2026-02-16 17:29:36.003016 | 
2026-02-16 17:29:36.003112 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:29:36.576144 | controller | changed
2026-02-16 17:29:36.582146 | 
2026-02-16 17:29:36.582211 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:29:36.608434 | controller | skipping: Conditional result was False
2026-02-16 17:29:36.620580 | 
2026-02-16 17:29:36.620651 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:29:37.153022 | controller | skipping: Conditional result was False
2026-02-16 17:29:37.164605 | 
2026-02-16 17:29:37.164716 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:29:37.699281 | controller | skipping: Conditional result was False
2026-02-16 17:29:37.710659 | 
2026-02-16 17:29:37.710733 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:29:37.736654 | controller | skipping: Conditional result was False
2026-02-16 17:29:37.746981 | 
2026-02-16 17:29:37.747068 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:29:38.279912 | controller | skipping: Conditional result was False
2026-02-16 17:29:38.293440 | 
2026-02-16 17:29:38.293548 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:29:38.826057 | controller | skipping: Conditional result was False
2026-02-16 17:29:38.839244 | 
2026-02-16 17:29:38.839382 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:29:38.865169 | controller | skipping: Conditional result was False
2026-02-16 17:29:38.874296 | 
2026-02-16 17:29:38.874386 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:29:38.899775 | controller | skipping: Conditional result was False
2026-02-16 17:29:38.911616 | 
2026-02-16 17:29:38.911751 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:29:39.492419 | controller | skipping: Conditional result was False
2026-02-16 17:29:39.502241 | 
2026-02-16 17:29:39.502334 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:29:40.035430 | controller | skipping: Conditional result was False
2026-02-16 17:29:40.045155 | 
2026-02-16 17:29:40.045226 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:29:40.070879 | controller | skipping: Conditional result was False
2026-02-16 17:29:40.082113 | 
2026-02-16 17:29:40.082197 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:29:40.107482 | controller | skipping: Conditional result was False
2026-02-16 17:29:40.124660 | 
2026-02-16 17:29:40.124749 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:29:40.523304 | controller | ok
2026-02-16 17:29:40.532913 | 
2026-02-16 17:29:40.532980 | PLAY RECAP
2026-02-16 17:29:40.533032 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:29:40.533056 | 
2026-02-16 17:29:40.728427 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:29:40.736476 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:29:41.339791 | 
2026-02-16 17:29:41.339932 | PLAY [all]
2026-02-16 17:29:41.351745 | 
2026-02-16 17:29:41.351823 | TASK [ensure-rust : Use rustup]
2026-02-16 17:29:41.386344 | controller | ok
2026-02-16 17:29:41.395554 | controller | included: /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:29:41.401069 | 
2026-02-16 17:29:41.401140 | TASK [ensure-rust : Install Rust]
2026-02-16 17:29:41.801360 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:29:41.941483 | controller | ERROR
2026-02-16 17:29:41.941822 | controller | {
2026-02-16 17:29:41.941889 | controller |   "delta": "0:00:00.011837",
2026-02-16 17:29:41.941941 | controller |   "end": "2026-02-16 17:29:41.804773",
2026-02-16 17:29:41.941985 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:41.942026 | controller |   "rc": 127,
2026-02-16 17:29:41.942068 | controller |   "start": "2026-02-16 17:29:41.792936"
2026-02-16 17:29:41.942118 | controller | }
failure
2026-02-16 17:29:41.944514 | 
2026-02-16 17:29:41.944610 | PLAY RECAP
2026-02-16 17:29:41.944722 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:29:41.944792 | 
2026-02-16 17:29:42.074340 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:29:42.085147 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:29:42.674757 | 
2026-02-16 17:29:42.675615 | PLAY [all]
2026-02-16 17:29:42.692510 | 
2026-02-16 17:29:42.692598 | TASK [Return built artifacts to Zuul]
2026-02-16 17:29:42.729302 | controller | ok
2026-02-16 17:29:42.735673 | 
2026-02-16 17:29:42.735746 | TASK [Get the Docker volume ID]
2026-02-16 17:29:43.276146 | controller | ERROR
2026-02-16 17:29:43.276466 | controller | {
2026-02-16 17:29:43.276498 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:29:43.276521 | controller |   "rc": 2
2026-02-16 17:29:43.276541 | controller | }
2026-02-16 17:29:43.276568 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:43.283571 | 
2026-02-16 17:29:43.283675 | TASK [Copy all of the pod logs]
2026-02-16 17:29:43.321240 | controller | ERROR
2026-02-16 17:29:43.321614 | controller | {
2026-02-16 17:29:43.321680 | 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/b4a1af18d13f49b1b1bc94538a1fd4b5/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml': line 21, 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-02-16 17:29:43.321730 | controller | }
2026-02-16 17:29:43.321793 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:43.323529 | 
2026-02-16 17:29:43.323585 | PLAY RECAP
2026-02-16 17:29:43.323636 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:29:43.323658 | 
2026-02-16 17:29:43.473855 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:29:43.480829 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:29:44.069711 | 
2026-02-16 17:29:44.069843 | PLAY [all]
2026-02-16 17:29:44.087650 | 
2026-02-16 17:29:44.087732 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:29:44.488077 | controller | changed
2026-02-16 17:29:44.498228 | 
2026-02-16 17:29:44.498345 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:29:45.538562 | controller | ok: Runtime: 0:00:00.477686
2026-02-16 17:29:45.546706 | 
2026-02-16 17:29:45.546777 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:29:46.087030 | controller | ok: Runtime: 0:00:00.021295
2026-02-16 17:29:46.096659 | 
2026-02-16 17:29:46.096735 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:29:46.643288 | controller | ok: Runtime: 0:00:00.022737
2026-02-16 17:29:46.649653 | 
2026-02-16 17:29:46.649720 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:29:47.527506 | controller | changed
2026-02-16 17:29:47.579659 | 
2026-02-16 17:29:47.579821 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:29:47.850062 | controller | changed
2026-02-16 17:29:47.858912 | 
2026-02-16 17:29:47.859030 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:29:48.207940 | controller | Output suppressed because no_log was given
2026-02-16 17:29:48.213784 | 
2026-02-16 17:29:48.213853 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:29:48.250445 | 
2026-02-16 17:29:48.250620 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:29:48.290352 | 
2026-02-16 17:29:48.290600 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:29:48.315864 | 
2026-02-16 17:29:48.316029 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:29:48.352427 | 
2026-02-16 17:29:48.352562 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:29:48.390385 | 
2026-02-16 17:29:48.390531 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:29:48.653056 | controller | changed
2026-02-16 17:29:48.659335 | 
2026-02-16 17:29:48.659404 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:29:48.681786 | controller | ok
2026-02-16 17:29:48.688660 | 
2026-02-16 17:29:48.688748 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:29:48.927874 | controller | Output suppressed because no_log was given
2026-02-16 17:29:48.933614 | 
2026-02-16 17:29:48.933680 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:29:48.970843 | 
2026-02-16 17:29:48.971008 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:29:49.013462 | 
2026-02-16 17:29:49.013632 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:29:49.675235 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:29:49.694694 | controller | Using python 3.12 to install setuptools
2026-02-16 17:29:49.733243 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:29:49.746648 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:29:50.054518 | controller | ERROR
2026-02-16 17:29:50.054851 | controller | {
2026-02-16 17:29:50.054899 | controller |   "delta": "0:00:00.480091",
2026-02-16 17:29:50.054928 | controller |   "end": "2026-02-16 17:29:49.749085",
2026-02-16 17:29:50.054953 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:50.054986 | controller |   "rc": 127,
2026-02-16 17:29:50.055011 | controller |   "start": "2026-02-16 17:29:49.268994"
2026-02-16 17:29:50.055035 | controller | }
2026-02-16 17:29:50.055070 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:50.068653 | 
2026-02-16 17:29:50.068727 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:29:50.315950 | controller | changed
2026-02-16 17:29:50.321498 | 
2026-02-16 17:29:50.321562 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:29:54.163098 | controller | Output suppressed because no_log was given
2026-02-16 17:29:54.175628 | 
2026-02-16 17:29:54.175719 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:29:54.209027 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.209462 | 
2026-02-16 17:29:54.210790 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.212910 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.215199 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.217676 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.219981 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.222409 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.224756 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.226969 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.229308 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.231650 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.234035 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.236483 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.238707 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.240923 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.243220 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.245488 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.248554 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.274580 | 
2026-02-16 17:29:54.274704 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:29:54.504498 | controller | ok
2026-02-16 17:29:54.510230 | 
2026-02-16 17:29:54.510299 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:29:55.085647 | controller | skipping: Conditional result was False
2026-02-16 17:29:55.103977 | 
2026-02-16 17:29:55.104115 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:29:55.365292 | controller | sudo: iptables-save: command not found
2026-02-16 17:29:55.410118 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:29:56.868504 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:29:57.145875 | controller | ok: Runtime: 0:00:01.522360
2026-02-16 17:29:57.161440 | 
2026-02-16 17:29:57.164600 | LOOP [stage-output : Register sources]
2026-02-16 17:30:06.803149 | controller | Output suppressed because no_log was given
2026-02-16 17:30:06.859837 | 
2026-02-16 17:30:06.859999 | TASK [stage-output : Check sudo]
2026-02-16 17:30:07.399604 | controller | ok: Runtime: 0:00:00.019966
2026-02-16 17:30:07.407694 | 
2026-02-16 17:30:07.407761 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:30:07.506341 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.506823 | 
2026-02-16 17:30:07.508678 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.509504 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.511881 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.548095 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.548577 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.549092 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.550027 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.552355 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.554607 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.556862 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.559304 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.561653 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.563814 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.584109 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.584593 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.585091 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.585559 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.586020 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.586661 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.588702 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.591103 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.593230 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.616970 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.617310 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.691747 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.703309 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.714409 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.722238 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.737099 | 
2026-02-16 17:30:07.737197 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:30:07.804738 | controller | ok
2026-02-16 17:30:07.811980 | 
2026-02-16 17:30:07.812050 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:30:08.059014 | controller | changed: "docs"
2026-02-16 17:30:08.272607 | controller | changed: "artifacts"
2026-02-16 17:30:08.497213 | controller | ok: "logs"
2026-02-16 17:30:08.518563 | 
2026-02-16 17:30:08.518786 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:30:08.808452 | controller | ok: Item: Runtime: 0:00:00.010646
2026-02-16 17:30:08.808859 | controller | changed: All items complete
2026-02-16 17:30:08.808897 | 
2026-02-16 17:30:09.040411 | controller | ok: Item: Runtime: 0:00:00.011160
2026-02-16 17:30:09.272578 | controller | ok: Item: Runtime: 0:00:00.009413
2026-02-16 17:30:09.505680 | controller | ok: Item: Runtime: 0:00:00.005372
2026-02-16 17:30:09.743133 | controller | ok: Item: Runtime: 0:00:00.007095
2026-02-16 17:30:09.977634 | controller | ok: Item: Runtime: 0:00:00.008513
2026-02-16 17:30:10.218261 | controller | ok: Item: Runtime: 0:00:00.008558
2026-02-16 17:30:10.461096 | controller | ok: Item: Runtime: 0:00:00.009851
2026-02-16 17:30:10.698395 | controller | ok: Item: Runtime: 0:00:00.007342
2026-02-16 17:30:10.950904 | controller | ok: Item: Runtime: 0:00:00.009537
2026-02-16 17:30:11.196375 | controller | ok: Item: Runtime: 0:00:00.009029
2026-02-16 17:30:11.474244 | controller | ok: Item: Runtime: 0:00:00.008907
2026-02-16 17:30:11.652216 | controller | ok: Item: Runtime: 0:00:00.008381
2026-02-16 17:30:11.889215 | controller | ok: Item: Runtime: 0:00:00.008370
2026-02-16 17:30:12.117945 | controller | ok: Item: Runtime: 0:00:00.007281
2026-02-16 17:30:12.347155 | controller | ok: Item: Runtime: 0:00:00.010197
2026-02-16 17:30:12.364223 | 
2026-02-16 17:30:12.364387 | TASK [stage-output : Make all log files readable]
2026-02-16 17:30:12.619479 | controller | changed
2026-02-16 17:30:12.625481 | 
2026-02-16 17:30:12.625545 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:30:12.955369 | controller | changed: Renamed files for staging.
2026-02-16 17:30:12.960598 | 
2026-02-16 17:30:12.960658 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:30:12.985181 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.997240 | 
2026-02-16 17:30:12.997332 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:30:13.038268 | 
2026-02-16 17:30:13.038385 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:30:13.667491 | controller | changed:
2026-02-16 17:30:13.667693 | controller | created directory /var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/work/logs/controller
2026-02-16 17:30:13.667721 | controller | cd+++++++++ logs/
2026-02-16 17:30:13.667742 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:30:13.667761 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:30:13.667780 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:30:13.667798 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:30:13.667816 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:30:13.667834 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:30:13.667852 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:30:13.667869 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:30:13.667887 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:30:13.667904 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:30:13.667922 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:30:13.667940 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:30:13.667963 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:30:13.667993 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:30:13.668011 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:30:13.668029 | controller | cd+++++++++ logs/apache/
2026-02-16 17:30:13.668046 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:30:13.668064 | controller | cd+++++++++ logs/etc/
2026-02-16 17:30:13.668081 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:30:13.668099 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:30:13.668116 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:30:13.668134 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:30:13.668151 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:30:13.668168 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:30:13.680176 | 
2026-02-16 17:30:13.680243 | TASK [Check if a tempest log exits]
2026-02-16 17:30:13.928301 | controller | ok
2026-02-16 17:30:13.933638 | 
2026-02-16 17:30:13.933725 | TASK [Link post-devstack tempest.log]
2026-02-16 17:30:13.950657 | controller | skipping: Conditional result was False
2026-02-16 17:30:13.960814 | 
2026-02-16 17:30:13.960877 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:30:14.210527 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:30:14.506336 | controller | ERROR
2026-02-16 17:30:14.506664 | controller | {
2026-02-16 17:30:14.506733 | controller |   "delta": "0:00:00.009218",
2026-02-16 17:30:14.506778 | controller |   "end": "2026-02-16 17:30:14.211180",
2026-02-16 17:30:14.506819 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:14.506857 | controller |   "rc": 127,
2026-02-16 17:30:14.506895 | controller |   "start": "2026-02-16 17:30:14.201962"
2026-02-16 17:30:14.506933 | controller | }
2026-02-16 17:30:14.506983 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:14.508686 | 
2026-02-16 17:30:14.508735 | PLAY RECAP
2026-02-16 17:30:14.508784 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:30:14.508805 | 
2026-02-16 17:30:14.699092 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:30:14.710400 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:30:15.303209 | 
2026-02-16 17:30:15.303346 | PLAY [all]
2026-02-16 17:30:15.315289 | 
2026-02-16 17:30:15.315369 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:30:15.360386 | controller | skipping: Conditional result was False
2026-02-16 17:30:15.370620 | 
2026-02-16 17:30:15.370798 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:30:15.415548 | controller | ok
2026-02-16 17:30:15.422533 | 
2026-02-16 17:30:15.422602 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:30:15.817039 | controller -> localhost | ok: "/var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/work/logs"
2026-02-16 17:30:16.042295 | controller -> localhost | changed: "/var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/work/artifacts"
2026-02-16 17:30:16.254139 | controller -> localhost | changed: "/var/lib/zuul/builds/b4a1af18d13f49b1b1bc94538a1fd4b5/work/docs"
2026-02-16 17:30:16.271160 | 
2026-02-16 17:30:16.271338 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:30:16.913999 | controller | changed: .d..t...... ./
2026-02-16 17:30:16.914358 | controller | changed: All items complete
2026-02-16 17:30:16.914394 | 
2026-02-16 17:30:17.385849 | controller | changed: .d..t...... ./
2026-02-16 17:30:17.854069 | controller | changed: .d..t...... ./
2026-02-16 17:30:17.878240 | 
2026-02-16 17:30:17.878321 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:30:18.296389 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008141
2026-02-16 17:30:18.523844 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007839
2026-02-16 17:30:18.539687 | 
2026-02-16 17:30:18.539832 | PLAY [all]
2026-02-16 17:30:18.547935 | 
2026-02-16 17:30:18.548005 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:30:19.041159 | controller | changed
2026-02-16 17:30:19.049836 | 
2026-02-16 17:30:19.049894 | PLAY RECAP
2026-02-16 17:30:19.049940 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:30:19.049962 | 
2026-02-16 17:30:19.188837 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:30:19.197654 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:30:19.762447 | 
2026-02-16 17:30:19.762587 | PLAY [localhost]
2026-02-16 17:30:19.773325 | 
2026-02-16 17:30:19.773398 | TASK [Generate Zuul manifest]
2026-02-16 17:30:19.803654 | localhost | ok
2026-02-16 17:30:19.821586 | 
2026-02-16 17:30:19.821666 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:30:20.199298 | localhost | changed
2026-02-16 17:30:20.214264 | 
2026-02-16 17:30:20.214348 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:30:20.246653 | localhost | ok
2026-02-16 17:30:20.254511 | 
2026-02-16 17:30:20.254574 | TASK [Upload logs]
2026-02-16 17:30:20.276072 | localhost | ok
2026-02-16 17:30:20.343999 | 
2026-02-16 17:30:20.344162 | TASK [Set zuul-log-path fact]
2026-02-16 17:30:20.366446 | localhost | ok
2026-02-16 17:30:20.380048 | 
2026-02-16 17:30:20.380112 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:30:20.410365 | localhost | ok
2026-02-16 17:30:20.417750 | 
2026-02-16 17:30:20.417817 | TASK [upload-logs : Create log directories]
2026-02-16 17:30:20.817338 | localhost | changed
2026-02-16 17:30:20.823469 | 
2026-02-16 17:30:20.823560 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:30:21.271628 | localhost -> localhost | ok: Runtime: 0:00:00.006137
2026-02-16 17:30:21.276790 | 
2026-02-16 17:30:21.276855 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:30:21.715164 | localhost | Output suppressed because no_log was given
2026-02-16 17:30:21.720695 | 
2026-02-16 17:30:21.720780 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:30:21.772577 | localhost | skipping: Conditional result was False
2026-02-16 17:30:21.779150 | localhost | skipping: Conditional result was False
2026-02-16 17:30:21.796291 | 
2026-02-16 17:30:21.796456 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:30:21.840890 | localhost | skipping: Conditional result was False
2026-02-16 17:30:21.841306 | 
2026-02-16 17:30:21.844109 | localhost | skipping: Conditional result was False
2026-02-16 17:30:21.861510 | 
2026-02-16 17:30:21.861712 | LOOP [upload-logs : Upload console log and json output]