2026-02-16 17:20:39.887113 | Job console starting
2026-02-16 17:20:40.190183 | Updating git repos
2026-02-16 17:20:46.529950 | Cloning repos into workspace
2026-02-16 17:20:51.783998 | Restoring repo states
2026-02-16 17:20:52.502384 | Merging changes
2026-02-16 17:20:53.210115 | Checking out repos
2026-02-16 17:20:58.201026 | Preparing playbooks
2026-02-16 17:21:00.942676 | Running Ansible setup
2026-02-16 17:21:04.907376 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:05.521026 | 
2026-02-16 17:21:05.521157 | PLAY [localhost]
2026-02-16 17:21:05.528886 | 
2026-02-16 17:21:05.528990 | TASK [Gathering Facts]
2026-02-16 17:21:06.446493 | localhost | ok
2026-02-16 17:21:06.466011 | 
2026-02-16 17:21:06.466205 | TASK [Setup log path fact]
2026-02-16 17:21:06.485842 | localhost | ok
2026-02-16 17:21:06.499738 | 
2026-02-16 17:21:06.499830 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:21:06.530645 | localhost | ok
2026-02-16 17:21:06.538390 | 
2026-02-16 17:21:06.538481 | TASK [emit-job-header : Print job information]
2026-02-16 17:21:06.587883 | # Job Information
2026-02-16 17:21:06.588048 | Ansible Version: 2.16.15
2026-02-16 17:21:06.588080 | Job: magnum-cluster-api-sonobuoy-v1.33.8-calico
2026-02-16 17:21:06.588102 | Pipeline: check
2026-02-16 17:21:06.588121 | Executor: 3a2793d2bd32
2026-02-16 17:21:06.588141 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:21:06.588163 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:21:06.590987 | 
2026-02-16 17:21:06.591071 | LOOP [emit-job-header : Print node information]
2026-02-16 17:21:06.688353 | localhost | ok:
2026-02-16 17:21:06.689401 | localhost | # Node Information
2026-02-16 17:21:06.689449 | localhost | Inventory Hostname: controller
2026-02-16 17:21:06.689480 | localhost | Hostname: np0000155586
2026-02-16 17:21:06.689507 | localhost | Username: zuul
2026-02-16 17:21:06.689538 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:21:06.689570 | localhost | Provider: yul1
2026-02-16 17:21:06.689601 | localhost | Region: ca-ymq-1
2026-02-16 17:21:06.689627 | localhost | Label: ubuntu-noble-16
2026-02-16 17:21:06.689652 | localhost | Product Name: OpenStack Nova
2026-02-16 17:21:06.689676 | localhost | Interface IP: 199.204.45.138
2026-02-16 17:21:06.707207 | 
2026-02-16 17:21:06.707385 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:21:07.081412 | localhost -> localhost | changed
2026-02-16 17:21:07.090031 | 
2026-02-16 17:21:07.090130 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:21:07.952057 | localhost -> localhost | changed
2026-02-16 17:21:07.967106 | 
2026-02-16 17:21:07.967171 | PLAY [all]
2026-02-16 17:21:07.978089 | 
2026-02-16 17:21:07.978170 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:21:08.224281 | controller -> localhost | ok
2026-02-16 17:21:08.239192 | 
2026-02-16 17:21:08.239296 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:21:08.286527 | controller | ok
2026-02-16 17:21:08.310485 | controller | included: /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:21:08.318173 | 
2026-02-16 17:21:08.318283 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:21:09.085300 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:21:09.086157 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/work/880f324cea9540a08466b88b62e474b0_id_rsa
2026-02-16 17:21:09.086190 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/work/880f324cea9540a08466b88b62e474b0_id_rsa.pub
2026-02-16 17:21:09.086213 | controller -> localhost | The key fingerprint is:
2026-02-16 17:21:09.086235 | controller -> localhost | SHA256:E5arW/eHDmBKW4G5APH2Yw4QzQgl/oEovXa9/XFRGPs zuul-build-sshkey
2026-02-16 17:21:09.086269 | controller -> localhost | The key's randomart image is:
2026-02-16 17:21:09.086290 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:21:09.086315 | controller -> localhost | |oo==        .    |
2026-02-16 17:21:09.086336 | controller -> localhost | |ooo+o  o .   +   |
2026-02-16 17:21:09.086355 | controller -> localhost | |ooo.+ o =   o .  |
2026-02-16 17:21:09.086375 | controller -> localhost | |. .+.+ o +   o   |
2026-02-16 17:21:09.086394 | controller -> localhost | |  o.o B S   . E  |
2026-02-16 17:21:09.086413 | controller -> localhost | | . . = X o   .   |
2026-02-16 17:21:09.086432 | controller -> localhost | |      * o + ..   |
2026-02-16 17:21:09.086450 | controller -> localhost | |       o o =. .  |
2026-02-16 17:21:09.086472 | controller -> localhost | |      .   ..o.   |
2026-02-16 17:21:09.086491 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:21:09.086537 | controller -> localhost | ok: Runtime: 0:00:00.365394
2026-02-16 17:21:09.094279 | 
2026-02-16 17:21:09.094343 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:21:09.131760 | controller | ok
2026-02-16 17:21:09.141925 | controller | included: /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:21:09.151413 | 
2026-02-16 17:21:09.151477 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:21:09.177015 | controller | skipping: Conditional result was False
2026-02-16 17:21:09.186162 | 
2026-02-16 17:21:09.186236 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:21:09.709743 | controller | changed
2026-02-16 17:21:09.718425 | 
2026-02-16 17:21:09.718499 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:21:09.947738 | controller | ok
2026-02-16 17:21:09.956173 | 
2026-02-16 17:21:09.956296 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:21:10.529798 | controller | changed
2026-02-16 17:21:10.539479 | 
2026-02-16 17:21:10.539557 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:21:11.112802 | controller | changed
2026-02-16 17:21:11.122769 | 
2026-02-16 17:21:11.122866 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:21:11.139266 | controller | skipping: Conditional result was False
2026-02-16 17:21:11.148264 | 
2026-02-16 17:21:11.148401 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:21:11.537756 | controller -> localhost | changed
2026-02-16 17:21:11.552231 | 
2026-02-16 17:21:11.552303 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:21:11.951554 | controller -> localhost | Identity added: /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/work/880f324cea9540a08466b88b62e474b0_id_rsa (zuul-build-sshkey)
2026-02-16 17:21:11.951814 | controller -> localhost | ok: Runtime: 0:00:00.018323
2026-02-16 17:21:12.007699 | 
2026-02-16 17:21:12.007839 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:21:12.317129 | controller | ok
2026-02-16 17:21:12.324377 | 
2026-02-16 17:21:12.324446 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:21:12.350474 | controller | skipping: Conditional result was False
2026-02-16 17:21:12.369144 | 
2026-02-16 17:21:12.369279 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:21:12.696101 | controller | ok
2026-02-16 17:21:12.707724 | 
2026-02-16 17:21:12.707790 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:21:46.486204 | controller | Output suppressed because no_log was given
2026-02-16 17:21:46.497877 | 
2026-02-16 17:21:46.497942 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:21:46.754082 | controller | ok: "logs"
2026-02-16 17:21:46.755235 | controller | ok: All items complete
2026-02-16 17:21:46.755643 | 
2026-02-16 17:21:46.920788 | controller | ok: "artifacts"
2026-02-16 17:21:47.102850 | controller | ok: "docs"
2026-02-16 17:21:47.120866 | 
2026-02-16 17:21:47.121043 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:21:47.333523 | controller | changed: "logs"
2026-02-16 17:21:47.549429 | controller | changed: "artifacts"
2026-02-16 17:21:47.806636 | controller | changed: "docs"
2026-02-16 17:21:47.823840 | 
2026-02-16 17:21:47.823971 | PLAY RECAP
2026-02-16 17:21:47.824016 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:21:47.824044 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:21:47.824065 | 
2026-02-16 17:21:48.010346 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:48.058532 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:21:48.788829 | 
2026-02-16 17:21:48.789615 | PLAY [all]
2026-02-16 17:21:48.817585 | 
2026-02-16 17:21:48.817791 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:21:49.178503 | controller | changed
2026-02-16 17:21:49.186820 | 
2026-02-16 17:21:49.186908 | TASK [Gather minimum local MTU]
2026-02-16 17:21:49.263303 | controller | ok
2026-02-16 17:21:49.275009 | 
2026-02-16 17:21:49.275088 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:21:49.336809 | controller | ok
2026-02-16 17:21:49.341951 | 
2026-02-16 17:21:49.342021 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:21:49.367209 | controller | skipping: Conditional result was False
2026-02-16 17:21:49.379507 | 
2026-02-16 17:21:49.379582 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:21:49.923401 | controller | ok: Runtime: 0:00:00.009746
2026-02-16 17:21:49.931323 | 
2026-02-16 17:21:49.931394 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:21:49.956997 | controller | skipping: Conditional result was False
2026-02-16 17:21:49.966758 | 
2026-02-16 17:21:49.966853 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:21:49.992434 | controller | skipping: Conditional result was False
2026-02-16 17:21:50.002169 | 
2026-02-16 17:21:50.002244 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:21:50.059208 | controller | ok
2026-02-16 17:21:50.069409 | controller | included: /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:21:50.075406 | 
2026-02-16 17:21:50.075473 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:21:50.132745 | controller | ok
2026-02-16 17:21:50.138607 | 
2026-02-16 17:21:50.138678 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:21:50.431518 | controller | ext4
2026-02-16 17:21:50.716631 | controller | ok: Runtime: 0:00:00.012318
2026-02-16 17:21:50.722358 | 
2026-02-16 17:21:50.722425 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:21:50.765928 | controller | ok
2026-02-16 17:21:50.772387 | 
2026-02-16 17:21:50.772452 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:21:50.807120 | controller | ok:
2026-02-16 17:21:50.807241 | controller | {
2026-02-16 17:21:50.807267 | controller |   "root_filesystem": "ext4"
2026-02-16 17:21:50.807287 | controller | }
2026-02-16 17:21:50.813090 | 
2026-02-16 17:21:50.813151 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:22:00.938820 | controller | 8192+0 records in
2026-02-16 17:22:00.939086 | controller | 8192+0 records out
2026-02-16 17:22:00.939138 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.86019 s, 871 MB/s
2026-02-16 17:22:01.378275 | controller | ok: Runtime: 0:00:09.870802
2026-02-16 17:22:01.385651 | 
2026-02-16 17:22:01.385719 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:22:01.630873 | controller | changed
2026-02-16 17:22:01.638937 | 
2026-02-16 17:22:01.639000 | TASK [configure-swap : Make swapfile]
2026-02-16 17:22:40.062384 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:22:40.063225 | controller | no label, UUID=5957c9ea-df6d-4414-bdbc-cdfc255cb1c9
2026-02-16 17:22:40.200479 | controller | ok: Runtime: 0:00:38.181589
2026-02-16 17:22:40.206174 | 
2026-02-16 17:22:40.206243 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:22:40.622772 | controller | changed
2026-02-16 17:22:40.628305 | 
2026-02-16 17:22:40.628374 | TASK [configure-swap : Add all swap]
2026-02-16 17:22:41.171248 | controller | ok: Runtime: 0:00:00.023268
2026-02-16 17:22:41.177165 | 
2026-02-16 17:22:41.177239 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:22:41.213084 | controller | ok:
2026-02-16 17:22:41.213293 | controller | {
2026-02-16 17:22:41.213320 | controller |   "swap_required": "8192"
2026-02-16 17:22:41.213341 | controller | }
2026-02-16 17:22:41.218827 | 
2026-02-16 17:22:41.218900 | TASK [configure-swap : Set swappiness]
2026-02-16 17:22:41.615450 | controller | changed
2026-02-16 17:22:41.622436 | 
2026-02-16 17:22:41.622597 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:22:41.653809 | controller | ok:
2026-02-16 17:22:41.654003 | controller | {
2026-02-16 17:22:41.654030 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:22:41.654055 | controller | }
2026-02-16 17:22:41.663857 | 
2026-02-16 17:22:41.663948 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:22:42.105420 | controller | changed
2026-02-16 17:22:42.113204 | 
2026-02-16 17:22:42.113275 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:22:42.362386 | controller | changed
2026-02-16 17:22:42.369603 | 
2026-02-16 17:22:42.369690 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:22:42.919958 | controller | changed
2026-02-16 17:22:42.928318 | 
2026-02-16 17:22:42.928411 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:22:43.194326 | controller | changed
2026-02-16 17:22:43.200786 | 
2026-02-16 17:22:43.200855 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:22:44.357655 | controller | changed
2026-02-16 17:22:44.364644 | 
2026-02-16 17:22:44.364710 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:22:44.615130 | controller | changed
2026-02-16 17:22:44.627603 | 
2026-02-16 17:22:44.627668 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:22:44.913247 | controller | changed
2026-02-16 17:22:44.920896 | 
2026-02-16 17:22:44.920984 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:22:45.288755 | controller | changed
2026-02-16 17:22:45.346460 | 
2026-02-16 17:22:45.346636 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:22:46.146888 | controller | changed
2026-02-16 17:22:46.156914 | 
2026-02-16 17:22:46.156998 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:22:46.486777 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:22:46.498451 | 
2026-02-16 17:22:46.498517 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:22:46.963899 | controller | ok: Item: Runtime: 0:00:00.185570
2026-02-16 17:22:47.513686 | controller | ok: Item: Runtime: 0:00:00.340518
2026-02-16 17:22:48.270730 | controller | ok: Item: Runtime: 0:00:00.539047
2026-02-16 17:22:48.572491 | controller | ok: Item: Runtime: 0:00:00.064231
2026-02-16 17:22:49.055957 | controller | ok: Item: Runtime: 0:00:00.264406
2026-02-16 17:22:50.572932 | controller | ok: Item: Runtime: 0:00:01.293136
2026-02-16 17:22:53.929971 | controller | ok: Item: Runtime: 0:00:03.125349
2026-02-16 17:22:54.252382 | controller | ok: Item: Runtime: 0:00:00.099657
2026-02-16 17:22:54.657575 | controller | ok: Item: Runtime: 0:00:00.193439
2026-02-16 17:22:54.999898 | controller | ok: Item: Runtime: 0:00:00.128283
2026-02-16 17:22:55.800732 | controller | ok: Item: Runtime: 0:00:00.577488
2026-02-16 17:22:56.579386 | controller | ok: Item: Runtime: 0:00:00.537462
2026-02-16 17:22:57.058012 | controller | ok: Item: Runtime: 0:00:00.242905
2026-02-16 17:22:57.503599 | controller | ok: Item: Runtime: 0:00:00.230588
2026-02-16 17:22:58.120596 | controller | ok: Item: Runtime: 0:00:00.398795
2026-02-16 17:22:59.578151 | controller | ok: Item: Runtime: 0:00:01.222437
2026-02-16 17:22:59.626085 | 
2026-02-16 17:22:59.626268 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:22:59.841225 | controller | ok: All paths examined
2026-02-16 17:22:59.850014 | 
2026-02-16 17:22:59.850106 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:23:00.096713 | controller | ok: All paths examined
2026-02-16 17:23:00.103995 | 
2026-02-16 17:23:00.104062 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:23:00.539130 | controller | ok: Item: Runtime: 0:00:00.157884
2026-02-16 17:23:00.539433 | controller | changed: All items complete
2026-02-16 17:23:00.539471 | 
2026-02-16 17:23:00.942956 | controller | ok: Item: Runtime: 0:00:00.191659
2026-02-16 17:23:00.958773 | 
2026-02-16 17:23:00.958947 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:23:01.000840 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.003258 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.006184 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.009495 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.012229 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.015374 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.018507 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.022271 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.025363 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.028293 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.031164 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.034278 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.037554 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.041617 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.044620 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.047481 | controller | skipping: Conditional result was False
2026-02-16 17:23:01.083120 | 
2026-02-16 17:23:01.083254 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:23:03.462029 | controller | changed
2026-02-16 17:23:03.473933 | 
2026-02-16 17:23:03.474005 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:23:03.721553 | controller | changed
2026-02-16 17:23:03.779975 | 
2026-02-16 17:23:03.780123 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:23:04.036065 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:23:04.318467 | controller | ERROR
2026-02-16 17:23:04.318692 | controller | {
2026-02-16 17:23:04.318732 | controller |   "delta": "0:00:00.009022",
2026-02-16 17:23:04.318761 | controller |   "end": "2026-02-16 17:23:04.036519",
2026-02-16 17:23:04.318786 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:04.318811 | controller |   "rc": 1,
2026-02-16 17:23:04.318836 | controller |   "start": "2026-02-16 17:23:04.027497"
2026-02-16 17:23:04.318860 | controller | }
2026-02-16 17:23:04.318892 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:04.327572 | 
2026-02-16 17:23:04.327668 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:23:04.581822 | controller | ok
2026-02-16 17:23:04.600198 | 
2026-02-16 17:23:04.600371 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:23:04.844389 | controller | /usr/bin/journalctl
2026-02-16 17:23:05.142037 | controller | ok: Runtime: 0:00:00.007864
2026-02-16 17:23:05.147505 | 
2026-02-16 17:23:05.147572 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:23:05.411637 | controller | 2026-02-16 17:23:05
2026-02-16 17:23:05.687904 | controller | ok: Runtime: 0:00:00.010611
2026-02-16 17:23:05.695519 | 
2026-02-16 17:23:05.695611 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:23:06.285353 | controller | changed
2026-02-16 17:23:06.292589 | 
2026-02-16 17:23:06.292653 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:23:06.318184 | controller | skipping: Conditional result was False
2026-02-16 17:23:06.330125 | 
2026-02-16 17:23:06.330217 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:23:06.865798 | controller | skipping: Conditional result was False
2026-02-16 17:23:06.876913 | 
2026-02-16 17:23:06.877045 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:23:07.407912 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.417999 | 
2026-02-16 17:23:07.418148 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:23:07.444601 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.457107 | 
2026-02-16 17:23:07.457229 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:23:07.989755 | controller | skipping: Conditional result was False
2026-02-16 17:23:07.998354 | 
2026-02-16 17:23:07.998425 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:23:08.526754 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.537164 | 
2026-02-16 17:23:08.537257 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:23:08.562804 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.573371 | 
2026-02-16 17:23:08.573440 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:23:08.598851 | controller | skipping: Conditional result was False
2026-02-16 17:23:08.610186 | 
2026-02-16 17:23:08.610282 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:23:09.193484 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.203448 | 
2026-02-16 17:23:09.203552 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:23:09.734902 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.747160 | 
2026-02-16 17:23:09.747241 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:23:09.772377 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.782496 | 
2026-02-16 17:23:09.782590 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:23:09.808610 | controller | skipping: Conditional result was False
2026-02-16 17:23:09.825461 | 
2026-02-16 17:23:09.825531 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:23:10.209340 | controller | ok
2026-02-16 17:23:10.220371 | 
2026-02-16 17:23:10.220430 | PLAY RECAP
2026-02-16 17:23:10.220479 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:23:10.220502 | 
2026-02-16 17:23:10.443909 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:23:10.450285 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:11.055143 | 
2026-02-16 17:23:11.055289 | PLAY [all]
2026-02-16 17:23:11.067506 | 
2026-02-16 17:23:11.067614 | TASK [ensure-rust : Use rustup]
2026-02-16 17:23:11.103315 | controller | ok
2026-02-16 17:23:11.112331 | controller | included: /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:23:11.117877 | 
2026-02-16 17:23:11.117954 | TASK [ensure-rust : Install Rust]
2026-02-16 17:23:11.520523 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:23:11.654527 | controller | ERROR
2026-02-16 17:23:11.654885 | controller | {
2026-02-16 17:23:11.654949 | controller |   "delta": "0:00:00.012547",
2026-02-16 17:23:11.654999 | controller |   "end": "2026-02-16 17:23:11.523710",
2026-02-16 17:23:11.655042 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:11.655083 | controller |   "rc": 127,
2026-02-16 17:23:11.655122 | controller |   "start": "2026-02-16 17:23:11.511163"
2026-02-16 17:23:11.655171 | controller | }
failure
2026-02-16 17:23:11.657633 | 
2026-02-16 17:23:11.657682 | PLAY RECAP
2026-02-16 17:23:11.657731 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:23:11.657752 | 
2026-02-16 17:23:11.800049 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:11.807299 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:12.396608 | 
2026-02-16 17:23:12.396747 | PLAY [all]
2026-02-16 17:23:12.413902 | 
2026-02-16 17:23:12.413991 | TASK [Return built artifacts to Zuul]
2026-02-16 17:23:12.447930 | controller | ok
2026-02-16 17:23:12.455178 | 
2026-02-16 17:23:12.455301 | TASK [Get the Docker volume ID]
2026-02-16 17:23:12.994933 | controller | ERROR
2026-02-16 17:23:12.995290 | controller | {
2026-02-16 17:23:12.995334 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:23:12.995366 | controller |   "rc": 2
2026-02-16 17:23:12.995393 | controller | }
2026-02-16 17:23:12.995428 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:13.004213 | 
2026-02-16 17:23:13.004309 | TASK [Copy all of the pod logs]
2026-02-16 17:23:13.050954 | controller | ERROR
2026-02-16 17:23:13.051247 | controller | {
2026-02-16 17:23:13.051304 | 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/880f324cea9540a08466b88b62e474b0/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:23:13.051345 | controller | }
2026-02-16 17:23:13.051397 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:13.052774 | 
2026-02-16 17:23:13.052847 | PLAY RECAP
2026-02-16 17:23:13.052913 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:23:13.052945 | 
2026-02-16 17:23:13.194742 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:13.204806 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:13.849231 | 
2026-02-16 17:23:13.849405 | PLAY [all]
2026-02-16 17:23:13.868162 | 
2026-02-16 17:23:13.868249 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:23:14.288482 | controller | changed
2026-02-16 17:23:14.295345 | 
2026-02-16 17:23:14.295424 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:23:15.330223 | controller | ok: Runtime: 0:00:00.465235
2026-02-16 17:23:15.335872 | 
2026-02-16 17:23:15.335944 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:23:15.878342 | controller | ok: Runtime: 0:00:00.020941
2026-02-16 17:23:15.885465 | 
2026-02-16 17:23:15.885543 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:23:16.428368 | controller | ok: Runtime: 0:00:00.023968
2026-02-16 17:23:16.434508 | 
2026-02-16 17:23:16.434580 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:23:17.346750 | controller | changed
2026-02-16 17:23:17.390706 | 
2026-02-16 17:23:17.390856 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:23:17.653448 | controller | changed
2026-02-16 17:23:17.658986 | 
2026-02-16 17:23:17.659056 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:18.019975 | controller | Output suppressed because no_log was given
2026-02-16 17:23:18.026351 | 
2026-02-16 17:23:18.026438 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:18.063195 | 
2026-02-16 17:23:18.063364 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:18.100408 | 
2026-02-16 17:23:18.100736 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:18.135708 | 
2026-02-16 17:23:18.135961 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:18.171261 | 
2026-02-16 17:23:18.171530 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:18.208497 | 
2026-02-16 17:23:18.208786 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:23:18.470840 | controller | changed
2026-02-16 17:23:18.478867 | 
2026-02-16 17:23:18.478939 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:23:18.514424 | controller | ok
2026-02-16 17:23:18.521646 | 
2026-02-16 17:23:18.521730 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:23:18.769406 | controller | Output suppressed because no_log was given
2026-02-16 17:23:18.774777 | 
2026-02-16 17:23:18.774855 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:23:18.811431 | 
2026-02-16 17:23:18.811591 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:23:18.853063 | 
2026-02-16 17:23:18.853224 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:23:19.535797 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:23:19.553980 | controller | Using python 3.12 to install setuptools
2026-02-16 17:23:19.602796 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:23:19.615647 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:23:19.898568 | controller | ERROR
2026-02-16 17:23:19.898810 | controller | {
2026-02-16 17:23:19.898857 | controller |   "delta": "0:00:00.506035",
2026-02-16 17:23:19.898887 | controller |   "end": "2026-02-16 17:23:19.618047",
2026-02-16 17:23:19.898913 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:19.898948 | controller |   "rc": 127,
2026-02-16 17:23:19.898973 | controller |   "start": "2026-02-16 17:23:19.112012"
2026-02-16 17:23:19.898998 | controller | }
2026-02-16 17:23:19.899033 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:19.911737 | 
2026-02-16 17:23:19.911817 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:23:20.158851 | controller | changed
2026-02-16 17:23:20.164637 | 
2026-02-16 17:23:20.164709 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:23:24.037438 | controller | Output suppressed because no_log was given
2026-02-16 17:23:24.051732 | 
2026-02-16 17:23:24.051836 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:24.086588 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.087080 | 
2026-02-16 17:23:24.089047 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.090064 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.092759 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.095295 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.097801 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.100159 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.102599 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.104892 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.107353 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.109842 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.112182 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.114499 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.116813 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.119235 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.121579 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.123860 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.127020 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.154768 | 
2026-02-16 17:23:24.154931 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:23:24.393907 | controller | ok
2026-02-16 17:23:24.399513 | 
2026-02-16 17:23:24.399579 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:23:24.973530 | controller | skipping: Conditional result was False
2026-02-16 17:23:24.989665 | 
2026-02-16 17:23:24.989756 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:23:25.256059 | controller | sudo: iptables-save: command not found
2026-02-16 17:23:25.302020 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:23:26.472288 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:23:26.532759 | controller | ok: Runtime: 0:00:01.239652
2026-02-16 17:23:26.545975 | 
2026-02-16 17:23:26.546049 | LOOP [stage-output : Register sources]
2026-02-16 17:23:36.307091 | controller | Output suppressed because no_log was given
2026-02-16 17:23:36.361108 | 
2026-02-16 17:23:36.361264 | TASK [stage-output : Check sudo]
2026-02-16 17:23:36.902651 | controller | ok: Runtime: 0:00:00.023244
2026-02-16 17:23:36.910889 | 
2026-02-16 17:23:36.910983 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:23:37.008861 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.009412 | 
2026-02-16 17:23:37.011286 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.012120 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.013894 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.048863 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.049250 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.049595 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.051294 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.053656 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.055769 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.057957 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.060078 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.062523 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.064602 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.084224 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.086299 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.088561 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.091073 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.093362 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.095655 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.098061 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.100591 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.103021 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.128483 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.129029 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.208064 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.221269 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.233521 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.244351 | controller | Output suppressed because no_log was given
2026-02-16 17:23:37.257306 | 
2026-02-16 17:23:37.257422 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:23:37.323871 | controller | ok
2026-02-16 17:23:37.331626 | 
2026-02-16 17:23:37.331726 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:23:37.590954 | controller | changed: "docs"
2026-02-16 17:23:37.810496 | controller | changed: "artifacts"
2026-02-16 17:23:38.025172 | controller | ok: "logs"
2026-02-16 17:23:38.041853 | 
2026-02-16 17:23:38.042040 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:23:38.339592 | controller | ok: Item: Runtime: 0:00:00.010932
2026-02-16 17:23:38.339849 | controller | changed: All items complete
2026-02-16 17:23:38.339877 | 
2026-02-16 17:23:38.582347 | controller | ok: Item: Runtime: 0:00:00.008542
2026-02-16 17:23:38.818952 | controller | ok: Item: Runtime: 0:00:00.008887
2026-02-16 17:23:39.057278 | controller | ok: Item: Runtime: 0:00:00.008948
2026-02-16 17:23:39.316780 | controller | ok: Item: Runtime: 0:00:00.008392
2026-02-16 17:23:39.533469 | controller | ok: Item: Runtime: 0:00:00.006947
2026-02-16 17:23:39.761252 | controller | ok: Item: Runtime: 0:00:00.008924
2026-02-16 17:23:39.998063 | controller | ok: Item: Runtime: 0:00:00.009119
2026-02-16 17:23:40.241477 | controller | ok: Item: Runtime: 0:00:00.011001
2026-02-16 17:23:40.483798 | controller | ok: Item: Runtime: 0:00:00.011235
2026-02-16 17:23:40.710535 | controller | ok: Item: Runtime: 0:00:00.009271
2026-02-16 17:23:40.980974 | controller | ok: Item: Runtime: 0:00:00.008479
2026-02-16 17:23:41.175854 | controller | ok: Item: Runtime: 0:00:00.010774
2026-02-16 17:23:41.407920 | controller | ok: Item: Runtime: 0:00:00.009106
2026-02-16 17:23:41.650607 | controller | ok: Item: Runtime: 0:00:00.009325
2026-02-16 17:23:41.867736 | controller | ok: Item: Runtime: 0:00:00.007538
2026-02-16 17:23:41.892331 | 
2026-02-16 17:23:41.892405 | TASK [stage-output : Make all log files readable]
2026-02-16 17:23:42.135395 | controller | changed
2026-02-16 17:23:42.143347 | 
2026-02-16 17:23:42.143437 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:23:42.483452 | controller | changed: Renamed files for staging.
2026-02-16 17:23:42.489618 | 
2026-02-16 17:23:42.489690 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:23:42.515642 | controller | skipping: Conditional result was False
2026-02-16 17:23:42.528408 | 
2026-02-16 17:23:42.528520 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:23:42.571613 | 
2026-02-16 17:23:42.571763 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:23:43.234041 | controller | changed:
2026-02-16 17:23:43.234264 | controller | created directory /var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/work/logs/controller
2026-02-16 17:23:43.234303 | controller | cd+++++++++ logs/
2026-02-16 17:23:43.234332 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:23:43.234361 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:23:43.234387 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:23:43.234413 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:23:43.234438 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:23:43.234463 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:23:43.234488 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:23:43.234513 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:23:43.234538 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:23:43.234563 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:23:43.234588 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:23:43.234613 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:23:43.234647 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:23:43.234742 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:23:43.234770 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:23:43.234795 | controller | cd+++++++++ logs/apache/
2026-02-16 17:23:43.234820 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:23:43.234845 | controller | cd+++++++++ logs/etc/
2026-02-16 17:23:43.234869 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:23:43.234894 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:23:43.234918 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:23:43.234942 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:23:43.234966 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:23:43.234990 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:23:43.258922 | 
2026-02-16 17:23:43.259121 | TASK [Check if a tempest log exits]
2026-02-16 17:23:43.531309 | controller | ok
2026-02-16 17:23:43.537297 | 
2026-02-16 17:23:43.537391 | TASK [Link post-devstack tempest.log]
2026-02-16 17:23:43.562709 | controller | skipping: Conditional result was False
2026-02-16 17:23:43.570096 | 
2026-02-16 17:23:43.570202 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:23:43.834879 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:23:44.113530 | controller | ERROR
2026-02-16 17:23:44.113771 | controller | {
2026-02-16 17:23:44.113804 | controller |   "delta": "0:00:00.008831",
2026-02-16 17:23:44.113825 | controller |   "end": "2026-02-16 17:23:43.835583",
2026-02-16 17:23:44.113843 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:44.113861 | controller |   "rc": 127,
2026-02-16 17:23:44.113879 | controller |   "start": "2026-02-16 17:23:43.826752"
2026-02-16 17:23:44.113896 | controller | }
2026-02-16 17:23:44.113920 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:44.115777 | 
2026-02-16 17:23:44.115827 | PLAY RECAP
2026-02-16 17:23:44.115877 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:23:44.115899 | 
2026-02-16 17:23:44.257233 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:44.266452 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:44.928993 | 
2026-02-16 17:23:44.929144 | PLAY [all]
2026-02-16 17:23:44.941914 | 
2026-02-16 17:23:44.941990 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:23:44.987709 | controller | skipping: Conditional result was False
2026-02-16 17:23:44.999003 | 
2026-02-16 17:23:44.999112 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:23:45.043626 | controller | ok
2026-02-16 17:23:45.052770 | 
2026-02-16 17:23:45.052892 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:23:45.456616 | controller -> localhost | ok: "/var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/work/logs"
2026-02-16 17:23:45.678717 | controller -> localhost | changed: "/var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/work/artifacts"
2026-02-16 17:23:45.898550 | controller -> localhost | changed: "/var/lib/zuul/builds/880f324cea9540a08466b88b62e474b0/work/docs"
2026-02-16 17:23:45.920925 | 
2026-02-16 17:23:45.921119 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:23:46.564205 | controller | changed: .d..t...... ./
2026-02-16 17:23:46.564573 | controller | changed: All items complete
2026-02-16 17:23:46.564638 | 
2026-02-16 17:23:47.022037 | controller | changed: .d..t...... ./
2026-02-16 17:23:47.489111 | controller | changed: .d..t...... ./
2026-02-16 17:23:47.517811 | 
2026-02-16 17:23:47.517982 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:23:47.971451 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007262
2026-02-16 17:23:48.237003 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010298
2026-02-16 17:23:48.258671 | 
2026-02-16 17:23:48.259403 | PLAY [all]
2026-02-16 17:23:48.267487 | 
2026-02-16 17:23:48.307995 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:23:48.799408 | controller | changed
2026-02-16 17:23:48.807639 | 
2026-02-16 17:23:48.807693 | PLAY RECAP
2026-02-16 17:23:48.807741 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:23:48.807764 | 
2026-02-16 17:23:48.921411 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:48.931303 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:23:49.509453 | 
2026-02-16 17:23:49.510141 | PLAY [localhost]
2026-02-16 17:23:49.521266 | 
2026-02-16 17:23:49.521347 | TASK [Generate Zuul manifest]
2026-02-16 17:23:49.541817 | localhost | ok
2026-02-16 17:23:49.616115 | 
2026-02-16 17:23:49.616296 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:23:50.026249 | localhost | changed
2026-02-16 17:23:50.038512 | 
2026-02-16 17:23:50.038591 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:23:50.077705 | localhost | ok
2026-02-16 17:23:50.084023 | 
2026-02-16 17:23:50.084085 | TASK [Upload logs]
2026-02-16 17:23:50.107260 | localhost | ok
2026-02-16 17:23:50.171907 | 
2026-02-16 17:23:50.172038 | TASK [Set zuul-log-path fact]
2026-02-16 17:23:50.195573 | localhost | ok
2026-02-16 17:23:50.209550 | 
2026-02-16 17:23:50.209619 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:23:50.238383 | localhost | ok
2026-02-16 17:23:50.245009 | 
2026-02-16 17:23:50.245077 | TASK [upload-logs : Create log directories]
2026-02-16 17:23:50.662998 | localhost | changed
2026-02-16 17:23:50.669823 | 
2026-02-16 17:23:50.669917 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:23:51.132259 | localhost -> localhost | ok: Runtime: 0:00:00.006637
2026-02-16 17:23:51.137550 | 
2026-02-16 17:23:51.137614 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:23:51.564585 | localhost | Output suppressed because no_log was given
2026-02-16 17:23:51.568446 | 
2026-02-16 17:23:51.568513 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:23:51.615209 | localhost | skipping: Conditional result was False
2026-02-16 17:23:51.621031 | localhost | skipping: Conditional result was False
2026-02-16 17:23:51.635119 | 
2026-02-16 17:23:51.635224 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:23:51.679316 | localhost | skipping: Conditional result was False
2026-02-16 17:23:51.679742 | 
2026-02-16 17:23:51.682555 | localhost | skipping: Conditional result was False
2026-02-16 17:23:51.699296 | 
2026-02-16 17:23:51.699473 | LOOP [upload-logs : Upload console log and json output]