2026-02-16 17:24:28.335204 | Job console starting
2026-02-16 17:24:28.366695 | Updating git repos
2026-02-16 17:24:37.142701 | Cloning repos into workspace
2026-02-16 17:24:43.301253 | Restoring repo states
2026-02-16 17:24:46.803995 | Merging changes
2026-02-16 17:24:47.328936 | Checking out repos
2026-02-16 17:24:50.408674 | Preparing playbooks
2026-02-16 17:24:51.928063 | Running Ansible setup
2026-02-16 17:24:57.785830 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:24:58.422481 | 
2026-02-16 17:24:58.422694 | PLAY [localhost]
2026-02-16 17:24:58.432138 | 
2026-02-16 17:24:58.432269 | TASK [Gathering Facts]
2026-02-16 17:24:59.313288 | localhost | ok
2026-02-16 17:24:59.326723 | 
2026-02-16 17:24:59.327412 | TASK [Setup log path fact]
2026-02-16 17:24:59.348338 | localhost | ok
2026-02-16 17:24:59.365263 | 
2026-02-16 17:24:59.365349 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:24:59.395348 | localhost | ok
2026-02-16 17:24:59.402913 | 
2026-02-16 17:24:59.402981 | TASK [emit-job-header : Print job information]
2026-02-16 17:24:59.445918 | # Job Information
2026-02-16 17:24:59.446111 | Ansible Version: 2.16.15
2026-02-16 17:24:59.446179 | Job: magnum-cluster-api-sonobuoy-v1.34.4-calico
2026-02-16 17:24:59.446272 | Pipeline: check
2026-02-16 17:24:59.446325 | Executor: 3a2793d2bd32
2026-02-16 17:24:59.446374 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:24:59.446424 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:24:59.449275 | 
2026-02-16 17:24:59.449337 | LOOP [emit-job-header : Print node information]
2026-02-16 17:24:59.544698 | localhost | ok:
2026-02-16 17:24:59.545049 | localhost | # Node Information
2026-02-16 17:24:59.545108 | localhost | Inventory Hostname: controller
2026-02-16 17:24:59.545154 | localhost | Hostname: np0000155598
2026-02-16 17:24:59.545192 | localhost | Username: zuul
2026-02-16 17:24:59.545231 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:24:59.545269 | localhost | Provider: yul1
2026-02-16 17:24:59.545305 | localhost | Region: ca-ymq-1
2026-02-16 17:24:59.545338 | localhost | Label: ubuntu-noble-16
2026-02-16 17:24:59.545373 | localhost | Product Name: OpenStack Nova
2026-02-16 17:24:59.545406 | localhost | Interface IP: 199.204.45.35
2026-02-16 17:24:59.556751 | 
2026-02-16 17:24:59.556876 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:24:59.926731 | localhost -> localhost | changed
2026-02-16 17:24:59.935585 | 
2026-02-16 17:24:59.935667 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:25:00.803819 | localhost -> localhost | changed
2026-02-16 17:25:00.815533 | 
2026-02-16 17:25:00.815654 | PLAY [all]
2026-02-16 17:25:00.832410 | 
2026-02-16 17:25:00.832851 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:25:01.065719 | controller -> localhost | ok
2026-02-16 17:25:01.074242 | 
2026-02-16 17:25:01.074311 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:25:01.105921 | controller | ok
2026-02-16 17:25:01.121714 | controller | included: /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:25:01.127818 | 
2026-02-16 17:25:01.127877 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:25:02.566919 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:25:02.567098 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/work/0b57477690fa411b859a939ca303d6b2_id_rsa
2026-02-16 17:25:02.567128 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/work/0b57477690fa411b859a939ca303d6b2_id_rsa.pub
2026-02-16 17:25:02.567180 | controller -> localhost | The key fingerprint is:
2026-02-16 17:25:02.567203 | controller -> localhost | SHA256:F2dUBJkfOgdTrrFbeBc537ohcB83iSzSvDLW//fUUX0 zuul-build-sshkey
2026-02-16 17:25:02.567232 | controller -> localhost | The key's randomart image is:
2026-02-16 17:25:02.567254 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:25:02.567307 | controller -> localhost | |            oB+  |
2026-02-16 17:25:02.567330 | controller -> localhost | |           .=.. o|
2026-02-16 17:25:02.567351 | controller -> localhost | |          . +=.=E|
2026-02-16 17:25:02.567371 | controller -> localhost | |          o++=+ O|
2026-02-16 17:25:02.567391 | controller -> localhost | |        S.o+==++=|
2026-02-16 17:25:02.567411 | controller -> localhost | |         .ooo= =+|
2026-02-16 17:25:02.567466 | controller -> localhost | |         + oo + o|
2026-02-16 17:25:02.567489 | controller -> localhost | |        . o .. +.|
2026-02-16 17:25:02.567532 | controller -> localhost | |             .o.+|
2026-02-16 17:25:02.567576 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:25:02.567664 | controller -> localhost | ok: Runtime: 0:00:01.008793
2026-02-16 17:25:02.578962 | 
2026-02-16 17:25:02.579093 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:25:02.619491 | controller | ok
2026-02-16 17:25:02.631070 | controller | included: /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:25:02.640896 | 
2026-02-16 17:25:02.640975 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:25:02.667339 | controller | skipping: Conditional result was False
2026-02-16 17:25:02.675867 | 
2026-02-16 17:25:02.675932 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:25:03.203531 | controller | changed
2026-02-16 17:25:03.211035 | 
2026-02-16 17:25:03.211104 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:25:03.429203 | controller | ok
2026-02-16 17:25:03.437351 | 
2026-02-16 17:25:03.437417 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:25:04.003774 | controller | changed
2026-02-16 17:25:04.012247 | 
2026-02-16 17:25:04.012370 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:25:04.559774 | controller | changed
2026-02-16 17:25:04.567283 | 
2026-02-16 17:25:04.567349 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:25:04.594803 | controller | skipping: Conditional result was False
2026-02-16 17:25:04.602955 | 
2026-02-16 17:25:04.603024 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:25:04.988731 | controller -> localhost | changed
2026-02-16 17:25:05.003807 | 
2026-02-16 17:25:05.003877 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:25:05.333758 | controller -> localhost | Identity added: /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/work/0b57477690fa411b859a939ca303d6b2_id_rsa (zuul-build-sshkey)
2026-02-16 17:25:05.334060 | controller -> localhost | ok: Runtime: 0:00:00.012389
2026-02-16 17:25:05.408776 | 
2026-02-16 17:25:05.409062 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:25:05.721300 | controller | ok
2026-02-16 17:25:05.727195 | 
2026-02-16 17:25:05.727257 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:25:05.751842 | controller | skipping: Conditional result was False
2026-02-16 17:25:05.768655 | 
2026-02-16 17:25:05.768719 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:25:06.088560 | controller | ok
2026-02-16 17:25:06.095548 | 
2026-02-16 17:25:06.095619 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:25:26.591745 | controller | Output suppressed because no_log was given
2026-02-16 17:25:26.601500 | 
2026-02-16 17:25:26.601564 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:25:26.846919 | controller | ok: "logs"
2026-02-16 17:25:26.847319 | controller | ok: All items complete
2026-02-16 17:25:26.847381 | 
2026-02-16 17:25:27.023269 | controller | ok: "artifacts"
2026-02-16 17:25:27.223378 | controller | ok: "docs"
2026-02-16 17:25:27.242713 | 
2026-02-16 17:25:27.242881 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:25:27.469695 | controller | changed: "logs"
2026-02-16 17:25:27.671172 | controller | changed: "artifacts"
2026-02-16 17:25:27.906027 | controller | changed: "docs"
2026-02-16 17:25:27.927203 | 
2026-02-16 17:25:27.927339 | PLAY RECAP
2026-02-16 17:25:27.927389 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:25:27.927418 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:25:27.927439 | 
2026-02-16 17:25:28.092944 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:25:28.098293 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:25:28.794727 | 
2026-02-16 17:25:28.794890 | PLAY [all]
2026-02-16 17:25:28.813091 | 
2026-02-16 17:25:28.813168 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:25:29.181088 | controller | changed
2026-02-16 17:25:29.465440 | 
2026-02-16 17:25:29.465625 | TASK [Gather minimum local MTU]
2026-02-16 17:25:29.549775 | controller | ok
2026-02-16 17:25:29.556620 | 
2026-02-16 17:25:29.556684 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:25:29.612621 | controller | ok
2026-02-16 17:25:29.618019 | 
2026-02-16 17:25:29.618091 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:25:29.644229 | controller | skipping: Conditional result was False
2026-02-16 17:25:29.657678 | 
2026-02-16 17:25:29.657769 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:25:30.198305 | controller | ok: Runtime: 0:00:00.010067
2026-02-16 17:25:30.622252 | 
2026-02-16 17:25:30.622399 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:25:30.657516 | controller | skipping: Conditional result was False
2026-02-16 17:25:30.667902 | 
2026-02-16 17:25:30.668021 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:25:30.694439 | controller | skipping: Conditional result was False
2026-02-16 17:25:30.704561 | 
2026-02-16 17:25:30.704637 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:25:30.761699 | controller | ok
2026-02-16 17:25:30.773359 | controller | included: /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:25:30.779702 | 
2026-02-16 17:25:30.779770 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:25:30.836534 | controller | ok
2026-02-16 17:25:30.844854 | 
2026-02-16 17:25:30.845017 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:25:31.117784 | controller | ext4
2026-02-16 17:25:31.420577 | controller | ok: Runtime: 0:00:00.011888
2026-02-16 17:25:31.425916 | 
2026-02-16 17:25:31.425987 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:25:31.461715 | controller | ok
2026-02-16 17:25:31.467690 | 
2026-02-16 17:25:31.467753 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:25:31.499808 | controller | ok:
2026-02-16 17:25:31.500025 | controller | {
2026-02-16 17:25:31.500064 | controller |   "root_filesystem": "ext4"
2026-02-16 17:25:31.500092 | controller | }
2026-02-16 17:25:31.508329 | 
2026-02-16 17:25:31.508415 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:25:41.352377 | controller | 8192+0 records in
2026-02-16 17:25:41.352691 | controller | 8192+0 records out
2026-02-16 17:25:41.352719 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.58099 s, 897 MB/s
2026-02-16 17:25:45.879747 | controller | ok: Runtime: 0:00:09.590894
2026-02-16 17:25:45.885876 | 
2026-02-16 17:25:45.885944 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:25:46.138036 | controller | changed
2026-02-16 17:25:46.144752 | 
2026-02-16 17:25:46.144820 | TASK [configure-swap : Make swapfile]
2026-02-16 17:25:54.348345 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:25:54.348535 | controller | no label, UUID=d41a7b8e-ba8f-4fa5-93bf-765764433b61
2026-02-16 17:25:54.773858 | controller | ok: Runtime: 0:00:07.952125
2026-02-16 17:25:54.781045 | 
2026-02-16 17:25:54.781117 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:25:55.375330 | controller | changed
2026-02-16 17:25:55.381226 | 
2026-02-16 17:25:55.381299 | TASK [configure-swap : Add all swap]
2026-02-16 17:25:55.924105 | controller | ok: Runtime: 0:00:00.198659
2026-02-16 17:25:55.930456 | 
2026-02-16 17:25:55.930525 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:25:55.965786 | controller | ok:
2026-02-16 17:25:55.965959 | controller | {
2026-02-16 17:25:55.965997 | controller |   "swap_required": "8192"
2026-02-16 17:25:55.966027 | controller | }
2026-02-16 17:25:55.973355 | 
2026-02-16 17:25:55.973439 | TASK [configure-swap : Set swappiness]
2026-02-16 17:25:56.445424 | controller | changed
2026-02-16 17:25:56.453251 | 
2026-02-16 17:25:56.453345 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:25:56.498277 | controller | ok:
2026-02-16 17:25:56.498501 | controller | {
2026-02-16 17:25:56.498539 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:25:56.498575 | controller | }
2026-02-16 17:25:56.509310 | 
2026-02-16 17:25:56.509412 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:25:57.135493 | controller | changed
2026-02-16 17:25:57.143037 | 
2026-02-16 17:25:57.143103 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:25:57.392823 | controller | changed
2026-02-16 17:25:57.398632 | 
2026-02-16 17:25:57.398699 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:25:57.962074 | controller | changed
2026-02-16 17:25:57.968492 | 
2026-02-16 17:25:57.968558 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:25:58.230153 | controller | changed
2026-02-16 17:25:58.459646 | 
2026-02-16 17:25:58.459770 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:25:59.544163 | controller | changed
2026-02-16 17:25:59.550316 | 
2026-02-16 17:25:59.550383 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:25:59.793775 | controller | changed
2026-02-16 17:25:59.804790 | 
2026-02-16 17:25:59.804854 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:26:00.099614 | controller | changed
2026-02-16 17:26:00.104845 | 
2026-02-16 17:26:00.104910 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:26:00.485425 | controller | changed
2026-02-16 17:26:00.538509 | 
2026-02-16 17:26:00.538675 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:26:01.320162 | controller | changed
2026-02-16 17:26:01.339272 | 
2026-02-16 17:26:01.339383 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:26:01.679535 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:26:01.692796 | 
2026-02-16 17:26:01.692865 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:26:02.151324 | controller | ok: Item: Runtime: 0:00:00.176285
2026-02-16 17:26:02.725544 | controller | ok: Item: Runtime: 0:00:00.354857
2026-02-16 17:26:03.456690 | controller | ok: Item: Runtime: 0:00:00.514822
2026-02-16 17:26:03.741845 | controller | ok: Item: Runtime: 0:00:00.065530
2026-02-16 17:26:04.220076 | controller | ok: Item: Runtime: 0:00:00.255742
2026-02-16 17:26:05.760325 | controller | ok: Item: Runtime: 0:00:01.310265
2026-02-16 17:26:09.203713 | controller | ok: Item: Runtime: 0:00:03.222023
2026-02-16 17:26:09.529441 | controller | ok: Item: Runtime: 0:00:00.095081
2026-02-16 17:26:09.944798 | controller | ok: Item: Runtime: 0:00:00.190402
2026-02-16 17:26:10.278053 | controller | ok: Item: Runtime: 0:00:00.118670
2026-02-16 17:26:11.044930 | controller | ok: Item: Runtime: 0:00:00.547261
2026-02-16 17:26:11.808850 | controller | ok: Item: Runtime: 0:00:00.543863
2026-02-16 17:26:12.277069 | controller | ok: Item: Runtime: 0:00:00.241931
2026-02-16 17:26:12.720198 | controller | ok: Item: Runtime: 0:00:00.220877
2026-02-16 17:26:13.319568 | controller | ok: Item: Runtime: 0:00:00.383440
2026-02-16 17:26:14.677229 | controller | ok: Item: Runtime: 0:00:01.137720
2026-02-16 17:26:14.704706 | 
2026-02-16 17:26:14.704883 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:26:14.926628 | controller | ok: All paths examined
2026-02-16 17:26:14.933237 | 
2026-02-16 17:26:14.933309 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:26:15.171832 | controller | ok: All paths examined
2026-02-16 17:26:15.178127 | 
2026-02-16 17:26:15.178193 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:26:15.613509 | controller | ok: Item: Runtime: 0:00:00.158445
2026-02-16 17:26:15.613770 | controller | changed: All items complete
2026-02-16 17:26:15.613800 | 
2026-02-16 17:26:16.008003 | controller | ok: Item: Runtime: 0:00:00.180505
2026-02-16 17:26:16.022515 | 
2026-02-16 17:26:16.022683 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:26:16.065464 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.067904 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.069911 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.073141 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.075849 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.078809 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.081958 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.085295 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.088381 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.091374 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.094294 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.097247 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.100193 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.103350 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.106427 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.109609 | controller | skipping: Conditional result was False
2026-02-16 17:26:16.136448 | 
2026-02-16 17:26:16.136620 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:26:18.537139 | controller | changed
2026-02-16 17:26:18.550792 | 
2026-02-16 17:26:18.550868 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:26:18.793596 | controller | changed
2026-02-16 17:26:18.854176 | 
2026-02-16 17:26:18.854304 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:26:19.107290 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:26:19.397016 | controller | ERROR
2026-02-16 17:26:19.397243 | controller | {
2026-02-16 17:26:19.397273 | controller |   "delta": "0:00:00.009006",
2026-02-16 17:26:19.397295 | controller |   "end": "2026-02-16 17:26:19.107834",
2026-02-16 17:26:19.397313 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:19.397332 | controller |   "rc": 1,
2026-02-16 17:26:19.397350 | controller |   "start": "2026-02-16 17:26:19.098828"
2026-02-16 17:26:19.397368 | controller | }
2026-02-16 17:26:19.397394 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:19.402991 | 
2026-02-16 17:26:19.403064 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:26:19.652199 | controller | ok
2026-02-16 17:26:19.663901 | 
2026-02-16 17:26:19.663965 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:26:19.889986 | controller | /usr/bin/journalctl
2026-02-16 17:26:20.206575 | controller | ok: Runtime: 0:00:00.008685
2026-02-16 17:26:20.212608 | 
2026-02-16 17:26:20.212681 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:26:20.471361 | controller | 2026-02-16 17:26:20
2026-02-16 17:26:20.752044 | controller | ok: Runtime: 0:00:00.011020
2026-02-16 17:26:20.760809 | 
2026-02-16 17:26:20.760986 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:26:21.342899 | controller | changed
2026-02-16 17:26:21.351955 | 
2026-02-16 17:26:21.352192 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:26:21.380274 | controller | skipping: Conditional result was False
2026-02-16 17:26:21.395228 | 
2026-02-16 17:26:21.395599 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:26:21.933993 | controller | skipping: Conditional result was False
2026-02-16 17:26:21.947244 | 
2026-02-16 17:26:21.947316 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:26:22.480607 | controller | skipping: Conditional result was False
2026-02-16 17:26:22.487888 | 
2026-02-16 17:26:22.487968 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:26:22.513316 | controller | skipping: Conditional result was False
2026-02-16 17:26:22.525344 | 
2026-02-16 17:26:22.525409 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:26:23.064113 | controller | skipping: Conditional result was False
2026-02-16 17:26:23.074883 | 
2026-02-16 17:26:23.074976 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:26:23.614080 | controller | skipping: Conditional result was False
2026-02-16 17:26:23.626717 | 
2026-02-16 17:26:23.626788 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:26:23.651887 | controller | skipping: Conditional result was False
2026-02-16 17:26:23.674620 | 
2026-02-16 17:26:23.674925 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:26:23.700790 | controller | skipping: Conditional result was False
2026-02-16 17:26:23.712822 | 
2026-02-16 17:26:23.712892 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:26:24.307006 | controller | skipping: Conditional result was False
2026-02-16 17:26:24.317635 | 
2026-02-16 17:26:24.317731 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:26:24.849999 | controller | skipping: Conditional result was False
2026-02-16 17:26:24.861978 | 
2026-02-16 17:26:24.862053 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:26:24.887590 | controller | skipping: Conditional result was False
2026-02-16 17:26:24.900179 | 
2026-02-16 17:26:24.900254 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:26:24.926620 | controller | skipping: Conditional result was False
2026-02-16 17:26:24.946461 | 
2026-02-16 17:26:24.946589 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:26:25.362403 | controller | ok
2026-02-16 17:26:25.371564 | 
2026-02-16 17:26:25.371619 | PLAY RECAP
2026-02-16 17:26:25.371664 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:26:25.371685 | 
2026-02-16 17:26:25.555188 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:26:25.560308 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:26.157151 | 
2026-02-16 17:26:26.157279 | PLAY [all]
2026-02-16 17:26:26.168881 | 
2026-02-16 17:26:26.168981 | TASK [ensure-rust : Use rustup]
2026-02-16 17:26:26.202220 | controller | ok
2026-02-16 17:26:26.211182 | controller | included: /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:26:26.216479 | 
2026-02-16 17:26:26.216549 | TASK [ensure-rust : Install Rust]
2026-02-16 17:26:26.623075 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:26:26.769276 | controller | ERROR
2026-02-16 17:26:26.769509 | controller | {
2026-02-16 17:26:26.769539 | controller |   "delta": "0:00:00.012996",
2026-02-16 17:26:26.769562 | controller |   "end": "2026-02-16 17:26:26.626319",
2026-02-16 17:26:26.769582 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:26.769601 | controller |   "rc": 127,
2026-02-16 17:26:26.769621 | controller |   "start": "2026-02-16 17:26:26.613323"
2026-02-16 17:26:26.769641 | controller | }
failure
2026-02-16 17:26:26.771313 | 
2026-02-16 17:26:26.771362 | PLAY RECAP
2026-02-16 17:26:26.771414 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:26:26.771435 | 
2026-02-16 17:26:26.894756 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:26.901585 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:27.507414 | 
2026-02-16 17:26:27.507553 | PLAY [all]
2026-02-16 17:26:27.523465 | 
2026-02-16 17:26:27.523545 | TASK [Return built artifacts to Zuul]
2026-02-16 17:26:27.558907 | controller | ok
2026-02-16 17:26:27.565656 | 
2026-02-16 17:26:27.565730 | TASK [Get the Docker volume ID]
2026-02-16 17:26:28.106650 | controller | ERROR
2026-02-16 17:26:28.107142 | controller | {
2026-02-16 17:26:28.107215 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:26:28.107264 | controller |   "rc": 2
2026-02-16 17:26:28.107310 | controller | }
2026-02-16 17:26:28.107367 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:28.113683 | 
2026-02-16 17:26:28.113747 | TASK [Copy all of the pod logs]
2026-02-16 17:26:28.147901 | controller | ERROR
2026-02-16 17:26:28.148061 | controller | {
2026-02-16 17:26:28.148090 | 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/0b57477690fa411b859a939ca303d6b2/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:26:28.148113 | controller | }
2026-02-16 17:26:28.148139 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:28.149200 | 
2026-02-16 17:26:28.149244 | PLAY RECAP
2026-02-16 17:26:28.149289 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:26:28.149310 | 
2026-02-16 17:26:28.280636 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:28.289011 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:26:28.888424 | 
2026-02-16 17:26:28.888552 | PLAY [all]
2026-02-16 17:26:28.907175 | 
2026-02-16 17:26:28.907257 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:26:29.352758 | controller | changed
2026-02-16 17:26:29.360237 | 
2026-02-16 17:26:29.360315 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:26:30.400112 | controller | ok: Runtime: 0:00:00.467638
2026-02-16 17:26:30.406624 | 
2026-02-16 17:26:30.406699 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:26:30.945128 | controller | ok: Runtime: 0:00:00.018874
2026-02-16 17:26:30.953537 | 
2026-02-16 17:26:30.953628 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:26:31.492945 | controller | ok: Runtime: 0:00:00.019246
2026-02-16 17:26:31.499555 | 
2026-02-16 17:26:31.499626 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:26:32.433042 | controller | changed
2026-02-16 17:26:32.483566 | 
2026-02-16 17:26:32.483713 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:26:32.723289 | controller | changed
2026-02-16 17:26:32.729218 | 
2026-02-16 17:26:32.729286 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:26:33.061500 | controller | Output suppressed because no_log was given
2026-02-16 17:26:33.067109 | 
2026-02-16 17:26:33.067187 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:26:33.105311 | 
2026-02-16 17:26:33.105544 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:26:33.142871 | 
2026-02-16 17:26:33.143109 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:26:33.179278 | 
2026-02-16 17:26:33.179453 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:26:33.214893 | 
2026-02-16 17:26:33.215086 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:26:33.249846 | 
2026-02-16 17:26:33.250014 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:26:33.510111 | controller | changed
2026-02-16 17:26:33.516836 | 
2026-02-16 17:26:33.516907 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:26:33.554046 | controller | ok
2026-02-16 17:26:33.561145 | 
2026-02-16 17:26:33.561225 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:26:33.805787 | controller | Output suppressed because no_log was given
2026-02-16 17:26:33.812024 | 
2026-02-16 17:26:33.812089 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:26:33.848804 | 
2026-02-16 17:26:33.848921 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:26:33.877820 | 
2026-02-16 17:26:33.877978 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:26:34.626551 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:26:34.645558 | controller | Using python 3.12 to install setuptools
2026-02-16 17:26:34.703710 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:26:34.716399 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:26:34.918998 | controller | ERROR
2026-02-16 17:26:34.919327 | controller | {
2026-02-16 17:26:34.919397 | controller |   "delta": "0:00:00.572939",
2026-02-16 17:26:34.919442 | controller |   "end": "2026-02-16 17:26:34.719006",
2026-02-16 17:26:34.919485 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:34.919536 | controller |   "rc": 127,
2026-02-16 17:26:34.919576 | controller |   "start": "2026-02-16 17:26:34.146067"
2026-02-16 17:26:34.919616 | controller | }
2026-02-16 17:26:34.919670 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:34.932720 | 
2026-02-16 17:26:34.932795 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:26:35.183203 | controller | changed
2026-02-16 17:26:35.190945 | 
2026-02-16 17:26:35.191036 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:26:38.962663 | controller | Output suppressed because no_log was given
2026-02-16 17:26:38.974231 | 
2026-02-16 17:26:38.974296 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:26:39.008708 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.009223 | 
2026-02-16 17:26:39.011321 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.012330 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.013987 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.016411 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.018687 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.020928 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.023280 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.025556 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.027776 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.030192 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.032395 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.034622 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.036794 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.039175 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.041383 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.043603 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.046821 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.076988 | 
2026-02-16 17:26:39.077180 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:26:39.315339 | controller | ok
2026-02-16 17:26:39.321141 | 
2026-02-16 17:26:39.321206 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:26:39.910167 | controller | skipping: Conditional result was False
2026-02-16 17:26:39.927819 | 
2026-02-16 17:26:39.927956 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:26:40.205068 | controller | sudo: iptables-save: command not found
2026-02-16 17:26:40.245403 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:26:41.359799 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:26:41.471010 | controller | ok: Runtime: 0:00:01.181464
2026-02-16 17:26:41.484048 | 
2026-02-16 17:26:41.484126 | LOOP [stage-output : Register sources]
2026-02-16 17:26:51.039371 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.097002 | 
2026-02-16 17:26:51.097190 | TASK [stage-output : Check sudo]
2026-02-16 17:26:51.635642 | controller | ok: Runtime: 0:00:00.021891
2026-02-16 17:26:51.642734 | 
2026-02-16 17:26:51.642808 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:26:51.759451 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.759960 | 
2026-02-16 17:26:51.761783 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.762317 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.764306 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.801820 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.802338 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.802796 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.804043 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.806534 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.808672 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.810850 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.813045 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.815157 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.817494 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.836229 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.836566 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.836886 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.837220 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.837537 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.839111 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.841233 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.844070 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.846553 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.870917 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.871247 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.958458 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.969509 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.980599 | controller | Output suppressed because no_log was given
2026-02-16 17:26:51.992567 | controller | Output suppressed because no_log was given
2026-02-16 17:26:52.006412 | 
2026-02-16 17:26:52.006483 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:26:52.073709 | controller | ok
2026-02-16 17:26:52.080648 | 
2026-02-16 17:26:52.080712 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:26:52.358820 | controller | changed: "docs"
2026-02-16 17:26:52.572634 | controller | changed: "artifacts"
2026-02-16 17:26:52.796876 | controller | ok: "logs"
2026-02-16 17:26:52.810259 | 
2026-02-16 17:26:52.810424 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:26:53.123126 | controller | ok: Item: Runtime: 0:00:00.012653
2026-02-16 17:26:53.123395 | controller | changed: All items complete
2026-02-16 17:26:53.123423 | 
2026-02-16 17:26:53.358861 | controller | ok: Item: Runtime: 0:00:00.009118
2026-02-16 17:26:53.621755 | controller | ok: Item: Runtime: 0:00:00.010060
2026-02-16 17:26:53.859072 | controller | ok: Item: Runtime: 0:00:00.009523
2026-02-16 17:26:54.066408 | controller | ok: Item: Runtime: 0:00:00.008005
2026-02-16 17:26:54.313777 | controller | ok: Item: Runtime: 0:00:00.010005
2026-02-16 17:26:54.559653 | controller | ok: Item: Runtime: 0:00:00.009424
2026-02-16 17:26:54.795048 | controller | ok: Item: Runtime: 0:00:00.008481
2026-02-16 17:26:55.030203 | controller | ok: Item: Runtime: 0:00:00.009646
2026-02-16 17:26:55.275238 | controller | ok: Item: Runtime: 0:00:00.012570
2026-02-16 17:26:55.498399 | controller | ok: Item: Runtime: 0:00:00.008884
2026-02-16 17:26:55.792283 | controller | ok: Item: Runtime: 0:00:00.013000
2026-02-16 17:26:55.989761 | controller | ok: Item: Runtime: 0:00:00.009871
2026-02-16 17:26:56.234968 | controller | ok: Item: Runtime: 0:00:00.008736
2026-02-16 17:26:56.465767 | controller | ok: Item: Runtime: 0:00:00.009121
2026-02-16 17:26:56.700026 | controller | ok: Item: Runtime: 0:00:00.009236
2026-02-16 17:26:56.726583 | 
2026-02-16 17:26:56.726734 | TASK [stage-output : Make all log files readable]
2026-02-16 17:26:56.981846 | controller | changed
2026-02-16 17:26:56.989018 | 
2026-02-16 17:26:56.989089 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:26:57.335970 | controller | changed: Renamed files for staging.
2026-02-16 17:26:57.342929 | 
2026-02-16 17:26:57.342991 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:26:57.368623 | controller | skipping: Conditional result was False
2026-02-16 17:26:57.390724 | 
2026-02-16 17:26:57.390808 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:26:57.434224 | 
2026-02-16 17:26:57.434371 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:26:58.119669 | controller | changed:
2026-02-16 17:26:58.119848 | controller | created directory /var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/work/logs/controller
2026-02-16 17:26:58.119876 | controller | cd+++++++++ logs/
2026-02-16 17:26:58.119897 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:26:58.119917 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:26:58.119936 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:26:58.119955 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:26:58.119973 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:26:58.119991 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:26:58.120009 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:26:58.120027 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:26:58.120045 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:26:58.120063 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:26:58.120081 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:26:58.120099 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:26:58.120119 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:26:58.120153 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:26:58.120173 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:26:58.120191 | controller | cd+++++++++ logs/apache/
2026-02-16 17:26:58.120208 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:26:58.120226 | controller | cd+++++++++ logs/etc/
2026-02-16 17:26:58.120243 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:26:58.120260 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:26:58.120278 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:26:58.120295 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:26:58.120312 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:26:58.120331 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:26:58.133402 | 
2026-02-16 17:26:58.133461 | TASK [Check if a tempest log exits]
2026-02-16 17:26:58.379819 | controller | ok
2026-02-16 17:26:58.386858 | 
2026-02-16 17:26:58.386922 | TASK [Link post-devstack tempest.log]
2026-02-16 17:26:58.411617 | controller | skipping: Conditional result was False
2026-02-16 17:26:58.421567 | 
2026-02-16 17:26:58.421654 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:26:58.674072 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:26:58.967445 | controller | ERROR
2026-02-16 17:26:58.967694 | controller | {
2026-02-16 17:26:58.967758 | controller |   "delta": "0:00:00.009247",
2026-02-16 17:26:58.967788 | controller |   "end": "2026-02-16 17:26:58.674845",
2026-02-16 17:26:58.967815 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:58.967841 | controller |   "rc": 127,
2026-02-16 17:26:58.967867 | controller |   "start": "2026-02-16 17:26:58.665598"
2026-02-16 17:26:58.967892 | controller | }
2026-02-16 17:26:58.967929 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:58.969730 | 
2026-02-16 17:26:58.969787 | PLAY RECAP
2026-02-16 17:26:58.969847 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:26:58.969877 | 
2026-02-16 17:26:59.145886 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:26:59.154743 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:26:59.864400 | 
2026-02-16 17:26:59.864564 | PLAY [all]
2026-02-16 17:26:59.876687 | 
2026-02-16 17:26:59.876764 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:26:59.922019 | controller | skipping: Conditional result was False
2026-02-16 17:26:59.929725 | 
2026-02-16 17:26:59.929833 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:26:59.973679 | controller | ok
2026-02-16 17:26:59.979725 | 
2026-02-16 17:26:59.979797 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:27:00.374393 | controller -> localhost | ok: "/var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/work/logs"
2026-02-16 17:27:00.591721 | controller -> localhost | changed: "/var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/work/artifacts"
2026-02-16 17:27:00.834344 | controller -> localhost | changed: "/var/lib/zuul/builds/0b57477690fa411b859a939ca303d6b2/work/docs"
2026-02-16 17:27:00.845819 | 
2026-02-16 17:27:00.846051 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:27:01.497592 | controller | changed: .d..t...... ./
2026-02-16 17:27:01.497903 | controller | changed: All items complete
2026-02-16 17:27:01.497942 | 
2026-02-16 17:27:01.958652 | controller | changed: .d..t...... ./
2026-02-16 17:27:02.427802 | controller | changed: .d..t...... ./
2026-02-16 17:27:02.455027 | 
2026-02-16 17:27:02.455647 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:27:02.906080 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009286
2026-02-16 17:27:03.142250 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008620
2026-02-16 17:27:03.155287 | 
2026-02-16 17:27:03.155433 | PLAY [all]
2026-02-16 17:27:03.163417 | 
2026-02-16 17:27:03.163492 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:27:03.659195 | controller | changed
2026-02-16 17:27:03.665194 | 
2026-02-16 17:27:03.665245 | PLAY RECAP
2026-02-16 17:27:03.665296 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:27:03.665319 | 
2026-02-16 17:27:03.800333 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:27:03.806891 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:27:04.407295 | 
2026-02-16 17:27:04.407443 | PLAY [localhost]
2026-02-16 17:27:04.418602 | 
2026-02-16 17:27:04.418677 | TASK [Generate Zuul manifest]
2026-02-16 17:27:04.441180 | localhost | ok
2026-02-16 17:27:04.456010 | 
2026-02-16 17:27:04.456086 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:27:04.832470 | localhost | changed
2026-02-16 17:27:04.846688 | 
2026-02-16 17:27:04.846951 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:27:04.878501 | localhost | ok
2026-02-16 17:27:04.885546 | 
2026-02-16 17:27:04.885608 | TASK [Upload logs]
2026-02-16 17:27:04.907594 | localhost | ok
2026-02-16 17:27:04.967077 | 
2026-02-16 17:27:04.967195 | TASK [Set zuul-log-path fact]
2026-02-16 17:27:04.989587 | localhost | ok
2026-02-16 17:27:05.001995 | 
2026-02-16 17:27:05.002061 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:27:05.034143 | localhost | ok
2026-02-16 17:27:05.042150 | 
2026-02-16 17:27:05.042215 | TASK [upload-logs : Create log directories]
2026-02-16 17:27:05.459143 | localhost | changed
2026-02-16 17:27:05.465791 | 
2026-02-16 17:27:05.465933 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:27:05.914705 | localhost -> localhost | ok: Runtime: 0:00:00.005978
2026-02-16 17:27:05.919977 | 
2026-02-16 17:27:05.920050 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:27:06.357188 | localhost | Output suppressed because no_log was given
2026-02-16 17:27:06.363594 | 
2026-02-16 17:27:06.363695 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:27:06.407155 | localhost | skipping: Conditional result was False
2026-02-16 17:27:06.413192 | localhost | skipping: Conditional result was False
2026-02-16 17:27:06.430283 | 
2026-02-16 17:27:06.430439 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:27:06.468677 | localhost | skipping: Conditional result was False
2026-02-16 17:27:06.469191 | 
2026-02-16 17:27:06.472061 | localhost | skipping: Conditional result was False
2026-02-16 17:27:06.484035 | 
2026-02-16 17:27:06.484181 | LOOP [upload-logs : Upload console log and json output]