2026-02-16 17:45:15.845677 | Job console starting
2026-02-16 17:45:16.094400 | Updating git repos
2026-02-16 17:45:21.493316 | Cloning repos into workspace
2026-02-16 17:45:27.074396 | Restoring repo states
2026-02-16 17:45:27.699316 | Merging changes
2026-02-16 17:45:28.317211 | Checking out repos
2026-02-16 17:45:32.739685 | Preparing playbooks
2026-02-16 17:45:34.621387 | Running Ansible setup
2026-02-16 17:45:38.308239 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:45:38.971502 | 
2026-02-16 17:45:39.108333 | PLAY [localhost]
2026-02-16 17:45:39.125375 | 
2026-02-16 17:45:39.125494 | TASK [Gathering Facts]
2026-02-16 17:45:40.068461 | localhost | ok
2026-02-16 17:45:40.078458 | 
2026-02-16 17:45:40.078565 | TASK [Setup log path fact]
2026-02-16 17:45:40.102527 | localhost | ok
2026-02-16 17:45:40.118294 | 
2026-02-16 17:45:40.118360 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:45:40.151928 | localhost | ok
2026-02-16 17:45:40.161941 | 
2026-02-16 17:45:40.162038 | TASK [emit-job-header : Print job information]
2026-02-16 17:45:40.207732 | # Job Information
2026-02-16 17:45:40.207854 | Ansible Version: 2.16.15
2026-02-16 17:45:40.207885 | Job: magnum-cluster-api-sonobuoy-v1.32.12-cilium
2026-02-16 17:45:40.207906 | Pipeline: check
2026-02-16 17:45:40.207926 | Executor: 3a2793d2bd32
2026-02-16 17:45:40.207945 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:45:40.207967 | Event ID: 37691bf0-0b5f-11f1-8a15-a065d2c16437
2026-02-16 17:45:40.211176 | 
2026-02-16 17:45:40.211249 | LOOP [emit-job-header : Print node information]
2026-02-16 17:45:40.333421 | localhost | ok:
2026-02-16 17:45:40.333795 | localhost | # Node Information
2026-02-16 17:45:40.333925 | localhost | Inventory Hostname: controller
2026-02-16 17:45:40.333975 | localhost | Hostname: np0000155630
2026-02-16 17:45:40.334019 | localhost | Username: zuul
2026-02-16 17:45:40.334069 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:45:40.334113 | localhost | Provider: yul1
2026-02-16 17:45:40.334155 | localhost | Region: ca-ymq-1
2026-02-16 17:45:40.334196 | localhost | Label: ubuntu-noble-16
2026-02-16 17:45:40.334236 | localhost | Product Name: OpenStack Nova
2026-02-16 17:45:40.334276 | localhost | Interface IP: 199.204.45.102
2026-02-16 17:45:40.351680 | 
2026-02-16 17:45:40.351788 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:45:40.772693 | localhost -> localhost | changed
2026-02-16 17:45:40.783139 | 
2026-02-16 17:45:40.783215 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:45:41.664796 | localhost -> localhost | changed
2026-02-16 17:45:41.671384 | 
2026-02-16 17:45:41.671441 | PLAY [all]
2026-02-16 17:45:41.679643 | 
2026-02-16 17:45:41.679703 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:45:41.928535 | controller -> localhost | ok
2026-02-16 17:45:41.941257 | 
2026-02-16 17:45:41.941372 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:45:41.973664 | controller | ok
2026-02-16 17:45:41.994250 | controller | included: /var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:45:42.000938 | 
2026-02-16 17:45:42.001021 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:45:42.928679 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:45:42.929512 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/work/fdd3572fdfb34e1aa9a5038583ef3e31_id_rsa
2026-02-16 17:45:42.929559 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/work/fdd3572fdfb34e1aa9a5038583ef3e31_id_rsa.pub
2026-02-16 17:45:42.929583 | controller -> localhost | The key fingerprint is:
2026-02-16 17:45:42.929605 | controller -> localhost | SHA256:biJbUY/rSibRgHE6zX94z7Lcm2dHlsoj43Cv03wnht0 zuul-build-sshkey
2026-02-16 17:45:42.929640 | controller -> localhost | The key's randomart image is:
2026-02-16 17:45:42.929661 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:45:42.929688 | controller -> localhost | |  . .            |
2026-02-16 17:45:42.929709 | controller -> localhost | |   B             |
2026-02-16 17:45:42.929729 | controller -> localhost | |  + +   .        |
2026-02-16 17:45:42.929748 | controller -> localhost | |   . + o o       |
2026-02-16 17:45:42.929768 | controller -> localhost | |    . = S .    . |
2026-02-16 17:45:42.929787 | controller -> localhost | |     . = +    +  |
2026-02-16 17:45:42.929805 | controller -> localhost | |    o = * ++ * . |
2026-02-16 17:45:42.929824 | controller -> localhost | |     B = *+oX * E|
2026-02-16 17:45:42.929852 | controller -> localhost | |    . ..+.*O.= o |
2026-02-16 17:45:42.929873 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:45:42.929924 | controller -> localhost | ok: Runtime: 0:00:00.480497
2026-02-16 17:45:42.936015 | 
2026-02-16 17:45:42.936096 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:45:42.973399 | controller | ok
2026-02-16 17:45:42.983142 | controller | included: /var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:45:42.991596 | 
2026-02-16 17:45:42.991658 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:45:43.016894 | controller | skipping: Conditional result was False
2026-02-16 17:45:43.028657 | 
2026-02-16 17:45:43.028787 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:45:43.580058 | controller | changed
2026-02-16 17:45:43.587576 | 
2026-02-16 17:45:43.587647 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:45:43.819924 | controller | ok
2026-02-16 17:45:43.826328 | 
2026-02-16 17:45:43.826426 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:45:44.413049 | controller | changed
2026-02-16 17:45:44.496435 | 
2026-02-16 17:45:44.496598 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:45:45.092847 | controller | changed
2026-02-16 17:45:45.099742 | 
2026-02-16 17:45:45.099803 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:45:45.124929 | controller | skipping: Conditional result was False
2026-02-16 17:45:45.134451 | 
2026-02-16 17:45:45.134535 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:45:45.517911 | controller -> localhost | changed
2026-02-16 17:45:45.538454 | 
2026-02-16 17:45:45.539191 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:45:45.906947 | controller -> localhost | Identity added: /var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/work/fdd3572fdfb34e1aa9a5038583ef3e31_id_rsa (zuul-build-sshkey)
2026-02-16 17:45:45.907380 | controller -> localhost | ok: Runtime: 0:00:00.015742
2026-02-16 17:45:45.959261 | 
2026-02-16 17:45:45.959413 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:45:46.262924 | controller | ok
2026-02-16 17:45:46.268686 | 
2026-02-16 17:45:46.268754 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:45:46.313385 | controller | skipping: Conditional result was False
2026-02-16 17:45:46.334103 | 
2026-02-16 17:45:46.334188 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:45:46.656494 | controller | ok
2026-02-16 17:45:46.666397 | 
2026-02-16 17:45:46.666492 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:46:07.186021 | controller | Output suppressed because no_log was given
2026-02-16 17:46:07.196210 | 
2026-02-16 17:46:07.196272 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:46:07.421852 | controller | ok: "logs"
2026-02-16 17:46:07.422281 | controller | ok: All items complete
2026-02-16 17:46:07.422333 | 
2026-02-16 17:46:07.601958 | controller | ok: "artifacts"
2026-02-16 17:46:07.799510 | controller | ok: "docs"
2026-02-16 17:46:07.822980 | 
2026-02-16 17:46:07.823126 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:46:08.047071 | controller | changed: "logs"
2026-02-16 17:46:08.245541 | controller | changed: "artifacts"
2026-02-16 17:46:08.501275 | controller | changed: "docs"
2026-02-16 17:46:08.520900 | 
2026-02-16 17:46:08.521055 | PLAY RECAP
2026-02-16 17:46:08.521118 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:46:08.521159 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:46:08.521191 | 
2026-02-16 17:46:08.652845 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:46:09.225672 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:46:09.891291 | 
2026-02-16 17:46:09.892195 | PLAY [all]
2026-02-16 17:46:09.910834 | 
2026-02-16 17:46:09.910960 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:46:10.318997 | controller | changed
2026-02-16 17:46:10.327824 | 
2026-02-16 17:46:10.327906 | TASK [Gather minimum local MTU]
2026-02-16 17:46:10.400878 | controller | ok
2026-02-16 17:46:10.407839 | 
2026-02-16 17:46:10.407909 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:46:10.462328 | controller | ok
2026-02-16 17:46:10.468696 | 
2026-02-16 17:46:10.468769 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:46:10.494130 | controller | skipping: Conditional result was False
2026-02-16 17:46:10.504900 | 
2026-02-16 17:46:10.504994 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:46:11.051027 | controller | ok: Runtime: 0:00:00.009453
2026-02-16 17:46:11.059091 | 
2026-02-16 17:46:11.059162 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:46:11.085124 | controller | skipping: Conditional result was False
2026-02-16 17:46:11.096901 | 
2026-02-16 17:46:11.097292 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:46:11.125499 | controller | skipping: Conditional result was False
2026-02-16 17:46:11.138605 | 
2026-02-16 17:46:11.138680 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:46:11.202360 | controller | ok
2026-02-16 17:46:11.219462 | controller | included: /var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:46:11.229277 | 
2026-02-16 17:46:11.229347 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:46:11.288481 | controller | ok
2026-02-16 17:46:11.295972 | 
2026-02-16 17:46:11.296040 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:46:11.590165 | controller | ext4
2026-02-16 17:46:11.887874 | controller | ok: Runtime: 0:00:00.012212
2026-02-16 17:46:11.893740 | 
2026-02-16 17:46:11.893810 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:46:11.931385 | controller | ok
2026-02-16 17:46:11.938037 | 
2026-02-16 17:46:11.938109 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:46:11.973457 | controller | ok:
2026-02-16 17:46:12.056641 | controller | {
2026-02-16 17:46:12.056846 | controller |   "root_filesystem": "ext4"
2026-02-16 17:46:12.056902 | controller | }
2026-02-16 17:46:12.068558 | 
2026-02-16 17:46:12.068702 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:46:22.373213 | controller | 8192+0 records in
2026-02-16 17:46:22.373471 | controller | 8192+0 records out
2026-02-16 17:46:22.373490 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.0329 s, 856 MB/s
2026-02-16 17:46:22.617610 | controller | ok: Runtime: 0:00:10.044084
2026-02-16 17:46:22.634728 | 
2026-02-16 17:46:22.634799 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:46:22.905406 | controller | changed
2026-02-16 17:46:22.912512 | 
2026-02-16 17:46:22.912589 | TASK [configure-swap : Make swapfile]
2026-02-16 17:47:00.273984 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:47:00.275738 | controller | no label, UUID=400b71d4-89f3-4318-87d1-06ff00f2dcd3
2026-02-16 17:47:00.715162 | controller | ok: Runtime: 0:00:37.113096
2026-02-16 17:47:00.721599 | 
2026-02-16 17:47:00.721673 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:47:01.172563 | controller | changed
2026-02-16 17:47:01.178020 | 
2026-02-16 17:47:01.178088 | TASK [configure-swap : Add all swap]
2026-02-16 17:47:01.719296 | controller | ok: Runtime: 0:00:00.020862
2026-02-16 17:47:01.724804 | 
2026-02-16 17:47:01.724869 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:47:01.759633 | controller | ok:
2026-02-16 17:47:01.759755 | controller | {
2026-02-16 17:47:01.759779 | controller |   "swap_required": "8192"
2026-02-16 17:47:01.759800 | controller | }
2026-02-16 17:47:01.765183 | 
2026-02-16 17:47:01.765248 | TASK [configure-swap : Set swappiness]
2026-02-16 17:47:02.188546 | controller | changed
2026-02-16 17:47:02.197937 | 
2026-02-16 17:47:02.198027 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:47:02.233735 | controller | ok:
2026-02-16 17:47:02.233919 | controller | {
2026-02-16 17:47:02.233955 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:47:02.233991 | controller | }
2026-02-16 17:47:02.246533 | 
2026-02-16 17:47:02.246616 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:47:02.740102 | controller | changed
2026-02-16 17:47:02.746964 | 
2026-02-16 17:47:02.747032 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:47:02.995510 | controller | changed
2026-02-16 17:47:03.001049 | 
2026-02-16 17:47:03.001114 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:47:03.582140 | controller | changed
2026-02-16 17:47:03.588408 | 
2026-02-16 17:47:03.588477 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:47:03.833818 | controller | changed
2026-02-16 17:47:03.840117 | 
2026-02-16 17:47:03.840198 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:47:04.913532 | controller | changed
2026-02-16 17:47:04.919981 | 
2026-02-16 17:47:04.920129 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:47:05.161195 | controller | changed
2026-02-16 17:47:05.172659 | 
2026-02-16 17:47:05.172727 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:47:05.461407 | controller | changed
2026-02-16 17:47:06.200948 | 
2026-02-16 17:47:06.201126 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:47:06.579632 | controller | changed
2026-02-16 17:47:06.628170 | 
2026-02-16 17:47:06.628329 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:47:07.465581 | controller | changed
2026-02-16 17:47:07.480126 | 
2026-02-16 17:47:07.480280 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:47:07.812699 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:47:07.828314 | 
2026-02-16 17:47:07.828498 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:47:08.350159 | controller | ok: Item: Runtime: 0:00:00.210588
2026-02-16 17:47:08.916991 | controller | ok: Item: Runtime: 0:00:00.356528
2026-02-16 17:47:09.691203 | controller | ok: Item: Runtime: 0:00:00.547634
2026-02-16 17:47:09.972279 | controller | ok: Item: Runtime: 0:00:00.063342
2026-02-16 17:47:10.478023 | controller | ok: Item: Runtime: 0:00:00.268716
2026-02-16 17:47:12.133423 | controller | ok: Item: Runtime: 0:00:01.430909
2026-02-16 17:47:15.563512 | controller | ok: Item: Runtime: 0:00:03.206328
2026-02-16 17:47:15.887898 | controller | ok: Item: Runtime: 0:00:00.097724
2026-02-16 17:47:16.372293 | controller | ok: Item: Runtime: 0:00:00.200400
2026-02-16 17:47:16.789271 | controller | ok: Item: Runtime: 0:00:00.187583
2026-02-16 17:47:17.550850 | controller | ok: Item: Runtime: 0:00:00.529571
2026-02-16 17:47:18.300802 | controller | ok: Item: Runtime: 0:00:00.523553
2026-02-16 17:47:18.770804 | controller | ok: Item: Runtime: 0:00:00.244560
2026-02-16 17:47:19.258609 | controller | ok: Item: Runtime: 0:00:00.233351
2026-02-16 17:47:19.858980 | controller | ok: Item: Runtime: 0:00:00.379023
2026-02-16 17:47:21.298576 | controller | ok: Item: Runtime: 0:00:01.210056
2026-02-16 17:47:24.063852 | 
2026-02-16 17:47:24.407699 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:47:24.626770 | controller | ok: All paths examined
2026-02-16 17:47:24.634716 | 
2026-02-16 17:47:24.634786 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:47:24.897276 | controller | ok: All paths examined
2026-02-16 17:47:24.903554 | 
2026-02-16 17:47:24.903619 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:47:25.339331 | controller | ok: Item: Runtime: 0:00:00.147885
2026-02-16 17:47:25.339644 | controller | changed: All items complete
2026-02-16 17:47:25.339676 | 
2026-02-16 17:47:25.768803 | controller | ok: Item: Runtime: 0:00:00.193727
2026-02-16 17:47:25.786238 | 
2026-02-16 17:47:25.786345 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:47:25.829777 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.832169 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.834832 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.837428 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.840465 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.844803 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.847072 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.849855 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.853054 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.856051 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.859032 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.862050 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.865008 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.868090 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.871465 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.874542 | controller | skipping: Conditional result was False
2026-02-16 17:47:25.909360 | 
2026-02-16 17:47:25.909471 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:47:28.398156 | controller | changed
2026-02-16 17:47:28.408529 | 
2026-02-16 17:47:28.408596 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:47:28.668704 | controller | changed
2026-02-16 17:47:28.726899 | 
2026-02-16 17:47:28.727043 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:47:28.974682 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:47:29.265166 | controller | ERROR
2026-02-16 17:47:29.265413 | controller | {
2026-02-16 17:47:29.265454 | controller |   "delta": "0:00:00.010342",
2026-02-16 17:47:29.265482 | controller |   "end": "2026-02-16 17:47:28.975165",
2026-02-16 17:47:29.265508 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:29.265535 | controller |   "rc": 1,
2026-02-16 17:47:29.265560 | controller |   "start": "2026-02-16 17:47:28.964823"
2026-02-16 17:47:29.265585 | controller | }
2026-02-16 17:47:29.265635 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:29.272313 | 
2026-02-16 17:47:29.272383 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:47:29.520219 | controller | ok
2026-02-16 17:47:29.553881 | 
2026-02-16 17:47:29.554016 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:47:29.799250 | controller | /usr/bin/journalctl
2026-02-16 17:47:30.095308 | controller | ok: Runtime: 0:00:00.008764
2026-02-16 17:47:30.101968 | 
2026-02-16 17:47:30.102035 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:47:30.371604 | controller | 2026-02-16 17:47:30
2026-02-16 17:47:30.642631 | controller | ok: Runtime: 0:00:00.010241
2026-02-16 17:47:30.648423 | 
2026-02-16 17:47:30.648492 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:47:31.251924 | controller | changed
2026-02-16 17:47:31.258151 | 
2026-02-16 17:47:31.258223 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:47:31.284436 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.294208 | 
2026-02-16 17:47:31.294275 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:47:31.825090 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.018008 | 
2026-02-16 17:47:32.018132 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:47:32.548390 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.559188 | 
2026-02-16 17:47:32.559256 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:47:32.583878 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.593955 | 
2026-02-16 17:47:32.594070 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:47:33.127106 | controller | skipping: Conditional result was False
2026-02-16 17:47:33.139516 | 
2026-02-16 17:47:33.139637 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:47:33.671803 | controller | skipping: Conditional result was False
2026-02-16 17:47:33.682511 | 
2026-02-16 17:47:33.682607 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:47:33.707684 | controller | skipping: Conditional result was False
2026-02-16 17:47:33.718050 | 
2026-02-16 17:47:33.718141 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:47:33.744153 | controller | skipping: Conditional result was False
2026-02-16 17:47:34.240442 | 
2026-02-16 17:47:34.240630 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:47:34.809483 | controller | skipping: Conditional result was False
2026-02-16 17:47:34.819518 | 
2026-02-16 17:47:34.819614 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:47:35.351402 | controller | skipping: Conditional result was False
2026-02-16 17:47:35.362603 | 
2026-02-16 17:47:35.362693 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:47:35.387672 | controller | skipping: Conditional result was False
2026-02-16 17:47:35.398823 | 
2026-02-16 17:47:35.398916 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:47:35.424284 | controller | skipping: Conditional result was False
2026-02-16 17:47:35.440082 | 
2026-02-16 17:47:35.440149 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:47:35.844095 | controller | ok
2026-02-16 17:47:35.854221 | 
2026-02-16 17:47:35.854277 | PLAY RECAP
2026-02-16 17:47:35.854333 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:47:35.854356 | 
2026-02-16 17:47:35.988454 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:47:35.996259 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:47:36.574185 | 
2026-02-16 17:47:36.574337 | PLAY [all]
2026-02-16 17:47:36.586707 | 
2026-02-16 17:47:36.586817 | TASK [Install curl]
2026-02-16 17:47:37.231732 | controller | ERROR
2026-02-16 17:47:37.232088 | controller | {
2026-02-16 17:47:37.232146 | controller |   "msg": "No package matching 'curl' is available"
2026-02-16 17:47:37.232203 | controller | }
failure
2026-02-16 17:47:37.233900 | 
2026-02-16 17:47:37.233966 | PLAY RECAP
2026-02-16 17:47:37.234044 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:47:37.234078 | 
2026-02-16 17:47:37.399582 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:47:37.407769 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:47:37.993589 | 
2026-02-16 17:47:37.993731 | PLAY [all]
2026-02-16 17:47:38.010038 | 
2026-02-16 17:47:38.010122 | TASK [Return built artifacts to Zuul]
2026-02-16 17:47:39.229183 | controller | ok
2026-02-16 17:47:39.237558 | 
2026-02-16 17:47:39.237720 | TASK [Get the Docker volume ID]
2026-02-16 17:47:39.780451 | controller | ERROR
2026-02-16 17:47:39.780944 | controller | {
2026-02-16 17:47:39.781194 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:47:39.781252 | controller |   "rc": 2
2026-02-16 17:47:39.781297 | controller | }
2026-02-16 17:47:39.781355 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:39.789262 | 
2026-02-16 17:47:39.789331 | TASK [Copy all of the pod logs]
2026-02-16 17:47:39.824993 | controller | ERROR
2026-02-16 17:47:39.825172 | controller | {
2026-02-16 17:47:39.825203 | 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/fdd3572fdfb34e1aa9a5038583ef3e31/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:47:39.825225 | controller | }
2026-02-16 17:47:39.825252 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:39.826219 | 
2026-02-16 17:47:39.826263 | PLAY RECAP
2026-02-16 17:47:39.826303 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:47:39.826324 | 
2026-02-16 17:47:40.568748 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:47:40.577620 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:47:41.198026 | 
2026-02-16 17:47:41.213463 | PLAY [all]
2026-02-16 17:47:41.240100 | 
2026-02-16 17:47:41.240256 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:47:41.650766 | controller | changed
2026-02-16 17:47:41.659879 | 
2026-02-16 17:47:41.659963 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:47:42.714558 | controller | ok: Runtime: 0:00:00.519483
2026-02-16 17:47:43.486946 | 
2026-02-16 17:47:43.487088 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:47:44.022352 | controller | ok: Runtime: 0:00:00.021728
2026-02-16 17:47:44.028258 | 
2026-02-16 17:47:44.028330 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:47:44.565642 | controller | ok: Runtime: 0:00:00.055970
2026-02-16 17:47:44.574172 | 
2026-02-16 17:47:44.574294 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:47:45.430852 | controller | changed
2026-02-16 17:47:45.489257 | 
2026-02-16 17:47:45.489410 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:47:45.738250 | controller | changed
2026-02-16 17:47:45.743943 | 
2026-02-16 17:47:45.744016 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:47:46.073474 | controller | Output suppressed because no_log was given
2026-02-16 17:47:46.078810 | 
2026-02-16 17:47:46.078885 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:47:46.115026 | 
2026-02-16 17:47:46.115237 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:47:46.150814 | 
2026-02-16 17:47:46.150974 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:47:46.186669 | 
2026-02-16 17:47:46.186828 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:47:46.222030 | 
2026-02-16 17:47:46.222193 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:47:46.259880 | 
2026-02-16 17:47:46.260051 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:47:46.542299 | controller | changed
2026-02-16 17:47:46.548275 | 
2026-02-16 17:47:46.548345 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:47:46.582707 | controller | ok
2026-02-16 17:47:46.589459 | 
2026-02-16 17:47:46.589522 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:47:46.829719 | controller | Output suppressed because no_log was given
2026-02-16 17:47:46.834989 | 
2026-02-16 17:47:46.835057 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:47:46.876516 | 
2026-02-16 17:47:47.761716 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:47:47.803544 | 
2026-02-16 17:47:47.803747 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:47:48.503082 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:47:48.523799 | controller | Using python 3.12 to install setuptools
2026-02-16 17:47:48.589240 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:47:48.602408 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:47:48.842901 | controller | ERROR
2026-02-16 17:47:48.843156 | controller | {
2026-02-16 17:47:48.843191 | controller |   "delta": "0:00:00.548197",
2026-02-16 17:47:48.843212 | controller |   "end": "2026-02-16 17:47:48.605207",
2026-02-16 17:47:48.843231 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:48.843255 | controller |   "rc": 127,
2026-02-16 17:47:48.843273 | controller |   "start": "2026-02-16 17:47:48.057010"
2026-02-16 17:47:48.843290 | controller | }
2026-02-16 17:47:48.843315 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:48.855228 | 
2026-02-16 17:47:48.855305 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:47:49.134556 | controller | changed
2026-02-16 17:47:49.140483 | 
2026-02-16 17:47:49.140553 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:47:52.960995 | controller | Output suppressed because no_log was given
2026-02-16 17:47:52.974352 | 
2026-02-16 17:47:52.974462 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:47:53.010074 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.010654 | 
2026-02-16 17:47:53.012730 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.014345 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.015983 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.018178 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.020481 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.022890 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.025412 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.027817 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.030138 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.032604 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.034925 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.037263 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.040086 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.042700 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.044987 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.047436 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.050133 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.085295 | 
2026-02-16 17:47:53.085460 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:47:53.312994 | controller | ok
2026-02-16 17:47:53.318458 | 
2026-02-16 17:47:53.318526 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:47:53.889171 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.905959 | 
2026-02-16 17:47:53.906083 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:47:54.172247 | controller | sudo: iptables-save: command not found
2026-02-16 17:47:54.213045 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:47:55.309239 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:47:55.444437 | controller | ok: Runtime: 0:00:01.158819
2026-02-16 17:47:55.455866 | 
2026-02-16 17:47:55.455939 | LOOP [stage-output : Register sources]
2026-02-16 17:48:04.893763 | controller | Output suppressed because no_log was given
2026-02-16 17:48:04.941391 | 
2026-02-16 17:48:04.941540 | TASK [stage-output : Check sudo]
2026-02-16 17:48:05.489019 | controller | ok: Runtime: 0:00:00.019422
2026-02-16 17:48:06.463280 | 
2026-02-16 17:48:06.463393 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:48:06.552569 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.553054 | 
2026-02-16 17:48:06.554800 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.555574 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.557545 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.592080 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.602340 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.602717 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.603075 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.603422 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.603764 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.604099 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.604436 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.605311 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.607480 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.635600 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.635967 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.636311 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.636654 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.637021 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.637372 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.637708 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.638045 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.638404 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.659130 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.659604 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.744860 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.753212 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.771005 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.776532 | controller | Output suppressed because no_log was given
2026-02-16 17:48:06.791688 | 
2026-02-16 17:48:06.791792 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:48:06.859552 | controller | ok
2026-02-16 17:48:06.866868 | 
2026-02-16 17:48:06.866938 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:48:07.126900 | controller | changed: "docs"
2026-02-16 17:48:07.364438 | controller | changed: "artifacts"
2026-02-16 17:48:07.580030 | controller | ok: "logs"
2026-02-16 17:48:07.601115 | 
2026-02-16 17:48:07.601297 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:48:07.880903 | controller | ok: Item: Runtime: 0:00:00.010241
2026-02-16 17:48:07.881276 | controller | changed: All items complete
2026-02-16 17:48:07.881308 | 
2026-02-16 17:48:08.105162 | controller | ok: Item: Runtime: 0:00:00.008158
2026-02-16 17:48:08.336019 | controller | ok: Item: Runtime: 0:00:00.008287
2026-02-16 17:48:08.591379 | controller | ok: Item: Runtime: 0:00:00.008999
2026-02-16 17:48:08.817432 | controller | ok: Item: Runtime: 0:00:00.005161
2026-02-16 17:48:09.061845 | controller | ok: Item: Runtime: 0:00:00.004668
2026-02-16 17:48:09.281366 | controller | ok: Item: Runtime: 0:00:00.007882
2026-02-16 17:48:09.517997 | controller | ok: Item: Runtime: 0:00:00.007990
2026-02-16 17:48:09.749670 | controller | ok: Item: Runtime: 0:00:00.009387
2026-02-16 17:48:09.982409 | controller | ok: Item: Runtime: 0:00:00.008134
2026-02-16 17:48:10.228087 | controller | ok: Item: Runtime: 0:00:00.009709
2026-02-16 17:48:10.500701 | controller | ok: Item: Runtime: 0:00:00.007358
2026-02-16 17:48:10.707696 | controller | ok: Item: Runtime: 0:00:00.009047
2026-02-16 17:48:10.936935 | controller | ok: Item: Runtime: 0:00:00.009521
2026-02-16 17:48:11.166856 | controller | ok: Item: Runtime: 0:00:00.008095
2026-02-16 17:48:11.417880 | controller | ok: Item: Runtime: 0:00:00.010257
2026-02-16 17:48:11.439156 | 
2026-02-16 17:48:11.439316 | TASK [stage-output : Make all log files readable]
2026-02-16 17:48:11.698654 | controller | changed
2026-02-16 17:48:11.707323 | 
2026-02-16 17:48:11.707442 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:48:12.035724 | controller | changed: Renamed files for staging.
2026-02-16 17:48:12.163272 | 
2026-02-16 17:48:12.163462 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:48:12.180989 | controller | skipping: Conditional result was False
2026-02-16 17:48:12.192615 | 
2026-02-16 17:48:12.192713 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:48:12.236427 | 
2026-02-16 17:48:12.236569 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:48:12.860853 | controller | changed:
2026-02-16 17:48:12.861175 | controller | created directory /var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/work/logs/controller
2026-02-16 17:48:12.861241 | controller | cd+++++++++ logs/
2026-02-16 17:48:12.861288 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:48:12.861332 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:48:12.861438 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:48:12.861489 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:48:12.861533 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:48:12.861574 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:48:12.861617 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:48:12.861658 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:48:12.861708 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:48:12.861765 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:48:12.861808 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:48:12.861848 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:48:12.861900 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:48:12.861944 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:48:12.861985 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:48:12.862025 | controller | cd+++++++++ logs/apache/
2026-02-16 17:48:12.862066 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:48:12.862106 | controller | cd+++++++++ logs/etc/
2026-02-16 17:48:12.862146 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:48:12.862186 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:48:12.862227 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:48:12.862267 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:48:12.862330 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:48:12.862374 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:48:12.874229 | 
2026-02-16 17:48:12.874304 | TASK [Check if a tempest log exits]
2026-02-16 17:48:13.133202 | controller | ok
2026-02-16 17:48:13.140342 | 
2026-02-16 17:48:13.140426 | TASK [Link post-devstack tempest.log]
2026-02-16 17:48:13.165783 | controller | skipping: Conditional result was False
2026-02-16 17:48:13.175402 | 
2026-02-16 17:48:13.175495 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:48:13.435327 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:48:13.714997 | controller | ERROR
2026-02-16 17:48:13.715247 | controller | {
2026-02-16 17:48:13.715293 | controller |   "delta": "0:00:00.008762",
2026-02-16 17:48:13.715325 | controller |   "end": "2026-02-16 17:48:13.436061",
2026-02-16 17:48:13.715353 | controller |   "msg": "non-zero return code",
2026-02-16 17:48:13.715380 | controller |   "rc": 127,
2026-02-16 17:48:13.715406 | controller |   "start": "2026-02-16 17:48:13.427299"
2026-02-16 17:48:13.715433 | controller | }
2026-02-16 17:48:13.715467 | controller | ERROR: Ignoring Errors
2026-02-16 17:48:13.717650 | 
2026-02-16 17:48:13.717733 | PLAY RECAP
2026-02-16 17:48:13.717840 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:48:13.717881 | 
2026-02-16 17:48:13.928844 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:48:13.938125 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:48:14.573234 | 
2026-02-16 17:48:14.573365 | PLAY [all]
2026-02-16 17:48:14.584976 | 
2026-02-16 17:48:14.585078 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:48:14.640731 | controller | skipping: Conditional result was False
2026-02-16 17:48:14.651314 | 
2026-02-16 17:48:14.651431 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:48:14.696202 | controller | ok
2026-02-16 17:48:14.702596 | 
2026-02-16 17:48:14.702668 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:48:15.097830 | controller -> localhost | ok: "/var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/work/logs"
2026-02-16 17:48:15.299571 | controller -> localhost | changed: "/var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/work/artifacts"
2026-02-16 17:48:15.516342 | controller -> localhost | changed: "/var/lib/zuul/builds/fdd3572fdfb34e1aa9a5038583ef3e31/work/docs"
2026-02-16 17:48:15.532089 | 
2026-02-16 17:48:15.532261 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:48:16.177359 | controller | changed: .d..t...... ./
2026-02-16 17:48:16.177737 | controller | changed: All items complete
2026-02-16 17:48:16.177782 | 
2026-02-16 17:48:16.631627 | controller | changed: .d..t...... ./
2026-02-16 17:48:17.097188 | controller | changed: .d..t...... ./
2026-02-16 17:48:17.115541 | 
2026-02-16 17:48:17.115742 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:48:18.225839 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.699136
2026-02-16 17:48:18.445606 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008071
2026-02-16 17:48:18.465456 | 
2026-02-16 17:48:18.465611 | PLAY [all]
2026-02-16 17:48:18.473782 | 
2026-02-16 17:48:18.473856 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:48:18.937780 | controller | changed
2026-02-16 17:48:18.943831 | 
2026-02-16 17:48:18.943883 | PLAY RECAP
2026-02-16 17:48:18.943930 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:48:18.943951 | 
2026-02-16 17:48:19.066771 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:48:19.075012 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:48:19.676935 | 
2026-02-16 17:48:19.677113 | PLAY [localhost]
2026-02-16 17:48:19.688113 | 
2026-02-16 17:48:19.688196 | TASK [Generate Zuul manifest]
2026-02-16 17:48:19.710033 | localhost | ok
2026-02-16 17:48:19.725854 | 
2026-02-16 17:48:19.725937 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:48:21.353812 | localhost | changed
2026-02-16 17:48:21.367240 | 
2026-02-16 17:48:21.367320 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:48:21.768880 | localhost | ok
2026-02-16 17:48:21.775637 | 
2026-02-16 17:48:21.775704 | TASK [Upload logs]
2026-02-16 17:48:21.799976 | localhost | ok
2026-02-16 17:48:21.859447 | 
2026-02-16 17:48:21.859571 | TASK [Set zuul-log-path fact]
2026-02-16 17:48:21.882013 | localhost | ok
2026-02-16 17:48:21.895504 | 
2026-02-16 17:48:21.895576 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:48:21.926846 | localhost | ok
2026-02-16 17:48:21.935894 | 
2026-02-16 17:48:21.935975 | TASK [upload-logs : Create log directories]
2026-02-16 17:48:22.778756 | localhost | changed
2026-02-16 17:48:22.785378 | 
2026-02-16 17:48:22.785472 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:48:23.234649 | localhost -> localhost | ok: Runtime: 0:00:00.006009
2026-02-16 17:48:23.239613 | 
2026-02-16 17:48:23.239681 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:48:25.524601 | localhost | Output suppressed because no_log was given
2026-02-16 17:48:27.640414 | 
2026-02-16 17:48:27.640529 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:48:27.683041 | localhost | skipping: Conditional result was False
2026-02-16 17:48:27.689071 | localhost | skipping: Conditional result was False
2026-02-16 17:48:27.702654 | 
2026-02-16 17:48:27.702750 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:48:27.743738 | localhost | skipping: Conditional result was False
2026-02-16 17:48:27.744133 | 
2026-02-16 17:48:27.747119 | localhost | skipping: Conditional result was False
2026-02-16 17:48:27.755517 | 
2026-02-16 17:48:27.755610 | LOOP [upload-logs : Upload console log and json output]