2026-02-16 17:45:15.363222 | Job console starting
2026-02-16 17:45:15.378489 | Updating git repos
2026-02-16 17:45:18.619710 | Cloning repos into workspace
2026-02-16 17:45:23.907714 | Restoring repo states
2026-02-16 17:45:24.494130 | Merging changes
2026-02-16 17:45:24.989378 | Checking out repos
2026-02-16 17:45:28.763955 | Preparing playbooks
2026-02-16 17:45:31.348328 | Running Ansible setup
2026-02-16 17:45:35.174224 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:45:35.782256 | 
2026-02-16 17:45:35.782389 | PLAY [localhost]
2026-02-16 17:45:35.790193 | 
2026-02-16 17:45:35.790268 | TASK [Gathering Facts]
2026-02-16 17:45:36.619393 | localhost | ok
2026-02-16 17:45:36.629429 | 
2026-02-16 17:45:36.629518 | TASK [Setup log path fact]
2026-02-16 17:45:36.651203 | localhost | ok
2026-02-16 17:45:36.662341 | 
2026-02-16 17:45:36.662406 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:45:36.693289 | localhost | ok
2026-02-16 17:45:36.700792 | 
2026-02-16 17:45:36.700858 | TASK [emit-job-header : Print job information]
2026-02-16 17:45:36.745437 | # Job Information
2026-02-16 17:45:36.745721 | Ansible Version: 2.16.15
2026-02-16 17:45:36.745826 | Job: magnum-cluster-api-sonobuoy-v1.32.12-calico
2026-02-16 17:45:36.745859 | Pipeline: check
2026-02-16 17:45:36.745886 | Executor: 3a2793d2bd32
2026-02-16 17:45:36.745913 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:45:36.745944 | Event ID: 37691bf0-0b5f-11f1-8a15-a065d2c16437
2026-02-16 17:45:36.750494 | 
2026-02-16 17:45:36.750590 | LOOP [emit-job-header : Print node information]
2026-02-16 17:45:36.862753 | localhost | ok:
2026-02-16 17:45:36.863061 | localhost | # Node Information
2026-02-16 17:45:36.863121 | localhost | Inventory Hostname: controller
2026-02-16 17:45:36.863168 | localhost | Hostname: np0000155629
2026-02-16 17:45:36.863211 | localhost | Username: zuul
2026-02-16 17:45:36.863259 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:45:36.863302 | localhost | Provider: yul1
2026-02-16 17:45:36.863344 | localhost | Region: ca-ymq-1
2026-02-16 17:45:36.863385 | localhost | Label: ubuntu-noble-16
2026-02-16 17:45:36.863427 | localhost | Product Name: OpenStack Nova
2026-02-16 17:45:36.863467 | localhost | Interface IP: 162.253.55.195
2026-02-16 17:45:36.879309 | 
2026-02-16 17:45:36.879454 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:45:37.518064 | localhost -> localhost | changed
2026-02-16 17:45:37.526470 | 
2026-02-16 17:45:37.526587 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:45:38.382882 | localhost -> localhost | changed
2026-02-16 17:45:38.389868 | 
2026-02-16 17:45:38.389924 | PLAY [all]
2026-02-16 17:45:38.398537 | 
2026-02-16 17:45:38.398603 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:45:38.625874 | controller -> localhost | ok
2026-02-16 17:45:38.638549 | 
2026-02-16 17:45:38.638618 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:45:38.674193 | controller | ok
2026-02-16 17:45:38.691928 | controller | included: /var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:45:38.698214 | 
2026-02-16 17:45:38.698276 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:45:40.917085 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:45:40.917290 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/work/a994bc0371f04aee9eee8d4d87d41e39_id_rsa
2026-02-16 17:45:40.917318 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/work/a994bc0371f04aee9eee8d4d87d41e39_id_rsa.pub
2026-02-16 17:45:40.917341 | controller -> localhost | The key fingerprint is:
2026-02-16 17:45:40.917362 | controller -> localhost | SHA256:u0dUf3HReXhgwJWZYZQu7UYAB4R02Z4VDeHLn7Mi4Rc zuul-build-sshkey
2026-02-16 17:45:40.917400 | controller -> localhost | The key's randomart image is:
2026-02-16 17:45:40.917421 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:45:40.917447 | controller -> localhost | |       ..o=*o+@#+|
2026-02-16 17:45:40.917469 | controller -> localhost | |        ....=+Bo*|
2026-02-16 17:45:40.917489 | controller -> localhost | |           o B..+|
2026-02-16 17:45:40.917508 | controller -> localhost | |          . +.=..|
2026-02-16 17:45:40.917527 | controller -> localhost | |        S.   +o. |
2026-02-16 17:45:40.917546 | controller -> localhost | |         ... Eo..|
2026-02-16 17:45:40.917565 | controller -> localhost | |        ... ...o.|
2026-02-16 17:45:40.917584 | controller -> localhost | |         ..o o  o|
2026-02-16 17:45:40.917605 | controller -> localhost | |        ..  o .. |
2026-02-16 17:45:40.917624 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:45:40.917669 | controller -> localhost | ok: Runtime: 0:00:01.768705
2026-02-16 17:45:40.923228 | 
2026-02-16 17:45:40.923288 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:45:40.958849 | controller | ok
2026-02-16 17:45:40.969159 | controller | included: /var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:45:40.977638 | 
2026-02-16 17:45:40.977725 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:45:41.002963 | controller | skipping: Conditional result was False
2026-02-16 17:45:41.013396 | 
2026-02-16 17:45:41.013467 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:45:41.555444 | controller | changed
2026-02-16 17:45:41.569233 | 
2026-02-16 17:45:41.569575 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:45:41.802675 | controller | ok
2026-02-16 17:45:41.815525 | 
2026-02-16 17:45:41.815647 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:45:42.400525 | controller | changed
2026-02-16 17:45:42.406257 | 
2026-02-16 17:45:42.406322 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:45:42.999160 | controller | changed
2026-02-16 17:45:43.008674 | 
2026-02-16 17:45:43.008983 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:45:43.035860 | controller | skipping: Conditional result was False
2026-02-16 17:45:43.048899 | 
2026-02-16 17:45:43.049394 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:45:43.441980 | controller -> localhost | changed
2026-02-16 17:45:43.458377 | 
2026-02-16 17:45:43.458466 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:45:43.782938 | controller -> localhost | Identity added: /var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/work/a994bc0371f04aee9eee8d4d87d41e39_id_rsa (zuul-build-sshkey)
2026-02-16 17:45:43.783145 | controller -> localhost | ok: Runtime: 0:00:00.015420
2026-02-16 17:45:43.826753 | 
2026-02-16 17:45:43.826886 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:45:44.125511 | controller | ok
2026-02-16 17:45:44.132508 | 
2026-02-16 17:45:44.132593 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:45:44.157776 | controller | skipping: Conditional result was False
2026-02-16 17:45:44.174282 | 
2026-02-16 17:45:44.174361 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:45:44.487166 | controller | ok
2026-02-16 17:45:44.496407 | 
2026-02-16 17:45:44.496492 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:46:05.032311 | controller | Output suppressed because no_log was given
2026-02-16 17:46:05.044589 | 
2026-02-16 17:46:05.044653 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:46:05.276946 | controller | ok: "logs"
2026-02-16 17:46:05.394234 | controller | ok: All items complete
2026-02-16 17:46:05.394352 | 
2026-02-16 17:46:05.452553 | controller | ok: "artifacts"
2026-02-16 17:46:05.640783 | controller | ok: "docs"
2026-02-16 17:46:05.662513 | 
2026-02-16 17:46:05.662648 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:46:05.882310 | controller | changed: "logs"
2026-02-16 17:46:06.059729 | controller | changed: "artifacts"
2026-02-16 17:46:06.283671 | controller | changed: "docs"
2026-02-16 17:46:06.303834 | 
2026-02-16 17:46:06.303955 | PLAY RECAP
2026-02-16 17:46:06.304006 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:46:06.304035 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:46:06.304058 | 
2026-02-16 17:46:06.462727 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:46:06.468654 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:46:07.117119 | 
2026-02-16 17:46:07.117629 | PLAY [all]
2026-02-16 17:46:07.135962 | 
2026-02-16 17:46:07.136039 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:46:07.522623 | controller | changed
2026-02-16 17:46:07.531538 | 
2026-02-16 17:46:07.531625 | TASK [Gather minimum local MTU]
2026-02-16 17:46:07.610962 | controller | ok
2026-02-16 17:46:07.616210 | 
2026-02-16 17:46:07.616279 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:46:07.673123 | controller | ok
2026-02-16 17:46:07.689584 | 
2026-02-16 17:46:07.689723 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:46:07.719702 | controller | skipping: Conditional result was False
2026-02-16 17:46:07.731706 | 
2026-02-16 17:46:07.731825 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:46:08.268466 | controller | ok: Runtime: 0:00:00.009619
2026-02-16 17:46:08.276708 | 
2026-02-16 17:46:08.276794 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:46:08.303249 | controller | skipping: Conditional result was False
2026-02-16 17:46:08.313896 | 
2026-02-16 17:46:08.314058 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:46:08.340880 | controller | skipping: Conditional result was False
2026-02-16 17:46:08.352412 | 
2026-02-16 17:46:08.352558 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:46:08.422097 | controller | ok
2026-02-16 17:46:08.434335 | controller | included: /var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:46:08.441324 | 
2026-02-16 17:46:08.441394 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:46:08.508927 | controller | ok
2026-02-16 17:46:08.516005 | 
2026-02-16 17:46:08.516074 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:46:08.795490 | controller | ext4
2026-02-16 17:46:09.256468 | controller | ok: Runtime: 0:00:00.011043
2026-02-16 17:46:09.261968 | 
2026-02-16 17:46:09.262031 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:46:09.293007 | controller | ok
2026-02-16 17:46:09.299748 | 
2026-02-16 17:46:09.299815 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:46:09.333947 | controller | ok:
2026-02-16 17:46:09.334081 | controller | {
2026-02-16 17:46:09.334109 | controller |   "root_filesystem": "ext4"
2026-02-16 17:46:09.334130 | controller | }
2026-02-16 17:46:09.340017 | 
2026-02-16 17:46:09.340082 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:46:19.358622 | controller | 8192+0 records in
2026-02-16 17:46:19.358909 | controller | 8192+0 records out
2026-02-16 17:46:19.358932 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.75173 s, 881 MB/s
2026-02-16 17:46:19.405447 | controller | ok: Runtime: 0:00:09.760386
2026-02-16 17:46:19.411317 | 
2026-02-16 17:46:19.411382 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:46:19.648562 | controller | changed
2026-02-16 17:46:19.656150 | 
2026-02-16 17:46:19.656216 | TASK [configure-swap : Make swapfile]
2026-02-16 17:46:43.542346 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:46:43.542445 | controller | no label, UUID=bd58dfcd-9d8b-42b3-b983-b7835c728388
2026-02-16 17:46:43.736383 | controller | ok: Runtime: 0:00:23.637813
2026-02-16 17:46:43.742465 | 
2026-02-16 17:46:43.742533 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:46:44.170590 | controller | changed
2026-02-16 17:46:44.176465 | 
2026-02-16 17:46:44.176531 | TASK [configure-swap : Add all swap]
2026-02-16 17:46:44.714582 | controller | ok: Runtime: 0:00:00.016095
2026-02-16 17:46:44.720353 | 
2026-02-16 17:46:44.720417 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:46:44.767934 | controller | ok:
2026-02-16 17:46:44.768100 | controller | {
2026-02-16 17:46:44.768136 | controller |   "swap_required": "8192"
2026-02-16 17:46:44.768163 | controller | }
2026-02-16 17:46:44.774762 | 
2026-02-16 17:46:44.774831 | TASK [configure-swap : Set swappiness]
2026-02-16 17:46:45.193539 | controller | changed
2026-02-16 17:46:45.199826 | 
2026-02-16 17:46:45.199889 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:46:45.234323 | controller | ok:
2026-02-16 17:46:45.234483 | controller | {
2026-02-16 17:46:45.234520 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:46:45.234556 | controller | }
2026-02-16 17:46:45.245569 | 
2026-02-16 17:46:45.245639 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:46:45.730196 | controller | changed
2026-02-16 17:46:45.739392 | 
2026-02-16 17:46:45.739496 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:46:45.988257 | controller | changed
2026-02-16 17:46:45.994265 | 
2026-02-16 17:46:45.994340 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:46:46.590366 | controller | changed
2026-02-16 17:46:46.597388 | 
2026-02-16 17:46:46.597461 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:46:46.844347 | controller | changed
2026-02-16 17:46:46.850629 | 
2026-02-16 17:46:46.850700 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:46:47.903816 | controller | changed
2026-02-16 17:46:47.910757 | 
2026-02-16 17:46:47.910843 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:46:48.142877 | controller | changed
2026-02-16 17:46:48.153932 | 
2026-02-16 17:46:48.153997 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:46:48.423193 | controller | changed
2026-02-16 17:46:48.431299 | 
2026-02-16 17:46:48.431404 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:46:48.789296 | controller | changed
2026-02-16 17:46:48.875070 | 
2026-02-16 17:46:48.875232 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:46:49.648212 | controller | changed
2026-02-16 17:46:49.659209 | 
2026-02-16 17:46:49.659304 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:46:50.008621 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:46:50.025830 | 
2026-02-16 17:46:50.025987 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:46:50.496339 | controller | ok: Item: Runtime: 0:00:00.175241
2026-02-16 17:46:51.052577 | controller | ok: Item: Runtime: 0:00:00.330441
2026-02-16 17:46:51.805645 | controller | ok: Item: Runtime: 0:00:00.535605
2026-02-16 17:46:52.082829 | controller | ok: Item: Runtime: 0:00:00.065594
2026-02-16 17:46:52.559124 | controller | ok: Item: Runtime: 0:00:00.251030
2026-02-16 17:46:54.035382 | controller | ok: Item: Runtime: 0:00:01.249625
2026-02-16 17:46:57.078623 | controller | ok: Item: Runtime: 0:00:02.834997
2026-02-16 17:46:57.398611 | controller | ok: Item: Runtime: 0:00:00.098312
2026-02-16 17:46:57.786004 | controller | ok: Item: Runtime: 0:00:00.187516
2026-02-16 17:46:58.117238 | controller | ok: Item: Runtime: 0:00:00.114813
2026-02-16 17:46:58.829813 | controller | ok: Item: Runtime: 0:00:00.498694
2026-02-16 17:46:59.540741 | controller | ok: Item: Runtime: 0:00:00.498868
2026-02-16 17:46:59.970903 | controller | ok: Item: Runtime: 0:00:00.215430
2026-02-16 17:47:00.447686 | controller | ok: Item: Runtime: 0:00:00.210756
2026-02-16 17:47:01.279892 | controller | ok: Item: Runtime: 0:00:00.375204
2026-02-16 17:47:02.318463 | controller | ok: Item: Runtime: 0:00:01.049222
2026-02-16 17:47:02.347597 | 
2026-02-16 17:47:02.347777 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:47:02.559127 | controller | ok: All paths examined
2026-02-16 17:47:02.566074 | 
2026-02-16 17:47:02.566145 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:47:02.803428 | controller | ok: All paths examined
2026-02-16 17:47:02.809711 | 
2026-02-16 17:47:02.809778 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:47:03.228432 | controller | ok: Item: Runtime: 0:00:00.141954
2026-02-16 17:47:03.228662 | controller | changed: All items complete
2026-02-16 17:47:03.228691 | 
2026-02-16 17:47:03.640139 | controller | ok: Item: Runtime: 0:00:00.202424
2026-02-16 17:47:03.654457 | 
2026-02-16 17:47:03.654626 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:47:03.695132 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.697353 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.699957 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.703150 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.706202 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.709431 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.712437 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.715776 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.718984 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.722082 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.724678 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.727637 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.730591 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.733661 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.736656 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.739820 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.772062 | 
2026-02-16 17:47:03.772187 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:47:06.098038 | controller | changed
2026-02-16 17:47:06.207964 | 
2026-02-16 17:47:06.208056 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:47:06.446690 | controller | changed
2026-02-16 17:47:06.504173 | 
2026-02-16 17:47:06.504313 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:47:06.750686 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:47:07.039253 | controller | ERROR
2026-02-16 17:47:07.039464 | controller | {
2026-02-16 17:47:07.039492 | controller |   "delta": "0:00:00.009438",
2026-02-16 17:47:07.039513 | controller |   "end": "2026-02-16 17:47:06.751133",
2026-02-16 17:47:07.039531 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:07.039549 | controller |   "rc": 1,
2026-02-16 17:47:07.039567 | controller |   "start": "2026-02-16 17:47:06.741695"
2026-02-16 17:47:07.039584 | controller | }
2026-02-16 17:47:07.039607 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:07.052452 | 
2026-02-16 17:47:07.052731 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:47:07.304061 | controller | ok
2026-02-16 17:47:07.316533 | 
2026-02-16 17:47:07.316598 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:47:07.555473 | controller | /usr/bin/journalctl
2026-02-16 17:47:07.855588 | controller | ok: Runtime: 0:00:00.008195
2026-02-16 17:47:07.862034 | 
2026-02-16 17:47:07.862107 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:47:08.099223 | controller | 2026-02-16 17:47:08
2026-02-16 17:47:08.413396 | controller | ok: Runtime: 0:00:00.008038
2026-02-16 17:47:08.420241 | 
2026-02-16 17:47:08.420308 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:47:09.009853 | controller | changed
2026-02-16 17:47:09.015926 | 
2026-02-16 17:47:09.016011 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:47:09.041192 | controller | skipping: Conditional result was False
2026-02-16 17:47:09.051815 | 
2026-02-16 17:47:09.051950 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:47:09.583376 | controller | skipping: Conditional result was False
2026-02-16 17:47:09.593840 | 
2026-02-16 17:47:09.593909 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:47:10.126482 | controller | skipping: Conditional result was False
2026-02-16 17:47:10.139120 | 
2026-02-16 17:47:10.139368 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:47:10.164587 | controller | skipping: Conditional result was False
2026-02-16 17:47:10.174395 | 
2026-02-16 17:47:10.174496 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:47:10.706359 | controller | skipping: Conditional result was False
2026-02-16 17:47:10.717790 | 
2026-02-16 17:47:10.717899 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:47:11.248508 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.258488 | 
2026-02-16 17:47:11.258579 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:47:11.283254 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.294521 | 
2026-02-16 17:47:11.294591 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:47:11.318729 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.327698 | 
2026-02-16 17:47:11.327790 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:47:11.918212 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.928750 | 
2026-02-16 17:47:11.928864 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:47:12.463024 | controller | skipping: Conditional result was False
2026-02-16 17:47:12.596471 | 
2026-02-16 17:47:12.596581 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:47:12.621204 | controller | skipping: Conditional result was False
2026-02-16 17:47:12.632425 | 
2026-02-16 17:47:12.632493 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:47:12.657669 | controller | skipping: Conditional result was False
2026-02-16 17:47:12.673858 | 
2026-02-16 17:47:12.673955 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:47:13.066870 | controller | ok
2026-02-16 17:47:13.076860 | 
2026-02-16 17:47:13.076919 | PLAY RECAP
2026-02-16 17:47:13.076987 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:47:13.077013 | 
2026-02-16 17:47:13.221603 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:47:13.231861 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:47:13.807236 | 
2026-02-16 17:47:13.807364 | PLAY [all]
2026-02-16 17:47:13.818827 | 
2026-02-16 17:47:13.818906 | TASK [Install curl]
2026-02-16 17:47:14.414091 | controller | ERROR
2026-02-16 17:47:14.414384 | controller | {
2026-02-16 17:47:14.414427 | controller |   "msg": "No package matching 'curl' is available"
2026-02-16 17:47:14.414458 | controller | }
failure
2026-02-16 17:47:14.415841 | 
2026-02-16 17:47:14.415893 | PLAY RECAP
2026-02-16 17:47:14.415961 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:47:14.415986 | 
2026-02-16 17:47:14.521505 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:47:14.530944 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:47:15.107381 | 
2026-02-16 17:47:15.107524 | PLAY [all]
2026-02-16 17:47:15.124002 | 
2026-02-16 17:47:15.124091 | TASK [Return built artifacts to Zuul]
2026-02-16 17:47:15.331155 | controller | ok
2026-02-16 17:47:15.336998 | 
2026-02-16 17:47:15.337077 | TASK [Get the Docker volume ID]
2026-02-16 17:47:15.878420 | controller | ERROR
2026-02-16 17:47:15.878787 | controller | {
2026-02-16 17:47:15.878832 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:47:15.878863 | controller |   "rc": 2
2026-02-16 17:47:15.878890 | controller | }
2026-02-16 17:47:15.878927 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:15.887663 | 
2026-02-16 17:47:15.887767 | TASK [Copy all of the pod logs]
2026-02-16 17:47:15.921595 | controller | ERROR
2026-02-16 17:47:15.921802 | controller | {
2026-02-16 17:47:15.921843 | 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/a994bc0371f04aee9eee8d4d87d41e39/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml': line 21, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-02-16 17:47:15.921874 | controller | }
2026-02-16 17:47:15.921913 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:15.923116 | 
2026-02-16 17:47:15.923177 | PLAY RECAP
2026-02-16 17:47:15.923237 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:47:15.923265 | 
2026-02-16 17:47:16.053636 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:47:16.062003 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:47:16.693326 | 
2026-02-16 17:47:16.693456 | PLAY [all]
2026-02-16 17:47:16.711489 | 
2026-02-16 17:47:16.711574 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:47:17.101531 | controller | changed
2026-02-16 17:47:17.109402 | 
2026-02-16 17:47:17.109484 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:47:18.152072 | controller | ok: Runtime: 0:00:00.451776
2026-02-16 17:47:18.161142 | 
2026-02-16 17:47:18.161243 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:47:18.713613 | controller | ok: Runtime: 0:00:00.020950
2026-02-16 17:47:18.721404 | 
2026-02-16 17:47:18.721480 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:47:19.262610 | controller | ok: Runtime: 0:00:00.020574
2026-02-16 17:47:19.270960 | 
2026-02-16 17:47:19.271060 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:47:20.200917 | controller | changed
2026-02-16 17:47:20.249874 | 
2026-02-16 17:47:20.250030 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:47:20.487158 | controller | changed
2026-02-16 17:47:20.494638 | 
2026-02-16 17:47:20.494711 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:47:20.822110 | controller | Output suppressed because no_log was given
2026-02-16 17:47:20.829573 | 
2026-02-16 17:47:20.829664 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:47:20.865212 | 
2026-02-16 17:47:20.865377 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:47:20.902410 | 
2026-02-16 17:47:20.902676 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:47:20.939259 | 
2026-02-16 17:47:20.939459 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:47:20.973842 | 
2026-02-16 17:47:20.974134 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:47:21.011640 | 
2026-02-16 17:47:21.011823 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:47:21.275131 | controller | changed
2026-02-16 17:47:24.043704 | 
2026-02-16 17:47:24.043842 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:47:24.078381 | controller | ok
2026-02-16 17:47:24.420363 | 
2026-02-16 17:47:24.420506 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:47:24.658983 | controller | Output suppressed because no_log was given
2026-02-16 17:47:24.666096 | 
2026-02-16 17:47:24.666196 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:47:24.703880 | 
2026-02-16 17:47:24.704053 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:47:24.734841 | 
2026-02-16 17:47:24.734994 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:47:25.403993 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:47:25.422992 | controller | Using python 3.12 to install setuptools
2026-02-16 17:47:25.461073 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:47:25.472058 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:47:25.780689 | controller | ERROR
2026-02-16 17:47:25.781152 | controller | {
2026-02-16 17:47:25.781237 | controller |   "delta": "0:00:00.480697",
2026-02-16 17:47:25.781288 | controller |   "end": "2026-02-16 17:47:25.474457",
2026-02-16 17:47:25.781335 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:25.781385 | controller |   "rc": 127,
2026-02-16 17:47:25.781430 | controller |   "start": "2026-02-16 17:47:24.993760"
2026-02-16 17:47:25.781474 | controller | }
2026-02-16 17:47:25.781536 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:25.798370 | 
2026-02-16 17:47:25.798448 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:47:26.056470 | controller | changed
2026-02-16 17:47:28.272159 | 
2026-02-16 17:47:28.272361 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:47:31.925222 | controller | Output suppressed because no_log was given
2026-02-16 17:47:31.937293 | 
2026-02-16 17:47:31.937366 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:47:31.971445 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.972028 | 
2026-02-16 17:47:31.974137 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.975776 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.977364 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.979496 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.981744 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.984157 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.986613 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.989478 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.992229 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.994278 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.996570 | controller | skipping: Conditional result was False
2026-02-16 17:47:31.998848 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.001384 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.003585 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.005872 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.009308 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.011464 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.037226 | 
2026-02-16 17:47:32.037351 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:47:32.264083 | controller | ok
2026-02-16 17:47:32.271455 | 
2026-02-16 17:47:32.271538 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:47:32.845993 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.864274 | 
2026-02-16 17:47:32.864356 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:47:33.121166 | controller | sudo: iptables-save: command not found
2026-02-16 17:47:33.163175 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:47:34.128159 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:47:34.405782 | controller | ok: Runtime: 0:00:01.027778
2026-02-16 17:47:34.418254 | 
2026-02-16 17:47:34.418334 | LOOP [stage-output : Register sources]
2026-02-16 17:47:43.604182 | controller | Output suppressed because no_log was given
2026-02-16 17:47:43.665464 | 
2026-02-16 17:47:43.665642 | TASK [stage-output : Check sudo]
2026-02-16 17:47:44.206857 | controller | ok: Runtime: 0:00:00.018230
2026-02-16 17:47:44.215026 | 
2026-02-16 17:47:44.215095 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:47:44.319505 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.319973 | 
2026-02-16 17:47:44.321750 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.322588 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.324584 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.361714 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.362127 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.362495 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.363900 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.366617 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.368767 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.371108 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.373321 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.375521 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.377765 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.397095 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.397612 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.398092 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.398612 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.399084 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.400149 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.402362 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.404623 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.406801 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.432379 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.432994 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.509410 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.519663 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.528949 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.540612 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.554779 | 
2026-02-16 17:47:44.554873 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:47:44.621491 | controller | ok
2026-02-16 17:47:44.629624 | 
2026-02-16 17:47:44.629736 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:47:44.865508 | controller | changed: "docs"
2026-02-16 17:47:45.101460 | controller | changed: "artifacts"
2026-02-16 17:47:45.312023 | controller | ok: "logs"
2026-02-16 17:47:45.325964 | 
2026-02-16 17:47:45.326142 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:47:45.594938 | controller | ok: Item: Runtime: 0:00:00.009935
2026-02-16 17:47:45.595340 | controller | changed: All items complete
2026-02-16 17:47:45.595374 | 
2026-02-16 17:47:45.815708 | controller | ok: Item: Runtime: 0:00:00.007161
2026-02-16 17:47:46.036050 | controller | ok: Item: Runtime: 0:00:00.009030
2026-02-16 17:47:46.250526 | controller | ok: Item: Runtime: 0:00:00.007348
2026-02-16 17:47:46.473696 | controller | ok: Item: Runtime: 0:00:00.007653
2026-02-16 17:47:46.686911 | controller | ok: Item: Runtime: 0:00:00.007717
2026-02-16 17:47:46.898721 | controller | ok: Item: Runtime: 0:00:00.007391
2026-02-16 17:47:47.770009 | controller | ok: Item: Runtime: 0:00:00.006725
2026-02-16 17:47:47.779478 | controller | ok: Item: Runtime: 0:00:00.007499
2026-02-16 17:47:47.789595 | controller | ok: Item: Runtime: 0:00:00.007738
2026-02-16 17:47:47.798141 | controller | ok: Item: Runtime: 0:00:00.007183
2026-02-16 17:47:48.018537 | controller | ok: Item: Runtime: 0:00:00.007190
2026-02-16 17:47:48.196088 | controller | ok: Item: Runtime: 0:00:00.007110
2026-02-16 17:47:48.445845 | controller | ok: Item: Runtime: 0:00:00.007648
2026-02-16 17:47:48.667462 | controller | ok: Item: Runtime: 0:00:00.008145
2026-02-16 17:47:48.886001 | controller | ok: Item: Runtime: 0:00:00.007683
2026-02-16 17:47:48.907116 | 
2026-02-16 17:47:48.907280 | TASK [stage-output : Make all log files readable]
2026-02-16 17:47:49.146739 | controller | changed
2026-02-16 17:47:49.158795 | 
2026-02-16 17:47:49.159022 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:47:49.478710 | controller | changed: Renamed files for staging.
2026-02-16 17:47:49.863955 | 
2026-02-16 17:47:49.864096 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:47:49.888758 | controller | skipping: Conditional result was False
2026-02-16 17:47:49.901364 | 
2026-02-16 17:47:49.901467 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:47:49.945159 | 
2026-02-16 17:47:49.945332 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:47:50.556545 | controller | changed:
2026-02-16 17:47:50.556749 | controller | created directory /var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/work/logs/controller
2026-02-16 17:47:50.556790 | controller | cd+++++++++ logs/
2026-02-16 17:47:50.556821 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:47:50.556850 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:47:50.556877 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:47:50.556904 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:47:50.556930 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:47:50.556985 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:47:50.557016 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:47:50.557041 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:47:50.557065 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:47:50.557090 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:47:50.557115 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:47:50.557140 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:47:50.557169 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:47:50.557196 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:47:50.557220 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:47:50.557249 | controller | cd+++++++++ logs/apache/
2026-02-16 17:47:50.557277 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:47:50.557305 | controller | cd+++++++++ logs/etc/
2026-02-16 17:47:50.557333 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:47:50.557361 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:47:50.557389 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:47:50.557417 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:47:50.557463 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:47:50.557492 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:47:50.584889 | 
2026-02-16 17:47:51.374340 | TASK [Check if a tempest log exits]
2026-02-16 17:47:51.624463 | controller | ok
2026-02-16 17:47:51.629717 | 
2026-02-16 17:47:51.629778 | TASK [Link post-devstack tempest.log]
2026-02-16 17:47:51.653023 | controller | skipping: Conditional result was False
2026-02-16 17:47:51.664658 | 
2026-02-16 17:47:51.664732 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:47:51.911896 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:47:52.206387 | controller | ERROR
2026-02-16 17:47:52.206708 | controller | {
2026-02-16 17:47:52.206759 | controller |   "delta": "0:00:00.008155",
2026-02-16 17:47:52.206789 | controller |   "end": "2026-02-16 17:47:51.912541",
2026-02-16 17:47:52.206814 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:52.206839 | controller |   "rc": 127,
2026-02-16 17:47:52.206864 | controller |   "start": "2026-02-16 17:47:51.904386"
2026-02-16 17:47:52.206888 | controller | }
2026-02-16 17:47:52.206921 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:52.209106 | 
2026-02-16 17:47:52.209173 | PLAY RECAP
2026-02-16 17:47:52.209237 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:47:52.209268 | 
2026-02-16 17:47:52.388180 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:47:52.399146 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:47:52.975110 | 
2026-02-16 17:47:52.975676 | PLAY [all]
2026-02-16 17:47:52.987453 | 
2026-02-16 17:47:52.987525 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:47:53.033094 | controller | skipping: Conditional result was False
2026-02-16 17:47:53.044564 | 
2026-02-16 17:47:53.044642 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:47:53.088864 | controller | ok
2026-02-16 17:47:53.097058 | 
2026-02-16 17:47:53.097151 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:47:53.520372 | controller -> localhost | ok: "/var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/work/logs"
2026-02-16 17:47:53.748367 | controller -> localhost | changed: "/var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/work/artifacts"
2026-02-16 17:47:53.955817 | controller -> localhost | changed: "/var/lib/zuul/builds/a994bc0371f04aee9eee8d4d87d41e39/work/docs"
2026-02-16 17:47:53.971452 | 
2026-02-16 17:47:53.971602 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:47:54.632346 | controller | changed: .d..t...... ./
2026-02-16 17:47:54.632610 | controller | changed: All items complete
2026-02-16 17:47:54.632648 | 
2026-02-16 17:47:55.130749 | controller | changed: .d..t...... ./
2026-02-16 17:47:55.597002 | controller | changed: .d..t...... ./
2026-02-16 17:47:55.618323 | 
2026-02-16 17:47:55.618491 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:47:56.054548 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.009579
2026-02-16 17:47:56.289699 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007163
2026-02-16 17:47:56.308932 | 
2026-02-16 17:47:56.309078 | PLAY [all]
2026-02-16 17:47:56.316550 | 
2026-02-16 17:47:56.316648 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:47:56.786728 | controller | changed
2026-02-16 17:47:56.794826 | 
2026-02-16 17:47:56.794875 | PLAY RECAP
2026-02-16 17:47:56.794919 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:47:56.794941 | 
2026-02-16 17:47:56.957775 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:47:56.967326 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:47:57.709755 | 
2026-02-16 17:47:58.169135 | PLAY [localhost]
2026-02-16 17:47:58.182308 | 
2026-02-16 17:47:58.182411 | TASK [Generate Zuul manifest]
2026-02-16 17:47:58.203501 | localhost | ok
2026-02-16 17:47:58.218910 | 
2026-02-16 17:47:58.218985 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:47:58.612078 | localhost | changed
2026-02-16 17:47:58.624937 | 
2026-02-16 17:47:58.625028 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:47:58.841282 | localhost | ok
2026-02-16 17:47:58.852391 | 
2026-02-16 17:47:58.852468 | TASK [Upload logs]
2026-02-16 17:47:58.874939 | localhost | ok
2026-02-16 17:47:58.933197 | 
2026-02-16 17:47:58.933301 | TASK [Set zuul-log-path fact]
2026-02-16 17:47:58.956942 | localhost | ok
2026-02-16 17:47:58.968613 | 
2026-02-16 17:47:58.968677 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:47:59.000951 | localhost | ok
2026-02-16 17:47:59.010746 | 
2026-02-16 17:47:59.010814 | TASK [upload-logs : Create log directories]
2026-02-16 17:47:59.417472 | localhost | changed
2026-02-16 17:47:59.424347 | 
2026-02-16 17:47:59.424418 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:48:00.790221 | localhost -> localhost | ok: Runtime: 0:00:00.005114
2026-02-16 17:48:00.794829 | 
2026-02-16 17:48:00.794891 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:48:01.308522 | localhost | Output suppressed because no_log was given
2026-02-16 17:48:01.312619 | 
2026-02-16 17:48:01.312697 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:48:01.362570 | localhost | skipping: Conditional result was False
2026-02-16 17:48:01.369598 | localhost | skipping: Conditional result was False
2026-02-16 17:48:01.387615 | 
2026-02-16 17:48:01.387810 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:48:01.431823 | localhost | skipping: Conditional result was False
2026-02-16 17:48:01.432182 | 
2026-02-16 17:48:01.435147 | localhost | skipping: Conditional result was False
2026-02-16 17:48:01.454187 | 
2026-02-16 17:48:01.454367 | LOOP [upload-logs : Upload console log and json output]