2026-02-16 17:46:10.462983 | Job console starting
2026-02-16 17:46:10.477245 | Updating git repos
2026-02-16 17:46:13.133577 | Cloning repos into workspace
2026-02-16 17:46:18.416215 | Restoring repo states
2026-02-16 17:46:21.307751 | Merging changes
2026-02-16 17:46:21.768544 | Checking out repos
2026-02-16 17:46:25.048685 | Preparing playbooks
2026-02-16 17:46:26.650238 | Running Ansible setup
2026-02-16 17:46:32.927567 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:46:33.544327 | 
2026-02-16 17:46:33.544479 | PLAY [localhost]
2026-02-16 17:46:33.552268 | 
2026-02-16 17:46:33.552339 | TASK [Gathering Facts]
2026-02-16 17:46:34.640235 | localhost | ok
2026-02-16 17:46:34.647580 | 
2026-02-16 17:46:34.647689 | TASK [Setup log path fact]
2026-02-16 17:46:34.669338 | localhost | ok
2026-02-16 17:46:34.682349 | 
2026-02-16 17:46:34.682433 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:46:34.713083 | localhost | ok
2026-02-16 17:46:34.722608 | 
2026-02-16 17:46:34.722690 | TASK [emit-job-header : Print job information]
2026-02-16 17:46:34.767034 | # Job Information
2026-02-16 17:46:34.767244 | Ansible Version: 2.16.15
2026-02-16 17:46:34.767292 | Job: magnum-cluster-api-sonobuoy-v1.35.1-cilium
2026-02-16 17:46:34.767325 | Pipeline: check
2026-02-16 17:46:34.767355 | Executor: 3a2793d2bd32
2026-02-16 17:46:34.767384 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:46:34.767417 | Event ID: 37691bf0-0b5f-11f1-8a15-a065d2c16437
2026-02-16 17:46:34.771532 | 
2026-02-16 17:46:34.771603 | LOOP [emit-job-header : Print node information]
2026-02-16 17:46:34.872362 | localhost | ok:
2026-02-16 17:46:34.872578 | localhost | # Node Information
2026-02-16 17:46:34.872607 | localhost | Inventory Hostname: controller
2026-02-16 17:46:34.872628 | localhost | Hostname: np0000155637
2026-02-16 17:46:34.872648 | localhost | Username: zuul
2026-02-16 17:46:34.872670 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:46:34.872691 | localhost | Provider: yul1
2026-02-16 17:46:34.872710 | localhost | Region: ca-ymq-1
2026-02-16 17:46:34.872729 | localhost | Label: ubuntu-noble-16
2026-02-16 17:46:34.872748 | localhost | Product Name: OpenStack Nova
2026-02-16 17:46:34.872766 | localhost | Interface IP: 199.204.45.78
2026-02-16 17:46:34.893158 | 
2026-02-16 17:46:34.893406 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:46:35.365964 | localhost -> localhost | changed
2026-02-16 17:46:35.376229 | 
2026-02-16 17:46:35.376326 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:46:36.368402 | localhost -> localhost | changed
2026-02-16 17:46:36.377215 | 
2026-02-16 17:46:36.377312 | PLAY [all]
2026-02-16 17:46:36.390614 | 
2026-02-16 17:46:36.848272 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:46:37.090175 | controller -> localhost | ok
2026-02-16 17:46:37.099558 | 
2026-02-16 17:46:37.099655 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:46:37.133254 | controller | ok
2026-02-16 17:46:37.152215 | controller | included: /var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:46:37.160443 | 
2026-02-16 17:46:37.160542 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:46:38.023665 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:46:38.024037 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/work/8fd6d94d470642218dc42685a78b47c5_id_rsa
2026-02-16 17:46:38.024146 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/work/8fd6d94d470642218dc42685a78b47c5_id_rsa.pub
2026-02-16 17:46:38.024207 | controller -> localhost | The key fingerprint is:
2026-02-16 17:46:38.024256 | controller -> localhost | SHA256:cTQ3ihQaWBhCKko0dOjrzxXXWBs+xSqPrXgEjOFupRY zuul-build-sshkey
2026-02-16 17:46:38.024325 | controller -> localhost | The key's randomart image is:
2026-02-16 17:46:38.024372 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:46:38.024417 | controller -> localhost | |.=+..=o o.o o    |
2026-02-16 17:46:38.024442 | controller -> localhost | |.oooo  + o.+ .   |
2026-02-16 17:46:38.024464 | controller -> localhost | |+.. + . oooo     |
2026-02-16 17:46:38.024484 | controller -> localhost | |+. E +  =o=      |
2026-02-16 17:46:38.024504 | controller -> localhost | |. o +..+S*       |
2026-02-16 17:46:38.024523 | controller -> localhost | | . =  o.= .      |
2026-02-16 17:46:38.024543 | controller -> localhost | |. o  ... o       |
2026-02-16 17:46:38.024562 | controller -> localhost | | .. . ...        |
2026-02-16 17:46:38.024584 | controller -> localhost | |  .o ...         |
2026-02-16 17:46:38.024604 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:46:38.024680 | controller -> localhost | ok: Runtime: 0:00:00.442400
2026-02-16 17:46:38.030218 | 
2026-02-16 17:46:38.030283 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:46:38.071647 | controller | ok
2026-02-16 17:46:38.083976 | controller | included: /var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:46:38.091972 | 
2026-02-16 17:46:38.092038 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:46:38.117597 | controller | skipping: Conditional result was False
2026-02-16 17:46:38.126451 | 
2026-02-16 17:46:38.126553 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:46:38.971840 | controller | changed
2026-02-16 17:46:38.981232 | 
2026-02-16 17:46:38.981409 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:46:39.213288 | controller | ok
2026-02-16 17:46:39.226852 | 
2026-02-16 17:46:39.227015 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:46:39.811561 | controller | changed
2026-02-16 17:46:39.817086 | 
2026-02-16 17:46:39.817153 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:46:40.409869 | controller | changed
2026-02-16 17:46:40.420122 | 
2026-02-16 17:46:40.420197 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:46:40.445565 | controller | skipping: Conditional result was False
2026-02-16 17:46:40.454907 | 
2026-02-16 17:46:40.455064 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:46:40.816184 | controller -> localhost | changed
2026-02-16 17:46:40.832907 | 
2026-02-16 17:46:40.832995 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:46:41.303762 | controller -> localhost | Identity added: /var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/work/8fd6d94d470642218dc42685a78b47c5_id_rsa (zuul-build-sshkey)
2026-02-16 17:46:41.303962 | controller -> localhost | ok: Runtime: 0:00:00.014796
2026-02-16 17:46:41.350997 | 
2026-02-16 17:46:41.351176 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:46:41.672021 | controller | ok
2026-02-16 17:46:41.684400 | 
2026-02-16 17:46:41.684514 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:46:41.710054 | controller | skipping: Conditional result was False
2026-02-16 17:46:41.726371 | 
2026-02-16 17:46:41.726463 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:46:42.045809 | controller | ok
2026-02-16 17:46:42.052841 | 
2026-02-16 17:46:42.052926 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:47:03.945560 | controller | Output suppressed because no_log was given
2026-02-16 17:47:03.955378 | 
2026-02-16 17:47:03.955455 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:47:04.213584 | controller | ok: "logs"
2026-02-16 17:47:04.213853 | controller | ok: All items complete
2026-02-16 17:47:04.213884 | 
2026-02-16 17:47:04.397587 | controller | ok: "artifacts"
2026-02-16 17:47:04.588672 | controller | ok: "docs"
2026-02-16 17:47:04.812859 | 
2026-02-16 17:47:04.813038 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:47:05.029600 | controller | changed: "logs"
2026-02-16 17:47:05.234261 | controller | changed: "artifacts"
2026-02-16 17:47:05.458061 | controller | changed: "docs"
2026-02-16 17:47:06.206099 | 
2026-02-16 17:47:06.206223 | PLAY RECAP
2026-02-16 17:47:06.206282 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:47:06.206322 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:47:06.206351 | 
2026-02-16 17:47:06.345630 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:47:06.355378 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:47:06.995880 | 
2026-02-16 17:47:06.996015 | PLAY [all]
2026-02-16 17:47:07.014174 | 
2026-02-16 17:47:07.014282 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:47:07.435649 | controller | changed
2026-02-16 17:47:07.442972 | 
2026-02-16 17:47:07.443049 | TASK [Gather minimum local MTU]
2026-02-16 17:47:07.519917 | controller | ok
2026-02-16 17:47:07.525108 | 
2026-02-16 17:47:07.525183 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:47:07.590621 | controller | ok
2026-02-16 17:47:07.598537 | 
2026-02-16 17:47:07.598813 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:47:07.625706 | controller | skipping: Conditional result was False
2026-02-16 17:47:07.639436 | 
2026-02-16 17:47:07.639510 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:47:08.179040 | controller | ok: Runtime: 0:00:00.009793
2026-02-16 17:47:08.186659 | 
2026-02-16 17:47:08.186739 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:47:08.211859 | controller | skipping: Conditional result was False
2026-02-16 17:47:08.225508 | 
2026-02-16 17:47:08.225600 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:47:08.309941 | controller | skipping: Conditional result was False
2026-02-16 17:47:08.320991 | 
2026-02-16 17:47:08.321100 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:47:08.379663 | controller | ok
2026-02-16 17:47:08.390022 | controller | included: /var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:47:08.396203 | 
2026-02-16 17:47:08.396276 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:47:08.472410 | controller | ok
2026-02-16 17:47:08.479533 | 
2026-02-16 17:47:08.479599 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:47:08.778898 | controller | ext4
2026-02-16 17:47:09.064898 | controller | ok: Runtime: 0:00:00.014903
2026-02-16 17:47:09.070519 | 
2026-02-16 17:47:09.070599 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:47:09.103829 | controller | ok
2026-02-16 17:47:09.111597 | 
2026-02-16 17:47:09.111679 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:47:09.142100 | controller | ok:
2026-02-16 17:47:09.142296 | controller | {
2026-02-16 17:47:09.142324 | controller |   "root_filesystem": "ext4"
2026-02-16 17:47:09.142344 | controller | }
2026-02-16 17:47:09.152282 | 
2026-02-16 17:47:09.152422 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:47:19.155638 | controller | 8192+0 records in
2026-02-16 17:47:19.155842 | controller | 8192+0 records out
2026-02-16 17:47:19.155859 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.71302 s, 884 MB/s
2026-02-16 17:47:19.209625 | controller | ok: Runtime: 0:00:09.722259
2026-02-16 17:47:19.217249 | 
2026-02-16 17:47:19.217322 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:47:19.456544 | controller | changed
2026-02-16 17:47:19.463363 | 
2026-02-16 17:47:19.463429 | TASK [configure-swap : Make swapfile]
2026-02-16 17:47:41.423221 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:47:41.423370 | controller | no label, UUID=0ed2943a-bb46-44f0-b9d6-f2a911d4dd48
2026-02-16 17:47:41.523151 | controller | ok: Runtime: 0:00:21.707842
2026-02-16 17:47:41.529060 | 
2026-02-16 17:47:41.529131 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:47:41.929554 | controller | changed
2026-02-16 17:47:41.934915 | 
2026-02-16 17:47:41.934985 | TASK [configure-swap : Add all swap]
2026-02-16 17:47:42.472828 | controller | ok: Runtime: 0:00:00.021075
2026-02-16 17:47:42.480633 | 
2026-02-16 17:47:42.480742 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:47:42.514596 | controller | ok:
2026-02-16 17:47:42.514777 | controller | {
2026-02-16 17:47:42.514813 | controller |   "swap_required": "8192"
2026-02-16 17:47:42.514840 | controller | }
2026-02-16 17:47:42.521658 | 
2026-02-16 17:47:43.477921 | TASK [configure-swap : Set swappiness]
2026-02-16 17:47:43.890891 | controller | changed
2026-02-16 17:47:43.897406 | 
2026-02-16 17:47:43.897470 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:47:43.930971 | controller | ok:
2026-02-16 17:47:43.931127 | controller | {
2026-02-16 17:47:43.931161 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:47:43.931195 | controller | }
2026-02-16 17:47:43.941824 | 
2026-02-16 17:47:43.941889 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:47:44.398749 | controller | changed
2026-02-16 17:47:44.405389 | 
2026-02-16 17:47:44.405455 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:47:44.651110 | controller | changed
2026-02-16 17:47:44.657642 | 
2026-02-16 17:47:44.657709 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:47:45.178183 | controller | changed
2026-02-16 17:47:45.185308 | 
2026-02-16 17:47:45.185373 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:47:45.421164 | controller | changed
2026-02-16 17:47:45.451971 | 
2026-02-16 17:47:45.452130 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:47:46.528717 | controller | changed
2026-02-16 17:47:46.535605 | 
2026-02-16 17:47:46.535701 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:47:46.774657 | controller | changed
2026-02-16 17:47:46.787384 | 
2026-02-16 17:47:46.787457 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:47:47.076848 | controller | changed
2026-02-16 17:47:47.766467 | 
2026-02-16 17:47:47.766569 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:47:48.147526 | controller | changed
2026-02-16 17:47:48.192910 | 
2026-02-16 17:47:48.193112 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:47:48.958909 | controller | changed
2026-02-16 17:47:48.971118 | 
2026-02-16 17:47:48.971185 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:47:49.300294 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:47:49.312096 | 
2026-02-16 17:47:49.312164 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:47:49.828386 | controller | ok: Item: Runtime: 0:00:00.206080
2026-02-16 17:47:50.412365 | controller | ok: Item: Runtime: 0:00:00.365756
2026-02-16 17:47:51.143582 | controller | ok: Item: Runtime: 0:00:00.507870
2026-02-16 17:47:51.426604 | controller | ok: Item: Runtime: 0:00:00.063403
2026-02-16 17:47:51.916055 | controller | ok: Item: Runtime: 0:00:00.262711
2026-02-16 17:47:53.457289 | controller | ok: Item: Runtime: 0:00:01.327478
2026-02-16 17:47:56.542034 | controller | ok: Item: Runtime: 0:00:02.870022
2026-02-16 17:47:56.858821 | controller | ok: Item: Runtime: 0:00:00.095050
2026-02-16 17:47:57.258565 | controller | ok: Item: Runtime: 0:00:00.181063
2026-02-16 17:47:57.599096 | controller | ok: Item: Runtime: 0:00:00.124220
2026-02-16 17:47:58.326405 | controller | ok: Item: Runtime: 0:00:00.509692
2026-02-16 17:47:59.105039 | controller | ok: Item: Runtime: 0:00:00.569093
2026-02-16 17:47:59.557295 | controller | ok: Item: Runtime: 0:00:00.228835
2026-02-16 17:48:00.448413 | controller | ok: Item: Runtime: 0:00:00.203991
2026-02-16 17:48:00.559613 | controller | ok: Item: Runtime: 0:00:00.358554
2026-02-16 17:48:01.887208 | controller | ok: Item: Runtime: 0:00:01.097550
2026-02-16 17:48:01.915544 | 
2026-02-16 17:48:01.916202 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:48:02.133389 | controller | ok: All paths examined
2026-02-16 17:48:02.139647 | 
2026-02-16 17:48:02.139725 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:48:02.387028 | controller | ok: All paths examined
2026-02-16 17:48:02.395602 | 
2026-02-16 17:48:02.395681 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:48:02.821847 | controller | ok: Item: Runtime: 0:00:00.149074
2026-02-16 17:48:02.822117 | controller | changed: All items complete
2026-02-16 17:48:02.822147 | 
2026-02-16 17:48:03.234292 | controller | ok: Item: Runtime: 0:00:00.198157
2026-02-16 17:48:03.258342 | 
2026-02-16 17:48:03.258538 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:48:03.302041 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.304349 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.307230 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.310049 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.313430 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.316451 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.319468 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.322692 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.326721 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.329959 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.332982 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.335762 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.338780 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.341809 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.344766 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.347831 | controller | skipping: Conditional result was False
2026-02-16 17:48:03.378757 | 
2026-02-16 17:48:03.378928 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:48:05.695946 | controller | changed
2026-02-16 17:48:06.468206 | 
2026-02-16 17:48:06.468325 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:48:06.716330 | controller | changed
2026-02-16 17:48:06.773261 | 
2026-02-16 17:48:06.773372 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:48:07.030034 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:48:07.313922 | controller | ERROR
2026-02-16 17:48:07.314252 | controller | {
2026-02-16 17:48:07.314320 | controller |   "delta": "0:00:00.010728",
2026-02-16 17:48:07.314372 | controller |   "end": "2026-02-16 17:48:07.030570",
2026-02-16 17:48:07.314420 | controller |   "msg": "non-zero return code",
2026-02-16 17:48:07.314529 | controller |   "rc": 1,
2026-02-16 17:48:07.314578 | controller |   "start": "2026-02-16 17:48:07.019842"
2026-02-16 17:48:07.314619 | controller | }
2026-02-16 17:48:07.314670 | controller | ERROR: Ignoring Errors
2026-02-16 17:48:07.320920 | 
2026-02-16 17:48:07.321016 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:48:07.568384 | controller | ok
2026-02-16 17:48:07.580444 | 
2026-02-16 17:48:07.580511 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:48:07.811102 | controller | /usr/bin/journalctl
2026-02-16 17:48:08.121729 | controller | ok: Runtime: 0:00:00.008532
2026-02-16 17:48:08.128988 | 
2026-02-16 17:48:08.129062 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:48:08.376474 | controller | 2026-02-16 17:48:08
2026-02-16 17:48:08.672359 | controller | ok: Runtime: 0:00:00.008268
2026-02-16 17:48:08.682026 | 
2026-02-16 17:48:08.682102 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:48:09.241036 | controller | changed
2026-02-16 17:48:09.246879 | 
2026-02-16 17:48:09.246943 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:48:09.272528 | controller | skipping: Conditional result was False
2026-02-16 17:48:09.282341 | 
2026-02-16 17:48:09.282406 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:48:09.814822 | controller | skipping: Conditional result was False
2026-02-16 17:48:09.827911 | 
2026-02-16 17:48:09.828118 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:48:10.361237 | controller | skipping: Conditional result was False
2026-02-16 17:48:10.371790 | 
2026-02-16 17:48:10.371899 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:48:10.396816 | controller | skipping: Conditional result was False
2026-02-16 17:48:10.407222 | 
2026-02-16 17:48:10.407295 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:48:10.939272 | controller | skipping: Conditional result was False
2026-02-16 17:48:10.950928 | 
2026-02-16 17:48:10.951024 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:48:11.482749 | controller | skipping: Conditional result was False
2026-02-16 17:48:11.492217 | 
2026-02-16 17:48:11.492310 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:48:11.518020 | controller | skipping: Conditional result was False
2026-02-16 17:48:11.527345 | 
2026-02-16 17:48:11.527441 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:48:11.552849 | controller | skipping: Conditional result was False
2026-02-16 17:48:11.562138 | 
2026-02-16 17:48:11.562227 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:48:12.139138 | controller | skipping: Conditional result was False
2026-02-16 17:48:12.166175 | 
2026-02-16 17:48:12.166311 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:48:12.695847 | controller | skipping: Conditional result was False
2026-02-16 17:48:12.707768 | 
2026-02-16 17:48:12.707840 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:48:12.732638 | controller | skipping: Conditional result was False
2026-02-16 17:48:12.743730 | 
2026-02-16 17:48:12.743797 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:48:12.768669 | controller | skipping: Conditional result was False
2026-02-16 17:48:12.784367 | 
2026-02-16 17:48:12.784438 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:48:13.165725 | controller | ok
2026-02-16 17:48:13.174734 | 
2026-02-16 17:48:13.174781 | PLAY RECAP
2026-02-16 17:48:13.174826 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:48:13.174848 | 
2026-02-16 17:48:13.311093 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:48:13.322709 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:48:13.896826 | 
2026-02-16 17:48:13.897573 | PLAY [all]
2026-02-16 17:48:13.909344 | 
2026-02-16 17:48:13.909415 | TASK [Install curl]
2026-02-16 17:48:14.499420 | controller | ERROR
2026-02-16 17:48:14.499692 | controller | {
2026-02-16 17:48:14.499734 | controller |   "msg": "No package matching 'curl' is available"
2026-02-16 17:48:14.499764 | controller | }
failure
2026-02-16 17:48:14.501042 | 
2026-02-16 17:48:14.501089 | PLAY RECAP
2026-02-16 17:48:14.501152 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:48:14.501176 | 
2026-02-16 17:48:14.611080 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:48:14.617260 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:48:15.206284 | 
2026-02-16 17:48:15.206430 | PLAY [all]
2026-02-16 17:48:15.223083 | 
2026-02-16 17:48:15.223171 | TASK [Return built artifacts to Zuul]
2026-02-16 17:48:15.784507 | controller | ok
2026-02-16 17:48:15.790525 | 
2026-02-16 17:48:15.790616 | TASK [Get the Docker volume ID]
2026-02-16 17:48:16.332275 | controller | ERROR
2026-02-16 17:48:16.332644 | controller | {
2026-02-16 17:48:16.332690 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:48:16.332722 | controller |   "rc": 2
2026-02-16 17:48:16.332750 | controller | }
2026-02-16 17:48:16.332786 | controller | ERROR: Ignoring Errors
2026-02-16 17:48:16.339926 | 
2026-02-16 17:48:16.339994 | TASK [Copy all of the pod logs]
2026-02-16 17:48:16.376053 | controller | ERROR
2026-02-16 17:48:16.376233 | controller | {
2026-02-16 17:48:16.376264 | 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/8fd6d94d470642218dc42685a78b47c5/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:48:16.376287 | controller | }
2026-02-16 17:48:16.376314 | controller | ERROR: Ignoring Errors
2026-02-16 17:48:16.377492 | 
2026-02-16 17:48:16.377541 | PLAY RECAP
2026-02-16 17:48:16.377589 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:48:16.377613 | 
2026-02-16 17:48:16.499738 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:48:16.509110 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:48:17.127041 | 
2026-02-16 17:48:17.127174 | PLAY [all]
2026-02-16 17:48:17.145258 | 
2026-02-16 17:48:17.145340 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:48:17.557946 | controller | changed
2026-02-16 17:48:18.199480 | 
2026-02-16 17:48:18.199632 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:48:19.255750 | controller | ok: Runtime: 0:00:00.490347
2026-02-16 17:48:19.266702 | 
2026-02-16 17:48:19.266784 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:48:19.806857 | controller | ok: Runtime: 0:00:00.020068
2026-02-16 17:48:19.814793 | 
2026-02-16 17:48:19.814862 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:48:20.363298 | controller | ok: Runtime: 0:00:00.024279
2026-02-16 17:48:20.370474 | 
2026-02-16 17:48:20.370546 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:48:21.228188 | controller | changed
2026-02-16 17:48:21.303622 | 
2026-02-16 17:48:21.303775 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:48:21.553228 | controller | changed
2026-02-16 17:48:21.559759 | 
2026-02-16 17:48:21.559830 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:48:21.890706 | controller | Output suppressed because no_log was given
2026-02-16 17:48:21.896710 | 
2026-02-16 17:48:21.896797 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:48:21.934063 | 
2026-02-16 17:48:21.934238 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:48:21.969753 | 
2026-02-16 17:48:21.969890 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:48:22.007957 | 
2026-02-16 17:48:22.008152 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:48:22.041736 | 
2026-02-16 17:48:22.041913 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:48:22.079020 | 
2026-02-16 17:48:22.079212 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:48:22.320607 | controller | changed
2026-02-16 17:48:22.780416 | 
2026-02-16 17:48:22.780554 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:48:22.813224 | controller | ok
2026-02-16 17:48:22.820819 | 
2026-02-16 17:48:22.820903 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:48:23.049391 | controller | Output suppressed because no_log was given
2026-02-16 17:48:23.054800 | 
2026-02-16 17:48:23.054884 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:48:23.101503 | 
2026-02-16 17:48:23.101640 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:48:23.142087 | 
2026-02-16 17:48:23.142224 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:48:23.822022 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:48:23.841010 | controller | Using python 3.12 to install setuptools
2026-02-16 17:48:23.879854 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:48:23.891840 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:48:24.184105 | controller | ERROR
2026-02-16 17:48:24.184383 | controller | {
2026-02-16 17:48:24.184429 | controller |   "delta": "0:00:00.504811",
2026-02-16 17:48:24.184459 | controller |   "end": "2026-02-16 17:48:23.893976",
2026-02-16 17:48:24.184484 | controller |   "msg": "non-zero return code",
2026-02-16 17:48:24.184516 | controller |   "rc": 127,
2026-02-16 17:48:24.184541 | controller |   "start": "2026-02-16 17:48:23.389165"
2026-02-16 17:48:24.184565 | controller | }
2026-02-16 17:48:24.184600 | controller | ERROR: Ignoring Errors
2026-02-16 17:48:24.198208 | 
2026-02-16 17:48:24.198285 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:48:24.435446 | controller | changed
2026-02-16 17:48:24.441946 | 
2026-02-16 17:48:24.442015 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:48:28.192254 | controller | Output suppressed because no_log was given
2026-02-16 17:48:28.204678 | 
2026-02-16 17:48:28.204774 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:48:28.237763 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.238077 | 
2026-02-16 17:48:28.239436 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.241867 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.244277 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.246775 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.249139 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.251977 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.254307 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.256568 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.258914 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.261205 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.263312 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.265730 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.268320 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.270606 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.272912 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.275156 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.278388 | controller | skipping: Conditional result was False
2026-02-16 17:48:28.304617 | 
2026-02-16 17:48:28.304686 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:48:28.534090 | controller | ok
2026-02-16 17:48:28.539582 | 
2026-02-16 17:48:28.539643 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:48:29.113504 | controller | skipping: Conditional result was False
2026-02-16 17:48:29.131990 | 
2026-02-16 17:48:29.132097 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:48:29.385089 | controller | sudo: iptables-save: command not found
2026-02-16 17:48:29.425910 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:48:30.518281 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:48:30.673743 | controller | ok: Runtime: 0:00:01.157207
2026-02-16 17:48:30.685098 | 
2026-02-16 17:48:30.685164 | LOOP [stage-output : Register sources]
2026-02-16 17:48:39.917978 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.164721 | 
2026-02-16 17:48:40.164869 | TASK [stage-output : Check sudo]
2026-02-16 17:48:40.705305 | controller | ok: Runtime: 0:00:00.021999
2026-02-16 17:48:40.711278 | 
2026-02-16 17:48:40.711349 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:48:40.804080 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.804455 | 
2026-02-16 17:48:40.805917 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.807417 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.809580 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.844018 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.844373 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.844707 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.846480 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.848696 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.850840 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.853034 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.855118 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.857234 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.859286 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.878342 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.878679 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.879004 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.879328 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.879650 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.880698 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.882785 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.885028 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.889641 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.918985 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.919322 | controller | Output suppressed because no_log was given
2026-02-16 17:48:40.998350 | controller | Output suppressed because no_log was given
2026-02-16 17:48:41.003927 | controller | Output suppressed because no_log was given
2026-02-16 17:48:41.015941 | controller | Output suppressed because no_log was given
2026-02-16 17:48:41.023647 | controller | Output suppressed because no_log was given
2026-02-16 17:48:41.038614 | 
2026-02-16 17:48:41.038690 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:48:41.104259 | controller | ok
2026-02-16 17:48:41.112014 | 
2026-02-16 17:48:41.112088 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:48:41.354192 | controller | changed: "docs"
2026-02-16 17:48:41.556205 | controller | changed: "artifacts"
2026-02-16 17:48:41.798712 | controller | ok: "logs"
2026-02-16 17:48:41.815886 | 
2026-02-16 17:48:41.815979 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:48:42.094743 | controller | ok: Item: Runtime: 0:00:00.009318
2026-02-16 17:48:42.095014 | controller | changed: All items complete
2026-02-16 17:48:42.095044 | 
2026-02-16 17:48:42.317150 | controller | ok: Item: Runtime: 0:00:00.008901
2026-02-16 17:48:46.081576 | controller | ok: Item: Runtime: 0:00:00.008959
2026-02-16 17:48:46.092337 | controller | ok: Item: Runtime: 0:00:00.008537
2026-02-16 17:48:46.100888 | controller | ok: Item: Runtime: 0:00:00.009251
2026-02-16 17:48:46.112130 | controller | ok: Item: Runtime: 0:00:00.006945
2026-02-16 17:48:46.120764 | controller | ok: Item: Runtime: 0:00:00.008958
2026-02-16 17:48:46.129750 | controller | ok: Item: Runtime: 0:00:00.007461
2026-02-16 17:48:46.138839 | controller | ok: Item: Runtime: 0:00:00.008560
2026-02-16 17:48:46.147542 | controller | ok: Item: Runtime: 0:00:00.008212
2026-02-16 17:48:46.156484 | controller | ok: Item: Runtime: 0:00:00.008494
2026-02-16 17:48:46.204444 | controller | ok: Item: Runtime: 0:00:00.008918
2026-02-16 17:48:46.213342 | controller | ok: Item: Runtime: 0:00:00.008925
2026-02-16 17:48:46.222161 | controller | ok: Item: Runtime: 0:00:00.009086
2026-02-16 17:48:46.231426 | controller | ok: Item: Runtime: 0:00:00.008262
2026-02-16 17:48:46.239956 | controller | ok: Item: Runtime: 0:00:00.009509
2026-02-16 17:48:46.264652 | 
2026-02-16 17:48:46.264818 | TASK [stage-output : Make all log files readable]
2026-02-16 17:48:46.504785 | controller | changed
2026-02-16 17:48:46.511393 | 
2026-02-16 17:48:46.511463 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:48:46.839504 | controller | changed: Renamed files for staging.
2026-02-16 17:48:46.845508 | 
2026-02-16 17:48:46.845579 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:48:46.871026 | controller | skipping: Conditional result was False
2026-02-16 17:48:46.883685 | 
2026-02-16 17:48:46.883783 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:48:46.924849 | 
2026-02-16 17:48:46.924989 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:48:50.616582 | controller | changed:
2026-02-16 17:48:50.616792 | controller | created directory /var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/work/logs/controller
2026-02-16 17:48:50.616832 | controller | cd+++++++++ logs/
2026-02-16 17:48:50.616862 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:48:50.616888 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:48:50.616914 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:48:50.616939 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:48:50.616989 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:48:50.617018 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:48:50.617043 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:48:50.617067 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:48:50.617092 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:48:50.617117 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:48:50.617142 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:48:50.617166 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:48:50.617196 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:48:50.617223 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:48:50.617248 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:48:50.617273 | controller | cd+++++++++ logs/apache/
2026-02-16 17:48:50.617297 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:48:50.617322 | controller | cd+++++++++ logs/etc/
2026-02-16 17:48:50.617347 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:48:50.617372 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:48:50.617397 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:48:50.617422 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:48:50.617471 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:48:50.617497 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:48:50.629804 | 
2026-02-16 17:48:50.629864 | TASK [Check if a tempest log exits]
2026-02-16 17:48:50.898416 | controller | ok
2026-02-16 17:48:50.905641 | 
2026-02-16 17:48:50.905709 | TASK [Link post-devstack tempest.log]
2026-02-16 17:48:50.930845 | controller | skipping: Conditional result was False
2026-02-16 17:48:50.941805 | 
2026-02-16 17:48:50.941868 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:48:51.189890 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:48:52.130300 | controller | ERROR
2026-02-16 17:48:52.130552 | controller | {
2026-02-16 17:48:52.130598 | controller |   "delta": "0:00:00.008420",
2026-02-16 17:48:52.130629 | controller |   "end": "2026-02-16 17:48:51.190414",
2026-02-16 17:48:52.130656 | controller |   "msg": "non-zero return code",
2026-02-16 17:48:52.130682 | controller |   "rc": 127,
2026-02-16 17:48:52.130707 | controller |   "start": "2026-02-16 17:48:51.181994"
2026-02-16 17:48:52.130732 | controller | }
2026-02-16 17:48:52.130766 | controller | ERROR: Ignoring Errors
2026-02-16 17:48:52.132922 | 
2026-02-16 17:48:52.133072 | PLAY RECAP
2026-02-16 17:48:52.133167 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:48:52.133215 | 
2026-02-16 17:48:52.278320 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:48:52.286965 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:48:52.880488 | 
2026-02-16 17:48:52.880631 | PLAY [all]
2026-02-16 17:48:52.892387 | 
2026-02-16 17:48:52.892467 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:48:52.968630 | controller | skipping: Conditional result was False
2026-02-16 17:48:52.981484 | 
2026-02-16 17:48:52.981714 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:48:53.028442 | controller | ok
2026-02-16 17:48:53.035856 | 
2026-02-16 17:48:53.035945 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:48:53.428133 | controller -> localhost | ok: "/var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/work/logs"
2026-02-16 17:48:53.637696 | controller -> localhost | changed: "/var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/work/artifacts"
2026-02-16 17:48:53.845418 | controller -> localhost | changed: "/var/lib/zuul/builds/8fd6d94d470642218dc42685a78b47c5/work/docs"
2026-02-16 17:48:53.862989 | 
2026-02-16 17:48:53.863154 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:48:54.512515 | controller | changed: .d..t...... ./
2026-02-16 17:48:54.512748 | controller | changed: All items complete
2026-02-16 17:48:54.512776 | 
2026-02-16 17:48:54.980762 | controller | changed: .d..t...... ./
2026-02-16 17:48:55.443952 | controller | changed: .d..t...... ./
2026-02-16 17:48:55.464056 | 
2026-02-16 17:48:55.464190 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:48:56.224360 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006846
2026-02-16 17:48:56.444248 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007420
2026-02-16 17:48:56.458666 | 
2026-02-16 17:48:56.458812 | PLAY [all]
2026-02-16 17:48:56.466661 | 
2026-02-16 17:48:56.466724 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:48:56.927831 | controller | changed
2026-02-16 17:48:56.933946 | 
2026-02-16 17:48:56.934000 | PLAY RECAP
2026-02-16 17:48:56.934044 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:48:56.934066 | 
2026-02-16 17:48:57.067925 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:48:57.076141 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:48:57.746693 | 
2026-02-16 17:48:57.746911 | PLAY [localhost]
2026-02-16 17:48:57.760095 | 
2026-02-16 17:48:57.760278 | TASK [Generate Zuul manifest]
2026-02-16 17:48:57.784332 | localhost | ok
2026-02-16 17:48:57.802879 | 
2026-02-16 17:48:57.803013 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:48:58.180213 | localhost | changed
2026-02-16 17:48:58.193784 | 
2026-02-16 17:48:58.193859 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:48:58.226259 | localhost | ok
2026-02-16 17:48:58.236434 | 
2026-02-16 17:48:58.236502 | TASK [Upload logs]
2026-02-16 17:48:58.257909 | localhost | ok
2026-02-16 17:48:58.316448 | 
2026-02-16 17:48:58.316576 | TASK [Set zuul-log-path fact]
2026-02-16 17:48:58.337862 | localhost | ok
2026-02-16 17:48:58.349859 | 
2026-02-16 17:48:58.349926 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:48:58.391855 | localhost | ok
2026-02-16 17:48:58.398766 | 
2026-02-16 17:48:58.398832 | TASK [upload-logs : Create log directories]
2026-02-16 17:48:58.807227 | localhost | changed
2026-02-16 17:48:58.812890 | 
2026-02-16 17:48:58.812977 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:48:59.262145 | localhost -> localhost | ok: Runtime: 0:00:00.005597
2026-02-16 17:48:59.267292 | 
2026-02-16 17:48:59.267358 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:49:00.034614 | localhost | Output suppressed because no_log was given
2026-02-16 17:49:00.039600 | 
2026-02-16 17:49:00.039694 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:49:00.084949 | localhost | skipping: Conditional result was False
2026-02-16 17:49:00.090659 | localhost | skipping: Conditional result was False
2026-02-16 17:49:00.106226 | 
2026-02-16 17:49:00.106406 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:49:00.148718 | localhost | skipping: Conditional result was False
2026-02-16 17:49:00.149146 | 
2026-02-16 17:49:00.152112 | localhost | skipping: Conditional result was False
2026-02-16 17:49:00.169305 | 
2026-02-16 17:49:00.169479 | LOOP [upload-logs : Upload console log and json output]