2026-02-16 17:32:42.535341 | Job console starting
2026-02-16 17:32:42.543757 | Updating git repos
2026-02-16 17:32:45.730446 | Cloning repos into workspace
2026-02-16 17:32:51.360338 | Restoring repo states
2026-02-16 17:32:54.509943 | Merging changes
2026-02-16 17:32:54.905590 | Checking out repos
2026-02-16 17:32:57.922066 | Preparing playbooks
2026-02-16 17:32:59.371515 | Running Ansible setup
2026-02-16 17:33:03.205338 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:33:03.794447 | 
2026-02-16 17:33:03.794593 | PLAY [localhost]
2026-02-16 17:33:03.802233 | 
2026-02-16 17:33:03.802306 | TASK [Gathering Facts]
2026-02-16 17:33:04.643072 | localhost | ok
2026-02-16 17:33:04.653501 | 
2026-02-16 17:33:04.653579 | TASK [Setup log path fact]
2026-02-16 17:33:04.686784 | localhost | ok
2026-02-16 17:33:04.702016 | 
2026-02-16 17:33:04.702092 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:33:04.734269 | localhost | ok
2026-02-16 17:33:04.741903 | 
2026-02-16 17:33:04.741976 | TASK [emit-job-header : Print job information]
2026-02-16 17:33:04.784517 | # Job Information
2026-02-16 17:33:04.784672 | Ansible Version: 2.16.15
2026-02-16 17:33:04.784716 | Job: magnum-cluster-api-sonobuoy-v1.34.4-calico
2026-02-16 17:33:04.784746 | Pipeline: check
2026-02-16 17:33:04.784773 | Executor: 3a2793d2bd32
2026-02-16 17:33:04.784816 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:33:04.784848 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:33:04.788499 | 
2026-02-16 17:33:04.788580 | LOOP [emit-job-header : Print node information]
2026-02-16 17:33:04.890709 | localhost | ok:
2026-02-16 17:33:04.891777 | localhost | # Node Information
2026-02-16 17:33:04.891823 | localhost | Inventory Hostname: controller
2026-02-16 17:33:04.891854 | localhost | Hostname: np0000155602
2026-02-16 17:33:04.891882 | localhost | Username: zuul
2026-02-16 17:33:04.891913 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:33:04.891940 | localhost | Provider: yul1
2026-02-16 17:33:04.891966 | localhost | Region: ca-ymq-1
2026-02-16 17:33:04.891992 | localhost | Label: ubuntu-noble-16
2026-02-16 17:33:04.892018 | localhost | Product Name: OpenStack Nova
2026-02-16 17:33:04.892043 | localhost | Interface IP: 199.204.45.141
2026-02-16 17:33:04.910496 | 
2026-02-16 17:33:04.910618 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:33:05.291038 | localhost -> localhost | changed
2026-02-16 17:33:05.299249 | 
2026-02-16 17:33:05.299352 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:33:06.127585 | localhost -> localhost | changed
2026-02-16 17:33:06.138513 | 
2026-02-16 17:33:06.138572 | PLAY [all]
2026-02-16 17:33:06.146961 | 
2026-02-16 17:33:06.147026 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:33:06.383615 | controller -> localhost | ok
2026-02-16 17:33:06.394884 | 
2026-02-16 17:33:06.395029 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:33:06.430258 | controller | ok
2026-02-16 17:33:06.448894 | controller | included: /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:33:06.455085 | 
2026-02-16 17:33:06.455146 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:33:07.515235 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:33:07.515423 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/work/be77247762b74190a9e94ebdaa73ab46_id_rsa
2026-02-16 17:33:07.515452 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/work/be77247762b74190a9e94ebdaa73ab46_id_rsa.pub
2026-02-16 17:33:07.515475 | controller -> localhost | The key fingerprint is:
2026-02-16 17:33:07.515496 | controller -> localhost | SHA256:/XLtjEdB/iET142kXZVRK2/7zt5Vfd8mjiE1y0C+uFY zuul-build-sshkey
2026-02-16 17:33:07.515533 | controller -> localhost | The key's randomart image is:
2026-02-16 17:33:07.515555 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:33:07.515584 | controller -> localhost | |             ..o@|
2026-02-16 17:33:07.515611 | controller -> localhost | |             +o++|
2026-02-16 17:33:07.515636 | controller -> localhost | |          . .++. |
2026-02-16 17:33:07.515661 | controller -> localhost | |         +   o=..|
2026-02-16 17:33:07.515686 | controller -> localhost | |        S + o o==|
2026-02-16 17:33:07.515711 | controller -> localhost | |         .E* +o B|
2026-02-16 17:33:07.515736 | controller -> localhost | |        ..+ *.o.=|
2026-02-16 17:33:07.515760 | controller -> localhost | |        .. + B.++|
2026-02-16 17:33:07.515783 | controller -> localhost | |       ..   o.+o*|
2026-02-16 17:33:07.515803 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:33:07.515851 | controller -> localhost | ok: Runtime: 0:00:00.662608
2026-02-16 17:33:07.521613 | 
2026-02-16 17:33:07.521687 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:33:07.555111 | controller | ok
2026-02-16 17:33:07.569474 | controller | included: /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:33:07.577168 | 
2026-02-16 17:33:07.577239 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:33:07.601415 | controller | skipping: Conditional result was False
2026-02-16 17:33:07.611357 | 
2026-02-16 17:33:07.611449 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:33:08.133767 | controller | changed
2026-02-16 17:33:08.140677 | 
2026-02-16 17:33:08.140738 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:33:08.366357 | controller | ok
2026-02-16 17:33:08.372852 | 
2026-02-16 17:33:08.372917 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:33:08.932191 | controller | changed
2026-02-16 17:33:08.942083 | 
2026-02-16 17:33:08.942174 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:33:09.507045 | controller | changed
2026-02-16 17:33:09.514500 | 
2026-02-16 17:33:09.514597 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:33:09.539767 | controller | skipping: Conditional result was False
2026-02-16 17:33:09.553471 | 
2026-02-16 17:33:09.553701 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:33:09.902620 | controller -> localhost | changed
2026-02-16 17:33:09.918834 | 
2026-02-16 17:33:09.918901 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:33:10.268205 | controller -> localhost | Identity added: /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/work/be77247762b74190a9e94ebdaa73ab46_id_rsa (zuul-build-sshkey)
2026-02-16 17:33:10.269252 | controller -> localhost | ok: Runtime: 0:00:00.014147
2026-02-16 17:33:10.315882 | 
2026-02-16 17:33:10.316041 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:33:10.615842 | controller | ok
2026-02-16 17:33:10.622513 | 
2026-02-16 17:33:10.622578 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:33:10.646916 | controller | skipping: Conditional result was False
2026-02-16 17:33:10.663917 | 
2026-02-16 17:33:10.663980 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:33:10.984781 | controller | ok
2026-02-16 17:33:10.991735 | 
2026-02-16 17:33:10.991799 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:33:29.926002 | controller | Output suppressed because no_log was given
2026-02-16 17:33:29.938825 | 
2026-02-16 17:33:29.938888 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:33:30.175994 | controller | ok: "logs"
2026-02-16 17:33:30.176264 | controller | ok: All items complete
2026-02-16 17:33:30.176293 | 
2026-02-16 17:33:30.362831 | controller | ok: "artifacts"
2026-02-16 17:33:30.562039 | controller | ok: "docs"
2026-02-16 17:33:30.582015 | 
2026-02-16 17:33:30.582203 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:33:30.819400 | controller | changed: "logs"
2026-02-16 17:33:31.014491 | controller | changed: "artifacts"
2026-02-16 17:33:31.249661 | controller | changed: "docs"
2026-02-16 17:33:31.271078 | 
2026-02-16 17:33:31.271238 | PLAY RECAP
2026-02-16 17:33:31.271305 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:33:31.271344 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:33:31.271373 | 
2026-02-16 17:33:31.416542 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:33:31.426256 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:33:32.019024 | 
2026-02-16 17:33:32.019163 | PLAY [all]
2026-02-16 17:33:32.036277 | 
2026-02-16 17:33:32.036352 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:33:32.406346 | controller | changed
2026-02-16 17:33:32.415728 | 
2026-02-16 17:33:32.415834 | TASK [Gather minimum local MTU]
2026-02-16 17:33:32.493478 | controller | ok
2026-02-16 17:33:32.498517 | 
2026-02-16 17:33:32.498583 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:33:32.552835 | controller | ok
2026-02-16 17:33:32.559939 | 
2026-02-16 17:33:32.560027 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:33:32.585511 | controller | skipping: Conditional result was False
2026-02-16 17:33:32.597504 | 
2026-02-16 17:33:32.597573 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:33:33.137951 | controller | ok: Runtime: 0:00:00.011395
2026-02-16 17:33:33.143644 | 
2026-02-16 17:33:33.143713 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:33:33.170407 | controller | skipping: Conditional result was False
2026-02-16 17:33:33.179790 | 
2026-02-16 17:33:33.179887 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:33:33.216704 | controller | skipping: Conditional result was False
2026-02-16 17:33:33.231928 | 
2026-02-16 17:33:33.232212 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:33:33.292344 | controller | ok
2026-02-16 17:33:33.302722 | controller | included: /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:33:33.308855 | 
2026-02-16 17:33:33.308932 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:33:33.362186 | controller | ok
2026-02-16 17:33:33.368493 | 
2026-02-16 17:33:33.368556 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:33:33.648146 | controller | ext4
2026-02-16 17:33:33.949500 | controller | ok: Runtime: 0:00:00.012486
2026-02-16 17:33:33.955074 | 
2026-02-16 17:33:33.955138 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:33:33.985374 | controller | ok
2026-02-16 17:33:33.990604 | 
2026-02-16 17:33:33.990670 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:33:34.019633 | controller | ok:
2026-02-16 17:33:34.019749 | controller | {
2026-02-16 17:33:34.019774 | controller |   "root_filesystem": "ext4"
2026-02-16 17:33:34.019794 | controller | }
2026-02-16 17:33:34.025502 | 
2026-02-16 17:33:34.025561 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:33:44.522512 | controller | 8192+0 records in
2026-02-16 17:33:44.522665 | controller | 8192+0 records out
2026-02-16 17:33:44.522687 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.2409 s, 839 MB/s
2026-02-16 17:33:44.582127 | controller | ok: Runtime: 0:00:10.250516
2026-02-16 17:33:44.589980 | 
2026-02-16 17:33:44.590065 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:33:44.837040 | controller | changed
2026-02-16 17:33:44.844616 | 
2026-02-16 17:33:44.844679 | TASK [configure-swap : Make swapfile]
2026-02-16 17:34:04.058219 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:34:04.058375 | controller | no label, UUID=289c7ea4-3631-40ab-a5ca-4b533ee5379b
2026-02-16 17:34:04.432090 | controller | ok: Runtime: 0:00:18.975485
2026-02-16 17:34:04.440018 | 
2026-02-16 17:34:04.440111 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:34:04.838535 | controller | changed
2026-02-16 17:34:04.846569 | 
2026-02-16 17:34:04.846682 | TASK [configure-swap : Add all swap]
2026-02-16 17:34:05.382934 | controller | ok: Runtime: 0:00:00.022050
2026-02-16 17:34:05.390669 | 
2026-02-16 17:34:05.390741 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:34:05.425956 | controller | ok:
2026-02-16 17:34:05.426171 | controller | {
2026-02-16 17:34:05.426208 | controller |   "swap_required": "8192"
2026-02-16 17:34:05.426237 | controller | }
2026-02-16 17:34:05.433724 | 
2026-02-16 17:34:05.433820 | TASK [configure-swap : Set swappiness]
2026-02-16 17:34:05.825457 | controller | changed
2026-02-16 17:34:05.831819 | 
2026-02-16 17:34:05.831884 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:34:05.866725 | controller | ok:
2026-02-16 17:34:05.866871 | controller | {
2026-02-16 17:34:05.866907 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:34:05.866942 | controller | }
2026-02-16 17:34:05.879457 | 
2026-02-16 17:34:05.879529 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:34:06.318433 | controller | changed
2026-02-16 17:34:06.327985 | 
2026-02-16 17:34:06.328054 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:34:06.573122 | controller | changed
2026-02-16 17:34:06.580786 | 
2026-02-16 17:34:06.580878 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:34:07.152169 | controller | changed
2026-02-16 17:34:07.159968 | 
2026-02-16 17:34:07.160056 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:34:07.409872 | controller | changed
2026-02-16 17:34:07.416929 | 
2026-02-16 17:34:07.417020 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:34:08.493608 | controller | changed
2026-02-16 17:34:08.499501 | 
2026-02-16 17:34:08.499569 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:34:08.739341 | controller | changed
2026-02-16 17:34:08.760049 | 
2026-02-16 17:34:08.760265 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:34:09.049707 | controller | changed
2026-02-16 17:34:09.057086 | 
2026-02-16 17:34:09.057171 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:34:09.426019 | controller | changed
2026-02-16 17:34:09.477417 | 
2026-02-16 17:34:09.477607 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:34:10.284182 | controller | changed
2026-02-16 17:34:10.299924 | 
2026-02-16 17:34:10.300190 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:34:10.621374 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:34:10.635286 | 
2026-02-16 17:34:10.635362 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:34:11.130778 | controller | ok: Item: Runtime: 0:00:00.200316
2026-02-16 17:34:11.708012 | controller | ok: Item: Runtime: 0:00:00.353494
2026-02-16 17:34:12.448364 | controller | ok: Item: Runtime: 0:00:00.523759
2026-02-16 17:34:12.730891 | controller | ok: Item: Runtime: 0:00:00.064532
2026-02-16 17:34:13.222534 | controller | ok: Item: Runtime: 0:00:00.267749
2026-02-16 17:34:14.765591 | controller | ok: Item: Runtime: 0:00:01.304100
2026-02-16 17:34:18.205126 | controller | ok: Item: Runtime: 0:00:03.217565
2026-02-16 17:34:18.529674 | controller | ok: Item: Runtime: 0:00:00.101430
2026-02-16 17:34:18.944532 | controller | ok: Item: Runtime: 0:00:00.192153
2026-02-16 17:34:19.297705 | controller | ok: Item: Runtime: 0:00:00.125653
2026-02-16 17:34:20.047356 | controller | ok: Item: Runtime: 0:00:00.519741
2026-02-16 17:34:20.794943 | controller | ok: Item: Runtime: 0:00:00.529768
2026-02-16 17:34:21.276425 | controller | ok: Item: Runtime: 0:00:00.254378
2026-02-16 17:34:21.711606 | controller | ok: Item: Runtime: 0:00:00.217641
2026-02-16 17:34:22.322040 | controller | ok: Item: Runtime: 0:00:00.385802
2026-02-16 17:34:23.667289 | controller | ok: Item: Runtime: 0:00:01.127519
2026-02-16 17:34:23.702430 | 
2026-02-16 17:34:23.702607 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:34:23.923808 | controller | ok: All paths examined
2026-02-16 17:34:23.930253 | 
2026-02-16 17:34:23.930319 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:34:24.193219 | controller | ok: All paths examined
2026-02-16 17:34:24.199701 | 
2026-02-16 17:34:24.199770 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:34:24.676059 | controller | ok: Item: Runtime: 0:00:00.192565
2026-02-16 17:34:24.676336 | controller | changed: All items complete
2026-02-16 17:34:24.676365 | 
2026-02-16 17:34:25.096596 | controller | ok: Item: Runtime: 0:00:00.188140
2026-02-16 17:34:25.111665 | 
2026-02-16 17:34:25.111871 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:34:25.154090 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.156292 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.158611 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.161725 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.164735 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.167723 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.170777 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.173869 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.177250 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.179968 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.182911 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.185897 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.188832 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.192025 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.195040 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.197917 | controller | skipping: Conditional result was False
2026-02-16 17:34:25.217496 | 
2026-02-16 17:34:25.217578 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:34:27.591479 | controller | changed
2026-02-16 17:34:27.602720 | 
2026-02-16 17:34:27.602793 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:34:27.841964 | controller | changed
2026-02-16 17:34:27.905092 | 
2026-02-16 17:34:27.905279 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:34:28.171402 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:34:28.444385 | controller | ERROR
2026-02-16 17:34:28.444684 | controller | {
2026-02-16 17:34:28.444747 | controller |   "delta": "0:00:00.010441",
2026-02-16 17:34:28.444794 | controller |   "end": "2026-02-16 17:34:28.171922",
2026-02-16 17:34:28.444839 | controller |   "msg": "non-zero return code",
2026-02-16 17:34:28.444880 | controller |   "rc": 1,
2026-02-16 17:34:28.444919 | controller |   "start": "2026-02-16 17:34:28.161481"
2026-02-16 17:34:28.444957 | controller | }
2026-02-16 17:34:28.445046 | controller | ERROR: Ignoring Errors
2026-02-16 17:34:28.453177 | 
2026-02-16 17:34:28.453246 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:34:28.703238 | controller | ok
2026-02-16 17:34:28.713858 | 
2026-02-16 17:34:28.713932 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:34:28.946620 | controller | /usr/bin/journalctl
2026-02-16 17:34:29.251845 | controller | ok: Runtime: 0:00:00.008085
2026-02-16 17:34:29.259300 | 
2026-02-16 17:34:29.259369 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:34:29.515795 | controller | 2026-02-16 17:34:29
2026-02-16 17:34:29.800151 | controller | ok: Runtime: 0:00:00.014074
2026-02-16 17:34:29.806278 | 
2026-02-16 17:34:29.806346 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:34:30.370568 | controller | changed
2026-02-16 17:34:30.377534 | 
2026-02-16 17:34:30.377600 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:34:30.402536 | controller | skipping: Conditional result was False
2026-02-16 17:34:30.411660 | 
2026-02-16 17:34:30.411750 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:34:30.942142 | controller | skipping: Conditional result was False
2026-02-16 17:34:30.951374 | 
2026-02-16 17:34:30.951470 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:34:31.485172 | controller | skipping: Conditional result was False
2026-02-16 17:34:31.496450 | 
2026-02-16 17:34:31.496521 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:34:31.521956 | controller | skipping: Conditional result was False
2026-02-16 17:34:31.532087 | 
2026-02-16 17:34:31.532179 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:34:32.064345 | controller | skipping: Conditional result was False
2026-02-16 17:34:32.075989 | 
2026-02-16 17:34:32.076093 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:34:32.606496 | controller | skipping: Conditional result was False
2026-02-16 17:34:32.617489 | 
2026-02-16 17:34:32.617616 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:34:32.642978 | controller | skipping: Conditional result was False
2026-02-16 17:34:32.653862 | 
2026-02-16 17:34:32.653972 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:34:32.679834 | controller | skipping: Conditional result was False
2026-02-16 17:34:32.690248 | 
2026-02-16 17:34:32.690371 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:34:33.279088 | controller | skipping: Conditional result was False
2026-02-16 17:34:33.288083 | 
2026-02-16 17:34:33.288172 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:34:33.818236 | controller | skipping: Conditional result was False
2026-02-16 17:34:33.829974 | 
2026-02-16 17:34:33.830049 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:34:33.855137 | controller | skipping: Conditional result was False
2026-02-16 17:34:33.865589 | 
2026-02-16 17:34:33.865680 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:34:33.891566 | controller | skipping: Conditional result was False
2026-02-16 17:34:33.907764 | 
2026-02-16 17:34:33.907841 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:34:34.294900 | controller | ok
2026-02-16 17:34:34.303751 | 
2026-02-16 17:34:34.303796 | PLAY RECAP
2026-02-16 17:34:34.303841 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:34:34.303863 | 
2026-02-16 17:34:34.449131 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:34:34.457045 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:34:35.059643 | 
2026-02-16 17:34:35.059779 | PLAY [all]
2026-02-16 17:34:35.071798 | 
2026-02-16 17:34:35.071877 | TASK [ensure-rust : Use rustup]
2026-02-16 17:34:35.107412 | controller | ok
2026-02-16 17:34:35.116791 | controller | included: /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:34:35.122246 | 
2026-02-16 17:34:35.122317 | TASK [ensure-rust : Install Rust]
2026-02-16 17:34:35.542793 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:34:35.659199 | controller | ERROR
2026-02-16 17:34:35.659571 | controller | {
2026-02-16 17:34:35.659623 | controller |   "delta": "0:00:00.012100",
2026-02-16 17:34:35.659657 | controller |   "end": "2026-02-16 17:34:35.545338",
2026-02-16 17:34:35.659686 | controller |   "msg": "non-zero return code",
2026-02-16 17:34:35.659713 | controller |   "rc": 127,
2026-02-16 17:34:35.659740 | controller |   "start": "2026-02-16 17:34:35.533238"
2026-02-16 17:34:35.659775 | controller | }
failure
2026-02-16 17:34:35.662056 | 
2026-02-16 17:34:35.662124 | PLAY RECAP
2026-02-16 17:34:35.662192 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:34:35.662222 | 
2026-02-16 17:34:35.790887 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:34:35.799069 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:34:36.360884 | 
2026-02-16 17:34:36.361033 | PLAY [all]
2026-02-16 17:34:36.376717 | 
2026-02-16 17:34:36.376810 | TASK [Return built artifacts to Zuul]
2026-02-16 17:34:36.409659 | controller | ok
2026-02-16 17:34:36.415951 | 
2026-02-16 17:34:36.416021 | TASK [Get the Docker volume ID]
2026-02-16 17:34:36.957220 | controller | ERROR
2026-02-16 17:34:36.957597 | controller | {
2026-02-16 17:34:36.957640 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:34:36.957671 | controller |   "rc": 2
2026-02-16 17:34:36.957698 | controller | }
2026-02-16 17:34:36.957734 | controller | ERROR: Ignoring Errors
2026-02-16 17:34:36.965599 | 
2026-02-16 17:34:36.965667 | TASK [Copy all of the pod logs]
2026-02-16 17:34:36.991178 | controller | ERROR
2026-02-16 17:34:36.991387 | controller | {
2026-02-16 17:34:36.991427 | 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/be77247762b74190a9e94ebdaa73ab46/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:34:36.991458 | controller | }
2026-02-16 17:34:36.991494 | controller | ERROR: Ignoring Errors
2026-02-16 17:34:36.992717 | 
2026-02-16 17:34:36.992780 | PLAY RECAP
2026-02-16 17:34:36.992839 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:34:36.992868 | 
2026-02-16 17:34:37.129710 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:34:37.134719 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:34:37.727404 | 
2026-02-16 17:34:37.727556 | PLAY [all]
2026-02-16 17:34:37.745974 | 
2026-02-16 17:34:37.746065 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:34:38.127872 | controller | changed
2026-02-16 17:34:38.135340 | 
2026-02-16 17:34:38.135426 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:34:39.173607 | controller | ok: Runtime: 0:00:00.454088
2026-02-16 17:34:39.180905 | 
2026-02-16 17:34:39.180997 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:34:39.721335 | controller | ok: Runtime: 0:00:00.019058
2026-02-16 17:34:39.729080 | 
2026-02-16 17:34:39.729204 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:34:40.271333 | controller | ok: Runtime: 0:00:00.023796
2026-02-16 17:34:40.277799 | 
2026-02-16 17:34:40.277869 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:34:41.134115 | controller | changed
2026-02-16 17:34:41.180627 | 
2026-02-16 17:34:41.180783 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:34:41.419446 | controller | changed
2026-02-16 17:34:41.426456 | 
2026-02-16 17:34:41.426527 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:34:41.745123 | controller | Output suppressed because no_log was given
2026-02-16 17:34:41.751806 | 
2026-02-16 17:34:41.751874 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:34:41.788325 | 
2026-02-16 17:34:41.788450 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:34:41.822441 | 
2026-02-16 17:34:41.822693 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:34:41.858607 | 
2026-02-16 17:34:41.858774 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:34:41.894303 | 
2026-02-16 17:34:41.894477 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:34:41.933508 | 
2026-02-16 17:34:41.933654 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:34:42.183377 | controller | changed
2026-02-16 17:34:42.189527 | 
2026-02-16 17:34:42.189598 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:34:42.223588 | controller | ok
2026-02-16 17:34:42.229654 | 
2026-02-16 17:34:42.229721 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:34:42.478124 | controller | Output suppressed because no_log was given
2026-02-16 17:34:42.484954 | 
2026-02-16 17:34:42.485037 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:34:42.521350 | 
2026-02-16 17:34:42.521517 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:34:42.562306 | 
2026-02-16 17:34:42.562437 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:34:43.226162 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:34:43.247580 | controller | Using python 3.12 to install setuptools
2026-02-16 17:34:43.285659 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:34:43.298056 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:34:43.604759 | controller | ERROR
2026-02-16 17:34:43.605190 | controller | {
2026-02-16 17:34:43.605264 | controller |   "delta": "0:00:00.493660",
2026-02-16 17:34:43.605310 | controller |   "end": "2026-02-16 17:34:43.300452",
2026-02-16 17:34:43.605351 | controller |   "msg": "non-zero return code",
2026-02-16 17:34:43.605402 | controller |   "rc": 127,
2026-02-16 17:34:43.605442 | controller |   "start": "2026-02-16 17:34:42.806792"
2026-02-16 17:34:43.605479 | controller | }
2026-02-16 17:34:43.605530 | controller | ERROR: Ignoring Errors
2026-02-16 17:34:43.617688 | 
2026-02-16 17:34:43.617762 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:34:43.857616 | controller | changed
2026-02-16 17:34:43.863162 | 
2026-02-16 17:34:43.863227 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:34:47.597610 | controller | Output suppressed because no_log was given
2026-02-16 17:34:47.605211 | 
2026-02-16 17:34:47.605277 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:34:47.638732 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.639272 | 
2026-02-16 17:34:47.641365 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.643062 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.644847 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.646807 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.648729 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.651042 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.653412 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.655703 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.658084 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.660647 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.662900 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.665261 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.667451 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.669727 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.671983 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.674197 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.677259 | controller | skipping: Conditional result was False
2026-02-16 17:34:47.701370 | 
2026-02-16 17:34:47.701470 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:34:47.937010 | controller | ok
2026-02-16 17:34:47.942291 | 
2026-02-16 17:34:47.942352 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:34:48.517368 | controller | skipping: Conditional result was False
2026-02-16 17:34:48.532187 | 
2026-02-16 17:34:48.532274 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:34:48.805157 | controller | sudo: iptables-save: command not found
2026-02-16 17:34:48.865868 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:34:49.938297 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:34:50.075612 | controller | ok: Runtime: 0:00:01.160121
2026-02-16 17:34:50.088506 | 
2026-02-16 17:34:50.088577 | LOOP [stage-output : Register sources]
2026-02-16 17:34:59.611100 | controller | Output suppressed because no_log was given
2026-02-16 17:34:59.678204 | 
2026-02-16 17:34:59.678424 | TASK [stage-output : Check sudo]
2026-02-16 17:35:00.218371 | controller | ok: Runtime: 0:00:00.018133
2026-02-16 17:35:00.226266 | 
2026-02-16 17:35:00.226351 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:35:00.323581 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.324061 | 
2026-02-16 17:35:00.326056 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.326703 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.328103 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.364153 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.364589 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.364922 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.365930 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.368161 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.370301 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.372358 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.375484 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.377532 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.379896 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.398495 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.399358 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.399753 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.400143 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.400487 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.400894 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.403016 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.405236 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.407462 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.433159 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.433579 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.505312 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.516229 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.527081 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.539250 | controller | Output suppressed because no_log was given
2026-02-16 17:35:00.551876 | 
2026-02-16 17:35:00.551941 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:35:00.619215 | controller | ok
2026-02-16 17:35:00.626734 | 
2026-02-16 17:35:00.626797 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:35:00.879296 | controller | changed: "docs"
2026-02-16 17:35:01.093153 | controller | changed: "artifacts"
2026-02-16 17:35:01.307394 | controller | ok: "logs"
2026-02-16 17:35:01.320792 | 
2026-02-16 17:35:01.320952 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:35:01.607199 | controller | ok: Item: Runtime: 0:00:00.010643
2026-02-16 17:35:01.607448 | controller | changed: All items complete
2026-02-16 17:35:01.607475 | 
2026-02-16 17:35:01.821827 | controller | ok: Item: Runtime: 0:00:00.008484
2026-02-16 17:35:02.047728 | controller | ok: Item: Runtime: 0:00:00.009550
2026-02-16 17:35:02.283549 | controller | ok: Item: Runtime: 0:00:00.009664
2026-02-16 17:35:02.507831 | controller | ok: Item: Runtime: 0:00:00.007523
2026-02-16 17:35:02.734658 | controller | ok: Item: Runtime: 0:00:00.007993
2026-02-16 17:35:02.963129 | controller | ok: Item: Runtime: 0:00:00.008231
2026-02-16 17:35:03.186710 | controller | ok: Item: Runtime: 0:00:00.009905
2026-02-16 17:35:03.415603 | controller | ok: Item: Runtime: 0:00:00.008958
2026-02-16 17:35:03.644004 | controller | ok: Item: Runtime: 0:00:00.009424
2026-02-16 17:35:03.877409 | controller | ok: Item: Runtime: 0:00:00.008121
2026-02-16 17:35:04.146769 | controller | ok: Item: Runtime: 0:00:00.006978
2026-02-16 17:35:04.339442 | controller | ok: Item: Runtime: 0:00:00.009517
2026-02-16 17:35:04.556584 | controller | ok: Item: Runtime: 0:00:00.007514
2026-02-16 17:35:04.790358 | controller | ok: Item: Runtime: 0:00:00.009655
2026-02-16 17:35:05.021383 | controller | ok: Item: Runtime: 0:00:00.008548
2026-02-16 17:35:05.052897 | 
2026-02-16 17:35:05.053210 | TASK [stage-output : Make all log files readable]
2026-02-16 17:35:05.302245 | controller | changed
2026-02-16 17:35:05.309205 | 
2026-02-16 17:35:05.309272 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:35:05.650226 | controller | changed: Renamed files for staging.
2026-02-16 17:35:05.655791 | 
2026-02-16 17:35:05.655857 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:35:05.681125 | controller | skipping: Conditional result was False
2026-02-16 17:35:05.688252 | 
2026-02-16 17:35:05.688318 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:35:05.728397 | 
2026-02-16 17:35:05.728523 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:35:06.371206 | controller | changed:
2026-02-16 17:35:06.371447 | controller | created directory /var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/work/logs/controller
2026-02-16 17:35:06.371485 | controller | cd+++++++++ logs/
2026-02-16 17:35:06.371514 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:35:06.371541 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:35:06.371567 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:35:06.371591 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:35:06.371616 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:35:06.371640 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:35:06.371664 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:35:06.371688 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:35:06.371712 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:35:06.371736 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:35:06.371760 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:35:06.371784 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:35:06.371820 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:35:06.371864 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:35:06.371890 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:35:06.371914 | controller | cd+++++++++ logs/apache/
2026-02-16 17:35:06.371938 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:35:06.371961 | controller | cd+++++++++ logs/etc/
2026-02-16 17:35:06.371985 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:35:06.372008 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:35:06.372032 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:35:06.372056 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:35:06.372080 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:35:06.372104 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:35:06.385523 | 
2026-02-16 17:35:06.385591 | TASK [Check if a tempest log exits]
2026-02-16 17:35:06.634428 | controller | ok
2026-02-16 17:35:06.641344 | 
2026-02-16 17:35:06.641411 | TASK [Link post-devstack tempest.log]
2026-02-16 17:35:06.666322 | controller | skipping: Conditional result was False
2026-02-16 17:35:06.677749 | 
2026-02-16 17:35:06.677816 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:35:06.930643 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:35:07.220072 | controller | ERROR
2026-02-16 17:35:07.220351 | controller | {
2026-02-16 17:35:07.220395 | controller |   "delta": "0:00:00.010271",
2026-02-16 17:35:07.220423 | controller |   "end": "2026-02-16 17:35:06.931369",
2026-02-16 17:35:07.220449 | controller |   "msg": "non-zero return code",
2026-02-16 17:35:07.220473 | controller |   "rc": 127,
2026-02-16 17:35:07.220497 | controller |   "start": "2026-02-16 17:35:06.921098"
2026-02-16 17:35:07.220520 | controller | }
2026-02-16 17:35:07.220552 | controller | ERROR: Ignoring Errors
2026-02-16 17:35:07.222629 | 
2026-02-16 17:35:07.222693 | PLAY RECAP
2026-02-16 17:35:07.222753 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:35:07.222782 | 
2026-02-16 17:35:07.428805 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:35:07.439281 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:35:08.037772 | 
2026-02-16 17:35:08.037917 | PLAY [all]
2026-02-16 17:35:08.049875 | 
2026-02-16 17:35:08.049950 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:35:08.095383 | controller | skipping: Conditional result was False
2026-02-16 17:35:08.105578 | 
2026-02-16 17:35:08.105658 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:35:08.154729 | controller | ok
2026-02-16 17:35:08.165974 | 
2026-02-16 17:35:08.166051 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:35:08.560858 | controller -> localhost | ok: "/var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/work/logs"
2026-02-16 17:35:08.795253 | controller -> localhost | changed: "/var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/work/artifacts"
2026-02-16 17:35:09.009350 | controller -> localhost | changed: "/var/lib/zuul/builds/be77247762b74190a9e94ebdaa73ab46/work/docs"
2026-02-16 17:35:09.022954 | 
2026-02-16 17:35:09.023028 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:35:09.669032 | controller | changed: .d..t...... ./
2026-02-16 17:35:09.669333 | controller | changed: All items complete
2026-02-16 17:35:09.669374 | 
2026-02-16 17:35:10.139199 | controller | changed: .d..t...... ./
2026-02-16 17:35:10.633002 | controller | changed: .d..t...... ./
2026-02-16 17:35:10.648590 | 
2026-02-16 17:35:10.648718 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:35:11.055446 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007241
2026-02-16 17:35:11.267051 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007268
2026-02-16 17:35:11.288473 | 
2026-02-16 17:35:11.288607 | PLAY [all]
2026-02-16 17:35:11.296217 | 
2026-02-16 17:35:11.296284 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:35:11.779123 | controller | changed
2026-02-16 17:35:11.787286 | 
2026-02-16 17:35:11.787337 | PLAY RECAP
2026-02-16 17:35:11.787387 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:35:11.787408 | 
2026-02-16 17:35:11.922632 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:35:11.930739 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:35:12.526715 | 
2026-02-16 17:35:12.526854 | PLAY [localhost]
2026-02-16 17:35:12.537947 | 
2026-02-16 17:35:12.538064 | TASK [Generate Zuul manifest]
2026-02-16 17:35:12.559808 | localhost | ok
2026-02-16 17:35:12.577891 | 
2026-02-16 17:35:12.577971 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:35:12.954275 | localhost | changed
2026-02-16 17:35:12.968623 | 
2026-02-16 17:35:12.968701 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:35:13.000916 | localhost | ok
2026-02-16 17:35:13.009378 | 
2026-02-16 17:35:13.009442 | TASK [Upload logs]
2026-02-16 17:35:13.031725 | localhost | ok
2026-02-16 17:35:13.093500 | 
2026-02-16 17:35:13.093637 | TASK [Set zuul-log-path fact]
2026-02-16 17:35:13.116657 | localhost | ok
2026-02-16 17:35:13.129863 | 
2026-02-16 17:35:13.129948 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:35:13.162640 | localhost | ok
2026-02-16 17:35:13.170943 | 
2026-02-16 17:35:13.171010 | TASK [upload-logs : Create log directories]
2026-02-16 17:35:13.600616 | localhost | changed
2026-02-16 17:35:13.606003 | 
2026-02-16 17:35:13.606078 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:35:14.039353 | localhost -> localhost | ok: Runtime: 0:00:00.005757
2026-02-16 17:35:14.044605 | 
2026-02-16 17:35:14.044696 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:35:14.473152 | localhost | Output suppressed because no_log was given
2026-02-16 17:35:14.476942 | 
2026-02-16 17:35:14.477038 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:35:14.520391 | localhost | skipping: Conditional result was False
2026-02-16 17:35:14.526557 | localhost | skipping: Conditional result was False
2026-02-16 17:35:14.539499 | 
2026-02-16 17:35:14.539639 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:35:14.580914 | localhost | skipping: Conditional result was False
2026-02-16 17:35:14.581232 | 
2026-02-16 17:35:14.584496 | localhost | skipping: Conditional result was False
2026-02-16 17:35:14.591857 | 
2026-02-16 17:35:14.592023 | LOOP [upload-logs : Upload console log and json output]