2026-02-16 17:28:24.440462 | Job console starting
2026-02-16 17:28:24.470320 | Updating git repos
2026-02-16 17:28:27.637500 | Cloning repos into workspace
2026-02-16 17:28:33.506592 | Restoring repo states
2026-02-16 17:28:36.137289 | Merging changes
2026-02-16 17:28:36.587156 | Checking out repos
2026-02-16 17:28:41.434780 | Preparing playbooks
2026-02-16 17:28:44.312021 | Running Ansible setup
2026-02-16 17:28:48.059330 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:28:48.684663 | 
2026-02-16 17:28:48.684807 | PLAY [localhost]
2026-02-16 17:28:48.692464 | 
2026-02-16 17:28:48.692533 | TASK [Gathering Facts]
2026-02-16 17:28:49.580003 | localhost | ok
2026-02-16 17:28:49.589991 | 
2026-02-16 17:28:49.590102 | TASK [Setup log path fact]
2026-02-16 17:28:49.613762 | localhost | ok
2026-02-16 17:28:49.624002 | 
2026-02-16 17:28:49.624076 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:28:49.655345 | localhost | ok
2026-02-16 17:28:49.663839 | 
2026-02-16 17:28:49.663929 | TASK [emit-job-header : Print job information]
2026-02-16 17:28:49.706854 | # Job Information
2026-02-16 17:28:49.707007 | Ansible Version: 2.16.15
2026-02-16 17:28:49.707052 | Job: magnum-cluster-api-sonobuoy-v1.35.1-calico
2026-02-16 17:28:49.707083 | Pipeline: check
2026-02-16 17:28:49.707112 | Executor: 3a2793d2bd32
2026-02-16 17:28:49.707140 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:28:49.707171 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:28:49.710744 | 
2026-02-16 17:28:49.710829 | LOOP [emit-job-header : Print node information]
2026-02-16 17:28:49.810215 | localhost | ok:
2026-02-16 17:28:49.811518 | localhost | # Node Information
2026-02-16 17:28:49.811602 | localhost | Inventory Hostname: controller
2026-02-16 17:28:49.811656 | localhost | Hostname: np0000155605
2026-02-16 17:28:49.811702 | localhost | Username: zuul
2026-02-16 17:28:49.811753 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:28:49.811798 | localhost | Provider: yul1
2026-02-16 17:28:49.811840 | localhost | Region: ca-ymq-1
2026-02-16 17:28:49.811882 | localhost | Label: ubuntu-noble-16
2026-02-16 17:28:49.811923 | localhost | Product Name: OpenStack Nova
2026-02-16 17:28:49.811964 | localhost | Interface IP: 199.204.45.141
2026-02-16 17:28:49.829914 | 
2026-02-16 17:28:49.830032 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:28:50.210191 | localhost -> localhost | changed
2026-02-16 17:28:50.218978 | 
2026-02-16 17:28:50.219094 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:28:51.029482 | localhost -> localhost | changed
2026-02-16 17:28:51.042158 | 
2026-02-16 17:28:51.042217 | PLAY [all]
2026-02-16 17:28:51.051035 | 
2026-02-16 17:28:51.051102 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:28:51.268747 | controller -> localhost | ok
2026-02-16 17:28:51.283533 | 
2026-02-16 17:28:51.283881 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:28:51.320003 | controller | ok
2026-02-16 17:28:51.337557 | controller | included: /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:28:51.344930 | 
2026-02-16 17:28:51.345024 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:28:52.098308 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:28:52.098572 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/work/daade4f675cf4802ba2c6225a040d301_id_rsa
2026-02-16 17:28:52.098612 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/work/daade4f675cf4802ba2c6225a040d301_id_rsa.pub
2026-02-16 17:28:52.098643 | controller -> localhost | The key fingerprint is:
2026-02-16 17:28:52.098672 | controller -> localhost | SHA256:WDQfvcT5fCrvBiRUYcUZeEJn5r5KMY7VBTKHZCx76fI zuul-build-sshkey
2026-02-16 17:28:52.098721 | controller -> localhost | The key's randomart image is:
2026-02-16 17:28:52.098751 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:28:52.098785 | controller -> localhost | |        o .BXBOo |
2026-02-16 17:28:52.098816 | controller -> localhost | |       . oo+XO+. |
2026-02-16 17:28:52.098845 | controller -> localhost | |        ...+ B. .|
2026-02-16 17:28:52.098873 | controller -> localhost | |       o  o =oo..|
2026-02-16 17:28:52.098901 | controller -> localhost | |      . S  =+ oo |
2026-02-16 17:28:52.098928 | controller -> localhost | |          .++o.. |
2026-02-16 17:28:52.098955 | controller -> localhost | |          .oo+.  |
2026-02-16 17:28:52.098982 | controller -> localhost | |           .E.o  |
2026-02-16 17:28:52.099012 | controller -> localhost | |            .o.  |
2026-02-16 17:28:52.099039 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:28:52.099102 | controller -> localhost | ok: Runtime: 0:00:00.345763
2026-02-16 17:28:52.106589 | 
2026-02-16 17:28:52.106653 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:28:52.144322 | controller | ok
2026-02-16 17:28:52.154017 | controller | included: /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:28:52.161934 | 
2026-02-16 17:28:52.161996 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:28:52.186973 | controller | skipping: Conditional result was False
2026-02-16 17:28:52.198544 | 
2026-02-16 17:28:52.198628 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:28:52.726496 | controller | changed
2026-02-16 17:28:52.735686 | 
2026-02-16 17:28:52.735772 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:28:52.965016 | controller | ok
2026-02-16 17:28:52.970728 | 
2026-02-16 17:28:52.970798 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:28:53.536129 | controller | changed
2026-02-16 17:28:53.543639 | 
2026-02-16 17:28:53.543707 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:28:54.104196 | controller | changed
2026-02-16 17:28:54.114230 | 
2026-02-16 17:28:54.114325 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:28:54.140216 | controller | skipping: Conditional result was False
2026-02-16 17:28:54.152690 | 
2026-02-16 17:28:54.152902 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:28:54.525039 | controller -> localhost | changed
2026-02-16 17:28:54.543340 | 
2026-02-16 17:28:54.543513 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:28:54.912932 | controller -> localhost | Identity added: /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/work/daade4f675cf4802ba2c6225a040d301_id_rsa (zuul-build-sshkey)
2026-02-16 17:28:54.913220 | controller -> localhost | ok: Runtime: 0:00:00.015549
2026-02-16 17:28:54.958329 | 
2026-02-16 17:28:54.958487 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:28:55.257060 | controller | ok
2026-02-16 17:28:55.264400 | 
2026-02-16 17:28:55.264486 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:28:55.289092 | controller | skipping: Conditional result was False
2026-02-16 17:28:55.306525 | 
2026-02-16 17:28:55.306610 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:28:55.648020 | controller | ok
2026-02-16 17:28:55.656213 | 
2026-02-16 17:28:55.656281 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:29:15.506918 | controller | Output suppressed because no_log was given
2026-02-16 17:29:15.517470 | 
2026-02-16 17:29:15.517535 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:29:15.776193 | controller | ok: "logs"
2026-02-16 17:29:15.776514 | controller | ok: All items complete
2026-02-16 17:29:15.776548 | 
2026-02-16 17:29:15.950513 | controller | ok: "artifacts"
2026-02-16 17:29:16.139252 | controller | ok: "docs"
2026-02-16 17:29:16.158274 | 
2026-02-16 17:29:16.158473 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:29:16.367773 | controller | changed: "logs"
2026-02-16 17:29:16.573758 | controller | changed: "artifacts"
2026-02-16 17:29:16.817779 | controller | changed: "docs"
2026-02-16 17:29:16.839010 | 
2026-02-16 17:29:16.839145 | PLAY RECAP
2026-02-16 17:29:16.839197 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:29:16.839227 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:29:16.839250 | 
2026-02-16 17:29:17.036046 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:29:17.044774 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:29:17.684659 | 
2026-02-16 17:29:17.684799 | PLAY [all]
2026-02-16 17:29:17.702295 | 
2026-02-16 17:29:17.702374 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:29:18.062217 | controller | changed
2026-02-16 17:29:18.070836 | 
2026-02-16 17:29:18.070924 | TASK [Gather minimum local MTU]
2026-02-16 17:29:18.148485 | controller | ok
2026-02-16 17:29:18.486381 | 
2026-02-16 17:29:18.486552 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:29:18.538703 | controller | ok
2026-02-16 17:29:18.544168 | 
2026-02-16 17:29:18.544240 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:29:18.570712 | controller | skipping: Conditional result was False
2026-02-16 17:29:18.579000 | 
2026-02-16 17:29:18.579068 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:29:19.134574 | controller | ok: Runtime: 0:00:00.010804
2026-02-16 17:29:19.141307 | 
2026-02-16 17:29:19.141378 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:29:19.168019 | controller | skipping: Conditional result was False
2026-02-16 17:29:19.176863 | 
2026-02-16 17:29:19.176943 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:29:19.202438 | controller | skipping: Conditional result was False
2026-02-16 17:29:19.212618 | 
2026-02-16 17:29:19.212709 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:29:19.268916 | controller | ok
2026-02-16 17:29:19.279596 | controller | included: /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:29:19.285950 | 
2026-02-16 17:29:19.286013 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:29:19.344680 | controller | ok
2026-02-16 17:29:19.350569 | 
2026-02-16 17:29:19.350634 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:29:19.625730 | controller | ext4
2026-02-16 17:29:19.929995 | controller | ok: Runtime: 0:00:00.012241
2026-02-16 17:29:19.935427 | 
2026-02-16 17:29:19.935490 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:29:19.969338 | controller | ok
2026-02-16 17:29:19.975885 | 
2026-02-16 17:29:19.975956 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:29:20.011058 | controller | ok:
2026-02-16 17:29:20.011216 | controller | {
2026-02-16 17:29:20.011250 | controller |   "root_filesystem": "ext4"
2026-02-16 17:29:20.011277 | controller | }
2026-02-16 17:29:20.018756 | 
2026-02-16 17:29:20.018818 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:29:30.412795 | controller | 8192+0 records in
2026-02-16 17:29:30.412933 | controller | 8192+0 records out
2026-02-16 17:29:30.412954 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.1193 s, 849 MB/s
2026-02-16 17:29:30.600860 | controller | ok: Runtime: 0:00:10.128063
2026-02-16 17:29:30.608336 | 
2026-02-16 17:29:30.608403 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:29:30.848631 | controller | changed
2026-02-16 17:29:30.855339 | 
2026-02-16 17:29:30.855404 | TASK [configure-swap : Make swapfile]
2026-02-16 17:29:57.411553 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:29:57.411756 | controller | no label, UUID=94514785-7f67-409a-b262-70ee1118743c
2026-02-16 17:29:57.466285 | controller | ok: Runtime: 0:00:26.329073
2026-02-16 17:29:57.471949 | 
2026-02-16 17:29:57.472023 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:29:57.874238 | controller | changed
2026-02-16 17:29:57.879842 | 
2026-02-16 17:29:57.879909 | TASK [configure-swap : Add all swap]
2026-02-16 17:29:58.421566 | controller | ok: Runtime: 0:00:00.083896
2026-02-16 17:29:58.427382 | 
2026-02-16 17:29:58.427448 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:29:58.462722 | controller | ok:
2026-02-16 17:29:58.462870 | controller | {
2026-02-16 17:29:58.462904 | controller |   "swap_required": "8192"
2026-02-16 17:29:58.462927 | controller | }
2026-02-16 17:29:58.468572 | 
2026-02-16 17:29:58.468652 | TASK [configure-swap : Set swappiness]
2026-02-16 17:29:58.938820 | controller | changed
2026-02-16 17:29:58.945360 | 
2026-02-16 17:29:58.945423 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:29:58.980546 | controller | ok:
2026-02-16 17:29:58.980760 | controller | {
2026-02-16 17:29:58.980815 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:29:58.980912 | controller | }
2026-02-16 17:29:58.990784 | 
2026-02-16 17:29:58.990844 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:29:59.886833 | controller | changed
2026-02-16 17:29:59.893387 | 
2026-02-16 17:29:59.893461 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:30:00.142186 | controller | changed
2026-02-16 17:30:00.147941 | 
2026-02-16 17:30:00.148005 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:30:00.852914 | controller | changed
2026-02-16 17:30:00.860941 | 
2026-02-16 17:30:00.861101 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:30:01.114714 | controller | changed
2026-02-16 17:30:01.121063 | 
2026-02-16 17:30:01.121133 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:30:02.264485 | controller | changed
2026-02-16 17:30:02.272038 | 
2026-02-16 17:30:02.272122 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:30:02.517385 | controller | changed
2026-02-16 17:30:02.529227 | 
2026-02-16 17:30:02.529290 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:30:02.814887 | controller | changed
2026-02-16 17:30:02.822495 | 
2026-02-16 17:30:02.822593 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:30:03.195398 | controller | changed
2026-02-16 17:30:03.244543 | 
2026-02-16 17:30:03.244699 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:30:04.028544 | controller | changed
2026-02-16 17:30:04.040763 | 
2026-02-16 17:30:04.040829 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:30:04.382505 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:30:04.394614 | 
2026-02-16 17:30:04.394680 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:30:04.875312 | controller | ok: Item: Runtime: 0:00:00.181143
2026-02-16 17:30:05.450313 | controller | ok: Item: Runtime: 0:00:00.352749
2026-02-16 17:30:06.174911 | controller | ok: Item: Runtime: 0:00:00.500355
2026-02-16 17:30:06.468230 | controller | ok: Item: Runtime: 0:00:00.060674
2026-02-16 17:30:06.940516 | controller | ok: Item: Runtime: 0:00:00.255528
2026-02-16 17:30:08.538476 | controller | ok: Item: Runtime: 0:00:01.368634
2026-02-16 17:30:11.419836 | controller | ok: Item: Runtime: 0:00:02.659405
2026-02-16 17:30:11.740467 | controller | ok: Item: Runtime: 0:00:00.094435
2026-02-16 17:30:12.157790 | controller | ok: Item: Runtime: 0:00:00.189625
2026-02-16 17:30:12.503407 | controller | ok: Item: Runtime: 0:00:00.119744
2026-02-16 17:30:13.276378 | controller | ok: Item: Runtime: 0:00:00.554901
2026-02-16 17:30:14.011135 | controller | ok: Item: Runtime: 0:00:00.515197
2026-02-16 17:30:14.460110 | controller | ok: Item: Runtime: 0:00:00.232256
2026-02-16 17:30:14.914693 | controller | ok: Item: Runtime: 0:00:00.219583
2026-02-16 17:30:15.531763 | controller | ok: Item: Runtime: 0:00:00.388626
2026-02-16 17:30:16.836814 | controller | ok: Item: Runtime: 0:00:01.096713
2026-02-16 17:30:16.869934 | 
2026-02-16 17:30:16.870124 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:30:17.088363 | controller | ok: All paths examined
2026-02-16 17:30:17.097388 | 
2026-02-16 17:30:17.097506 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:30:17.361791 | controller | ok: All paths examined
2026-02-16 17:30:17.367866 | 
2026-02-16 17:30:17.367934 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:30:17.798611 | controller | ok: Item: Runtime: 0:00:00.146103
2026-02-16 17:30:17.799015 | controller | changed: All items complete
2026-02-16 17:30:17.799055 | 
2026-02-16 17:30:18.202932 | controller | ok: Item: Runtime: 0:00:00.190463
2026-02-16 17:30:18.214262 | 
2026-02-16 17:30:18.214440 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:30:18.257290 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.260523 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.262552 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.265129 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.268120 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.270847 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.273879 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.276918 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.280074 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.286452 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.293782 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.299839 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.302977 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.306093 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.308873 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.312181 | controller | skipping: Conditional result was False
2026-02-16 17:30:18.339342 | 
2026-02-16 17:30:18.339484 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:30:20.714489 | controller | changed
2026-02-16 17:30:20.725157 | 
2026-02-16 17:30:20.725225 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:30:20.974991 | controller | changed
2026-02-16 17:30:21.033995 | 
2026-02-16 17:30:21.034136 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:30:21.281421 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:30:21.573893 | controller | ERROR
2026-02-16 17:30:21.574128 | controller | {
2026-02-16 17:30:21.574169 | controller |   "delta": "0:00:00.012478",
2026-02-16 17:30:21.574198 | controller |   "end": "2026-02-16 17:30:21.282157",
2026-02-16 17:30:21.574224 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:21.574252 | controller |   "rc": 1,
2026-02-16 17:30:21.574280 | controller |   "start": "2026-02-16 17:30:21.269679"
2026-02-16 17:30:21.574305 | controller | }
2026-02-16 17:30:21.574340 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:21.581839 | 
2026-02-16 17:30:21.581928 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:30:21.830593 | controller | ok
2026-02-16 17:30:21.841341 | 
2026-02-16 17:30:21.841407 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:30:22.073677 | controller | /usr/bin/journalctl
2026-02-16 17:30:22.381855 | controller | ok: Runtime: 0:00:00.007102
2026-02-16 17:30:22.387491 | 
2026-02-16 17:30:22.387559 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:30:22.643485 | controller | 2026-02-16 17:30:22
2026-02-16 17:30:22.940344 | controller | ok: Runtime: 0:00:00.009518
2026-02-16 17:30:22.948242 | 
2026-02-16 17:30:22.948372 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:30:23.521675 | controller | changed
2026-02-16 17:30:23.527737 | 
2026-02-16 17:30:23.527798 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:30:23.552422 | controller | skipping: Conditional result was False
2026-02-16 17:30:23.558755 | 
2026-02-16 17:30:23.558817 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:30:24.091300 | controller | skipping: Conditional result was False
2026-02-16 17:30:24.097785 | 
2026-02-16 17:30:24.097849 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:30:24.625622 | controller | skipping: Conditional result was False
2026-02-16 17:30:24.640326 | 
2026-02-16 17:30:24.640426 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:30:24.666502 | controller | skipping: Conditional result was False
2026-02-16 17:30:24.678042 | 
2026-02-16 17:30:24.678109 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:30:25.208768 | controller | skipping: Conditional result was False
2026-02-16 17:30:25.219219 | 
2026-02-16 17:30:25.219298 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:30:25.753144 | controller | skipping: Conditional result was False
2026-02-16 17:30:25.765523 | 
2026-02-16 17:30:25.765594 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:30:25.791360 | controller | skipping: Conditional result was False
2026-02-16 17:30:25.801513 | 
2026-02-16 17:30:25.801644 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:30:25.825689 | controller | skipping: Conditional result was False
2026-02-16 17:30:25.837369 | 
2026-02-16 17:30:25.837440 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:30:26.429710 | controller | skipping: Conditional result was False
2026-02-16 17:30:26.441626 | 
2026-02-16 17:30:26.441699 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:30:26.972543 | controller | skipping: Conditional result was False
2026-02-16 17:30:26.984379 | 
2026-02-16 17:30:26.984486 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:30:27.010418 | controller | skipping: Conditional result was False
2026-02-16 17:30:27.022018 | 
2026-02-16 17:30:27.022105 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:30:27.047810 | controller | skipping: Conditional result was False
2026-02-16 17:30:27.064802 | 
2026-02-16 17:30:27.064868 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:30:27.503448 | controller | ok
2026-02-16 17:30:27.513608 | 
2026-02-16 17:30:27.513665 | PLAY RECAP
2026-02-16 17:30:27.513716 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:30:27.513739 | 
2026-02-16 17:30:27.657462 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:30:27.664107 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:30:28.285954 | 
2026-02-16 17:30:28.286099 | PLAY [all]
2026-02-16 17:30:28.298566 | 
2026-02-16 17:30:28.298648 | TASK [ensure-rust : Use rustup]
2026-02-16 17:30:28.332005 | controller | ok
2026-02-16 17:30:28.340992 | controller | included: /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:30:28.346684 | 
2026-02-16 17:30:28.346756 | TASK [ensure-rust : Install Rust]
2026-02-16 17:30:28.767215 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:30:28.885919 | controller | ERROR
2026-02-16 17:30:28.886161 | controller | {
2026-02-16 17:30:28.886203 | controller |   "delta": "0:00:00.022416",
2026-02-16 17:30:28.886235 | controller |   "end": "2026-02-16 17:30:28.770890",
2026-02-16 17:30:28.886262 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:28.886287 | controller |   "rc": 127,
2026-02-16 17:30:28.886313 | controller |   "start": "2026-02-16 17:30:28.748474"
2026-02-16 17:30:28.886344 | controller | }
failure
2026-02-16 17:30:28.891131 | 
2026-02-16 17:30:28.891344 | PLAY RECAP
2026-02-16 17:30:28.891445 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:30:28.891494 | 
2026-02-16 17:30:29.033295 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:30:29.043593 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:30:29.670513 | 
2026-02-16 17:30:29.671208 | PLAY [all]
2026-02-16 17:30:29.688282 | 
2026-02-16 17:30:29.688426 | TASK [Return built artifacts to Zuul]
2026-02-16 17:30:29.722690 | controller | ok
2026-02-16 17:30:29.730012 | 
2026-02-16 17:30:29.730112 | TASK [Get the Docker volume ID]
2026-02-16 17:30:30.272186 | controller | ERROR
2026-02-16 17:30:30.272511 | controller | {
2026-02-16 17:30:30.272543 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:30:30.272566 | controller |   "rc": 2
2026-02-16 17:30:30.272586 | controller | }
2026-02-16 17:30:30.272611 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:30.279039 | 
2026-02-16 17:30:30.279110 | TASK [Copy all of the pod logs]
2026-02-16 17:30:30.314462 | controller | ERROR
2026-02-16 17:30:30.314726 | controller | {
2026-02-16 17:30:30.314769 | 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/daade4f675cf4802ba2c6225a040d301/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:30:30.314800 | controller | }
2026-02-16 17:30:30.314861 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:30.316283 | 
2026-02-16 17:30:30.316347 | PLAY RECAP
2026-02-16 17:30:30.316409 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:30:30.316439 | 
2026-02-16 17:30:30.486173 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:30:30.496508 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:30:31.129695 | 
2026-02-16 17:30:31.129886 | PLAY [all]
2026-02-16 17:30:31.155817 | 
2026-02-16 17:30:31.156065 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:30:31.557491 | controller | changed
2026-02-16 17:30:31.568117 | 
2026-02-16 17:30:31.568219 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:30:32.607596 | controller | ok: Runtime: 0:00:00.454976
2026-02-16 17:30:32.615279 | 
2026-02-16 17:30:32.615351 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:30:33.155085 | controller | ok: Runtime: 0:00:00.019636
2026-02-16 17:30:33.162973 | 
2026-02-16 17:30:33.163050 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:30:33.703501 | controller | ok: Runtime: 0:00:00.026753
2026-02-16 17:30:33.709860 | 
2026-02-16 17:30:33.709941 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:30:34.575927 | controller | changed
2026-02-16 17:30:34.625815 | 
2026-02-16 17:30:34.625979 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:30:34.876647 | controller | changed
2026-02-16 17:30:34.884996 | 
2026-02-16 17:30:34.885109 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:30:35.204326 | controller | Output suppressed because no_log was given
2026-02-16 17:30:35.211626 | 
2026-02-16 17:30:35.211718 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:30:35.248001 | 
2026-02-16 17:30:35.248167 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:30:35.273869 | 
2026-02-16 17:30:35.274031 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:30:35.306787 | 
2026-02-16 17:30:35.306952 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:30:35.340398 | 
2026-02-16 17:30:35.340568 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:30:35.379695 | 
2026-02-16 17:30:35.380034 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:30:35.649924 | controller | changed
2026-02-16 17:30:35.655791 | 
2026-02-16 17:30:35.655861 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:30:35.691566 | controller | ok
2026-02-16 17:30:35.696778 | 
2026-02-16 17:30:35.696841 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:30:35.943545 | controller | Output suppressed because no_log was given
2026-02-16 17:30:35.950842 | 
2026-02-16 17:30:35.950916 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:30:35.991072 | 
2026-02-16 17:30:35.991213 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:30:36.036315 | 
2026-02-16 17:30:36.036491 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:30:36.688198 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:30:36.705767 | controller | Using python 3.12 to install setuptools
2026-02-16 17:30:36.743049 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:30:36.753069 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:30:37.078085 | controller | ERROR
2026-02-16 17:30:37.078349 | controller | {
2026-02-16 17:30:37.078395 | controller |   "delta": "0:00:00.469608",
2026-02-16 17:30:37.078426 | controller |   "end": "2026-02-16 17:30:36.754943",
2026-02-16 17:30:37.078453 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:37.078486 | controller |   "rc": 127,
2026-02-16 17:30:37.078513 | controller |   "start": "2026-02-16 17:30:36.285335"
2026-02-16 17:30:37.078539 | controller | }
2026-02-16 17:30:37.078573 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:37.091885 | 
2026-02-16 17:30:37.091958 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:30:37.321484 | controller | changed
2026-02-16 17:30:37.330801 | 
2026-02-16 17:30:37.330878 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:30:41.141588 | controller | Output suppressed because no_log was given
2026-02-16 17:30:41.157179 | 
2026-02-16 17:30:41.157322 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:30:41.191472 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.191965 | 
2026-02-16 17:30:41.193962 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.195615 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.197591 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.199464 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.201831 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.204161 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.206540 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.208862 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.211249 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.213734 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.216010 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.218320 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.220635 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.222965 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.225210 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.227495 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.230531 | controller | skipping: Conditional result was False
2026-02-16 17:30:41.262855 | 
2026-02-16 17:30:41.263023 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:30:41.502610 | controller | ok
2026-02-16 17:30:41.510206 | 
2026-02-16 17:30:41.510281 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:30:42.096534 | controller | skipping: Conditional result was False
2026-02-16 17:30:42.113576 | 
2026-02-16 17:30:42.113672 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:30:42.396208 | controller | sudo: iptables-save: command not found
2026-02-16 17:30:42.436174 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:30:43.549900 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:30:43.653016 | controller | ok: Runtime: 0:00:01.176592
2026-02-16 17:30:43.666055 | 
2026-02-16 17:30:43.666133 | LOOP [stage-output : Register sources]
2026-02-16 17:30:53.055836 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.110799 | 
2026-02-16 17:30:53.110944 | TASK [stage-output : Check sudo]
2026-02-16 17:30:53.648567 | controller | ok: Runtime: 0:00:00.021936
2026-02-16 17:30:53.656393 | 
2026-02-16 17:30:53.656485 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:30:53.762967 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.763598 | 
2026-02-16 17:30:53.765605 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.766498 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.767588 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.805304 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.805669 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.806012 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.806517 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.808758 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.810927 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.813060 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.815403 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.817703 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.819981 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.838193 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.838687 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.839156 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.839630 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.840084 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.841735 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.843839 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.846247 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.848772 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.873906 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.874435 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.948488 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.957540 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.973098 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.978645 | controller | Output suppressed because no_log was given
2026-02-16 17:30:53.992695 | 
2026-02-16 17:30:53.992768 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:30:54.059566 | controller | ok
2026-02-16 17:30:54.068895 | 
2026-02-16 17:30:54.069001 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:30:54.320334 | controller | changed: "docs"
2026-02-16 17:30:54.522774 | controller | changed: "artifacts"
2026-02-16 17:30:54.738417 | controller | ok: "logs"
2026-02-16 17:30:54.751594 | 
2026-02-16 17:30:54.751744 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:30:55.042842 | controller | ok: Item: Runtime: 0:00:00.016808
2026-02-16 17:30:55.043171 | controller | changed: All items complete
2026-02-16 17:30:55.043199 | 
2026-02-16 17:30:55.267186 | controller | ok: Item: Runtime: 0:00:00.009246
2026-02-16 17:30:55.490917 | controller | ok: Item: Runtime: 0:00:00.008023
2026-02-16 17:30:55.718120 | controller | ok: Item: Runtime: 0:00:00.009715
2026-02-16 17:30:55.935594 | controller | ok: Item: Runtime: 0:00:00.007834
2026-02-16 17:30:56.157006 | controller | ok: Item: Runtime: 0:00:00.004364
2026-02-16 17:30:56.358187 | controller | ok: Item: Runtime: 0:00:00.007073
2026-02-16 17:30:56.570857 | controller | ok: Item: Runtime: 0:00:00.007192
2026-02-16 17:30:56.817988 | controller | ok: Item: Runtime: 0:00:00.007578
2026-02-16 17:30:57.035698 | controller | ok: Item: Runtime: 0:00:00.007915
2026-02-16 17:30:57.263349 | controller | ok: Item: Runtime: 0:00:00.005977
2026-02-16 17:30:57.528341 | controller | ok: Item: Runtime: 0:00:00.005940
2026-02-16 17:30:57.711580 | controller | ok: Item: Runtime: 0:00:00.008065
2026-02-16 17:30:57.935363 | controller | ok: Item: Runtime: 0:00:00.007459
2026-02-16 17:30:58.168293 | controller | ok: Item: Runtime: 0:00:00.006520
2026-02-16 17:30:58.407728 | controller | ok: Item: Runtime: 0:00:00.010656
2026-02-16 17:30:58.432364 | 
2026-02-16 17:30:58.432524 | TASK [stage-output : Make all log files readable]
2026-02-16 17:30:58.691737 | controller | changed
2026-02-16 17:30:58.699997 | 
2026-02-16 17:30:58.700085 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:30:59.023040 | controller | changed: Renamed files for staging.
2026-02-16 17:30:59.028661 | 
2026-02-16 17:30:59.028728 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:30:59.054021 | controller | skipping: Conditional result was False
2026-02-16 17:30:59.065150 | 
2026-02-16 17:30:59.065244 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:30:59.109096 | 
2026-02-16 17:30:59.109285 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:30:59.711089 | controller | changed:
2026-02-16 17:30:59.711302 | controller | created directory /var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/work/logs/controller
2026-02-16 17:30:59.711343 | controller | cd+++++++++ logs/
2026-02-16 17:30:59.711373 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:30:59.711401 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:30:59.711427 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:30:59.711454 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:30:59.711479 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:30:59.711505 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:30:59.711529 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:30:59.711554 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:30:59.711578 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:30:59.711603 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:30:59.711627 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:30:59.711651 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:30:59.711686 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:30:59.711729 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:30:59.711755 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:30:59.711780 | controller | cd+++++++++ logs/apache/
2026-02-16 17:30:59.711804 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:30:59.711828 | controller | cd+++++++++ logs/etc/
2026-02-16 17:30:59.711852 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:30:59.711877 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:30:59.711901 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:30:59.711925 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:30:59.711949 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:30:59.711973 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:30:59.725323 | 
2026-02-16 17:30:59.725389 | TASK [Check if a tempest log exits]
2026-02-16 17:30:59.968809 | controller | ok
2026-02-16 17:30:59.976300 | 
2026-02-16 17:30:59.976394 | TASK [Link post-devstack tempest.log]
2026-02-16 17:30:59.999916 | controller | skipping: Conditional result was False
2026-02-16 17:31:00.011432 | 
2026-02-16 17:31:00.011525 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:31:00.268924 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:31:00.558335 | controller | ERROR
2026-02-16 17:31:00.559450 | controller | {
2026-02-16 17:31:00.559506 | controller |   "delta": "0:00:00.007917",
2026-02-16 17:31:00.559538 | controller |   "end": "2026-02-16 17:31:00.269581",
2026-02-16 17:31:00.559565 | controller |   "msg": "non-zero return code",
2026-02-16 17:31:00.559590 | controller |   "rc": 127,
2026-02-16 17:31:00.559614 | controller |   "start": "2026-02-16 17:31:00.261664"
2026-02-16 17:31:00.559640 | controller | }
2026-02-16 17:31:00.559674 | controller | ERROR: Ignoring Errors
2026-02-16 17:31:00.561620 | 
2026-02-16 17:31:00.561670 | PLAY RECAP
2026-02-16 17:31:00.561718 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:31:00.561739 | 
2026-02-16 17:31:00.696258 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:31:00.702624 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:31:01.314871 | 
2026-02-16 17:31:01.315014 | PLAY [all]
2026-02-16 17:31:01.328123 | 
2026-02-16 17:31:01.328204 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:31:01.373756 | controller | skipping: Conditional result was False
2026-02-16 17:31:01.382607 | 
2026-02-16 17:31:01.382713 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:31:01.428137 | controller | ok
2026-02-16 17:31:01.435097 | 
2026-02-16 17:31:01.435166 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:31:01.815384 | controller -> localhost | ok: "/var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/work/logs"
2026-02-16 17:31:02.020519 | controller -> localhost | changed: "/var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/work/artifacts"
2026-02-16 17:31:02.231040 | controller -> localhost | changed: "/var/lib/zuul/builds/daade4f675cf4802ba2c6225a040d301/work/docs"
2026-02-16 17:31:02.247658 | 
2026-02-16 17:31:02.247798 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:31:02.923608 | controller | changed: .d..t...... ./
2026-02-16 17:31:02.923895 | controller | changed: All items complete
2026-02-16 17:31:02.923940 | 
2026-02-16 17:31:03.389841 | controller | changed: .d..t...... ./
2026-02-16 17:31:03.865783 | controller | changed: .d..t...... ./
2026-02-16 17:31:03.890191 | 
2026-02-16 17:31:03.890271 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:31:04.313364 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008544
2026-02-16 17:31:04.537263 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007688
2026-02-16 17:31:04.557917 | 
2026-02-16 17:31:04.558902 | PLAY [all]
2026-02-16 17:31:04.567573 | 
2026-02-16 17:31:04.567641 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:31:05.037336 | controller | changed
2026-02-16 17:31:05.043683 | 
2026-02-16 17:31:05.043734 | PLAY RECAP
2026-02-16 17:31:05.043785 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:31:05.043807 | 
2026-02-16 17:31:05.177332 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:31:05.184728 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:31:05.762989 | 
2026-02-16 17:31:05.763138 | PLAY [localhost]
2026-02-16 17:31:05.774185 | 
2026-02-16 17:31:05.774279 | TASK [Generate Zuul manifest]
2026-02-16 17:31:05.796886 | localhost | ok
2026-02-16 17:31:05.814110 | 
2026-02-16 17:31:05.814192 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:31:06.219234 | localhost | changed
2026-02-16 17:31:06.230663 | 
2026-02-16 17:31:06.230751 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:31:06.263240 | localhost | ok
2026-02-16 17:31:06.270453 | 
2026-02-16 17:31:06.270518 | TASK [Upload logs]
2026-02-16 17:31:06.289442 | localhost | ok
2026-02-16 17:31:06.349765 | 
2026-02-16 17:31:06.349889 | TASK [Set zuul-log-path fact]
2026-02-16 17:31:06.371794 | localhost | ok
2026-02-16 17:31:06.387793 | 
2026-02-16 17:31:06.387864 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:31:06.419945 | localhost | ok
2026-02-16 17:31:06.429612 | 
2026-02-16 17:31:06.429707 | TASK [upload-logs : Create log directories]
2026-02-16 17:31:06.827071 | localhost | changed
2026-02-16 17:31:06.833730 | 
2026-02-16 17:31:06.833830 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:31:07.256106 | localhost -> localhost | ok: Runtime: 0:00:00.005416
2026-02-16 17:31:07.260852 | 
2026-02-16 17:31:07.260923 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:31:07.710318 | localhost | Output suppressed because no_log was given
2026-02-16 17:31:07.716414 | 
2026-02-16 17:31:07.716484 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:31:07.770606 | localhost | skipping: Conditional result was False
2026-02-16 17:31:07.776681 | localhost | skipping: Conditional result was False
2026-02-16 17:31:07.791397 | 
2026-02-16 17:31:07.791600 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:31:07.836037 | localhost | skipping: Conditional result was False
2026-02-16 17:31:07.836439 | 
2026-02-16 17:31:07.839204 | localhost | skipping: Conditional result was False
2026-02-16 17:31:07.857670 | 
2026-02-16 17:31:07.857803 | LOOP [upload-logs : Upload console log and json output]