2026-02-16 17:27:27.057719 | Job console starting
2026-02-16 17:27:27.064303 | Updating git repos
2026-02-16 17:27:29.926137 | Cloning repos into workspace
2026-02-16 17:27:35.457670 | Restoring repo states
2026-02-16 17:27:38.237608 | Merging changes
2026-02-16 17:27:38.628315 | Checking out repos
2026-02-16 17:27:41.528643 | Preparing playbooks
2026-02-16 17:27:43.150345 | Running Ansible setup
2026-02-16 17:27:46.875403 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:27:47.504215 | 
2026-02-16 17:27:47.504353 | PLAY [localhost]
2026-02-16 17:27:47.511955 | 
2026-02-16 17:27:47.512024 | TASK [Gathering Facts]
2026-02-16 17:27:48.389962 | localhost | ok
2026-02-16 17:27:48.397015 | 
2026-02-16 17:27:48.397137 | TASK [Setup log path fact]
2026-02-16 17:27:48.414605 | localhost | ok
2026-02-16 17:27:48.428259 | 
2026-02-16 17:27:48.428354 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:27:48.457831 | localhost | ok
2026-02-16 17:27:48.469290 | 
2026-02-16 17:27:48.469362 | TASK [emit-job-header : Print job information]
2026-02-16 17:27:48.513381 | # Job Information
2026-02-16 17:27:48.513730 | Ansible Version: 2.16.15
2026-02-16 17:27:48.513802 | Job: magnum-cluster-api-sonobuoy-v1.32.12-cilium
2026-02-16 17:27:48.513852 | Pipeline: check
2026-02-16 17:27:48.513897 | Executor: 3a2793d2bd32
2026-02-16 17:27:48.513940 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:27:48.513990 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:27:48.519167 | 
2026-02-16 17:27:48.519272 | LOOP [emit-job-header : Print node information]
2026-02-16 17:27:48.700207 | localhost | ok:
2026-02-16 17:27:48.700605 | localhost | # Node Information
2026-02-16 17:27:48.700690 | localhost | Inventory Hostname: controller
2026-02-16 17:27:48.700752 | localhost | Hostname: np0000155601
2026-02-16 17:27:48.700806 | localhost | Username: zuul
2026-02-16 17:27:48.700865 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:27:48.700920 | localhost | Provider: yul1
2026-02-16 17:27:48.701093 | localhost | Region: ca-ymq-1
2026-02-16 17:27:48.701161 | localhost | Label: ubuntu-noble-16
2026-02-16 17:27:48.701206 | localhost | Product Name: OpenStack Nova
2026-02-16 17:27:48.701248 | localhost | Interface IP: 199.204.45.169
2026-02-16 17:27:48.723383 | 
2026-02-16 17:27:48.723610 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:27:49.108897 | localhost -> localhost | changed
2026-02-16 17:27:49.118010 | 
2026-02-16 17:27:49.118106 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:27:49.954966 | localhost -> localhost | changed
2026-02-16 17:27:49.965058 | 
2026-02-16 17:27:49.965120 | PLAY [all]
2026-02-16 17:27:49.974029 | 
2026-02-16 17:27:49.974112 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:27:50.222215 | controller -> localhost | ok
2026-02-16 17:27:50.233390 | 
2026-02-16 17:27:50.233478 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:27:50.268449 | controller | ok
2026-02-16 17:27:50.285015 | controller | included: /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:27:50.291732 | 
2026-02-16 17:27:50.291800 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:27:51.271074 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:27:51.271284 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/work/8465c79bcebd488d874855ed8e839bca_id_rsa
2026-02-16 17:27:51.271326 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/work/8465c79bcebd488d874855ed8e839bca_id_rsa.pub
2026-02-16 17:27:51.271358 | controller -> localhost | The key fingerprint is:
2026-02-16 17:27:51.271387 | controller -> localhost | SHA256:/iAaaFCu6anfity7ELZcBz3eg1GgfiWUEBC/0tCSKOE zuul-build-sshkey
2026-02-16 17:27:51.271426 | controller -> localhost | The key's randomart image is:
2026-02-16 17:27:51.271456 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:27:51.271488 | controller -> localhost | |.oooooo.         |
2026-02-16 17:27:51.271517 | controller -> localhost | |o.+ +..          |
2026-02-16 17:27:51.271544 | controller -> localhost | |oE.= = .         |
2026-02-16 17:27:51.271572 | controller -> localhost | |.o= + B          |
2026-02-16 17:27:51.271598 | controller -> localhost | |.+.= = oS        |
2026-02-16 17:27:51.271625 | controller -> localhost | |o+=.o  ..        |
2026-02-16 17:27:51.271651 | controller -> localhost | |o+o . . o        |
2026-02-16 17:27:51.271678 | controller -> localhost | |oo+. o . o       |
2026-02-16 17:27:51.271707 | controller -> localhost | |+=o==     .      |
2026-02-16 17:27:51.271735 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:27:51.271793 | controller -> localhost | ok: Runtime: 0:00:00.552633
2026-02-16 17:27:51.280147 | 
2026-02-16 17:27:51.280233 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:27:51.315216 | controller | ok
2026-02-16 17:27:51.326322 | controller | included: /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:27:51.334235 | 
2026-02-16 17:27:51.334294 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:27:51.359060 | controller | skipping: Conditional result was False
2026-02-16 17:27:51.368084 | 
2026-02-16 17:27:51.368167 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:27:51.910946 | controller | changed
2026-02-16 17:27:51.917255 | 
2026-02-16 17:27:51.917333 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:27:52.143201 | controller | ok
2026-02-16 17:27:52.148870 | 
2026-02-16 17:27:52.148940 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:27:52.714476 | controller | changed
2026-02-16 17:27:52.720500 | 
2026-02-16 17:27:52.720572 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:27:53.289935 | controller | changed
2026-02-16 17:27:53.298946 | 
2026-02-16 17:27:53.299016 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:27:53.324606 | controller | skipping: Conditional result was False
2026-02-16 17:27:53.335235 | 
2026-02-16 17:27:53.335301 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:27:53.704433 | controller -> localhost | changed
2026-02-16 17:27:53.722109 | 
2026-02-16 17:27:53.722177 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:27:54.051064 | controller -> localhost | Identity added: /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/work/8465c79bcebd488d874855ed8e839bca_id_rsa (zuul-build-sshkey)
2026-02-16 17:27:54.051232 | controller -> localhost | ok: Runtime: 0:00:00.013946
2026-02-16 17:27:54.100890 | 
2026-02-16 17:27:54.101063 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:27:54.409702 | controller | ok
2026-02-16 17:27:54.415859 | 
2026-02-16 17:27:54.415920 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:27:54.440388 | controller | skipping: Conditional result was False
2026-02-16 17:27:54.456391 | 
2026-02-16 17:27:54.456458 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:27:54.791583 | controller | ok
2026-02-16 17:27:54.798657 | 
2026-02-16 17:27:54.798758 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:28:14.182133 | controller | Output suppressed because no_log was given
2026-02-16 17:28:14.191766 | 
2026-02-16 17:28:14.191833 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:28:14.435076 | controller | ok: "logs"
2026-02-16 17:28:14.435365 | controller | ok: All items complete
2026-02-16 17:28:14.435419 | 
2026-02-16 17:28:14.634302 | controller | ok: "artifacts"
2026-02-16 17:28:14.827996 | controller | ok: "docs"
2026-02-16 17:28:14.848732 | 
2026-02-16 17:28:14.848926 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:28:15.088299 | controller | changed: "logs"
2026-02-16 17:28:15.303468 | controller | changed: "artifacts"
2026-02-16 17:28:15.556941 | controller | changed: "docs"
2026-02-16 17:28:15.579210 | 
2026-02-16 17:28:15.579353 | PLAY RECAP
2026-02-16 17:28:15.579414 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:28:15.579477 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:28:15.579504 | 
2026-02-16 17:28:15.751797 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:28:15.760917 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:28:16.416042 | 
2026-02-16 17:28:16.480086 | PLAY [all]
2026-02-16 17:28:16.503611 | 
2026-02-16 17:28:16.503694 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:28:16.874104 | controller | changed
2026-02-16 17:28:16.881028 | 
2026-02-16 17:28:16.881127 | TASK [Gather minimum local MTU]
2026-02-16 17:28:16.967463 | controller | ok
2026-02-16 17:28:16.973276 | 
2026-02-16 17:28:16.973375 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:28:17.028827 | controller | ok
2026-02-16 17:28:17.035456 | 
2026-02-16 17:28:17.035525 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:28:17.060598 | controller | skipping: Conditional result was False
2026-02-16 17:28:17.073392 | 
2026-02-16 17:28:17.073477 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:28:17.616166 | controller | ok: Runtime: 0:00:00.011373
2026-02-16 17:28:17.623774 | 
2026-02-16 17:28:17.623848 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:28:17.649448 | controller | skipping: Conditional result was False
2026-02-16 17:28:17.659713 | 
2026-02-16 17:28:17.659816 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:28:17.685619 | controller | skipping: Conditional result was False
2026-02-16 17:28:17.695143 | 
2026-02-16 17:28:17.695237 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:28:17.761602 | controller | ok
2026-02-16 17:28:17.772115 | controller | included: /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:28:17.778194 | 
2026-02-16 17:28:17.778260 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:28:17.832224 | controller | ok
2026-02-16 17:28:17.838598 | 
2026-02-16 17:28:17.838663 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:28:18.127816 | controller | ext4
2026-02-16 17:28:18.421466 | controller | ok: Runtime: 0:00:00.012768
2026-02-16 17:28:18.427090 | 
2026-02-16 17:28:18.427157 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:28:18.460951 | controller | ok
2026-02-16 17:28:18.466562 | 
2026-02-16 17:28:18.466629 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:28:18.503336 | controller | ok:
2026-02-16 17:28:18.503462 | controller | {
2026-02-16 17:28:18.503487 | controller |   "root_filesystem": "ext4"
2026-02-16 17:28:18.503507 | controller | }
2026-02-16 17:28:18.509360 | 
2026-02-16 17:28:18.509420 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:28:29.248766 | controller | 8192+0 records in
2026-02-16 17:28:29.249429 | controller | 8192+0 records out
2026-02-16 17:28:29.249456 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.4786 s, 820 MB/s
2026-02-16 17:28:29.560502 | controller | ok: Runtime: 0:00:10.490889
2026-02-16 17:28:29.567219 | 
2026-02-16 17:28:29.567304 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:28:29.818685 | controller | changed
2026-02-16 17:28:29.825622 | 
2026-02-16 17:28:29.825706 | TASK [configure-swap : Make swapfile]
2026-02-16 17:28:36.815582 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:28:36.815751 | controller | no label, UUID=65a9a52d-ed89-4f31-95e1-0b991e2f42e0
2026-02-16 17:28:36.876320 | controller | ok: Runtime: 0:00:06.740798
2026-02-16 17:28:36.882780 | 
2026-02-16 17:28:36.882848 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:28:37.282531 | controller | changed
2026-02-16 17:28:37.373244 | 
2026-02-16 17:28:37.373479 | TASK [configure-swap : Add all swap]
2026-02-16 17:28:37.916429 | controller | ok: Runtime: 0:00:00.015556
2026-02-16 17:28:37.922373 | 
2026-02-16 17:28:37.922437 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:28:37.959738 | controller | ok:
2026-02-16 17:28:37.959867 | controller | {
2026-02-16 17:28:37.959893 | controller |   "swap_required": "8192"
2026-02-16 17:28:37.959913 | controller | }
2026-02-16 17:28:37.966114 | 
2026-02-16 17:28:37.966187 | TASK [configure-swap : Set swappiness]
2026-02-16 17:28:38.395603 | controller | changed
2026-02-16 17:28:38.404565 | 
2026-02-16 17:28:38.404644 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:28:38.437708 | controller | ok:
2026-02-16 17:28:38.437896 | controller | {
2026-02-16 17:28:38.437934 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:28:38.437970 | controller | }
2026-02-16 17:28:38.449018 | 
2026-02-16 17:28:38.449087 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:28:38.931116 | controller | changed
2026-02-16 17:28:38.940457 | 
2026-02-16 17:28:38.940530 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:28:39.188413 | controller | changed
2026-02-16 17:28:39.196201 | 
2026-02-16 17:28:39.196267 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:28:39.745348 | controller | changed
2026-02-16 17:28:40.454149 | 
2026-02-16 17:28:40.454258 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:28:40.702130 | controller | changed
2026-02-16 17:28:40.708745 | 
2026-02-16 17:28:40.708815 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:28:41.820197 | controller | changed
2026-02-16 17:28:41.825912 | 
2026-02-16 17:28:41.825983 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:28:42.076721 | controller | changed
2026-02-16 17:28:42.183090 | 
2026-02-16 17:28:42.183235 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:28:42.485735 | controller | changed
2026-02-16 17:28:42.493650 | 
2026-02-16 17:28:42.493732 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:28:42.875923 | controller | changed
2026-02-16 17:28:42.923928 | 
2026-02-16 17:28:42.924090 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:28:43.720303 | controller | changed
2026-02-16 17:28:43.732573 | 
2026-02-16 17:28:43.732658 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:28:44.062576 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:28:44.076924 | 
2026-02-16 17:28:44.077019 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:28:44.561529 | controller | ok: Item: Runtime: 0:00:00.192654
2026-02-16 17:28:45.145498 | controller | ok: Item: Runtime: 0:00:00.349828
2026-02-16 17:28:45.905485 | controller | ok: Item: Runtime: 0:00:00.545901
2026-02-16 17:28:46.194479 | controller | ok: Item: Runtime: 0:00:00.063484
2026-02-16 17:28:46.687491 | controller | ok: Item: Runtime: 0:00:00.263469
2026-02-16 17:28:48.237651 | controller | ok: Item: Runtime: 0:00:01.329198
2026-02-16 17:28:51.719705 | controller | ok: Item: Runtime: 0:00:03.257032
2026-02-16 17:28:52.042714 | controller | ok: Item: Runtime: 0:00:00.095684
2026-02-16 17:28:52.459168 | controller | ok: Item: Runtime: 0:00:00.192588
2026-02-16 17:28:52.802857 | controller | ok: Item: Runtime: 0:00:00.120683
2026-02-16 17:28:53.563682 | controller | ok: Item: Runtime: 0:00:00.543325
2026-02-16 17:28:54.330690 | controller | ok: Item: Runtime: 0:00:00.525112
2026-02-16 17:28:54.822795 | controller | ok: Item: Runtime: 0:00:00.250284
2026-02-16 17:28:55.267786 | controller | ok: Item: Runtime: 0:00:00.221060
2026-02-16 17:28:55.905718 | controller | ok: Item: Runtime: 0:00:00.401714
2026-02-16 17:28:57.235744 | controller | ok: Item: Runtime: 0:00:01.112395
2026-02-16 17:28:57.264839 | 
2026-02-16 17:28:57.265020 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:28:57.483034 | controller | ok: All paths examined
2026-02-16 17:28:57.492427 | 
2026-02-16 17:28:57.492494 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:28:57.736934 | controller | ok: All paths examined
2026-02-16 17:28:57.745056 | 
2026-02-16 17:28:57.745128 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:28:58.184357 | controller | ok: Item: Runtime: 0:00:00.152245
2026-02-16 17:28:58.184612 | controller | changed: All items complete
2026-02-16 17:28:58.184642 | 
2026-02-16 17:28:58.584723 | controller | ok: Item: Runtime: 0:00:00.181876
2026-02-16 17:28:58.603399 | 
2026-02-16 17:28:58.603586 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:28:58.649793 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.654568 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.655624 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.659060 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.662293 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.665435 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.668674 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.672221 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.675642 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.678707 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.681768 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.684852 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.687957 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.691061 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.694538 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.698159 | controller | skipping: Conditional result was False
2026-02-16 17:28:58.725227 | 
2026-02-16 17:28:58.725401 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:29:01.086409 | controller | changed
2026-02-16 17:29:01.096971 | 
2026-02-16 17:29:01.097038 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:29:01.346797 | controller | changed
2026-02-16 17:29:01.412292 | 
2026-02-16 17:29:01.412447 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:29:01.688098 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:29:01.956826 | controller | ERROR
2026-02-16 17:29:01.957098 | controller | {
2026-02-16 17:29:01.957129 | controller |   "delta": "0:00:00.011493",
2026-02-16 17:29:01.957151 | controller |   "end": "2026-02-16 17:29:01.688602",
2026-02-16 17:29:01.957170 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:01.957188 | controller |   "rc": 1,
2026-02-16 17:29:01.957207 | controller |   "start": "2026-02-16 17:29:01.677109"
2026-02-16 17:29:01.957225 | controller | }
2026-02-16 17:29:01.957250 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:01.962899 | 
2026-02-16 17:29:01.962974 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:29:02.240608 | controller | ok
2026-02-16 17:29:02.252515 | 
2026-02-16 17:29:02.252576 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:29:02.498406 | controller | /usr/bin/journalctl
2026-02-16 17:29:02.793755 | controller | ok: Runtime: 0:00:00.007872
2026-02-16 17:29:02.799389 | 
2026-02-16 17:29:02.799458 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:29:03.067763 | controller | 2026-02-16 17:29:03
2026-02-16 17:29:03.341824 | controller | ok: Runtime: 0:00:00.009762
2026-02-16 17:29:03.347616 | 
2026-02-16 17:29:03.347689 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:29:03.935873 | controller | changed
2026-02-16 17:29:03.941703 | 
2026-02-16 17:29:03.941764 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:29:03.967398 | controller | skipping: Conditional result was False
2026-02-16 17:29:03.977227 | 
2026-02-16 17:29:03.977317 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:29:04.509653 | controller | skipping: Conditional result was False
2026-02-16 17:29:04.519719 | 
2026-02-16 17:29:04.519832 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:29:05.056101 | controller | skipping: Conditional result was False
2026-02-16 17:29:05.067772 | 
2026-02-16 17:29:05.067864 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:29:05.094302 | controller | skipping: Conditional result was False
2026-02-16 17:29:05.104607 | 
2026-02-16 17:29:05.104694 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:29:05.640774 | controller | skipping: Conditional result was False
2026-02-16 17:29:05.649025 | 
2026-02-16 17:29:05.649164 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:29:06.185305 | controller | skipping: Conditional result was False
2026-02-16 17:29:06.195953 | 
2026-02-16 17:29:06.196050 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:29:06.220875 | controller | skipping: Conditional result was False
2026-02-16 17:29:06.231622 | 
2026-02-16 17:29:06.231765 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:29:06.258308 | controller | skipping: Conditional result was False
2026-02-16 17:29:06.272299 | 
2026-02-16 17:29:06.272392 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:29:06.864197 | controller | skipping: Conditional result was False
2026-02-16 17:29:06.875572 | 
2026-02-16 17:29:06.875672 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:29:07.405434 | controller | skipping: Conditional result was False
2026-02-16 17:29:07.417023 | 
2026-02-16 17:29:07.417147 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:29:07.442340 | controller | skipping: Conditional result was False
2026-02-16 17:29:07.452283 | 
2026-02-16 17:29:07.452353 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:29:07.477754 | controller | skipping: Conditional result was False
2026-02-16 17:29:07.496398 | 
2026-02-16 17:29:07.496487 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:29:07.882086 | controller | ok
2026-02-16 17:29:07.967326 | 
2026-02-16 17:29:07.967427 | PLAY RECAP
2026-02-16 17:29:07.967482 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:29:07.967506 | 
2026-02-16 17:29:08.121742 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:29:08.132972 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:29:08.728317 | 
2026-02-16 17:29:08.728482 | PLAY [all]
2026-02-16 17:29:08.741160 | 
2026-02-16 17:29:08.741240 | TASK [ensure-rust : Use rustup]
2026-02-16 17:29:08.774769 | controller | ok
2026-02-16 17:29:08.781548 | controller | included: /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:29:08.796163 | 
2026-02-16 17:29:08.796389 | TASK [ensure-rust : Install Rust]
2026-02-16 17:29:09.201304 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:29:09.337517 | controller | ERROR
2026-02-16 17:29:09.337829 | controller | {
2026-02-16 17:29:09.337892 | controller |   "delta": "0:00:00.012936",
2026-02-16 17:29:09.337933 | controller |   "end": "2026-02-16 17:29:09.204043",
2026-02-16 17:29:09.337956 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:09.337977 | controller |   "rc": 127,
2026-02-16 17:29:09.337995 | controller |   "start": "2026-02-16 17:29:09.191107"
2026-02-16 17:29:09.338018 | controller | }
failure
2026-02-16 17:29:09.339633 | 
2026-02-16 17:29:09.339682 | PLAY RECAP
2026-02-16 17:29:09.339731 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:29:09.339753 | 
2026-02-16 17:29:09.470312 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:29:09.475687 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:29:10.090801 | 
2026-02-16 17:29:10.090950 | PLAY [all]
2026-02-16 17:29:10.107475 | 
2026-02-16 17:29:10.107564 | TASK [Return built artifacts to Zuul]
2026-02-16 17:29:10.142217 | controller | ok
2026-02-16 17:29:10.150978 | 
2026-02-16 17:29:10.151095 | TASK [Get the Docker volume ID]
2026-02-16 17:29:10.694576 | controller | ERROR
2026-02-16 17:29:10.695030 | controller | {
2026-02-16 17:29:10.695062 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:29:10.695085 | controller |   "rc": 2
2026-02-16 17:29:10.695105 | controller | }
2026-02-16 17:29:10.695131 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:10.701509 | 
2026-02-16 17:29:10.701584 | TASK [Copy all of the pod logs]
2026-02-16 17:29:10.737368 | controller | ERROR
2026-02-16 17:29:10.737642 | controller | {
2026-02-16 17:29:10.737685 | 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/8465c79bcebd488d874855ed8e839bca/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:29:10.737718 | controller | }
2026-02-16 17:29:10.737756 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:10.739226 | 
2026-02-16 17:29:10.739309 | PLAY RECAP
2026-02-16 17:29:10.739380 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:29:10.739412 | 
2026-02-16 17:29:10.896549 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:29:11.388856 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:29:12.048077 | 
2026-02-16 17:29:12.048199 | PLAY [all]
2026-02-16 17:29:12.074159 | 
2026-02-16 17:29:12.074239 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:29:12.473139 | controller | changed
2026-02-16 17:29:12.482113 | 
2026-02-16 17:29:12.482198 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:29:13.520390 | controller | ok: Runtime: 0:00:00.455220
2026-02-16 17:29:13.527527 | 
2026-02-16 17:29:13.527603 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:29:14.065526 | controller | ok: Runtime: 0:00:00.023784
2026-02-16 17:29:14.072596 | 
2026-02-16 17:29:14.072666 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:29:14.616912 | controller | ok: Runtime: 0:00:00.022659
2026-02-16 17:29:14.623430 | 
2026-02-16 17:29:14.623499 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:29:15.531335 | controller | changed
2026-02-16 17:29:15.580757 | 
2026-02-16 17:29:15.580926 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:29:15.827365 | controller | changed
2026-02-16 17:29:15.833925 | 
2026-02-16 17:29:15.833996 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:29:16.185636 | controller | Output suppressed because no_log was given
2026-02-16 17:29:16.193623 | 
2026-02-16 17:29:16.193723 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:29:16.236628 | 
2026-02-16 17:29:16.236838 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:29:16.260913 | 
2026-02-16 17:29:16.261134 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:29:16.284174 | 
2026-02-16 17:29:16.284394 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:29:16.319083 | 
2026-02-16 17:29:16.319257 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:29:16.356860 | 
2026-02-16 17:29:16.357055 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:29:16.606111 | controller | changed
2026-02-16 17:29:16.613283 | 
2026-02-16 17:29:16.613349 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:29:16.647062 | controller | ok
2026-02-16 17:29:16.654436 | 
2026-02-16 17:29:16.654552 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:29:16.893294 | controller | Output suppressed because no_log was given
2026-02-16 17:29:16.899546 | 
2026-02-16 17:29:16.899618 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:29:16.936074 | 
2026-02-16 17:29:16.936238 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:29:16.977345 | 
2026-02-16 17:29:16.977474 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:29:17.655726 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:29:17.675378 | controller | Using python 3.12 to install setuptools
2026-02-16 17:29:17.710809 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:29:17.720488 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:29:18.019099 | controller | ERROR
2026-02-16 17:29:18.019352 | controller | {
2026-02-16 17:29:18.019398 | controller |   "delta": "0:00:00.482828",
2026-02-16 17:29:18.019427 | controller |   "end": "2026-02-16 17:29:17.722440",
2026-02-16 17:29:18.019452 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:18.019485 | controller |   "rc": 127,
2026-02-16 17:29:18.019512 | controller |   "start": "2026-02-16 17:29:17.239612"
2026-02-16 17:29:18.019538 | controller | }
2026-02-16 17:29:18.019573 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:18.032305 | 
2026-02-16 17:29:18.032377 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:29:18.310481 | controller | changed
2026-02-16 17:29:18.486080 | 
2026-02-16 17:29:18.486245 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:29:22.343999 | controller | Output suppressed because no_log was given
2026-02-16 17:29:22.880919 | 
2026-02-16 17:29:22.881046 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:29:22.911871 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.912169 | 
2026-02-16 17:29:22.913519 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.915530 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.918161 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.920444 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.922713 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.924973 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.927348 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.929655 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.931939 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.934355 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.936794 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.939085 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.941353 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.943607 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.945883 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.948044 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.951092 | controller | skipping: Conditional result was False
2026-02-16 17:29:22.980195 | 
2026-02-16 17:29:22.980270 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:29:23.207422 | controller | ok
2026-02-16 17:29:23.213547 | 
2026-02-16 17:29:23.213612 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:29:23.795776 | controller | skipping: Conditional result was False
2026-02-16 17:29:23.812255 | 
2026-02-16 17:29:23.812346 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:29:24.087684 | controller | sudo: iptables-save: command not found
2026-02-16 17:29:24.128194 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:29:25.231196 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:29:25.354047 | controller | ok: Runtime: 0:00:01.166660
2026-02-16 17:29:25.364571 | 
2026-02-16 17:29:25.364640 | LOOP [stage-output : Register sources]
2026-02-16 17:29:35.123252 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.174819 | 
2026-02-16 17:29:35.175023 | TASK [stage-output : Check sudo]
2026-02-16 17:29:35.718514 | controller | ok: Runtime: 0:00:00.024635
2026-02-16 17:29:35.726222 | 
2026-02-16 17:29:35.726297 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:29:35.822053 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.822631 | 
2026-02-16 17:29:35.824261 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.824773 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.826978 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.863787 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.864166 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.864505 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.864836 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.866965 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.869065 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.871161 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.873341 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.875477 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.877558 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.895546 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.896060 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.896523 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.897004 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.897462 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.899139 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.901106 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.903279 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.905376 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.931550 | controller | Output suppressed because no_log was given
2026-02-16 17:29:35.931935 | controller | Output suppressed because no_log was given
2026-02-16 17:29:36.002755 | controller | Output suppressed because no_log was given
2026-02-16 17:29:36.013655 | controller | Output suppressed because no_log was given
2026-02-16 17:29:36.024227 | controller | Output suppressed because no_log was given
2026-02-16 17:29:36.032375 | controller | Output suppressed because no_log was given
2026-02-16 17:29:36.048829 | 
2026-02-16 17:29:36.048914 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:29:36.127293 | controller | ok
2026-02-16 17:29:36.135102 | 
2026-02-16 17:29:36.135198 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:29:36.413342 | controller | changed: "docs"
2026-02-16 17:29:36.648089 | controller | changed: "artifacts"
2026-02-16 17:29:36.862637 | controller | ok: "logs"
2026-02-16 17:29:36.885042 | 
2026-02-16 17:29:36.885220 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:29:37.186247 | controller | ok: Item: Runtime: 0:00:00.010913
2026-02-16 17:29:37.186536 | controller | changed: All items complete
2026-02-16 17:29:37.186575 | 
2026-02-16 17:29:37.422706 | controller | ok: Item: Runtime: 0:00:00.006359
2026-02-16 17:29:37.665012 | controller | ok: Item: Runtime: 0:00:00.010830
2026-02-16 17:29:37.916131 | controller | ok: Item: Runtime: 0:00:00.009864
2026-02-16 17:29:38.160741 | controller | ok: Item: Runtime: 0:00:00.010130
2026-02-16 17:29:38.392718 | controller | ok: Item: Runtime: 0:00:00.007731
2026-02-16 17:29:38.621834 | controller | ok: Item: Runtime: 0:00:00.008653
2026-02-16 17:29:38.857883 | controller | ok: Item: Runtime: 0:00:00.007802
2026-02-16 17:29:39.085552 | controller | ok: Item: Runtime: 0:00:00.009586
2026-02-16 17:29:39.327651 | controller | ok: Item: Runtime: 0:00:00.010843
2026-02-16 17:29:39.579294 | controller | ok: Item: Runtime: 0:00:00.010180
2026-02-16 17:29:39.858418 | controller | ok: Item: Runtime: 0:00:00.008186
2026-02-16 17:29:40.052264 | controller | ok: Item: Runtime: 0:00:00.009199
2026-02-16 17:29:40.296347 | controller | ok: Item: Runtime: 0:00:00.009286
2026-02-16 17:29:40.548590 | controller | ok: Item: Runtime: 0:00:00.008888
2026-02-16 17:29:40.783319 | controller | ok: Item: Runtime: 0:00:00.011257
2026-02-16 17:29:40.808166 | 
2026-02-16 17:29:40.808318 | TASK [stage-output : Make all log files readable]
2026-02-16 17:29:41.066122 | controller | changed
2026-02-16 17:29:41.074330 | 
2026-02-16 17:29:41.074422 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:29:41.412714 | controller | changed: Renamed files for staging.
2026-02-16 17:29:41.419176 | 
2026-02-16 17:29:41.419252 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:29:41.444607 | controller | skipping: Conditional result was False
2026-02-16 17:29:41.457528 | 
2026-02-16 17:29:41.457683 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:29:41.503585 | 
2026-02-16 17:29:41.503817 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:29:42.148432 | controller | changed:
2026-02-16 17:29:42.148617 | controller | created directory /var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/work/logs/controller
2026-02-16 17:29:42.148645 | controller | cd+++++++++ logs/
2026-02-16 17:29:42.148666 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:29:42.148687 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:29:42.148706 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:29:42.148725 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:29:42.148743 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:29:42.148762 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:29:42.148780 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:29:42.148798 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:29:42.148816 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:29:42.148834 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:29:42.148852 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:29:42.148869 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:29:42.148895 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:29:42.148929 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:29:42.148949 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:29:42.149010 | controller | cd+++++++++ logs/apache/
2026-02-16 17:29:42.149030 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:29:42.149050 | controller | cd+++++++++ logs/etc/
2026-02-16 17:29:42.149068 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:29:42.149086 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:29:42.149104 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:29:42.149122 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:29:42.149140 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:29:42.149158 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:29:42.160926 | 
2026-02-16 17:29:42.161009 | TASK [Check if a tempest log exits]
2026-02-16 17:29:42.439412 | controller | ok
2026-02-16 17:29:42.445556 | 
2026-02-16 17:29:42.445619 | TASK [Link post-devstack tempest.log]
2026-02-16 17:29:42.470339 | controller | skipping: Conditional result was False
2026-02-16 17:29:42.480778 | 
2026-02-16 17:29:42.480840 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:29:42.735577 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:29:43.019818 | controller | ERROR
2026-02-16 17:29:43.020098 | controller | {
2026-02-16 17:29:43.020147 | controller |   "delta": "0:00:00.010139",
2026-02-16 17:29:43.020176 | controller |   "end": "2026-02-16 17:29:42.736285",
2026-02-16 17:29:43.020202 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:43.020227 | controller |   "rc": 127,
2026-02-16 17:29:43.020252 | controller |   "start": "2026-02-16 17:29:42.726146"
2026-02-16 17:29:43.020276 | controller | }
2026-02-16 17:29:43.020308 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:43.022171 | 
2026-02-16 17:29:43.022234 | PLAY RECAP
2026-02-16 17:29:43.022298 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:29:43.022328 | 
2026-02-16 17:29:43.166116 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:29:43.171633 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:29:43.784225 | 
2026-02-16 17:29:43.784355 | PLAY [all]
2026-02-16 17:29:43.795992 | 
2026-02-16 17:29:43.796066 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:29:43.841591 | controller | skipping: Conditional result was False
2026-02-16 17:29:43.847872 | 
2026-02-16 17:29:43.847954 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:29:43.893353 | controller | ok
2026-02-16 17:29:43.902099 | 
2026-02-16 17:29:43.902206 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:29:44.297708 | controller -> localhost | ok: "/var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/work/logs"
2026-02-16 17:29:44.501500 | controller -> localhost | changed: "/var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/work/artifacts"
2026-02-16 17:29:44.710358 | controller -> localhost | changed: "/var/lib/zuul/builds/8465c79bcebd488d874855ed8e839bca/work/docs"
2026-02-16 17:29:44.728026 | 
2026-02-16 17:29:44.728182 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:29:45.392101 | controller | changed: .d..t...... ./
2026-02-16 17:29:45.392358 | controller | changed: All items complete
2026-02-16 17:29:45.392385 | 
2026-02-16 17:29:45.860714 | controller | changed: .d..t...... ./
2026-02-16 17:29:46.337709 | controller | changed: .d..t...... ./
2026-02-16 17:29:46.363798 | 
2026-02-16 17:29:46.363981 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:29:46.787592 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007252
2026-02-16 17:29:47.031024 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007832
2026-02-16 17:29:47.046264 | 
2026-02-16 17:29:47.046413 | PLAY [all]
2026-02-16 17:29:47.053776 | 
2026-02-16 17:29:47.053841 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:29:47.535861 | controller | changed
2026-02-16 17:29:47.542116 | 
2026-02-16 17:29:47.542167 | PLAY RECAP
2026-02-16 17:29:47.542215 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:29:47.542236 | 
2026-02-16 17:29:47.720490 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:29:47.726135 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:29:48.310463 | 
2026-02-16 17:29:48.310601 | PLAY [localhost]
2026-02-16 17:29:48.321752 | 
2026-02-16 17:29:48.321825 | TASK [Generate Zuul manifest]
2026-02-16 17:29:48.344093 | localhost | ok
2026-02-16 17:29:48.359923 | 
2026-02-16 17:29:48.360000 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:29:48.747829 | localhost | changed
2026-02-16 17:29:48.763192 | 
2026-02-16 17:29:48.763276 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:29:48.792242 | localhost | ok
2026-02-16 17:29:48.802044 | 
2026-02-16 17:29:48.802141 | TASK [Upload logs]
2026-02-16 17:29:48.820304 | localhost | ok
2026-02-16 17:29:48.883655 | 
2026-02-16 17:29:48.883774 | TASK [Set zuul-log-path fact]
2026-02-16 17:29:48.906212 | localhost | ok
2026-02-16 17:29:48.920660 | 
2026-02-16 17:29:48.920727 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:29:48.950677 | localhost | ok
2026-02-16 17:29:48.958332 | 
2026-02-16 17:29:48.958395 | TASK [upload-logs : Create log directories]
2026-02-16 17:29:49.368425 | localhost | changed
2026-02-16 17:29:49.375105 | 
2026-02-16 17:29:49.375207 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:29:49.891887 | localhost -> localhost | ok: Runtime: 0:00:00.005452
2026-02-16 17:29:49.897382 | 
2026-02-16 17:29:49.897472 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:29:50.345620 | localhost | Output suppressed because no_log was given
2026-02-16 17:29:50.352132 | 
2026-02-16 17:29:50.352226 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:29:50.400298 | localhost | skipping: Conditional result was False
2026-02-16 17:29:50.406513 | localhost | skipping: Conditional result was False
2026-02-16 17:29:50.417925 | 
2026-02-16 17:29:50.418103 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:29:50.463123 | localhost | skipping: Conditional result was False
2026-02-16 17:29:50.463709 | 
2026-02-16 17:29:50.467036 | localhost | skipping: Conditional result was False
2026-02-16 17:29:50.486950 | 
2026-02-16 17:29:50.487139 | LOOP [upload-logs : Upload console log and json output]