2026-02-16 17:45:16.615120 | Job console starting
2026-02-16 17:45:16.736835 | Updating git repos
2026-02-16 17:45:24.497579 | Cloning repos into workspace
2026-02-16 17:45:30.879285 | Restoring repo states
2026-02-16 17:45:33.538407 | Merging changes
2026-02-16 17:45:34.315066 | Checking out repos
2026-02-16 17:45:37.271075 | Preparing playbooks
2026-02-16 17:45:39.108476 | Running Ansible setup
2026-02-16 17:45:43.090784 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:45:43.695489 | 
2026-02-16 17:45:43.695674 | PLAY [localhost]
2026-02-16 17:45:43.704554 | 
2026-02-16 17:45:43.704672 | TASK [Gathering Facts]
2026-02-16 17:45:44.655528 | localhost | ok
2026-02-16 17:45:44.665395 | 
2026-02-16 17:45:44.665476 | TASK [Setup log path fact]
2026-02-16 17:45:44.686558 | localhost | ok
2026-02-16 17:45:44.700232 | 
2026-02-16 17:45:44.700304 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:45:44.727822 | localhost | ok
2026-02-16 17:45:44.733845 | 
2026-02-16 17:45:44.733913 | TASK [emit-job-header : Print job information]
2026-02-16 17:45:44.778045 | # Job Information
2026-02-16 17:45:44.778332 | Ansible Version: 2.16.15
2026-02-16 17:45:44.778403 | Job: magnum-cluster-api-sonobuoy-v1.33.8-calico
2026-02-16 17:45:44.778451 | Pipeline: check
2026-02-16 17:45:44.778495 | Executor: 3a2793d2bd32
2026-02-16 17:45:44.778539 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:45:44.778588 | Event ID: 37691bf0-0b5f-11f1-8a15-a065d2c16437
2026-02-16 17:45:44.782591 | 
2026-02-16 17:45:44.782663 | LOOP [emit-job-header : Print node information]
2026-02-16 17:45:44.890192 | localhost | ok:
2026-02-16 17:45:44.890450 | localhost | # Node Information
2026-02-16 17:45:44.890503 | localhost | Inventory Hostname: controller
2026-02-16 17:45:44.890542 | localhost | Hostname: np0000155631
2026-02-16 17:45:44.890575 | localhost | Username: zuul
2026-02-16 17:45:44.890612 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:45:44.890645 | localhost | Provider: yul1
2026-02-16 17:45:44.890677 | localhost | Region: ca-ymq-1
2026-02-16 17:45:44.890709 | localhost | Label: ubuntu-noble-16
2026-02-16 17:45:44.890740 | localhost | Product Name: OpenStack Nova
2026-02-16 17:45:44.890772 | localhost | Interface IP: 162.253.55.62
2026-02-16 17:45:44.901211 | 
2026-02-16 17:45:44.901437 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:45:45.283179 | localhost -> localhost | changed
2026-02-16 17:45:45.292025 | 
2026-02-16 17:45:45.292372 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:45:46.199692 | localhost -> localhost | changed
2026-02-16 17:45:46.207561 | 
2026-02-16 17:45:46.207619 | PLAY [all]
2026-02-16 17:45:46.217784 | 
2026-02-16 17:45:46.217854 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:45:46.465493 | controller -> localhost | ok
2026-02-16 17:45:46.476306 | 
2026-02-16 17:45:46.476370 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:45:46.511086 | controller | ok
2026-02-16 17:45:46.526712 | controller | included: /var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:45:46.533432 | 
2026-02-16 17:45:46.533492 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:45:47.536237 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:45:47.536427 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/work/ac1bf73ab9dd450d88e65937eee27f62_id_rsa
2026-02-16 17:45:47.536455 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/work/ac1bf73ab9dd450d88e65937eee27f62_id_rsa.pub
2026-02-16 17:45:47.536478 | controller -> localhost | The key fingerprint is:
2026-02-16 17:45:47.536498 | controller -> localhost | SHA256:rHrGFK1W5WiY06hh+F6UeLROu30aa8icPrJcBgkX57U zuul-build-sshkey
2026-02-16 17:45:47.536532 | controller -> localhost | The key's randomart image is:
2026-02-16 17:45:47.536553 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:45:47.536577 | controller -> localhost | |    . . .        |
2026-02-16 17:45:47.536598 | controller -> localhost | |     +.. ..      |
2026-02-16 17:45:47.536619 | controller -> localhost | |  ...o.OE+       |
2026-02-16 17:45:47.536639 | controller -> localhost | |  .o+.%.* .      |
2026-02-16 17:45:47.536658 | controller -> localhost | |   ooB BS        |
2026-02-16 17:45:47.536677 | controller -> localhost | |    o.B.         |
2026-02-16 17:45:47.536696 | controller -> localhost | |   . B+=.        |
2026-02-16 17:45:47.536715 | controller -> localhost | |   .o+@ oo.      |
2026-02-16 17:45:47.536738 | controller -> localhost | |    +*.ooo       |
2026-02-16 17:45:47.536758 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:45:47.536800 | controller -> localhost | ok: Runtime: 0:00:00.603044
2026-02-16 17:45:47.542372 | 
2026-02-16 17:45:47.542435 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:45:47.577487 | controller | ok
2026-02-16 17:45:47.586714 | controller | included: /var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:45:47.594646 | 
2026-02-16 17:45:47.594703 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:45:47.618936 | controller | skipping: Conditional result was False
2026-02-16 17:45:47.628412 | 
2026-02-16 17:45:47.628500 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:45:48.167807 | controller | changed
2026-02-16 17:45:48.176901 | 
2026-02-16 17:45:48.177213 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:45:48.403912 | controller | ok
2026-02-16 17:45:48.411781 | 
2026-02-16 17:45:48.411848 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:45:48.969833 | controller | changed
2026-02-16 17:45:48.975893 | 
2026-02-16 17:45:48.975957 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:45:49.525403 | controller | changed
2026-02-16 17:45:49.533316 | 
2026-02-16 17:45:49.533382 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:45:49.557646 | controller | skipping: Conditional result was False
2026-02-16 17:45:49.567912 | 
2026-02-16 17:45:49.568005 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:45:49.936039 | controller -> localhost | changed
2026-02-16 17:45:49.965805 | 
2026-02-16 17:45:49.966000 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:45:50.330486 | controller -> localhost | Identity added: /var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/work/ac1bf73ab9dd450d88e65937eee27f62_id_rsa (zuul-build-sshkey)
2026-02-16 17:45:50.330711 | controller -> localhost | ok: Runtime: 0:00:00.019302
2026-02-16 17:45:50.393936 | 
2026-02-16 17:45:50.394082 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:45:50.715204 | controller | ok
2026-02-16 17:45:50.723014 | 
2026-02-16 17:45:50.723118 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:45:50.749217 | controller | skipping: Conditional result was False
2026-02-16 17:45:50.765178 | 
2026-02-16 17:45:50.765248 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:45:51.091162 | controller | ok
2026-02-16 17:45:51.099405 | 
2026-02-16 17:45:51.099524 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:46:12.433531 | controller | Output suppressed because no_log was given
2026-02-16 17:46:12.443158 | 
2026-02-16 17:46:12.443223 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:46:12.679051 | controller | ok: "logs"
2026-02-16 17:46:12.679863 | controller | ok: All items complete
2026-02-16 17:46:12.679899 | 
2026-02-16 17:46:12.864583 | controller | ok: "artifacts"
2026-02-16 17:46:13.077567 | controller | ok: "docs"
2026-02-16 17:46:13.089268 | 
2026-02-16 17:46:13.089451 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:46:13.317297 | controller | changed: "logs"
2026-02-16 17:46:13.509962 | controller | changed: "artifacts"
2026-02-16 17:46:13.746360 | controller | changed: "docs"
2026-02-16 17:46:13.767069 | 
2026-02-16 17:46:13.767212 | PLAY RECAP
2026-02-16 17:46:13.767357 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:46:13.767419 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:46:13.767452 | 
2026-02-16 17:46:13.880089 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:46:13.889040 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:46:14.529973 | 
2026-02-16 17:46:14.530172 | PLAY [all]
2026-02-16 17:46:14.549548 | 
2026-02-16 17:46:14.549665 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:46:14.939576 | controller | changed
2026-02-16 17:46:14.950144 | 
2026-02-16 17:46:14.950493 | TASK [Gather minimum local MTU]
2026-02-16 17:46:15.027051 | controller | ok
2026-02-16 17:46:15.035875 | 
2026-02-16 17:46:15.036027 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:46:15.093791 | controller | ok
2026-02-16 17:46:15.101060 | 
2026-02-16 17:46:15.101132 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:46:15.127607 | controller | skipping: Conditional result was False
2026-02-16 17:46:15.139478 | 
2026-02-16 17:46:15.139574 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:46:15.679896 | controller | ok: Runtime: 0:00:00.009220
2026-02-16 17:46:15.688502 | 
2026-02-16 17:46:15.688610 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:46:15.715396 | controller | skipping: Conditional result was False
2026-02-16 17:46:15.725254 | 
2026-02-16 17:46:15.725497 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:46:15.751996 | controller | skipping: Conditional result was False
2026-02-16 17:46:15.760256 | 
2026-02-16 17:46:15.760384 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:46:15.817292 | controller | ok
2026-02-16 17:46:15.828183 | controller | included: /var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:46:15.834162 | 
2026-02-16 17:46:15.834224 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:46:15.890195 | controller | ok
2026-02-16 17:46:15.896381 | 
2026-02-16 17:46:15.896453 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:46:16.181944 | controller | ext4
2026-02-16 17:46:16.473772 | controller | ok: Runtime: 0:00:00.011958
2026-02-16 17:46:16.479782 | 
2026-02-16 17:46:16.479866 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:46:16.514013 | controller | ok
2026-02-16 17:46:16.521673 | 
2026-02-16 17:46:16.521783 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:46:16.556083 | controller | ok:
2026-02-16 17:46:16.556351 | controller | {
2026-02-16 17:46:16.556413 | controller |   "root_filesystem": "ext4"
2026-02-16 17:46:16.556460 | controller | }
2026-02-16 17:46:16.565769 | 
2026-02-16 17:46:16.565848 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:46:26.867698 | controller | 8192+0 records in
2026-02-16 17:46:26.867860 | controller | 8192+0 records out
2026-02-16 17:46:26.867881 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.0373 s, 856 MB/s
2026-02-16 17:46:27.123007 | controller | ok: Runtime: 0:00:10.047109
2026-02-16 17:46:27.128795 | 
2026-02-16 17:46:27.128864 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:46:27.378885 | controller | changed
2026-02-16 17:46:27.398218 | 
2026-02-16 17:46:27.398592 | TASK [configure-swap : Make swapfile]
2026-02-16 17:46:42.852542 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:46:42.852701 | controller | no label, UUID=17872a28-ae3d-4e1b-bfbd-abd566dac7de
2026-02-16 17:46:42.958895 | controller | ok: Runtime: 0:00:15.210571
2026-02-16 17:46:42.965921 | 
2026-02-16 17:46:42.965993 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:46:43.367202 | controller | changed
2026-02-16 17:46:43.373270 | 
2026-02-16 17:46:43.373366 | TASK [configure-swap : Add all swap]
2026-02-16 17:46:43.917194 | controller | ok: Runtime: 0:00:00.045349
2026-02-16 17:46:43.923339 | 
2026-02-16 17:46:43.923412 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:46:43.954957 | controller | ok:
2026-02-16 17:46:43.955110 | controller | {
2026-02-16 17:46:43.955135 | controller |   "swap_required": "8192"
2026-02-16 17:46:43.955155 | controller | }
2026-02-16 17:46:43.962150 | 
2026-02-16 17:46:43.962229 | TASK [configure-swap : Set swappiness]
2026-02-16 17:46:44.426351 | controller | changed
2026-02-16 17:46:44.434937 | 
2026-02-16 17:46:44.435037 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:46:44.470183 | controller | ok:
2026-02-16 17:46:44.470415 | controller | {
2026-02-16 17:46:44.470461 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:46:44.470568 | controller | }
2026-02-16 17:46:44.484188 | 
2026-02-16 17:46:44.484285 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:46:45.078016 | controller | changed
2026-02-16 17:46:45.085828 | 
2026-02-16 17:46:45.085909 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:46:45.330759 | controller | changed
2026-02-16 17:46:45.337895 | 
2026-02-16 17:46:45.337959 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:46:45.885924 | controller | changed
2026-02-16 17:46:45.894895 | 
2026-02-16 17:46:45.894970 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:46:46.133516 | controller | changed
2026-02-16 17:46:46.143279 | 
2026-02-16 17:46:46.143352 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:46:47.314885 | controller | changed
2026-02-16 17:46:47.322725 | 
2026-02-16 17:46:47.322801 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:46:47.549772 | controller | changed
2026-02-16 17:46:47.562886 | 
2026-02-16 17:46:47.562991 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:46:47.838943 | controller | changed
2026-02-16 17:46:47.844406 | 
2026-02-16 17:46:47.844474 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:46:48.200066 | controller | changed
2026-02-16 17:46:48.248579 | 
2026-02-16 17:46:48.248751 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:46:49.077138 | controller | changed
2026-02-16 17:46:49.090935 | 
2026-02-16 17:46:49.091036 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:46:49.419071 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:46:49.433744 | 
2026-02-16 17:46:49.433831 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:46:49.924779 | controller | ok: Item: Runtime: 0:00:00.183892
2026-02-16 17:46:50.486756 | controller | ok: Item: Runtime: 0:00:00.325693
2026-02-16 17:46:51.244779 | controller | ok: Item: Runtime: 0:00:00.512976
2026-02-16 17:46:51.528183 | controller | ok: Item: Runtime: 0:00:00.061630
2026-02-16 17:46:52.025952 | controller | ok: Item: Runtime: 0:00:00.261543
2026-02-16 17:46:53.571413 | controller | ok: Item: Runtime: 0:00:01.338387
2026-02-16 17:46:57.010695 | controller | ok: Item: Runtime: 0:00:03.219651
2026-02-16 17:46:57.344403 | controller | ok: Item: Runtime: 0:00:00.099580
2026-02-16 17:46:57.760959 | controller | ok: Item: Runtime: 0:00:00.191909
2026-02-16 17:46:58.109046 | controller | ok: Item: Runtime: 0:00:00.122575
2026-02-16 17:46:58.857825 | controller | ok: Item: Runtime: 0:00:00.531467
2026-02-16 17:46:59.618210 | controller | ok: Item: Runtime: 0:00:00.538864
2026-02-16 17:47:00.091436 | controller | ok: Item: Runtime: 0:00:00.243063
2026-02-16 17:47:00.554945 | controller | ok: Item: Runtime: 0:00:00.232834
2026-02-16 17:47:01.283969 | controller | ok: Item: Runtime: 0:00:00.368710
2026-02-16 17:47:02.448551 | controller | ok: Item: Runtime: 0:00:01.091444
2026-02-16 17:47:02.480628 | 
2026-02-16 17:47:02.480816 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:47:02.697149 | controller | ok: All paths examined
2026-02-16 17:47:02.703666 | 
2026-02-16 17:47:02.703730 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:47:02.952283 | controller | ok: All paths examined
2026-02-16 17:47:02.960222 | 
2026-02-16 17:47:02.960294 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:47:03.418775 | controller | ok: Item: Runtime: 0:00:00.168427
2026-02-16 17:47:03.419063 | controller | changed: All items complete
2026-02-16 17:47:03.419094 | 
2026-02-16 17:47:03.834120 | controller | ok: Item: Runtime: 0:00:00.183464
2026-02-16 17:47:03.855930 | 
2026-02-16 17:47:03.856112 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:47:03.914568 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.917218 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.920069 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.922839 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.925999 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.950704 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.951497 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.952301 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.953066 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.953709 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.954342 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.954973 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.955601 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.956262 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.956914 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.960020 | controller | skipping: Conditional result was False
2026-02-16 17:47:03.991311 | 
2026-02-16 17:47:03.991516 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:47:06.420322 | controller | changed
2026-02-16 17:47:06.433567 | 
2026-02-16 17:47:06.433664 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:47:06.682025 | controller | changed
2026-02-16 17:47:06.750879 | 
2026-02-16 17:47:06.751028 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:47:07.005051 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:47:07.295685 | controller | ERROR
2026-02-16 17:47:07.295992 | controller | {
2026-02-16 17:47:07.296035 | controller |   "delta": "0:00:00.009632",
2026-02-16 17:47:07.296065 | controller |   "end": "2026-02-16 17:47:07.005624",
2026-02-16 17:47:07.296093 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:07.296144 | controller |   "rc": 1,
2026-02-16 17:47:07.296172 | controller |   "start": "2026-02-16 17:47:06.995992"
2026-02-16 17:47:07.296199 | controller | }
2026-02-16 17:47:07.296233 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:07.304702 | 
2026-02-16 17:47:07.304835 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:47:07.551225 | controller | ok
2026-02-16 17:47:07.563025 | 
2026-02-16 17:47:07.563105 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:47:07.820854 | controller | /usr/bin/journalctl
2026-02-16 17:47:08.107687 | controller | ok: Runtime: 0:00:00.007671
2026-02-16 17:47:08.114901 | 
2026-02-16 17:47:08.114968 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:47:08.372079 | controller | 2026-02-16 17:47:08
2026-02-16 17:47:08.657253 | controller | ok: Runtime: 0:00:00.010860
2026-02-16 17:47:08.664415 | 
2026-02-16 17:47:08.664487 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:47:09.238271 | controller | changed
2026-02-16 17:47:09.244470 | 
2026-02-16 17:47:09.244538 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:47:09.270140 | controller | skipping: Conditional result was False
2026-02-16 17:47:09.281385 | 
2026-02-16 17:47:09.281493 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:47:09.818962 | controller | skipping: Conditional result was False
2026-02-16 17:47:09.829346 | 
2026-02-16 17:47:09.829442 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:47:10.361353 | controller | skipping: Conditional result was False
2026-02-16 17:47:10.534016 | 
2026-02-16 17:47:10.534149 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:47:10.559047 | controller | skipping: Conditional result was False
2026-02-16 17:47:10.572303 | 
2026-02-16 17:47:10.572456 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:47:11.102847 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.114730 | 
2026-02-16 17:47:11.114801 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:47:11.645323 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.655991 | 
2026-02-16 17:47:11.656074 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:47:11.680641 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.687207 | 
2026-02-16 17:47:11.687275 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:47:11.712799 | controller | skipping: Conditional result was False
2026-02-16 17:47:11.859405 | 
2026-02-16 17:47:11.859500 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:47:12.435416 | controller | skipping: Conditional result was False
2026-02-16 17:47:12.596529 | 
2026-02-16 17:47:12.596663 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:47:13.127202 | controller | skipping: Conditional result was False
2026-02-16 17:47:13.140062 | 
2026-02-16 17:47:13.140132 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:47:13.164668 | controller | skipping: Conditional result was False
2026-02-16 17:47:13.176390 | 
2026-02-16 17:47:13.176460 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:47:13.199044 | controller | skipping: Conditional result was False
2026-02-16 17:47:13.216624 | 
2026-02-16 17:47:13.216690 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:47:13.612690 | controller | ok
2026-02-16 17:47:13.622477 | 
2026-02-16 17:47:13.622529 | PLAY RECAP
2026-02-16 17:47:13.622584 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:47:13.622607 | 
2026-02-16 17:47:13.761133 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:47:13.767863 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:47:14.339693 | 
2026-02-16 17:47:14.339848 | PLAY [all]
2026-02-16 17:47:14.352446 | 
2026-02-16 17:47:14.352548 | TASK [Install curl]
2026-02-16 17:47:15.026660 | controller | ERROR
2026-02-16 17:47:15.026942 | controller | {
2026-02-16 17:47:15.026984 | controller |   "msg": "No package matching 'curl' is available"
2026-02-16 17:47:15.027014 | controller | }
failure
2026-02-16 17:47:15.028320 | 
2026-02-16 17:47:15.028366 | PLAY RECAP
2026-02-16 17:47:15.028425 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:47:15.028449 | 
2026-02-16 17:47:15.152271 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:47:15.161150 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:47:15.730127 | 
2026-02-16 17:47:15.730252 | PLAY [all]
2026-02-16 17:47:15.746310 | 
2026-02-16 17:47:15.746391 | TASK [Return built artifacts to Zuul]
2026-02-16 17:47:15.900579 | controller | ok
2026-02-16 17:47:15.908010 | 
2026-02-16 17:47:15.908093 | TASK [Get the Docker volume ID]
2026-02-16 17:47:16.448463 | controller | ERROR
2026-02-16 17:47:16.448833 | controller | {
2026-02-16 17:47:16.448880 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:47:16.448913 | controller |   "rc": 2
2026-02-16 17:47:16.448942 | controller | }
2026-02-16 17:47:16.449004 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:16.457462 | 
2026-02-16 17:47:16.457544 | TASK [Copy all of the pod logs]
2026-02-16 17:47:16.491697 | controller | ERROR
2026-02-16 17:47:16.492046 | controller | {
2026-02-16 17:47:16.492077 | 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/ac1bf73ab9dd450d88e65937eee27f62/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:16.492101 | controller | }
2026-02-16 17:47:16.492128 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:16.493247 | 
2026-02-16 17:47:16.493319 | PLAY RECAP
2026-02-16 17:47:16.493381 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:47:16.493412 | 
2026-02-16 17:47:16.628648 | 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.637755 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:47:17.264946 | 
2026-02-16 17:47:17.265165 | PLAY [all]
2026-02-16 17:47:17.288156 | 
2026-02-16 17:47:17.288330 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:47:17.687505 | controller | changed
2026-02-16 17:47:17.698112 | 
2026-02-16 17:47:17.698232 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:47:18.742792 | controller | ok: Runtime: 0:00:00.485481
2026-02-16 17:47:18.750393 | 
2026-02-16 17:47:18.750464 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:47:19.295201 | controller | ok: Runtime: 0:00:00.020348
2026-02-16 17:47:19.302467 | 
2026-02-16 17:47:19.302543 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:47:19.843089 | controller | ok: Runtime: 0:00:00.021436
2026-02-16 17:47:19.852686 | 
2026-02-16 17:47:19.852784 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:47:20.720031 | controller | changed
2026-02-16 17:47:20.775195 | 
2026-02-16 17:47:20.775362 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:47:21.022300 | controller | changed
2026-02-16 17:47:21.030776 | 
2026-02-16 17:47:21.030847 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:47:21.359860 | controller | Output suppressed because no_log was given
2026-02-16 17:47:24.043221 | 
2026-02-16 17:47:24.043358 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:47:24.080424 | 
2026-02-16 17:47:24.407722 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:47:24.446080 | 
2026-02-16 17:47:24.446231 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:47:24.482275 | 
2026-02-16 17:47:24.482450 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:47:24.519966 | 
2026-02-16 17:47:24.520101 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:47:24.557993 | 
2026-02-16 17:47:24.558135 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:47:24.817980 | controller | changed
2026-02-16 17:47:24.824630 | 
2026-02-16 17:47:24.824705 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:47:24.860406 | controller | ok
2026-02-16 17:47:24.865858 | 
2026-02-16 17:47:24.865923 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:47:25.123383 | controller | Output suppressed because no_log was given
2026-02-16 17:47:25.129998 | 
2026-02-16 17:47:25.130063 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:47:25.157347 | 
2026-02-16 17:47:25.157514 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:47:25.200030 | 
2026-02-16 17:47:25.200162 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:47:25.871494 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:47:25.890585 | controller | Using python 3.12 to install setuptools
2026-02-16 17:47:25.941963 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:47:25.953054 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:47:28.270645 | controller | ERROR
2026-02-16 17:47:28.270879 | controller | {
2026-02-16 17:47:28.270924 | controller |   "delta": "0:00:00.516038",
2026-02-16 17:47:28.270953 | controller |   "end": "2026-02-16 17:47:25.955454",
2026-02-16 17:47:28.270979 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:28.271011 | controller |   "rc": 127,
2026-02-16 17:47:28.271036 | controller |   "start": "2026-02-16 17:47:25.439416"
2026-02-16 17:47:28.271064 | controller | }
2026-02-16 17:47:28.271099 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:28.286025 | 
2026-02-16 17:47:28.286124 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:47:28.542738 | controller | changed
2026-02-16 17:47:28.550517 | 
2026-02-16 17:47:28.550583 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:47:32.347834 | controller | Output suppressed because no_log was given
2026-02-16 17:47:32.359234 | 
2026-02-16 17:47:32.359345 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:47:32.393682 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.394273 | 
2026-02-16 17:47:32.396316 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.398047 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.399689 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.401554 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.403872 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.406172 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.408736 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.411096 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.413751 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.416410 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.418662 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.420980 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.423270 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.425544 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.427789 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.430115 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.433247 | controller | skipping: Conditional result was False
2026-02-16 17:47:32.466017 | 
2026-02-16 17:47:32.466150 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:47:32.690165 | controller | ok
2026-02-16 17:47:32.697599 | 
2026-02-16 17:47:32.697674 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:47:33.270932 | controller | skipping: Conditional result was False
2026-02-16 17:47:33.281906 | 
2026-02-16 17:47:33.282010 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:47:33.542095 | controller | sudo: iptables-save: command not found
2026-02-16 17:47:33.578137 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:47:34.976990 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:47:35.385345 | controller | ok: Runtime: 0:00:01.453794
2026-02-16 17:47:35.395598 | 
2026-02-16 17:47:35.395669 | LOOP [stage-output : Register sources]
2026-02-16 17:47:44.941147 | controller | Output suppressed because no_log was given
2026-02-16 17:47:44.992004 | 
2026-02-16 17:47:44.992159 | TASK [stage-output : Check sudo]
2026-02-16 17:47:45.532303 | controller | ok: Runtime: 0:00:00.019362
2026-02-16 17:47:45.538382 | 
2026-02-16 17:47:45.538449 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:47:45.627561 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.627906 | 
2026-02-16 17:47:45.629105 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.630030 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.632204 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.668737 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.669284 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.669787 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.670279 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.671972 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.674184 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.676253 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.678373 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.680471 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.683043 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.701771 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.702292 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.702786 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.703547 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.704030 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.704504 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.706559 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.708697 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.710798 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.736204 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.736838 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.809171 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.819873 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.830631 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.841642 | controller | Output suppressed because no_log was given
2026-02-16 17:47:45.855754 | 
2026-02-16 17:47:45.855827 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:47:45.918724 | controller | ok
2026-02-16 17:47:45.926758 | 
2026-02-16 17:47:45.926824 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:47:46.179625 | controller | changed: "docs"
2026-02-16 17:47:46.385415 | controller | changed: "artifacts"
2026-02-16 17:47:46.595986 | controller | ok: "logs"
2026-02-16 17:47:46.609561 | 
2026-02-16 17:47:46.609740 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:47:46.894637 | controller | ok: Item: Runtime: 0:00:00.008163
2026-02-16 17:47:47.755942 | controller | changed: All items complete
2026-02-16 17:47:47.756152 | 
2026-02-16 17:47:47.767678 | controller | ok: Item: Runtime: 0:00:00.007448
2026-02-16 17:47:47.777089 | controller | ok: Item: Runtime: 0:00:00.009958
2026-02-16 17:47:47.786512 | controller | ok: Item: Runtime: 0:00:00.008582
2026-02-16 17:47:47.795248 | controller | ok: Item: Runtime: 0:00:00.008303
2026-02-16 17:47:48.032731 | controller | ok: Item: Runtime: 0:00:00.007516
2026-02-16 17:47:48.258369 | controller | ok: Item: Runtime: 0:00:00.007766
2026-02-16 17:47:48.489705 | controller | ok: Item: Runtime: 0:00:00.009033
2026-02-16 17:47:48.725745 | controller | ok: Item: Runtime: 0:00:00.008301
2026-02-16 17:47:48.954925 | controller | ok: Item: Runtime: 0:00:00.009444
2026-02-16 17:47:49.184535 | controller | ok: Item: Runtime: 0:00:00.007494
2026-02-16 17:47:49.453352 | controller | ok: Item: Runtime: 0:00:00.007930
2026-02-16 17:47:49.871763 | controller | ok: Item: Runtime: 0:00:00.007916
2026-02-16 17:47:49.881009 | controller | ok: Item: Runtime: 0:00:00.005363
2026-02-16 17:47:50.095934 | controller | ok: Item: Runtime: 0:00:00.007339
2026-02-16 17:47:50.326589 | controller | ok: Item: Runtime: 0:00:00.008215
2026-02-16 17:47:50.348972 | 
2026-02-16 17:47:50.349155 | TASK [stage-output : Make all log files readable]
2026-02-16 17:47:50.577662 | controller | changed
2026-02-16 17:47:50.583783 | 
2026-02-16 17:47:51.373874 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:47:51.691997 | controller | changed: Renamed files for staging.
2026-02-16 17:47:51.697641 | 
2026-02-16 17:47:51.697707 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:47:51.722705 | controller | skipping: Conditional result was False
2026-02-16 17:47:51.736432 | 
2026-02-16 17:47:51.736508 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:47:51.778682 | 
2026-02-16 17:47:51.778813 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:47:52.467736 | controller | changed:
2026-02-16 17:47:52.467919 | controller | created directory /var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/work/logs/controller
2026-02-16 17:47:52.467947 | controller | cd+++++++++ logs/
2026-02-16 17:47:52.467969 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:47:52.467989 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:47:52.468009 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:47:52.468027 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:47:52.468046 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:47:52.468065 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:47:52.468084 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:47:52.468102 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:47:52.468121 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:47:52.468139 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:47:52.468157 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:47:52.468175 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:47:52.468197 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:47:52.468216 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:47:52.468235 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:47:52.468253 | controller | cd+++++++++ logs/apache/
2026-02-16 17:47:52.468271 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:47:52.468290 | controller | cd+++++++++ logs/etc/
2026-02-16 17:47:52.468308 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:47:52.468326 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:47:52.468345 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:47:52.468363 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:47:52.468400 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:47:52.468420 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:47:52.480063 | 
2026-02-16 17:47:52.480124 | TASK [Check if a tempest log exits]
2026-02-16 17:47:52.738742 | controller | ok
2026-02-16 17:47:52.744142 | 
2026-02-16 17:47:52.744207 | TASK [Link post-devstack tempest.log]
2026-02-16 17:47:52.771577 | controller | skipping: Conditional result was False
2026-02-16 17:47:52.782401 | 
2026-02-16 17:47:52.782490 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:47:53.042426 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:47:53.329531 | controller | ERROR
2026-02-16 17:47:53.329781 | controller | {
2026-02-16 17:47:53.329828 | controller |   "delta": "0:00:00.008510",
2026-02-16 17:47:53.329859 | controller |   "end": "2026-02-16 17:47:53.043117",
2026-02-16 17:47:53.329886 | controller |   "msg": "non-zero return code",
2026-02-16 17:47:53.329913 | controller |   "rc": 127,
2026-02-16 17:47:53.329939 | controller |   "start": "2026-02-16 17:47:53.034607"
2026-02-16 17:47:53.329965 | controller | }
2026-02-16 17:47:53.330001 | controller | ERROR: Ignoring Errors
2026-02-16 17:47:53.332075 | 
2026-02-16 17:47:53.332141 | PLAY RECAP
2026-02-16 17:47:53.332205 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:47:53.332236 | 
2026-02-16 17:47:53.495486 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:47:53.505032 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:47:54.124527 | 
2026-02-16 17:47:54.124659 | PLAY [all]
2026-02-16 17:47:54.136334 | 
2026-02-16 17:47:54.136407 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:47:54.172505 | controller | skipping: Conditional result was False
2026-02-16 17:47:54.183582 | 
2026-02-16 17:47:54.183709 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:47:54.231422 | controller | ok
2026-02-16 17:47:54.239939 | 
2026-02-16 17:47:54.240053 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:47:54.607302 | controller -> localhost | ok: "/var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/work/logs"
2026-02-16 17:47:54.810037 | controller -> localhost | changed: "/var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/work/artifacts"
2026-02-16 17:47:55.008578 | controller -> localhost | changed: "/var/lib/zuul/builds/ac1bf73ab9dd450d88e65937eee27f62/work/docs"
2026-02-16 17:47:55.023934 | 
2026-02-16 17:47:55.024078 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:47:55.699550 | controller | changed: .d..t...... ./
2026-02-16 17:47:55.699849 | controller | changed: All items complete
2026-02-16 17:47:55.699891 | 
2026-02-16 17:47:56.184812 | controller | changed: .d..t...... ./
2026-02-16 17:47:56.658619 | controller | changed: .d..t...... ./
2026-02-16 17:47:56.685532 | 
2026-02-16 17:47:56.686306 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:47:57.111271 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008339
2026-02-16 17:47:57.334415 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007980
2026-02-16 17:47:57.356934 | 
2026-02-16 17:47:57.357098 | PLAY [all]
2026-02-16 17:47:57.364848 | 
2026-02-16 17:47:57.364917 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:47:57.826875 | controller | changed
2026-02-16 17:47:58.182932 | 
2026-02-16 17:47:58.183053 | PLAY RECAP
2026-02-16 17:47:58.183123 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:47:58.183154 | 
2026-02-16 17:47:58.316258 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:47:58.327822 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:47:58.929830 | 
2026-02-16 17:47:58.929985 | PLAY [localhost]
2026-02-16 17:47:58.941260 | 
2026-02-16 17:47:58.941358 | TASK [Generate Zuul manifest]
2026-02-16 17:47:58.961539 | localhost | ok
2026-02-16 17:47:58.982441 | 
2026-02-16 17:47:58.982617 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:47:59.356630 | localhost | changed
2026-02-16 17:47:59.369555 | 
2026-02-16 17:47:59.369631 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:48:00.444867 | localhost | ok
2026-02-16 17:48:00.452737 | 
2026-02-16 17:48:00.452803 | TASK [Upload logs]
2026-02-16 17:48:00.476401 | localhost | ok
2026-02-16 17:48:00.536421 | 
2026-02-16 17:48:00.536560 | TASK [Set zuul-log-path fact]
2026-02-16 17:48:00.555002 | localhost | ok
2026-02-16 17:48:00.565655 | 
2026-02-16 17:48:00.565735 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:48:00.599476 | localhost | ok
2026-02-16 17:48:00.609656 | 
2026-02-16 17:48:00.609750 | TASK [upload-logs : Create log directories]
2026-02-16 17:48:01.025334 | localhost | changed
2026-02-16 17:48:01.032051 | 
2026-02-16 17:48:01.032133 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:48:01.496913 | localhost -> localhost | ok: Runtime: 0:00:00.006152
2026-02-16 17:48:01.502277 | 
2026-02-16 17:48:01.502348 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:48:01.947223 | localhost | Output suppressed because no_log was given
2026-02-16 17:48:01.950940 | 
2026-02-16 17:48:01.951006 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:48:01.995058 | localhost | skipping: Conditional result was False
2026-02-16 17:48:02.001430 | localhost | skipping: Conditional result was False
2026-02-16 17:48:02.014964 | 
2026-02-16 17:48:02.015159 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:48:02.056761 | localhost | skipping: Conditional result was False
2026-02-16 17:48:02.057253 | 
2026-02-16 17:48:02.059872 | localhost | skipping: Conditional result was False
2026-02-16 17:48:02.069292 | 
2026-02-16 17:48:02.069429 | LOOP [upload-logs : Upload console log and json output]