2026-02-16 17:28:59.414521 | Job console starting
2026-02-16 17:28:59.506082 | Updating git repos
2026-02-16 17:29:02.455621 | Cloning repos into workspace
2026-02-16 17:29:09.393518 | Restoring repo states
2026-02-16 17:29:13.146247 | Merging changes
2026-02-16 17:29:13.947039 | Checking out repos
2026-02-16 17:29:24.662272 | Preparing playbooks
2026-02-16 17:29:30.234493 | Running Ansible setup
2026-02-16 17:29:33.900662 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:29:34.494428 | 
2026-02-16 17:29:34.494560 | PLAY [localhost]
2026-02-16 17:29:34.501935 | 
2026-02-16 17:29:34.502005 | TASK [Gathering Facts]
2026-02-16 17:29:35.380658 | localhost | ok
2026-02-16 17:29:35.391201 | 
2026-02-16 17:29:35.391307 | TASK [Setup log path fact]
2026-02-16 17:29:35.411641 | localhost | ok
2026-02-16 17:29:35.431447 | 
2026-02-16 17:29:35.431750 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:29:35.464664 | localhost | ok
2026-02-16 17:29:35.472897 | 
2026-02-16 17:29:35.472991 | TASK [emit-job-header : Print job information]
2026-02-16 17:29:35.518325 | # Job Information
2026-02-16 17:29:35.518590 | Ansible Version: 2.16.15
2026-02-16 17:29:35.518662 | Job: magnum-cluster-api-sonobuoy-v1.33.8-cilium
2026-02-16 17:29:35.518712 | Pipeline: check
2026-02-16 17:29:35.518757 | Executor: 3a2793d2bd32
2026-02-16 17:29:35.518799 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:29:35.519058 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:29:35.522396 | 
2026-02-16 17:29:35.522469 | LOOP [emit-job-header : Print node information]
2026-02-16 17:29:35.622152 | localhost | ok:
2026-02-16 17:29:35.622359 | localhost | # Node Information
2026-02-16 17:29:35.622399 | localhost | Inventory Hostname: controller
2026-02-16 17:29:35.622429 | localhost | Hostname: np0000155606
2026-02-16 17:29:35.622456 | localhost | Username: zuul
2026-02-16 17:29:35.622486 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:29:35.622513 | localhost | Provider: yul1
2026-02-16 17:29:35.622540 | localhost | Region: ca-ymq-1
2026-02-16 17:29:35.622566 | localhost | Label: ubuntu-noble-16
2026-02-16 17:29:35.622591 | localhost | Product Name: OpenStack Nova
2026-02-16 17:29:35.622616 | localhost | Interface IP: 199.204.45.3
2026-02-16 17:29:35.640522 | 
2026-02-16 17:29:35.640625 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:29:36.046090 | localhost -> localhost | changed
2026-02-16 17:29:36.055689 | 
2026-02-16 17:29:36.055804 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:29:36.901856 | localhost -> localhost | changed
2026-02-16 17:29:36.910994 | 
2026-02-16 17:29:36.911073 | PLAY [all]
2026-02-16 17:29:36.919676 | 
2026-02-16 17:29:36.919741 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:29:37.145730 | controller -> localhost | ok
2026-02-16 17:29:37.157461 | 
2026-02-16 17:29:37.157536 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:29:37.192797 | controller | ok
2026-02-16 17:29:37.210498 | controller | included: /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:29:37.216725 | 
2026-02-16 17:29:37.216784 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:29:38.546028 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:29:38.546285 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/work/53e723a9da7c4d70be9030711237bf4b_id_rsa
2026-02-16 17:29:38.546330 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/work/53e723a9da7c4d70be9030711237bf4b_id_rsa.pub
2026-02-16 17:29:38.546365 | controller -> localhost | The key fingerprint is:
2026-02-16 17:29:38.546397 | controller -> localhost | SHA256:42UemtWXIUZggNJTnFQpcYCaDSYBEkN2nhNXiFT1Q+E zuul-build-sshkey
2026-02-16 17:29:38.546450 | controller -> localhost | The key's randomart image is:
2026-02-16 17:29:38.546482 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:29:38.546514 | controller -> localhost | |*=o=o++O*O*o.    |
2026-02-16 17:29:38.546546 | controller -> localhost | |o.+.B.= Bo..     |
2026-02-16 17:29:38.546576 | controller -> localhost | |   * * . E  o .  |
2026-02-16 17:29:38.546607 | controller -> localhost | |    + .   .o . o |
2026-02-16 17:29:38.546637 | controller -> localhost | |        S = . o  |
2026-02-16 17:29:38.546666 | controller -> localhost | |       . O . .   |
2026-02-16 17:29:38.546695 | controller -> localhost | |        + .      |
2026-02-16 17:29:38.546723 | controller -> localhost | |                 |
2026-02-16 17:29:38.546754 | controller -> localhost | |                 |
2026-02-16 17:29:38.546784 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:29:38.546852 | controller -> localhost | ok: Runtime: 0:00:00.890738
2026-02-16 17:29:38.552826 | 
2026-02-16 17:29:38.552899 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:29:38.590161 | controller | ok
2026-02-16 17:29:38.602561 | controller | included: /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:29:38.610980 | 
2026-02-16 17:29:38.611074 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:29:38.636699 | controller | skipping: Conditional result was False
2026-02-16 17:29:38.648798 | 
2026-02-16 17:29:38.648937 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:29:39.180613 | controller | changed
2026-02-16 17:29:39.187326 | 
2026-02-16 17:29:39.187405 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:29:39.404241 | controller | ok
2026-02-16 17:29:39.412228 | 
2026-02-16 17:29:39.412297 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:29:39.984377 | controller | changed
2026-02-16 17:29:39.989905 | 
2026-02-16 17:29:39.989969 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:29:40.542328 | controller | changed
2026-02-16 17:29:40.551651 | 
2026-02-16 17:29:40.551743 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:29:40.576869 | controller | skipping: Conditional result was False
2026-02-16 17:29:40.588800 | 
2026-02-16 17:29:40.588866 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:29:40.959032 | controller -> localhost | changed
2026-02-16 17:29:40.977680 | 
2026-02-16 17:29:40.977828 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:29:41.306812 | controller -> localhost | Identity added: /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/work/53e723a9da7c4d70be9030711237bf4b_id_rsa (zuul-build-sshkey)
2026-02-16 17:29:41.307103 | controller -> localhost | ok: Runtime: 0:00:00.013901
2026-02-16 17:29:41.354715 | 
2026-02-16 17:29:41.354883 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:29:41.662505 | controller | ok
2026-02-16 17:29:41.667892 | 
2026-02-16 17:29:41.667961 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:29:41.693202 | controller | skipping: Conditional result was False
2026-02-16 17:29:41.710977 | 
2026-02-16 17:29:41.711064 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:29:42.026909 | controller | ok
2026-02-16 17:29:42.033910 | 
2026-02-16 17:29:42.033978 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:30:01.167895 | controller | Output suppressed because no_log was given
2026-02-16 17:30:01.177863 | 
2026-02-16 17:30:01.177930 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:30:01.412746 | controller | ok: "logs"
2026-02-16 17:30:01.413063 | controller | ok: All items complete
2026-02-16 17:30:01.413148 | 
2026-02-16 17:30:01.598365 | controller | ok: "artifacts"
2026-02-16 17:30:01.789522 | controller | ok: "docs"
2026-02-16 17:30:01.804590 | 
2026-02-16 17:30:01.804747 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:30:02.023501 | controller | changed: "logs"
2026-02-16 17:30:02.212419 | controller | changed: "artifacts"
2026-02-16 17:30:02.433801 | controller | changed: "docs"
2026-02-16 17:30:02.454703 | 
2026-02-16 17:30:02.454788 | PLAY RECAP
2026-02-16 17:30:02.454851 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:30:02.454895 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:30:02.454925 | 
2026-02-16 17:30:02.579542 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:30:02.589364 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:30:03.199118 | 
2026-02-16 17:30:03.199319 | PLAY [all]
2026-02-16 17:30:03.217927 | 
2026-02-16 17:30:03.218015 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:30:03.578539 | controller | changed
2026-02-16 17:30:03.586138 | 
2026-02-16 17:30:03.586219 | TASK [Gather minimum local MTU]
2026-02-16 17:30:03.661731 | controller | ok
2026-02-16 17:30:03.669029 | 
2026-02-16 17:30:03.669168 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:30:03.724211 | controller | ok
2026-02-16 17:30:03.732631 | 
2026-02-16 17:30:03.732733 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:30:03.757572 | controller | skipping: Conditional result was False
2026-02-16 17:30:03.768799 | 
2026-02-16 17:30:03.768890 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:30:04.310660 | controller | ok: Runtime: 0:00:00.010077
2026-02-16 17:30:04.318633 | 
2026-02-16 17:30:04.318732 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:30:04.344151 | controller | skipping: Conditional result was False
2026-02-16 17:30:04.353978 | 
2026-02-16 17:30:04.354074 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:30:04.380658 | controller | skipping: Conditional result was False
2026-02-16 17:30:04.391889 | 
2026-02-16 17:30:04.392036 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:30:04.451807 | controller | ok
2026-02-16 17:30:04.464874 | controller | included: /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:30:04.471342 | 
2026-02-16 17:30:04.471407 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:30:04.525479 | controller | ok
2026-02-16 17:30:04.533061 | 
2026-02-16 17:30:04.533128 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:30:04.802513 | controller | ext4
2026-02-16 17:30:05.107230 | controller | ok: Runtime: 0:00:00.012280
2026-02-16 17:30:05.113041 | 
2026-02-16 17:30:05.113106 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:30:05.148628 | controller | ok
2026-02-16 17:30:05.154432 | 
2026-02-16 17:30:05.154496 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:30:05.190460 | controller | ok:
2026-02-16 17:30:05.190634 | controller | {
2026-02-16 17:30:05.190668 | controller |   "root_filesystem": "ext4"
2026-02-16 17:30:05.190696 | controller | }
2026-02-16 17:30:05.198743 | 
2026-02-16 17:30:05.198827 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:30:15.349298 | controller | 8192+0 records in
2026-02-16 17:30:15.349492 | controller | 8192+0 records out
2026-02-16 17:30:15.349513 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.88964 s, 869 MB/s
2026-02-16 17:30:15.759792 | controller | ok: Runtime: 0:00:09.897786
2026-02-16 17:30:15.768012 | 
2026-02-16 17:30:15.768109 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:30:16.010115 | controller | changed
2026-02-16 17:30:16.019183 | 
2026-02-16 17:30:16.019252 | TASK [configure-swap : Make swapfile]
2026-02-16 17:30:28.785364 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:30:28.786345 | controller | no label, UUID=85fdca02-5213-4199-bee9-62bc76016c39
2026-02-16 17:30:29.067604 | controller | ok: Runtime: 0:00:12.530612
2026-02-16 17:30:29.073201 | 
2026-02-16 17:30:29.073268 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:30:29.474364 | controller | changed
2026-02-16 17:30:29.481283 | 
2026-02-16 17:30:29.481370 | TASK [configure-swap : Add all swap]
2026-02-16 17:30:30.028153 | controller | ok: Runtime: 0:00:00.024294
2026-02-16 17:30:30.033753 | 
2026-02-16 17:30:30.033820 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:30:30.068835 | controller | ok:
2026-02-16 17:30:30.069010 | controller | {
2026-02-16 17:30:30.069047 | controller |   "swap_required": "8192"
2026-02-16 17:30:30.069075 | controller | }
2026-02-16 17:30:30.075938 | 
2026-02-16 17:30:30.076024 | TASK [configure-swap : Set swappiness]
2026-02-16 17:30:30.476422 | controller | changed
2026-02-16 17:30:30.483186 | 
2026-02-16 17:30:30.483256 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:30:30.517784 | controller | ok:
2026-02-16 17:30:30.517948 | controller | {
2026-02-16 17:30:30.517983 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:30:30.518017 | controller | }
2026-02-16 17:30:30.530129 | 
2026-02-16 17:30:30.530195 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:30:30.971860 | controller | changed
2026-02-16 17:30:30.979161 | 
2026-02-16 17:30:30.979230 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:30:31.228189 | controller | changed
2026-02-16 17:30:31.233858 | 
2026-02-16 17:30:31.233928 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:30:31.788194 | controller | changed
2026-02-16 17:30:31.794271 | 
2026-02-16 17:30:31.794345 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:30:32.044700 | controller | changed
2026-02-16 17:30:32.050865 | 
2026-02-16 17:30:32.050935 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:30:33.109724 | controller | changed
2026-02-16 17:30:33.117388 | 
2026-02-16 17:30:33.117477 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:30:33.355756 | controller | changed
2026-02-16 17:30:33.368833 | 
2026-02-16 17:30:33.368899 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:30:33.659443 | controller | changed
2026-02-16 17:30:33.664788 | 
2026-02-16 17:30:33.664855 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:30:34.032998 | controller | changed
2026-02-16 17:30:34.085501 | 
2026-02-16 17:30:34.085657 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:30:34.848839 | controller | changed
2026-02-16 17:30:34.863055 | 
2026-02-16 17:30:34.863132 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:30:35.191041 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:30:35.205183 | 
2026-02-16 17:30:35.205253 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:30:35.673661 | controller | ok: Item: Runtime: 0:00:00.184263
2026-02-16 17:30:36.260570 | controller | ok: Item: Runtime: 0:00:00.360181
2026-02-16 17:30:37.022059 | controller | ok: Item: Runtime: 0:00:00.546065
2026-02-16 17:30:37.283678 | controller | ok: Item: Runtime: 0:00:00.055912
2026-02-16 17:30:37.738986 | controller | ok: Item: Runtime: 0:00:00.246326
2026-02-16 17:30:39.183307 | controller | ok: Item: Runtime: 0:00:01.230142
2026-02-16 17:30:42.661761 | controller | ok: Item: Runtime: 0:00:03.259076
2026-02-16 17:30:42.980472 | controller | ok: Item: Runtime: 0:00:00.096686
2026-02-16 17:30:43.389354 | controller | ok: Item: Runtime: 0:00:00.190145
2026-02-16 17:30:43.733764 | controller | ok: Item: Runtime: 0:00:00.119928
2026-02-16 17:30:44.480143 | controller | ok: Item: Runtime: 0:00:00.532757
2026-02-16 17:30:45.225905 | controller | ok: Item: Runtime: 0:00:00.533146
2026-02-16 17:30:45.675678 | controller | ok: Item: Runtime: 0:00:00.224191
2026-02-16 17:30:46.110439 | controller | ok: Item: Runtime: 0:00:00.216383
2026-02-16 17:30:46.686470 | controller | ok: Item: Runtime: 0:00:00.353005
2026-02-16 17:30:48.077632 | controller | ok: Item: Runtime: 0:00:01.172237
2026-02-16 17:30:48.110097 | 
2026-02-16 17:30:48.110293 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:30:48.329807 | controller | ok: All paths examined
2026-02-16 17:30:48.336489 | 
2026-02-16 17:30:48.336560 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:30:48.575668 | controller | ok: All paths examined
2026-02-16 17:30:48.584636 | 
2026-02-16 17:30:48.584709 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:30:49.010954 | controller | ok: Item: Runtime: 0:00:00.150997
2026-02-16 17:30:49.011332 | controller | changed: All items complete
2026-02-16 17:30:49.011378 | 
2026-02-16 17:30:49.421898 | controller | ok: Item: Runtime: 0:00:00.184445
2026-02-16 17:30:49.448881 | 
2026-02-16 17:30:49.449090 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:30:49.491072 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.493404 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.495630 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.498727 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.501440 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.504445 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.507469 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.510583 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.513956 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.517017 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.520386 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.523417 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.526673 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.529601 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.532701 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.535851 | controller | skipping: Conditional result was False
2026-02-16 17:30:49.561150 | 
2026-02-16 17:30:49.561283 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:30:51.928713 | controller | changed
2026-02-16 17:30:51.942871 | 
2026-02-16 17:30:51.942971 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:30:52.183671 | controller | changed
2026-02-16 17:30:52.237570 | 
2026-02-16 17:30:52.237718 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:30:52.485836 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:30:52.779840 | controller | ERROR
2026-02-16 17:30:52.779972 | controller | {
2026-02-16 17:30:52.780001 | controller |   "delta": "0:00:00.010974",
2026-02-16 17:30:52.780021 | controller |   "end": "2026-02-16 17:30:52.486511",
2026-02-16 17:30:52.780039 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:52.780057 | controller |   "rc": 1,
2026-02-16 17:30:52.780075 | controller |   "start": "2026-02-16 17:30:52.475537"
2026-02-16 17:30:52.780092 | controller | }
2026-02-16 17:30:52.780114 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:52.785632 | 
2026-02-16 17:30:52.785702 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:30:53.024264 | controller | ok
2026-02-16 17:30:53.035010 | 
2026-02-16 17:30:53.035070 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:30:53.265325 | controller | /usr/bin/journalctl
2026-02-16 17:30:53.574999 | controller | ok: Runtime: 0:00:00.006618
2026-02-16 17:30:53.580574 | 
2026-02-16 17:30:53.580637 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:30:53.826204 | controller | 2026-02-16 17:30:53
2026-02-16 17:30:54.119068 | controller | ok: Runtime: 0:00:00.010107
2026-02-16 17:30:54.126505 | 
2026-02-16 17:30:54.126597 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:30:54.681104 | controller | changed
2026-02-16 17:30:54.688309 | 
2026-02-16 17:30:54.688382 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:30:54.713804 | controller | skipping: Conditional result was False
2026-02-16 17:30:54.726037 | 
2026-02-16 17:30:54.726139 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:30:55.261545 | controller | skipping: Conditional result was False
2026-02-16 17:30:55.272808 | 
2026-02-16 17:30:55.272912 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:30:55.807227 | controller | skipping: Conditional result was False
2026-02-16 17:30:55.817356 | 
2026-02-16 17:30:55.817455 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:30:55.843353 | controller | skipping: Conditional result was False
2026-02-16 17:30:55.851425 | 
2026-02-16 17:30:55.851518 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:30:56.384257 | controller | skipping: Conditional result was False
2026-02-16 17:30:56.397142 | 
2026-02-16 17:30:56.397249 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:30:56.930077 | controller | skipping: Conditional result was False
2026-02-16 17:30:56.941069 | 
2026-02-16 17:30:56.941168 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:30:56.966416 | controller | skipping: Conditional result was False
2026-02-16 17:30:56.978927 | 
2026-02-16 17:30:56.979000 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:30:57.004089 | controller | skipping: Conditional result was False
2026-02-16 17:30:57.012902 | 
2026-02-16 17:30:57.013043 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:30:57.596496 | controller | skipping: Conditional result was False
2026-02-16 17:30:57.607045 | 
2026-02-16 17:30:57.607143 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:30:58.139149 | controller | skipping: Conditional result was False
2026-02-16 17:30:58.151759 | 
2026-02-16 17:30:58.151836 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:30:58.176999 | controller | skipping: Conditional result was False
2026-02-16 17:30:58.186870 | 
2026-02-16 17:30:58.186960 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:30:58.212130 | controller | skipping: Conditional result was False
2026-02-16 17:30:58.228662 | 
2026-02-16 17:30:58.228752 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:30:58.640783 | controller | ok
2026-02-16 17:30:58.650312 | 
2026-02-16 17:30:58.650365 | PLAY RECAP
2026-02-16 17:30:58.650415 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:30:58.650437 | 
2026-02-16 17:30:58.790062 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:30:58.795377 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:30:59.407189 | 
2026-02-16 17:30:59.407329 | PLAY [all]
2026-02-16 17:30:59.419882 | 
2026-02-16 17:30:59.419968 | TASK [ensure-rust : Use rustup]
2026-02-16 17:30:59.453721 | controller | ok
2026-02-16 17:30:59.463197 | controller | included: /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:30:59.468702 | 
2026-02-16 17:30:59.468774 | TASK [ensure-rust : Install Rust]
2026-02-16 17:30:59.876279 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:31:00.009930 | controller | ERROR
2026-02-16 17:31:00.010143 | controller | {
2026-02-16 17:31:00.010173 | controller |   "delta": "0:00:00.012761",
2026-02-16 17:31:00.010196 | controller |   "end": "2026-02-16 17:30:59.880678",
2026-02-16 17:31:00.010217 | controller |   "msg": "non-zero return code",
2026-02-16 17:31:00.010235 | controller |   "rc": 127,
2026-02-16 17:31:00.010254 | controller |   "start": "2026-02-16 17:30:59.867917"
2026-02-16 17:31:00.010277 | controller | }
failure
2026-02-16 17:31:00.011754 | 
2026-02-16 17:31:00.011801 | PLAY RECAP
2026-02-16 17:31:00.011848 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:31:00.011870 | 
2026-02-16 17:31:00.136054 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:31:00.144599 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:31:00.735422 | 
2026-02-16 17:31:00.735596 | PLAY [all]
2026-02-16 17:31:00.757051 | 
2026-02-16 17:31:00.757205 | TASK [Return built artifacts to Zuul]
2026-02-16 17:31:00.790764 | controller | ok
2026-02-16 17:31:00.798585 | 
2026-02-16 17:31:00.798692 | TASK [Get the Docker volume ID]
2026-02-16 17:31:01.341464 | controller | ERROR
2026-02-16 17:31:01.341941 | controller | {
2026-02-16 17:31:01.341991 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:31:01.342023 | controller |   "rc": 2
2026-02-16 17:31:01.342051 | controller | }
2026-02-16 17:31:01.342089 | controller | ERROR: Ignoring Errors
2026-02-16 17:31:01.351228 | 
2026-02-16 17:31:01.351392 | TASK [Copy all of the pod logs]
2026-02-16 17:31:01.387106 | controller | ERROR
2026-02-16 17:31:01.387450 | controller | {
2026-02-16 17:31:01.387533 | 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/53e723a9da7c4d70be9030711237bf4b/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:31:01.387594 | controller | }
2026-02-16 17:31:01.387649 | controller | ERROR: Ignoring Errors
2026-02-16 17:31:01.389413 | 
2026-02-16 17:31:01.389489 | PLAY RECAP
2026-02-16 17:31:01.389539 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:31:01.389561 | 
2026-02-16 17:31:01.535117 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:31:01.541915 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:31:02.157127 | 
2026-02-16 17:31:02.157272 | PLAY [all]
2026-02-16 17:31:02.175304 | 
2026-02-16 17:31:02.175391 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:31:02.565865 | controller | changed
2026-02-16 17:31:02.573269 | 
2026-02-16 17:31:02.573348 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:31:03.614155 | controller | ok: Runtime: 0:00:00.457517
2026-02-16 17:31:03.622023 | 
2026-02-16 17:31:03.622128 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:31:04.165259 | controller | ok: Runtime: 0:00:00.019868
2026-02-16 17:31:04.171498 | 
2026-02-16 17:31:04.171571 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:31:04.709774 | controller | ok: Runtime: 0:00:00.017907
2026-02-16 17:31:04.717295 | 
2026-02-16 17:31:04.717364 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:31:05.564629 | controller | changed
2026-02-16 17:31:05.606206 | 
2026-02-16 17:31:05.606357 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:31:05.845193 | controller | changed
2026-02-16 17:31:05.850863 | 
2026-02-16 17:31:05.850937 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:31:06.178121 | controller | Output suppressed because no_log was given
2026-02-16 17:31:06.183781 | 
2026-02-16 17:31:06.183863 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:31:06.221801 | 
2026-02-16 17:31:06.221982 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:31:06.258312 | 
2026-02-16 17:31:06.258460 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:31:06.295062 | 
2026-02-16 17:31:06.295236 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:31:06.333235 | 
2026-02-16 17:31:06.333410 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:31:06.372985 | 
2026-02-16 17:31:06.373208 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:31:06.615440 | controller | changed
2026-02-16 17:31:06.621818 | 
2026-02-16 17:31:06.621893 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:31:06.659946 | controller | ok
2026-02-16 17:31:06.668184 | 
2026-02-16 17:31:06.668266 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:31:06.926770 | controller | Output suppressed because no_log was given
2026-02-16 17:31:06.932296 | 
2026-02-16 17:31:06.932369 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:31:06.970329 | 
2026-02-16 17:31:06.970514 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:31:06.996261 | 
2026-02-16 17:31:06.996454 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:31:07.659646 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:31:07.679016 | controller | Using python 3.12 to install setuptools
2026-02-16 17:31:07.722766 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:31:07.733368 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:31:08.043992 | controller | ERROR
2026-02-16 17:31:08.044287 | controller | {
2026-02-16 17:31:08.044340 | controller |   "delta": "0:00:00.493410",
2026-02-16 17:31:08.044372 | controller |   "end": "2026-02-16 17:31:07.735791",
2026-02-16 17:31:08.044401 | controller |   "msg": "non-zero return code",
2026-02-16 17:31:08.044442 | controller |   "rc": 127,
2026-02-16 17:31:08.044471 | controller |   "start": "2026-02-16 17:31:07.242381"
2026-02-16 17:31:08.044500 | controller | }
2026-02-16 17:31:08.044542 | controller | ERROR: Ignoring Errors
2026-02-16 17:31:08.062157 | 
2026-02-16 17:31:08.062312 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:31:08.321765 | controller | changed
2026-02-16 17:31:08.328724 | 
2026-02-16 17:31:08.328797 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:31:12.141387 | controller | Output suppressed because no_log was given
2026-02-16 17:31:12.153840 | 
2026-02-16 17:31:12.153905 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:31:12.185900 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.186281 | 
2026-02-16 17:31:12.188188 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.189764 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.191961 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.194250 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.196561 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.199172 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.201662 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.204416 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.206680 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.209161 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.211390 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.213664 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.215953 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.218190 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.220450 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.222828 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.225792 | controller | skipping: Conditional result was False
2026-02-16 17:31:12.259919 | 
2026-02-16 17:31:12.260102 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:31:12.488333 | controller | ok
2026-02-16 17:31:12.493963 | 
2026-02-16 17:31:12.494028 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:31:13.067271 | controller | skipping: Conditional result was False
2026-02-16 17:31:13.082467 | 
2026-02-16 17:31:13.082556 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:31:13.350456 | controller | sudo: iptables-save: command not found
2026-02-16 17:31:13.391860 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:31:14.498164 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:31:14.628543 | controller | ok: Runtime: 0:00:01.165141
2026-02-16 17:31:14.642807 | 
2026-02-16 17:31:14.642880 | LOOP [stage-output : Register sources]
2026-02-16 17:31:23.897872 | controller | Output suppressed because no_log was given
2026-02-16 17:31:23.955523 | 
2026-02-16 17:31:23.955699 | TASK [stage-output : Check sudo]
2026-02-16 17:31:24.494737 | controller | ok: Runtime: 0:00:00.021947
2026-02-16 17:31:24.501938 | 
2026-02-16 17:31:24.502008 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:31:24.594550 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.594943 | 
2026-02-16 17:31:24.596218 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.597567 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.599714 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.635911 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.636417 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.636894 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.637385 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.638831 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.641028 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.643164 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.645286 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.647407 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.649780 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.666923 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.667409 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.667955 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.668433 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.668896 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.671067 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.673116 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.675386 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.677492 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.700572 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.700908 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.774594 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.783371 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.799540 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.810440 | controller | Output suppressed because no_log was given
2026-02-16 17:31:24.822668 | 
2026-02-16 17:31:24.822747 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:31:24.889282 | controller | ok
2026-02-16 17:31:24.896750 | 
2026-02-16 17:31:24.896819 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:31:25.151313 | controller | changed: "docs"
2026-02-16 17:31:25.361494 | controller | changed: "artifacts"
2026-02-16 17:31:25.567156 | controller | ok: "logs"
2026-02-16 17:31:25.588232 | 
2026-02-16 17:31:25.588473 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:31:25.872947 | controller | ok: Item: Runtime: 0:00:00.011428
2026-02-16 17:31:25.873226 | controller | changed: All items complete
2026-02-16 17:31:25.873254 | 
2026-02-16 17:31:26.090240 | controller | ok: Item: Runtime: 0:00:00.008015
2026-02-16 17:31:26.311945 | controller | ok: Item: Runtime: 0:00:00.008472
2026-02-16 17:31:26.550900 | controller | ok: Item: Runtime: 0:00:00.009000
2026-02-16 17:31:26.774631 | controller | ok: Item: Runtime: 0:00:00.007882
2026-02-16 17:31:26.999081 | controller | ok: Item: Runtime: 0:00:00.007468
2026-02-16 17:31:27.215083 | controller | ok: Item: Runtime: 0:00:00.006023
2026-02-16 17:31:27.459115 | controller | ok: Item: Runtime: 0:00:00.008944
2026-02-16 17:31:27.680314 | controller | ok: Item: Runtime: 0:00:00.008645
2026-02-16 17:31:27.899934 | controller | ok: Item: Runtime: 0:00:00.008919
2026-02-16 17:31:28.124201 | controller | ok: Item: Runtime: 0:00:00.008241
2026-02-16 17:31:28.392531 | controller | ok: Item: Runtime: 0:00:00.008260
2026-02-16 17:31:28.567783 | controller | ok: Item: Runtime: 0:00:00.010283
2026-02-16 17:31:28.800449 | controller | ok: Item: Runtime: 0:00:00.008567
2026-02-16 17:31:29.033455 | controller | ok: Item: Runtime: 0:00:00.011229
2026-02-16 17:31:29.256649 | controller | ok: Item: Runtime: 0:00:00.008635
2026-02-16 17:31:29.282781 | 
2026-02-16 17:31:29.282979 | TASK [stage-output : Make all log files readable]
2026-02-16 17:31:29.529393 | controller | changed
2026-02-16 17:31:29.535565 | 
2026-02-16 17:31:29.535632 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:31:29.882372 | controller | changed: Renamed files for staging.
2026-02-16 17:31:29.887713 | 
2026-02-16 17:31:29.887776 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:31:29.912110 | controller | skipping: Conditional result was False
2026-02-16 17:31:29.924845 | 
2026-02-16 17:31:29.924936 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:31:29.966386 | 
2026-02-16 17:31:29.966504 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:31:30.598885 | controller | changed:
2026-02-16 17:31:30.599074 | controller | created directory /var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/work/logs/controller
2026-02-16 17:31:30.599101 | controller | cd+++++++++ logs/
2026-02-16 17:31:30.599122 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:31:30.599141 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:31:30.599160 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:31:30.599178 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:31:30.599196 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:31:30.599214 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:31:30.599232 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:31:30.599250 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:31:30.599267 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:31:30.599285 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:31:30.599303 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:31:30.599321 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:31:30.599346 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:31:30.599395 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:31:30.599417 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:31:30.599436 | controller | cd+++++++++ logs/apache/
2026-02-16 17:31:30.599454 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:31:30.599471 | controller | cd+++++++++ logs/etc/
2026-02-16 17:31:30.599489 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:31:30.599507 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:31:30.599526 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:31:30.599543 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:31:30.599561 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:31:30.599578 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:31:30.611852 | 
2026-02-16 17:31:30.611949 | TASK [Check if a tempest log exits]
2026-02-16 17:31:30.859992 | controller | ok
2026-02-16 17:31:30.865265 | 
2026-02-16 17:31:30.865357 | TASK [Link post-devstack tempest.log]
2026-02-16 17:31:30.891187 | controller | skipping: Conditional result was False
2026-02-16 17:31:30.903251 | 
2026-02-16 17:31:30.903317 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:31:31.154080 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:31:31.446048 | controller | ERROR
2026-02-16 17:31:31.446402 | controller | {
2026-02-16 17:31:31.446468 | controller |   "delta": "0:00:00.008239",
2026-02-16 17:31:31.446492 | controller |   "end": "2026-02-16 17:31:31.154693",
2026-02-16 17:31:31.446511 | controller |   "msg": "non-zero return code",
2026-02-16 17:31:31.446529 | controller |   "rc": 127,
2026-02-16 17:31:31.446548 | controller |   "start": "2026-02-16 17:31:31.146454"
2026-02-16 17:31:31.446565 | controller | }
2026-02-16 17:31:31.446589 | controller | ERROR: Ignoring Errors
2026-02-16 17:31:31.448457 | 
2026-02-16 17:31:31.448509 | PLAY RECAP
2026-02-16 17:31:31.448562 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:31:31.448584 | 
2026-02-16 17:31:31.601012 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:31:31.611022 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:31:32.230795 | 
2026-02-16 17:31:32.230939 | PLAY [all]
2026-02-16 17:31:32.242955 | 
2026-02-16 17:31:32.243027 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:31:32.288271 | controller | skipping: Conditional result was False
2026-02-16 17:31:32.299564 | 
2026-02-16 17:31:32.299647 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:31:32.343266 | controller | ok
2026-02-16 17:31:32.351496 | 
2026-02-16 17:31:32.351562 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:31:32.722007 | controller -> localhost | ok: "/var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/work/logs"
2026-02-16 17:31:32.926238 | controller -> localhost | changed: "/var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/work/artifacts"
2026-02-16 17:31:33.136309 | controller -> localhost | changed: "/var/lib/zuul/builds/53e723a9da7c4d70be9030711237bf4b/work/docs"
2026-02-16 17:31:33.154012 | 
2026-02-16 17:31:33.154154 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:31:33.789171 | controller | changed: .d..t...... ./
2026-02-16 17:31:33.789455 | controller | changed: All items complete
2026-02-16 17:31:33.789482 | 
2026-02-16 17:31:34.273570 | controller | changed: .d..t...... ./
2026-02-16 17:31:34.738968 | controller | changed: .d..t...... ./
2026-02-16 17:31:34.764302 | 
2026-02-16 17:31:34.764485 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:31:35.172594 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008674
2026-02-16 17:31:35.384489 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005489
2026-02-16 17:31:35.405527 | 
2026-02-16 17:31:35.405700 | PLAY [all]
2026-02-16 17:31:35.414111 | 
2026-02-16 17:31:35.414181 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:31:35.894431 | controller | changed
2026-02-16 17:31:35.902894 | 
2026-02-16 17:31:35.902964 | PLAY RECAP
2026-02-16 17:31:35.903027 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:31:35.903058 | 
2026-02-16 17:31:36.025783 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:31:36.036268 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:31:36.651536 | 
2026-02-16 17:31:36.651679 | PLAY [localhost]
2026-02-16 17:31:36.662225 | 
2026-02-16 17:31:36.662297 | TASK [Generate Zuul manifest]
2026-02-16 17:31:36.682268 | localhost | ok
2026-02-16 17:31:36.699184 | 
2026-02-16 17:31:36.699268 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:31:37.054653 | localhost | changed
2026-02-16 17:31:37.068903 | 
2026-02-16 17:31:37.068996 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:31:37.110894 | localhost | ok
2026-02-16 17:31:37.120363 | 
2026-02-16 17:31:37.120449 | TASK [Upload logs]
2026-02-16 17:31:37.142738 | localhost | ok
2026-02-16 17:31:37.206919 | 
2026-02-16 17:31:37.207040 | TASK [Set zuul-log-path fact]
2026-02-16 17:31:37.228135 | localhost | ok
2026-02-16 17:31:37.241266 | 
2026-02-16 17:31:37.241335 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:31:37.273243 | localhost | ok
2026-02-16 17:31:37.283313 | 
2026-02-16 17:31:37.283402 | TASK [upload-logs : Create log directories]
2026-02-16 17:31:37.700856 | localhost | changed
2026-02-16 17:31:37.707584 | 
2026-02-16 17:31:37.707657 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:31:38.139274 | localhost -> localhost | ok: Runtime: 0:00:00.006231
2026-02-16 17:31:38.144061 | 
2026-02-16 17:31:38.144128 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:31:38.581473 | localhost | Output suppressed because no_log was given
2026-02-16 17:31:38.586400 | 
2026-02-16 17:31:38.586488 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:31:38.632176 | localhost | skipping: Conditional result was False
2026-02-16 17:31:38.638161 | localhost | skipping: Conditional result was False
2026-02-16 17:31:38.652009 | 
2026-02-16 17:31:38.652219 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:31:38.695998 | localhost | skipping: Conditional result was False
2026-02-16 17:31:38.696394 | 
2026-02-16 17:31:38.699292 | localhost | skipping: Conditional result was False
2026-02-16 17:31:38.717950 | 
2026-02-16 17:31:38.718155 | LOOP [upload-logs : Upload console log and json output]