2026-02-16 17:38:10.594170 | Job console starting
2026-02-16 17:38:10.860095 | Updating git repos
2026-02-16 17:38:20.886169 | Cloning repos into workspace
2026-02-16 17:38:28.448069 | Restoring repo states
2026-02-16 17:38:31.366804 | Merging changes
2026-02-16 17:38:31.993686 | Checking out repos
2026-02-16 17:38:37.695480 | Preparing playbooks
2026-02-16 17:38:42.008477 | Running Ansible setup
2026-02-16 17:38:46.283621 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:38:46.948805 | 
2026-02-16 17:38:46.948991 | PLAY [localhost]
2026-02-16 17:38:46.957188 | 
2026-02-16 17:38:46.957272 | TASK [Gathering Facts]
2026-02-16 17:38:47.917449 | localhost | ok
2026-02-16 17:38:47.934005 | 
2026-02-16 17:38:47.934105 | TASK [Setup log path fact]
2026-02-16 17:38:47.955062 | localhost | ok
2026-02-16 17:38:47.968092 | 
2026-02-16 17:38:47.968240 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:38:47.998773 | localhost | ok
2026-02-16 17:38:48.011059 | 
2026-02-16 17:38:48.011143 | TASK [emit-job-header : Print job information]
2026-02-16 17:38:48.055291 | # Job Information
2026-02-16 17:38:48.055460 | Ansible Version: 2.16.15
2026-02-16 17:38:48.055507 | Job: magnum-cluster-api-sonobuoy-v1.34.4-calico
2026-02-16 17:38:48.055539 | Pipeline: check
2026-02-16 17:38:48.055567 | Executor: 3a2793d2bd32
2026-02-16 17:38:48.055596 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:38:48.055641 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:38:48.059564 | 
2026-02-16 17:38:48.059654 | LOOP [emit-job-header : Print node information]
2026-02-16 17:38:48.188575 | localhost | ok:
2026-02-16 17:38:48.192224 | localhost | # Node Information
2026-02-16 17:38:48.192266 | localhost | Inventory Hostname: controller
2026-02-16 17:38:48.192288 | localhost | Hostname: np0000155613
2026-02-16 17:38:48.192309 | localhost | Username: zuul
2026-02-16 17:38:48.192332 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:38:48.192351 | localhost | Provider: yul1
2026-02-16 17:38:48.192369 | localhost | Region: ca-ymq-1
2026-02-16 17:38:48.192388 | localhost | Label: ubuntu-noble-16
2026-02-16 17:38:48.192407 | localhost | Product Name: OpenStack Nova
2026-02-16 17:38:48.192424 | localhost | Interface IP: 199.204.45.141
2026-02-16 17:38:48.211735 | 
2026-02-16 17:38:48.211939 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:38:48.651854 | localhost -> localhost | changed
2026-02-16 17:38:48.661012 | 
2026-02-16 17:38:48.661191 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:38:49.559514 | localhost -> localhost | changed
2026-02-16 17:38:49.569148 | 
2026-02-16 17:38:49.569231 | PLAY [all]
2026-02-16 17:38:49.584887 | 
2026-02-16 17:38:49.585086 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:38:49.814263 | controller -> localhost | ok
2026-02-16 17:38:49.825550 | 
2026-02-16 17:38:49.825679 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:38:49.869296 | controller | ok
2026-02-16 17:38:49.889616 | controller | included: /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:38:49.896652 | 
2026-02-16 17:38:49.896724 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:38:50.926246 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:38:50.926442 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/work/a789d3fc9e73493abc29d52d7400049d_id_rsa
2026-02-16 17:38:50.926472 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/work/a789d3fc9e73493abc29d52d7400049d_id_rsa.pub
2026-02-16 17:38:50.926495 | controller -> localhost | The key fingerprint is:
2026-02-16 17:38:50.926517 | controller -> localhost | SHA256:JHhQbM1El1b7Hphra7rIstQgCgcCTP8EiLss6kPojLk zuul-build-sshkey
2026-02-16 17:38:50.926556 | controller -> localhost | The key's randomart image is:
2026-02-16 17:38:50.926593 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:38:50.926621 | controller -> localhost | |=....o.=o .o.    |
2026-02-16 17:38:50.926643 | controller -> localhost | |oo. .oo o.o  .   |
2026-02-16 17:38:50.926664 | controller -> localhost | |o. ..oo ..  .    |
2026-02-16 17:38:50.926684 | controller -> localhost | |o.  o. o     +   |
2026-02-16 17:38:50.926704 | controller -> localhost | |+.. ... S   o o  |
2026-02-16 17:38:50.926723 | controller -> localhost | |+= . . o     o . |
2026-02-16 17:38:50.926743 | controller -> localhost | |B..   . .   o .  |
2026-02-16 17:38:50.926762 | controller -> localhost | |++   ... . ...   |
2026-02-16 17:38:50.926784 | controller -> localhost | |Eo.   .oo o+.    |
2026-02-16 17:38:50.926815 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:38:50.926864 | controller -> localhost | ok: Runtime: 0:00:00.599622
2026-02-16 17:38:50.941262 | 
2026-02-16 17:38:50.941639 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:38:50.971614 | controller | ok
2026-02-16 17:38:50.983422 | controller | included: /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:38:50.992351 | 
2026-02-16 17:38:50.992411 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:38:51.018985 | controller | skipping: Conditional result was False
2026-02-16 17:38:51.027811 | 
2026-02-16 17:38:51.028090 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:38:51.598488 | controller | changed
2026-02-16 17:38:51.605483 | 
2026-02-16 17:38:51.605555 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:38:51.831891 | controller | ok
2026-02-16 17:38:51.839285 | 
2026-02-16 17:38:51.839354 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:38:52.398979 | controller | changed
2026-02-16 17:38:52.404466 | 
2026-02-16 17:38:52.404528 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:38:52.997099 | controller | changed
2026-02-16 17:38:53.004770 | 
2026-02-16 17:38:53.004836 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:38:53.027527 | controller | skipping: Conditional result was False
2026-02-16 17:38:53.042795 | 
2026-02-16 17:38:53.043077 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:38:53.488680 | controller -> localhost | changed
2026-02-16 17:38:53.504143 | 
2026-02-16 17:38:53.504258 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:38:53.865888 | controller -> localhost | Identity added: /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/work/a789d3fc9e73493abc29d52d7400049d_id_rsa (zuul-build-sshkey)
2026-02-16 17:38:53.866172 | controller -> localhost | ok: Runtime: 0:00:00.015780
2026-02-16 17:38:53.930369 | 
2026-02-16 17:38:53.930520 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:38:54.229895 | controller | ok
2026-02-16 17:38:54.236469 | 
2026-02-16 17:38:54.236593 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:38:54.263400 | controller | skipping: Conditional result was False
2026-02-16 17:38:54.288490 | 
2026-02-16 17:38:54.288570 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:38:54.615587 | controller | ok
2026-02-16 17:38:54.625579 | 
2026-02-16 17:38:54.625685 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:39:15.926209 | controller | Output suppressed because no_log was given
2026-02-16 17:39:15.939430 | 
2026-02-16 17:39:15.939510 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:39:16.182858 | controller | ok: "logs"
2026-02-16 17:39:16.183115 | controller | ok: All items complete
2026-02-16 17:39:16.183144 | 
2026-02-16 17:39:16.367987 | controller | ok: "artifacts"
2026-02-16 17:39:16.561468 | controller | ok: "docs"
2026-02-16 17:39:16.575700 | 
2026-02-16 17:39:16.708918 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:39:16.935050 | controller | changed: "logs"
2026-02-16 17:39:17.130038 | controller | changed: "artifacts"
2026-02-16 17:39:17.380696 | controller | changed: "docs"
2026-02-16 17:39:17.402052 | 
2026-02-16 17:39:17.402200 | PLAY RECAP
2026-02-16 17:39:17.402253 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:39:17.402303 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:39:17.402325 | 
2026-02-16 17:39:17.579691 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:39:17.590452 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:39:18.221514 | 
2026-02-16 17:39:18.246716 | PLAY [all]
2026-02-16 17:39:18.269385 | 
2026-02-16 17:39:18.269585 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:39:18.662852 | controller | changed
2026-02-16 17:39:18.669587 | 
2026-02-16 17:39:18.669669 | TASK [Gather minimum local MTU]
2026-02-16 17:39:18.745767 | controller | ok
2026-02-16 17:39:18.753901 | 
2026-02-16 17:39:18.753996 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:39:18.807439 | controller | ok
2026-02-16 17:39:18.814685 | 
2026-02-16 17:39:18.814787 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:39:18.840692 | controller | skipping: Conditional result was False
2026-02-16 17:39:18.855426 | 
2026-02-16 17:39:18.855611 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:39:19.428122 | controller | ok: Runtime: 0:00:00.010381
2026-02-16 17:39:19.433876 | 
2026-02-16 17:39:19.433960 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:39:19.459248 | controller | skipping: Conditional result was False
2026-02-16 17:39:19.473074 | 
2026-02-16 17:39:19.473176 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:39:19.499216 | controller | skipping: Conditional result was False
2026-02-16 17:39:19.510441 | 
2026-02-16 17:39:19.510560 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:39:19.567738 | controller | ok
2026-02-16 17:39:19.579369 | controller | included: /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:39:19.585320 | 
2026-02-16 17:39:19.585406 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:39:19.637914 | controller | ok
2026-02-16 17:39:19.645321 | 
2026-02-16 17:39:19.645426 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:39:19.953275 | controller | ext4
2026-02-16 17:39:20.214345 | controller | ok: Runtime: 0:00:00.017103
2026-02-16 17:39:20.219696 | 
2026-02-16 17:39:20.219763 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:39:20.255726 | controller | ok
2026-02-16 17:39:20.263034 | 
2026-02-16 17:39:20.263108 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:39:20.298697 | controller | ok:
2026-02-16 17:39:20.298919 | controller | {
2026-02-16 17:39:20.299024 | controller |   "root_filesystem": "ext4"
2026-02-16 17:39:20.299072 | controller | }
2026-02-16 17:39:20.305041 | 
2026-02-16 17:39:20.305110 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:39:30.426140 | controller | 8192+0 records in
2026-02-16 17:39:30.426295 | controller | 8192+0 records out
2026-02-16 17:39:30.426316 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.80344 s, 876 MB/s
2026-02-16 17:39:30.891484 | controller | ok: Runtime: 0:00:09.830577
2026-02-16 17:39:30.898662 | 
2026-02-16 17:39:30.898792 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:39:31.155045 | controller | changed
2026-02-16 17:39:31.161620 | 
2026-02-16 17:39:31.161715 | TASK [configure-swap : Make swapfile]
2026-02-16 17:40:38.589645 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:40:38.589735 | controller | no label, UUID=321a2f94-e149-4434-b94c-11783841b56f
2026-02-16 17:40:39.019830 | controller | ok: Runtime: 0:01:07.153439
2026-02-16 17:40:39.029077 | 
2026-02-16 17:40:39.029146 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:40:39.430786 | controller | changed
2026-02-16 17:40:39.438890 | 
2026-02-16 17:40:39.438960 | TASK [configure-swap : Add all swap]
2026-02-16 17:40:39.981605 | controller | ok: Runtime: 0:00:00.015947
2026-02-16 17:40:39.989716 | 
2026-02-16 17:40:39.989805 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:40:40.024009 | controller | ok:
2026-02-16 17:40:40.024173 | controller | {
2026-02-16 17:40:40.024209 | controller |   "swap_required": "8192"
2026-02-16 17:40:40.024237 | controller | }
2026-02-16 17:40:40.033433 | 
2026-02-16 17:40:40.033523 | TASK [configure-swap : Set swappiness]
2026-02-16 17:40:40.443613 | controller | changed
2026-02-16 17:40:40.451195 | 
2026-02-16 17:40:40.451274 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:40:40.486433 | controller | ok:
2026-02-16 17:40:40.486643 | controller | {
2026-02-16 17:40:40.486682 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:40:40.486720 | controller | }
2026-02-16 17:40:40.499245 | 
2026-02-16 17:40:40.499324 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:40:40.939726 | controller | changed
2026-02-16 17:40:40.949710 | 
2026-02-16 17:40:40.949785 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:40:41.194352 | controller | changed
2026-02-16 17:40:41.202559 | 
2026-02-16 17:40:41.202651 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:40:41.738017 | controller | changed
2026-02-16 17:40:41.745968 | 
2026-02-16 17:40:41.746041 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:40:42.008080 | controller | changed
2026-02-16 17:40:42.014661 | 
2026-02-16 17:40:42.014731 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:40:43.103718 | controller | changed
2026-02-16 17:40:43.111323 | 
2026-02-16 17:40:43.111388 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:40:43.358733 | controller | changed
2026-02-16 17:40:43.372257 | 
2026-02-16 17:40:43.372321 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:40:43.672044 | controller | changed
2026-02-16 17:40:43.679532 | 
2026-02-16 17:40:43.679597 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:40:44.060346 | controller | changed
2026-02-16 17:40:44.107406 | 
2026-02-16 17:40:44.107563 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:40:44.891338 | controller | changed
2026-02-16 17:40:44.915193 | 
2026-02-16 17:40:44.915445 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:40:45.277508 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:40:45.293681 | 
2026-02-16 17:40:45.293757 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:40:45.777558 | controller | ok: Item: Runtime: 0:00:00.194149
2026-02-16 17:40:46.360257 | controller | ok: Item: Runtime: 0:00:00.358223
2026-02-16 17:40:47.137614 | controller | ok: Item: Runtime: 0:00:00.561652
2026-02-16 17:40:47.429779 | controller | ok: Item: Runtime: 0:00:00.065950
2026-02-16 17:40:47.919308 | controller | ok: Item: Runtime: 0:00:00.271561
2026-02-16 17:40:49.565007 | controller | ok: Item: Runtime: 0:00:01.413352
2026-02-16 17:40:52.699299 | controller | ok: Item: Runtime: 0:00:02.911472
2026-02-16 17:40:53.014869 | controller | ok: Item: Runtime: 0:00:00.097067
2026-02-16 17:40:53.442121 | controller | ok: Item: Runtime: 0:00:00.187585
2026-02-16 17:40:53.782323 | controller | ok: Item: Runtime: 0:00:00.122362
2026-02-16 17:40:54.694452 | controller | ok: Item: Runtime: 0:00:00.697683
2026-02-16 17:40:55.440649 | controller | ok: Item: Runtime: 0:00:00.529771
2026-02-16 17:40:55.917653 | controller | ok: Item: Runtime: 0:00:00.245752
2026-02-16 17:40:56.371437 | controller | ok: Item: Runtime: 0:00:00.233354
2026-02-16 17:40:56.993138 | controller | ok: Item: Runtime: 0:00:00.390506
2026-02-16 17:40:58.388657 | controller | ok: Item: Runtime: 0:00:01.176376
2026-02-16 17:40:58.429864 | 
2026-02-16 17:40:58.430115 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:40:58.649873 | controller | ok: All paths examined
2026-02-16 17:40:58.656393 | 
2026-02-16 17:40:58.656462 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:40:58.896037 | controller | ok: All paths examined
2026-02-16 17:40:58.902354 | 
2026-02-16 17:40:58.902428 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:40:59.351724 | controller | ok: Item: Runtime: 0:00:00.157771
2026-02-16 17:40:59.351973 | controller | changed: All items complete
2026-02-16 17:40:59.352003 | 
2026-02-16 17:40:59.757432 | controller | ok: Item: Runtime: 0:00:00.188276
2026-02-16 17:40:59.778528 | 
2026-02-16 17:40:59.778698 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:40:59.825230 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.838014 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.839885 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.841303 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.842349 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.843257 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.844247 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.846465 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.849999 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.852998 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.856012 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.859272 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.862419 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.865938 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.869072 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.872461 | controller | skipping: Conditional result was False
2026-02-16 17:40:59.906031 | 
2026-02-16 17:40:59.906181 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:41:02.356492 | controller | changed
2026-02-16 17:41:02.371127 | 
2026-02-16 17:41:02.371293 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:41:02.608777 | controller | changed
2026-02-16 17:41:02.664015 | 
2026-02-16 17:41:02.664168 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:41:02.910801 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:41:03.205855 | controller | ERROR
2026-02-16 17:41:03.206112 | controller | {
2026-02-16 17:41:03.206149 | controller |   "delta": "0:00:00.011216",
2026-02-16 17:41:03.206189 | controller |   "end": "2026-02-16 17:41:02.911257",
2026-02-16 17:41:03.206217 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:03.206237 | controller |   "rc": 1,
2026-02-16 17:41:03.206277 | controller |   "start": "2026-02-16 17:41:02.900041"
2026-02-16 17:41:03.206301 | controller | }
2026-02-16 17:41:03.206327 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:03.212157 | 
2026-02-16 17:41:03.212226 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:41:03.460393 | controller | ok
2026-02-16 17:41:03.490349 | 
2026-02-16 17:41:03.490455 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:41:03.740574 | controller | /usr/bin/journalctl
2026-02-16 17:41:04.037887 | controller | ok: Runtime: 0:00:00.007187
2026-02-16 17:41:04.044529 | 
2026-02-16 17:41:04.044637 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:41:04.303034 | controller | 2026-02-16 17:41:04
2026-02-16 17:41:04.587649 | controller | ok: Runtime: 0:00:00.009469
2026-02-16 17:41:04.593533 | 
2026-02-16 17:41:04.593604 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:41:05.178156 | controller | changed
2026-02-16 17:41:05.184763 | 
2026-02-16 17:41:05.184840 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:41:05.211200 | controller | skipping: Conditional result was False
2026-02-16 17:41:05.227783 | 
2026-02-16 17:41:05.227931 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:41:05.759805 | controller | skipping: Conditional result was False
2026-02-16 17:41:05.770130 | 
2026-02-16 17:41:05.770210 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:41:06.300723 | controller | skipping: Conditional result was False
2026-02-16 17:41:06.310775 | 
2026-02-16 17:41:06.310873 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:41:06.336866 | controller | skipping: Conditional result was False
2026-02-16 17:41:06.346866 | 
2026-02-16 17:41:06.346942 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:41:06.877824 | controller | skipping: Conditional result was False
2026-02-16 17:41:06.888698 | 
2026-02-16 17:41:06.888772 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:41:07.420361 | controller | skipping: Conditional result was False
2026-02-16 17:41:07.430648 | 
2026-02-16 17:41:07.430724 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:41:07.455655 | controller | skipping: Conditional result was False
2026-02-16 17:41:07.462588 | 
2026-02-16 17:41:07.462661 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:41:07.487646 | controller | skipping: Conditional result was False
2026-02-16 17:41:07.493865 | 
2026-02-16 17:41:07.493935 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:41:08.068369 | controller | skipping: Conditional result was False
2026-02-16 17:41:08.074817 | 
2026-02-16 17:41:08.074892 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:41:08.615280 | controller | skipping: Conditional result was False
2026-02-16 17:41:08.625433 | 
2026-02-16 17:41:08.625523 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:41:08.650281 | controller | skipping: Conditional result was False
2026-02-16 17:41:08.660988 | 
2026-02-16 17:41:08.661060 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:41:08.685427 | controller | skipping: Conditional result was False
2026-02-16 17:41:08.709802 | 
2026-02-16 17:41:08.709896 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:41:09.147564 | controller | ok
2026-02-16 17:41:09.160291 | 
2026-02-16 17:41:09.160516 | PLAY RECAP
2026-02-16 17:41:09.160620 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:41:09.160670 | 
2026-02-16 17:41:09.374494 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:41:09.382996 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:41:10.022988 | 
2026-02-16 17:41:10.023143 | PLAY [all]
2026-02-16 17:41:10.036527 | 
2026-02-16 17:41:10.036625 | TASK [ensure-rust : Use rustup]
2026-02-16 17:41:10.073895 | controller | ok
2026-02-16 17:41:10.081960 | controller | included: /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:41:10.087425 | 
2026-02-16 17:41:10.087503 | TASK [ensure-rust : Install Rust]
2026-02-16 17:41:10.490397 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:41:10.624229 | controller | ERROR
2026-02-16 17:41:10.624449 | controller | {
2026-02-16 17:41:10.624479 | controller |   "delta": "0:00:00.013398",
2026-02-16 17:41:10.624503 | controller |   "end": "2026-02-16 17:41:10.493669",
2026-02-16 17:41:10.624523 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:10.624541 | controller |   "rc": 127,
2026-02-16 17:41:10.624558 | controller |   "start": "2026-02-16 17:41:10.480271"
2026-02-16 17:41:10.624580 | controller | }
failure
2026-02-16 17:41:10.626094 | 
2026-02-16 17:41:10.626143 | PLAY RECAP
2026-02-16 17:41:10.626190 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:41:10.626211 | 
2026-02-16 17:41:10.756567 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:41:10.765138 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:41:11.446417 | 
2026-02-16 17:41:11.446575 | PLAY [all]
2026-02-16 17:41:11.463673 | 
2026-02-16 17:41:11.463789 | TASK [Return built artifacts to Zuul]
2026-02-16 17:41:11.496675 | controller | ok
2026-02-16 17:41:11.504030 | 
2026-02-16 17:41:11.504130 | TASK [Get the Docker volume ID]
2026-02-16 17:41:12.049208 | controller | ERROR
2026-02-16 17:41:12.049529 | controller | {
2026-02-16 17:41:12.049561 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:41:12.049584 | controller |   "rc": 2
2026-02-16 17:41:12.049604 | controller | }
2026-02-16 17:41:12.049631 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:12.055980 | 
2026-02-16 17:41:12.056063 | TASK [Copy all of the pod logs]
2026-02-16 17:41:12.090528 | controller | ERROR
2026-02-16 17:41:12.090787 | controller | {
2026-02-16 17:41:12.090817 | 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/a789d3fc9e73493abc29d52d7400049d/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:41:12.090840 | controller | }
2026-02-16 17:41:12.090867 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:12.092036 | 
2026-02-16 17:41:12.092083 | PLAY RECAP
2026-02-16 17:41:12.092129 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:41:12.092150 | 
2026-02-16 17:41:12.267345 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:41:12.278616 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:12.948506 | 
2026-02-16 17:41:12.948812 | PLAY [all]
2026-02-16 17:41:12.969722 | 
2026-02-16 17:41:12.969869 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:41:13.401431 | controller | changed
2026-02-16 17:41:13.423037 | 
2026-02-16 17:41:13.424064 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:41:14.465960 | controller | ok: Runtime: 0:00:00.460604
2026-02-16 17:41:14.472248 | 
2026-02-16 17:41:14.472324 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:41:15.019800 | controller | ok: Runtime: 0:00:00.021365
2026-02-16 17:41:15.029417 | 
2026-02-16 17:41:15.029493 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:41:15.567532 | controller | ok: Runtime: 0:00:00.024016
2026-02-16 17:41:15.573677 | 
2026-02-16 17:41:15.577637 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:41:16.499156 | controller | changed
2026-02-16 17:41:16.563881 | 
2026-02-16 17:41:16.564051 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:41:16.803512 | controller | changed
2026-02-16 17:41:16.810205 | 
2026-02-16 17:41:16.810275 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:41:17.155469 | controller | Output suppressed because no_log was given
2026-02-16 17:41:17.160871 | 
2026-02-16 17:41:17.160942 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:41:17.214679 | 
2026-02-16 17:41:17.214900 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:41:17.251879 | 
2026-02-16 17:41:17.252115 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:41:17.278465 | 
2026-02-16 17:41:17.278700 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:41:17.312617 | 
2026-02-16 17:41:17.312821 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:41:17.364848 | 
2026-02-16 17:41:17.365075 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:41:17.620348 | controller | changed
2026-02-16 17:41:17.628687 | 
2026-02-16 17:41:17.628760 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:41:17.663909 | controller | ok
2026-02-16 17:41:17.670482 | 
2026-02-16 17:41:17.670551 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:41:17.909046 | controller | Output suppressed because no_log was given
2026-02-16 17:41:17.914439 | 
2026-02-16 17:41:17.914504 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:41:17.943202 | 
2026-02-16 17:41:17.943361 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:41:17.978226 | 
2026-02-16 17:41:17.978371 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:41:18.692156 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:41:18.711504 | controller | Using python 3.12 to install setuptools
2026-02-16 17:41:18.762293 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:41:18.785905 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:41:19.020564 | controller | ERROR
2026-02-16 17:41:19.020716 | controller | {
2026-02-16 17:41:19.020751 | controller |   "delta": "0:00:00.554328",
2026-02-16 17:41:19.020772 | controller |   "end": "2026-02-16 17:41:18.788020",
2026-02-16 17:41:19.020792 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:19.020816 | controller |   "rc": 127,
2026-02-16 17:41:19.020834 | controller |   "start": "2026-02-16 17:41:18.233692"
2026-02-16 17:41:19.020852 | controller | }
2026-02-16 17:41:19.020877 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:19.033127 | 
2026-02-16 17:41:19.033249 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:41:19.299412 | controller | changed
2026-02-16 17:41:19.307751 | 
2026-02-16 17:41:19.307941 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:41:23.209332 | controller | Output suppressed because no_log was given
2026-02-16 17:41:23.219582 | 
2026-02-16 17:41:23.219664 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:23.253919 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.254497 | 
2026-02-16 17:41:23.256438 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.258547 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.260350 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.262897 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.264770 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.267363 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.269720 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.272278 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.275063 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.278172 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.280564 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.282654 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.285044 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.287558 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.290876 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.293592 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.296898 | controller | skipping: Conditional result was False
2026-02-16 17:41:23.325379 | 
2026-02-16 17:41:23.325720 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:41:23.553949 | controller | ok
2026-02-16 17:41:23.561969 | 
2026-02-16 17:41:23.562065 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:41:24.156042 | controller | skipping: Conditional result was False
2026-02-16 17:41:24.168452 | 
2026-02-16 17:41:24.168537 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:41:24.454842 | controller | sudo: iptables-save: command not found
2026-02-16 17:41:24.497886 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:41:25.902204 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:41:26.215080 | controller | ok: Runtime: 0:00:01.467317
2026-02-16 17:41:26.241043 | 
2026-02-16 17:41:26.241156 | LOOP [stage-output : Register sources]
2026-02-16 17:41:35.755106 | controller | Output suppressed because no_log was given
2026-02-16 17:41:35.807698 | 
2026-02-16 17:41:35.807854 | TASK [stage-output : Check sudo]
2026-02-16 17:41:36.349122 | controller | ok: Runtime: 0:00:00.023039
2026-02-16 17:41:36.355571 | 
2026-02-16 17:41:36.355641 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:41:36.452059 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.452614 | 
2026-02-16 17:41:36.454478 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.455299 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.457094 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.493846 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.494214 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.494545 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.494873 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.496613 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.498807 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.501016 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.503106 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.505201 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.507349 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.526357 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.526706 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.527034 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.527356 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.527669 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.528836 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.531054 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.533362 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.535427 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.559290 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.559641 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.633007 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.643872 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.655018 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.666718 | controller | Output suppressed because no_log was given
2026-02-16 17:41:36.681248 | 
2026-02-16 17:41:36.681320 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:41:36.744867 | controller | ok
2026-02-16 17:41:36.753059 | 
2026-02-16 17:41:36.753127 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:41:37.004455 | controller | changed: "docs"
2026-02-16 17:41:37.212186 | controller | changed: "artifacts"
2026-02-16 17:41:37.432839 | controller | ok: "logs"
2026-02-16 17:41:37.447690 | 
2026-02-16 17:41:37.447826 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:41:37.722348 | controller | ok: Item: Runtime: 0:00:00.010682
2026-02-16 17:41:37.722615 | controller | changed: All items complete
2026-02-16 17:41:37.722644 | 
2026-02-16 17:41:37.966286 | controller | ok: Item: Runtime: 0:00:00.005585
2026-02-16 17:41:38.192800 | controller | ok: Item: Runtime: 0:00:00.007104
2026-02-16 17:41:38.413866 | controller | ok: Item: Runtime: 0:00:00.009368
2026-02-16 17:41:38.638469 | controller | ok: Item: Runtime: 0:00:00.007876
2026-02-16 17:41:38.867750 | controller | ok: Item: Runtime: 0:00:00.008569
2026-02-16 17:41:39.087504 | controller | ok: Item: Runtime: 0:00:00.008175
2026-02-16 17:41:39.312128 | controller | ok: Item: Runtime: 0:00:00.008351
2026-02-16 17:41:39.544003 | controller | ok: Item: Runtime: 0:00:00.007803
2026-02-16 17:41:39.754687 | controller | ok: Item: Runtime: 0:00:00.006825
2026-02-16 17:41:39.983966 | controller | ok: Item: Runtime: 0:00:00.008434
2026-02-16 17:41:40.273850 | controller | ok: Item: Runtime: 0:00:00.009880
2026-02-16 17:41:40.434940 | controller | ok: Item: Runtime: 0:00:00.008030
2026-02-16 17:41:40.648994 | controller | ok: Item: Runtime: 0:00:00.007418
2026-02-16 17:41:40.874822 | controller | ok: Item: Runtime: 0:00:00.006153
2026-02-16 17:41:41.099654 | controller | ok: Item: Runtime: 0:00:00.007677
2026-02-16 17:41:41.122306 | 
2026-02-16 17:41:41.122453 | TASK [stage-output : Make all log files readable]
2026-02-16 17:41:41.370584 | controller | changed
2026-02-16 17:41:41.376650 | 
2026-02-16 17:41:41.376719 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:41:41.705336 | controller | changed: Renamed files for staging.
2026-02-16 17:41:41.715275 | 
2026-02-16 17:41:41.715344 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:41:41.741105 | controller | skipping: Conditional result was False
2026-02-16 17:41:41.755038 | 
2026-02-16 17:41:41.755126 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:41:41.800593 | 
2026-02-16 17:41:41.800948 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:41:42.417745 | controller | changed:
2026-02-16 17:41:42.417934 | controller | created directory /var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/work/logs/controller
2026-02-16 17:41:42.417962 | controller | cd+++++++++ logs/
2026-02-16 17:41:42.417983 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:41:42.418003 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:41:42.418022 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:41:42.418041 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:41:42.418059 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:41:42.418077 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:41:42.418095 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:41:42.418114 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:41:42.418132 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:41:42.418150 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:41:42.418167 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:41:42.418185 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:41:42.418207 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:41:42.418242 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:41:42.418272 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:41:42.418298 | controller | cd+++++++++ logs/apache/
2026-02-16 17:41:42.418320 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:41:42.418338 | controller | cd+++++++++ logs/etc/
2026-02-16 17:41:42.418355 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:41:42.418373 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:41:42.418390 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:41:42.418408 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:41:42.418426 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:41:42.418443 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:41:42.431057 | 
2026-02-16 17:41:42.431119 | TASK [Check if a tempest log exits]
2026-02-16 17:41:42.696865 | controller | ok
2026-02-16 17:41:42.702103 | 
2026-02-16 17:41:42.702166 | TASK [Link post-devstack tempest.log]
2026-02-16 17:41:42.717743 | controller | skipping: Conditional result was False
2026-02-16 17:41:42.723971 | 
2026-02-16 17:41:42.724036 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:41:42.972334 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:41:43.266110 | controller | ERROR
2026-02-16 17:41:43.266323 | controller | {
2026-02-16 17:41:43.266367 | controller |   "delta": "0:00:00.007409",
2026-02-16 17:41:43.266397 | controller |   "end": "2026-02-16 17:41:42.972830",
2026-02-16 17:41:43.266467 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:43.266498 | controller |   "rc": 127,
2026-02-16 17:41:43.266523 | controller |   "start": "2026-02-16 17:41:42.965421"
2026-02-16 17:41:43.266547 | controller | }
2026-02-16 17:41:43.266580 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:43.268431 | 
2026-02-16 17:41:43.268495 | PLAY RECAP
2026-02-16 17:41:43.268559 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:41:43.268588 | 
2026-02-16 17:41:43.457119 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:43.466613 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:44.072551 | 
2026-02-16 17:41:44.072691 | PLAY [all]
2026-02-16 17:41:44.084366 | 
2026-02-16 17:41:44.084442 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:41:44.129375 | controller | skipping: Conditional result was False
2026-02-16 17:41:44.135182 | 
2026-02-16 17:41:44.135264 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:41:44.175350 | controller | ok
2026-02-16 17:41:44.181616 | 
2026-02-16 17:41:44.181685 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:41:44.556482 | controller -> localhost | ok: "/var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/work/logs"
2026-02-16 17:41:44.755163 | controller -> localhost | changed: "/var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/work/artifacts"
2026-02-16 17:41:44.961642 | controller -> localhost | changed: "/var/lib/zuul/builds/a789d3fc9e73493abc29d52d7400049d/work/docs"
2026-02-16 17:41:44.980847 | 
2026-02-16 17:41:44.980949 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:41:45.618698 | controller | changed: .d..t...... ./
2026-02-16 17:41:45.618947 | controller | changed: All items complete
2026-02-16 17:41:45.618986 | 
2026-02-16 17:41:46.091457 | controller | changed: .d..t...... ./
2026-02-16 17:41:46.565396 | controller | changed: .d..t...... ./
2026-02-16 17:41:46.586932 | 
2026-02-16 17:41:46.587102 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:41:47.030127 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007312
2026-02-16 17:41:47.288828 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006858
2026-02-16 17:41:47.310388 | 
2026-02-16 17:41:47.310576 | PLAY [all]
2026-02-16 17:41:47.321197 | 
2026-02-16 17:41:47.321290 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:41:47.813369 | controller | changed
2026-02-16 17:41:47.820343 | 
2026-02-16 17:41:47.820395 | PLAY RECAP
2026-02-16 17:41:47.820442 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:41:47.820464 | 
2026-02-16 17:41:47.947086 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:47.956994 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:41:48.526624 | 
2026-02-16 17:41:48.526749 | PLAY [localhost]
2026-02-16 17:41:48.536883 | 
2026-02-16 17:41:48.536972 | TASK [Generate Zuul manifest]
2026-02-16 17:41:48.558253 | localhost | ok
2026-02-16 17:41:48.573696 | 
2026-02-16 17:41:48.573771 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:41:48.927933 | localhost | changed
2026-02-16 17:41:48.941893 | 
2026-02-16 17:41:48.941984 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:41:48.973476 | localhost | ok
2026-02-16 17:41:48.981841 | 
2026-02-16 17:41:48.981924 | TASK [Upload logs]
2026-02-16 17:41:49.002358 | localhost | ok
2026-02-16 17:41:49.081263 | 
2026-02-16 17:41:49.081431 | TASK [Set zuul-log-path fact]
2026-02-16 17:41:49.103821 | localhost | ok
2026-02-16 17:41:49.120181 | 
2026-02-16 17:41:49.120295 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:41:49.163366 | localhost | ok
2026-02-16 17:41:49.170973 | 
2026-02-16 17:41:49.171040 | TASK [upload-logs : Create log directories]
2026-02-16 17:41:49.553695 | localhost | changed
2026-02-16 17:41:49.558401 | 
2026-02-16 17:41:49.558470 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:41:49.997438 | localhost -> localhost | ok: Runtime: 0:00:00.005911
2026-02-16 17:41:50.002279 | 
2026-02-16 17:41:50.002343 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:41:50.420005 | localhost | Output suppressed because no_log was given
2026-02-16 17:41:50.423710 | 
2026-02-16 17:41:50.423775 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:41:50.468542 | localhost | skipping: Conditional result was False
2026-02-16 17:41:50.474530 | localhost | skipping: Conditional result was False
2026-02-16 17:41:50.483879 | 
2026-02-16 17:41:50.484083 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:41:50.521418 | localhost | skipping: Conditional result was False
2026-02-16 17:41:50.521877 | 
2026-02-16 17:41:50.524457 | localhost | skipping: Conditional result was False
2026-02-16 17:41:50.541505 | 
2026-02-16 17:41:50.541653 | LOOP [upload-logs : Upload console log and json output]