2026-02-16 17:42:03.596008 | Job console starting
2026-02-16 17:42:03.604234 | Updating git repos
2026-02-16 17:42:06.284901 | Cloning repos into workspace
2026-02-16 17:42:13.049730 | Restoring repo states
2026-02-16 17:42:15.898066 | Merging changes
2026-02-16 17:42:16.586299 | Checking out repos
2026-02-16 17:42:20.319368 | Preparing playbooks
2026-02-16 17:42:22.177142 | Running Ansible setup
2026-02-16 17:42:25.933408 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:26.560979 | 
2026-02-16 17:42:26.561303 | PLAY [localhost]
2026-02-16 17:42:26.569407 | 
2026-02-16 17:42:26.569481 | TASK [Gathering Facts]
2026-02-16 17:42:27.442257 | localhost | ok
2026-02-16 17:42:27.452014 | 
2026-02-16 17:42:27.452089 | TASK [Setup log path fact]
2026-02-16 17:42:27.471706 | localhost | ok
2026-02-16 17:42:27.482898 | 
2026-02-16 17:42:27.482959 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:42:27.512324 | localhost | ok
2026-02-16 17:42:27.520835 | 
2026-02-16 17:42:27.520908 | TASK [emit-job-header : Print job information]
2026-02-16 17:42:27.561892 | # Job Information
2026-02-16 17:42:27.562042 | Ansible Version: 2.16.15
2026-02-16 17:42:27.562086 | Job: magnum-cluster-api-sonobuoy-v1.32.12-cilium
2026-02-16 17:42:27.562117 | Pipeline: check
2026-02-16 17:42:27.562144 | Executor: 3a2793d2bd32
2026-02-16 17:42:27.562171 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:42:27.562202 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:42:27.565795 | 
2026-02-16 17:42:27.565880 | LOOP [emit-job-header : Print node information]
2026-02-16 17:42:27.716530 | localhost | ok:
2026-02-16 17:42:27.716767 | localhost | # Node Information
2026-02-16 17:42:27.716805 | localhost | Inventory Hostname: controller
2026-02-16 17:42:27.716834 | localhost | Hostname: np0000155625
2026-02-16 17:42:27.716860 | localhost | Username: zuul
2026-02-16 17:42:27.716890 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:42:27.716917 | localhost | Provider: yul1
2026-02-16 17:42:27.716970 | localhost | Region: ca-ymq-1
2026-02-16 17:42:27.716999 | localhost | Label: ubuntu-noble-16
2026-02-16 17:42:27.717024 | localhost | Product Name: OpenStack Nova
2026-02-16 17:42:27.717049 | localhost | Interface IP: 199.204.45.109
2026-02-16 17:42:27.728637 | 
2026-02-16 17:42:27.728797 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:42:28.118929 | localhost -> localhost | changed
2026-02-16 17:42:28.129058 | 
2026-02-16 17:42:28.129158 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:42:28.967569 | localhost -> localhost | changed
2026-02-16 17:42:28.974348 | 
2026-02-16 17:42:28.974408 | PLAY [all]
2026-02-16 17:42:28.983220 | 
2026-02-16 17:42:28.983287 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:42:29.250675 | controller -> localhost | ok
2026-02-16 17:42:29.262585 | 
2026-02-16 17:42:29.262658 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:42:29.300795 | controller | ok
2026-02-16 17:42:29.322372 | controller | included: /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:42:29.330689 | 
2026-02-16 17:42:29.330760 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:42:31.139944 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:42:31.140574 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/work/f4e847830b35456e983ae992522c161c_id_rsa
2026-02-16 17:42:31.140608 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/work/f4e847830b35456e983ae992522c161c_id_rsa.pub
2026-02-16 17:42:31.140631 | controller -> localhost | The key fingerprint is:
2026-02-16 17:42:31.140652 | controller -> localhost | SHA256:UcLqPyohN2ijmztOUTZwi1liPTXPfkyFy18Ng0T/jTc zuul-build-sshkey
2026-02-16 17:42:31.140688 | controller -> localhost | The key's randomart image is:
2026-02-16 17:42:31.140709 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:42:31.140735 | controller -> localhost | | +.o.o .. =+.    |
2026-02-16 17:42:31.140756 | controller -> localhost | |. Bo. + .+...o   |
2026-02-16 17:42:31.140775 | controller -> localhost | | o =.  +o..  .+  |
2026-02-16 17:42:31.140794 | controller -> localhost | |  o . o o+   ..o.|
2026-02-16 17:42:31.140813 | controller -> localhost | | . . . .So. . .Eo|
2026-02-16 17:42:31.140832 | controller -> localhost | |  * + . .  .   ..|
2026-02-16 17:42:31.140851 | controller -> localhost | | + + o .         |
2026-02-16 17:42:31.140870 | controller -> localhost | |+.  .   o        |
2026-02-16 17:42:31.140891 | controller -> localhost | |==   ... .       |
2026-02-16 17:42:31.140911 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:42:31.140973 | controller -> localhost | ok: Runtime: 0:00:01.349805
2026-02-16 17:42:31.146857 | 
2026-02-16 17:42:31.146925 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:42:31.183813 | controller | ok
2026-02-16 17:42:31.194674 | controller | included: /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:42:31.266531 | 
2026-02-16 17:42:31.266597 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:42:31.290735 | controller | skipping: Conditional result was False
2026-02-16 17:42:31.303045 | 
2026-02-16 17:42:31.303108 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:42:31.871288 | controller | changed
2026-02-16 17:42:31.880314 | 
2026-02-16 17:42:31.880381 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:42:32.108840 | controller | ok
2026-02-16 17:42:32.114447 | 
2026-02-16 17:42:32.114527 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:42:32.701433 | controller | changed
2026-02-16 17:42:32.712941 | 
2026-02-16 17:42:32.713022 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:42:33.287937 | controller | changed
2026-02-16 17:42:33.296417 | 
2026-02-16 17:42:33.296513 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:42:33.320427 | controller | skipping: Conditional result was False
2026-02-16 17:42:33.331237 | 
2026-02-16 17:42:33.331324 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:42:33.700576 | controller -> localhost | changed
2026-02-16 17:42:33.716142 | 
2026-02-16 17:42:33.716231 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:42:34.054126 | controller -> localhost | Identity added: /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/work/f4e847830b35456e983ae992522c161c_id_rsa (zuul-build-sshkey)
2026-02-16 17:42:34.054372 | controller -> localhost | ok: Runtime: 0:00:00.014239
2026-02-16 17:42:34.105009 | 
2026-02-16 17:42:34.105193 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:42:34.424719 | controller | ok
2026-02-16 17:42:34.430270 | 
2026-02-16 17:42:34.430339 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:42:34.455750 | controller | skipping: Conditional result was False
2026-02-16 17:42:34.472756 | 
2026-02-16 17:42:34.472848 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:42:34.793059 | controller | ok
2026-02-16 17:42:34.801227 | 
2026-02-16 17:42:34.801292 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:42:54.314470 | controller | Output suppressed because no_log was given
2026-02-16 17:42:54.328587 | 
2026-02-16 17:42:54.328682 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:42:54.576641 | controller | ok: "logs"
2026-02-16 17:42:54.576916 | controller | ok: All items complete
2026-02-16 17:42:54.576946 | 
2026-02-16 17:42:54.764718 | controller | ok: "artifacts"
2026-02-16 17:42:54.964380 | controller | ok: "docs"
2026-02-16 17:42:54.980434 | 
2026-02-16 17:42:54.980620 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:42:55.224540 | controller | changed: "logs"
2026-02-16 17:42:55.414935 | controller | changed: "artifacts"
2026-02-16 17:42:55.648607 | controller | changed: "docs"
2026-02-16 17:42:55.664530 | 
2026-02-16 17:42:55.664655 | PLAY RECAP
2026-02-16 17:42:55.664706 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:42:55.664733 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:42:55.664754 | 
2026-02-16 17:42:55.839415 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:55.844998 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:42:56.476740 | 
2026-02-16 17:42:56.477664 | PLAY [all]
2026-02-16 17:42:56.496118 | 
2026-02-16 17:42:56.496199 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:42:56.921840 | controller | changed
2026-02-16 17:42:56.931520 | 
2026-02-16 17:42:56.931612 | TASK [Gather minimum local MTU]
2026-02-16 17:42:57.008040 | controller | ok
2026-02-16 17:42:57.014661 | 
2026-02-16 17:42:57.014731 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:42:57.069391 | controller | ok
2026-02-16 17:42:57.074514 | 
2026-02-16 17:42:57.074582 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:42:57.100472 | controller | skipping: Conditional result was False
2026-02-16 17:42:57.122022 | 
2026-02-16 17:42:57.122101 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:42:57.659841 | controller | ok: Runtime: 0:00:00.011418
2026-02-16 17:42:57.667323 | 
2026-02-16 17:42:57.667395 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:42:57.693226 | controller | skipping: Conditional result was False
2026-02-16 17:42:57.702911 | 
2026-02-16 17:42:57.703001 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:42:57.729069 | controller | skipping: Conditional result was False
2026-02-16 17:42:57.739077 | 
2026-02-16 17:42:57.739167 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:42:57.797903 | controller | ok
2026-02-16 17:42:57.808531 | controller | included: /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:42:57.814584 | 
2026-02-16 17:42:57.814685 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:42:57.865263 | controller | ok
2026-02-16 17:42:57.870895 | 
2026-02-16 17:42:57.870958 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:42:58.143643 | controller | ext4
2026-02-16 17:42:58.468339 | controller | ok: Runtime: 0:00:00.011689
2026-02-16 17:42:58.474168 | 
2026-02-16 17:42:58.474246 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:42:58.511495 | controller | ok
2026-02-16 17:42:58.519837 | 
2026-02-16 17:42:58.519953 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:42:58.553873 | controller | ok:
2026-02-16 17:42:58.554117 | controller | {
2026-02-16 17:42:58.554155 | controller |   "root_filesystem": "ext4"
2026-02-16 17:42:58.554186 | controller | }
2026-02-16 17:42:58.567116 | 
2026-02-16 17:42:58.567369 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:43:08.450047 | controller | 8192+0 records in
2026-02-16 17:43:08.450198 | controller | 8192+0 records out
2026-02-16 17:43:08.450219 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.60826 s, 894 MB/s
2026-02-16 17:43:08.703339 | controller | ok: Runtime: 0:00:09.617577
2026-02-16 17:43:08.710249 | 
2026-02-16 17:43:08.710313 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:43:08.964149 | controller | changed
2026-02-16 17:43:08.972166 | 
2026-02-16 17:43:08.972230 | TASK [configure-swap : Make swapfile]
2026-02-16 17:43:21.747522 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:43:21.747661 | controller | no label, UUID=d87602f4-50e3-4937-accf-62d792694128
2026-02-16 17:43:22.077711 | controller | ok: Runtime: 0:00:12.535045
2026-02-16 17:43:22.084790 | 
2026-02-16 17:43:22.084855 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:43:22.601064 | controller | changed
2026-02-16 17:43:22.606623 | 
2026-02-16 17:43:22.606688 | TASK [configure-swap : Add all swap]
2026-02-16 17:43:23.159009 | controller | ok: Runtime: 0:00:00.245480
2026-02-16 17:43:23.164738 | 
2026-02-16 17:43:23.164803 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:43:23.199004 | controller | ok:
2026-02-16 17:43:23.199227 | controller | {
2026-02-16 17:43:23.199263 | controller |   "swap_required": "8192"
2026-02-16 17:43:23.199291 | controller | }
2026-02-16 17:43:23.205889 | 
2026-02-16 17:43:23.205975 | TASK [configure-swap : Set swappiness]
2026-02-16 17:43:23.617183 | controller | changed
2026-02-16 17:43:23.624183 | 
2026-02-16 17:43:23.624246 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:43:23.659495 | controller | ok:
2026-02-16 17:43:23.659655 | controller | {
2026-02-16 17:43:23.659690 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:43:23.659726 | controller | }
2026-02-16 17:43:23.670804 | 
2026-02-16 17:43:23.670871 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:43:24.094879 | controller | changed
2026-02-16 17:43:24.102665 | 
2026-02-16 17:43:24.102727 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:43:24.341068 | controller | changed
2026-02-16 17:43:24.349540 | 
2026-02-16 17:43:24.349607 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:43:24.871206 | controller | changed
2026-02-16 17:43:24.877232 | 
2026-02-16 17:43:24.877311 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:43:25.134685 | controller | changed
2026-02-16 17:43:25.141904 | 
2026-02-16 17:43:25.141973 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:43:26.220282 | controller | changed
2026-02-16 17:43:26.226074 | 
2026-02-16 17:43:26.226137 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:43:26.504833 | controller | changed
2026-02-16 17:43:26.517909 | 
2026-02-16 17:43:26.517976 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:43:26.806745 | controller | changed
2026-02-16 17:43:26.813616 | 
2026-02-16 17:43:26.813680 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:43:27.184924 | controller | changed
2026-02-16 17:43:27.239440 | 
2026-02-16 17:43:27.239608 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:43:28.005411 | controller | changed
2026-02-16 17:43:28.015924 | 
2026-02-16 17:43:28.016050 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:43:28.358607 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:43:28.370851 | 
2026-02-16 17:43:28.370925 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:43:28.829564 | controller | ok: Item: Runtime: 0:00:00.184902
2026-02-16 17:43:29.395568 | controller | ok: Item: Runtime: 0:00:00.348885
2026-02-16 17:43:30.164845 | controller | ok: Item: Runtime: 0:00:00.549540
2026-02-16 17:43:30.443673 | controller | ok: Item: Runtime: 0:00:00.065227
2026-02-16 17:43:30.937882 | controller | ok: Item: Runtime: 0:00:00.266492
2026-02-16 17:43:32.463342 | controller | ok: Item: Runtime: 0:00:01.302910
2026-02-16 17:43:35.565974 | controller | ok: Item: Runtime: 0:00:02.886924
2026-02-16 17:43:35.879820 | controller | ok: Item: Runtime: 0:00:00.090698
2026-02-16 17:43:36.293737 | controller | ok: Item: Runtime: 0:00:00.189928
2026-02-16 17:43:36.648554 | controller | ok: Item: Runtime: 0:00:00.121884
2026-02-16 17:43:37.440810 | controller | ok: Item: Runtime: 0:00:00.561884
2026-02-16 17:43:38.184294 | controller | ok: Item: Runtime: 0:00:00.522187
2026-02-16 17:43:38.650689 | controller | ok: Item: Runtime: 0:00:00.242916
2026-02-16 17:43:39.079674 | controller | ok: Item: Runtime: 0:00:00.206608
2026-02-16 17:43:39.678808 | controller | ok: Item: Runtime: 0:00:00.374440
2026-02-16 17:43:41.179159 | controller | ok: Item: Runtime: 0:00:01.280053
2026-02-16 17:43:41.208686 | 
2026-02-16 17:43:41.208859 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:43:41.427888 | controller | ok: All paths examined
2026-02-16 17:43:41.434288 | 
2026-02-16 17:43:41.434353 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:43:41.683697 | controller | ok: All paths examined
2026-02-16 17:43:41.690115 | 
2026-02-16 17:43:41.690179 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:43:42.114894 | controller | ok: Item: Runtime: 0:00:00.142851
2026-02-16 17:43:42.115169 | controller | changed: All items complete
2026-02-16 17:43:42.115199 | 
2026-02-16 17:43:42.527940 | controller | ok: Item: Runtime: 0:00:00.188303
2026-02-16 17:43:42.550142 | 
2026-02-16 17:43:42.550288 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:43:42.592192 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.594414 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.597470 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.600310 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.603344 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.606358 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.609376 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.612512 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.615682 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.618955 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.621874 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.624844 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.627546 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.630717 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.634716 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.636670 | controller | skipping: Conditional result was False
2026-02-16 17:43:42.660889 | 
2026-02-16 17:43:42.661066 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:43:44.999757 | controller | changed
2026-02-16 17:43:45.009993 | 
2026-02-16 17:43:45.010056 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:43:45.258200 | controller | changed
2026-02-16 17:43:45.310540 | 
2026-02-16 17:43:45.310678 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:43:45.560605 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:43:45.848287 | controller | ERROR
2026-02-16 17:43:45.848539 | controller | {
2026-02-16 17:43:45.848584 | controller |   "delta": "0:00:00.012605",
2026-02-16 17:43:45.848613 | controller |   "end": "2026-02-16 17:43:45.561240",
2026-02-16 17:43:45.848639 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:45.848665 | controller |   "rc": 1,
2026-02-16 17:43:45.848692 | controller |   "start": "2026-02-16 17:43:45.548635"
2026-02-16 17:43:45.848717 | controller | }
2026-02-16 17:43:45.848750 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:45.856706 | 
2026-02-16 17:43:45.856888 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:43:46.105980 | controller | ok
2026-02-16 17:43:46.118328 | 
2026-02-16 17:43:46.118397 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:43:46.356117 | controller | /usr/bin/journalctl
2026-02-16 17:43:46.659321 | controller | ok: Runtime: 0:00:00.006894
2026-02-16 17:43:46.664755 | 
2026-02-16 17:43:46.664829 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:43:46.924775 | controller | 2026-02-16 17:43:46
2026-02-16 17:43:47.207641 | controller | ok: Runtime: 0:00:00.009759
2026-02-16 17:43:47.213614 | 
2026-02-16 17:43:47.213694 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:43:47.772894 | controller | changed
2026-02-16 17:43:47.779535 | 
2026-02-16 17:43:47.779599 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:43:47.804433 | controller | skipping: Conditional result was False
2026-02-16 17:43:47.814968 | 
2026-02-16 17:43:47.815103 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:43:48.347646 | controller | skipping: Conditional result was False
2026-02-16 17:43:48.358905 | 
2026-02-16 17:43:48.358974 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:43:48.887891 | controller | skipping: Conditional result was False
2026-02-16 17:43:48.898709 | 
2026-02-16 17:43:48.898861 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:43:48.923967 | controller | skipping: Conditional result was False
2026-02-16 17:43:48.936671 | 
2026-02-16 17:43:48.936878 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:43:49.467816 | controller | skipping: Conditional result was False
2026-02-16 17:43:49.478823 | 
2026-02-16 17:43:49.478965 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:43:50.009786 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.016778 | 
2026-02-16 17:43:50.016846 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:43:50.042375 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.051847 | 
2026-02-16 17:43:50.051972 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:43:50.077178 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.087699 | 
2026-02-16 17:43:50.087820 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:43:50.667367 | controller | skipping: Conditional result was False
2026-02-16 17:43:50.674222 | 
2026-02-16 17:43:50.674358 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:43:51.205239 | controller | skipping: Conditional result was False
2026-02-16 17:43:51.217004 | 
2026-02-16 17:43:51.217074 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:43:51.241691 | controller | skipping: Conditional result was False
2026-02-16 17:43:51.252713 | 
2026-02-16 17:43:51.252779 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:43:51.278193 | controller | skipping: Conditional result was False
2026-02-16 17:43:51.293181 | 
2026-02-16 17:43:51.293246 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:43:51.674789 | controller | ok
2026-02-16 17:43:51.683707 | 
2026-02-16 17:43:51.683751 | PLAY RECAP
2026-02-16 17:43:51.683798 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:43:51.683821 | 
2026-02-16 17:43:51.822019 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:43:51.827288 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:43:52.399587 | 
2026-02-16 17:43:52.399731 | PLAY [all]
2026-02-16 17:43:52.411636 | 
2026-02-16 17:43:52.411711 | TASK [ensure-rust : Use rustup]
2026-02-16 17:43:52.447111 | controller | ok
2026-02-16 17:43:52.454099 | controller | included: /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:43:52.459559 | 
2026-02-16 17:43:52.459633 | TASK [ensure-rust : Install Rust]
2026-02-16 17:43:52.878476 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:43:52.999355 | controller | ERROR
2026-02-16 17:43:52.999541 | controller | {
2026-02-16 17:43:52.999581 | controller |   "delta": "0:00:00.011906",
2026-02-16 17:43:52.999612 | controller |   "end": "2026-02-16 17:43:52.881120",
2026-02-16 17:43:52.999639 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:52.999664 | controller |   "rc": 127,
2026-02-16 17:43:52.999689 | controller |   "start": "2026-02-16 17:43:52.869214"
2026-02-16 17:43:52.999719 | controller | }
failure
2026-02-16 17:43:53.001848 | 
2026-02-16 17:43:53.001912 | PLAY RECAP
2026-02-16 17:43:53.001975 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:43:53.002006 | 
2026-02-16 17:43:53.125249 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:43:53.136091 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:43:53.740636 | 
2026-02-16 17:43:53.740785 | PLAY [all]
2026-02-16 17:43:53.757050 | 
2026-02-16 17:43:53.757125 | TASK [Return built artifacts to Zuul]
2026-02-16 17:43:53.787695 | controller | ok
2026-02-16 17:43:53.793290 | 
2026-02-16 17:43:53.793363 | TASK [Get the Docker volume ID]
2026-02-16 17:43:54.333471 | controller | ERROR
2026-02-16 17:43:54.333878 | controller | {
2026-02-16 17:43:54.333922 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:43:54.333952 | controller |   "rc": 2
2026-02-16 17:43:54.333979 | controller | }
2026-02-16 17:43:54.334015 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:54.341704 | 
2026-02-16 17:43:54.341773 | TASK [Copy all of the pod logs]
2026-02-16 17:43:54.378158 | controller | ERROR
2026-02-16 17:43:54.378572 | controller | {
2026-02-16 17:43:54.378649 | 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/f4e847830b35456e983ae992522c161c/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:43:54.378698 | controller | }
2026-02-16 17:43:54.378756 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:54.380600 | 
2026-02-16 17:43:54.380696 | PLAY RECAP
2026-02-16 17:43:54.380780 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:43:54.380977 | 
2026-02-16 17:43:54.537599 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:43:54.547906 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:43:55.198209 | 
2026-02-16 17:43:55.198347 | PLAY [all]
2026-02-16 17:43:55.216063 | 
2026-02-16 17:43:55.216151 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:43:55.637903 | controller | changed
2026-02-16 17:43:55.645537 | 
2026-02-16 17:43:55.645622 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:43:56.680344 | controller | ok: Runtime: 0:00:00.453407
2026-02-16 17:43:56.686287 | 
2026-02-16 17:43:56.686371 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:43:57.229076 | controller | ok: Runtime: 0:00:00.021390
2026-02-16 17:43:57.236407 | 
2026-02-16 17:43:57.236487 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:43:57.775023 | controller | ok: Runtime: 0:00:00.021130
2026-02-16 17:43:57.782227 | 
2026-02-16 17:43:57.782293 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:43:58.636768 | controller | changed
2026-02-16 17:43:58.684705 | 
2026-02-16 17:43:58.684856 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:43:58.933869 | controller | changed
2026-02-16 17:43:58.942433 | 
2026-02-16 17:43:58.942519 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:43:59.284144 | controller | Output suppressed because no_log was given
2026-02-16 17:43:59.290514 | 
2026-02-16 17:43:59.290584 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:43:59.319590 | 
2026-02-16 17:43:59.319768 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:43:59.353823 | 
2026-02-16 17:43:59.354001 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:43:59.389447 | 
2026-02-16 17:43:59.389625 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:43:59.424857 | 
2026-02-16 17:43:59.425050 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:43:59.462667 | 
2026-02-16 17:43:59.462840 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:43:59.711935 | controller | changed
2026-02-16 17:43:59.718005 | 
2026-02-16 17:43:59.718077 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:43:59.753436 | controller | ok
2026-02-16 17:43:59.760526 | 
2026-02-16 17:43:59.760608 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:43:59.998498 | controller | Output suppressed because no_log was given
2026-02-16 17:44:00.005354 | 
2026-02-16 17:44:00.005419 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:44:00.045733 | 
2026-02-16 17:44:00.046002 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:44:00.088875 | 
2026-02-16 17:44:00.089111 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:44:00.733075 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:44:00.751482 | controller | Using python 3.12 to install setuptools
2026-02-16 17:44:00.788534 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:44:00.800798 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:44:01.132363 | controller | ERROR
2026-02-16 17:44:01.132759 | controller | {
2026-02-16 17:44:01.132853 | controller |   "delta": "0:00:00.471362",
2026-02-16 17:44:01.132911 | controller |   "end": "2026-02-16 17:44:00.803271",
2026-02-16 17:44:01.132995 | controller |   "msg": "non-zero return code",
2026-02-16 17:44:01.133070 | controller |   "rc": 127,
2026-02-16 17:44:01.133115 | controller |   "start": "2026-02-16 17:44:00.331909"
2026-02-16 17:44:01.133155 | controller | }
2026-02-16 17:44:01.133212 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:01.146044 | 
2026-02-16 17:44:01.146159 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:44:01.406729 | controller | changed
2026-02-16 17:44:01.413101 | 
2026-02-16 17:44:01.413188 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:44:05.086353 | controller | Output suppressed because no_log was given
2026-02-16 17:44:05.099409 | 
2026-02-16 17:44:05.099480 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:44:05.135902 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.136390 | 
2026-02-16 17:44:05.137746 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.138754 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.139736 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.140435 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.141618 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.144163 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.146528 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.148766 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.151187 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.153755 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.155901 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.158169 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.160455 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.162699 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.165006 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.167178 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.170151 | controller | skipping: Conditional result was False
2026-02-16 17:44:05.198855 | 
2026-02-16 17:44:05.198993 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:44:05.435064 | controller | ok
2026-02-16 17:44:05.442105 | 
2026-02-16 17:44:05.442172 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:44:06.017877 | controller | skipping: Conditional result was False
2026-02-16 17:44:06.034290 | 
2026-02-16 17:44:06.034368 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:44:06.295558 | controller | sudo: iptables-save: command not found
2026-02-16 17:44:06.334080 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:44:07.407550 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:44:07.578072 | controller | ok: Runtime: 0:00:01.133681
2026-02-16 17:44:07.590463 | 
2026-02-16 17:44:07.590530 | LOOP [stage-output : Register sources]
2026-02-16 17:44:16.877935 | controller | Output suppressed because no_log was given
2026-02-16 17:44:16.921238 | 
2026-02-16 17:44:16.921392 | TASK [stage-output : Check sudo]
2026-02-16 17:44:17.468935 | controller | ok: Runtime: 0:00:00.018362
2026-02-16 17:44:17.477370 | 
2026-02-16 17:44:17.477460 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:44:17.575517 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.575957 | 
2026-02-16 17:44:17.577931 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.578792 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.581074 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.617946 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.618391 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.618732 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.619059 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.620771 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.622959 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.625195 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.627307 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.629786 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.631896 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.651194 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.651760 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.652234 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.652715 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.653330 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.653950 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.655933 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.658211 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.660291 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.684846 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.685247 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.760116 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.770852 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.781414 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.792166 | controller | Output suppressed because no_log was given
2026-02-16 17:44:17.802720 | 
2026-02-16 17:44:17.802830 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:44:17.879623 | controller | ok
2026-02-16 17:44:17.886786 | 
2026-02-16 17:44:17.886870 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:44:18.127653 | controller | changed: "docs"
2026-02-16 17:44:18.323534 | controller | changed: "artifacts"
2026-02-16 17:44:18.527670 | controller | ok: "logs"
2026-02-16 17:44:18.538247 | 
2026-02-16 17:44:18.538429 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:44:18.810717 | controller | ok: Item: Runtime: 0:00:00.009003
2026-02-16 17:44:18.810959 | controller | changed: All items complete
2026-02-16 17:44:18.810991 | 
2026-02-16 17:44:19.028932 | controller | ok: Item: Runtime: 0:00:00.010331
2026-02-16 17:44:19.246997 | controller | ok: Item: Runtime: 0:00:00.007326
2026-02-16 17:44:19.488020 | controller | ok: Item: Runtime: 0:00:00.008811
2026-02-16 17:44:19.725266 | controller | ok: Item: Runtime: 0:00:00.008274
2026-02-16 17:44:19.957661 | controller | ok: Item: Runtime: 0:00:00.008334
2026-02-16 17:44:20.193960 | controller | ok: Item: Runtime: 0:00:00.009424
2026-02-16 17:44:20.437969 | controller | ok: Item: Runtime: 0:00:00.007978
2026-02-16 17:44:20.663717 | controller | ok: Item: Runtime: 0:00:00.005103
2026-02-16 17:44:20.885004 | controller | ok: Item: Runtime: 0:00:00.007659
2026-02-16 17:44:21.106057 | controller | ok: Item: Runtime: 0:00:00.008956
2026-02-16 17:44:21.378522 | controller | ok: Item: Runtime: 0:00:00.008298
2026-02-16 17:44:21.554614 | controller | ok: Item: Runtime: 0:00:00.008760
2026-02-16 17:44:21.774873 | controller | ok: Item: Runtime: 0:00:00.009042
2026-02-16 17:44:21.996459 | controller | ok: Item: Runtime: 0:00:00.008131
2026-02-16 17:44:22.215513 | controller | ok: Item: Runtime: 0:00:00.008582
2026-02-16 17:44:22.242805 | 
2026-02-16 17:44:22.242981 | TASK [stage-output : Make all log files readable]
2026-02-16 17:44:22.502761 | controller | changed
2026-02-16 17:44:22.508870 | 
2026-02-16 17:44:22.508938 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:44:22.838681 | controller | changed: Renamed files for staging.
2026-02-16 17:44:22.844155 | 
2026-02-16 17:44:22.844227 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:44:22.870267 | controller | skipping: Conditional result was False
2026-02-16 17:44:22.881460 | 
2026-02-16 17:44:22.881615 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:44:22.914447 | 
2026-02-16 17:44:22.914621 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:44:23.552270 | controller | changed:
2026-02-16 17:44:23.553049 | controller | created directory /var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/work/logs/controller
2026-02-16 17:44:23.553097 | controller | cd+++++++++ logs/
2026-02-16 17:44:23.553128 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:44:23.553157 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:44:23.553185 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:44:23.553212 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:44:23.553239 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:44:23.553266 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:44:23.553293 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:44:23.553320 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:44:23.553346 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:44:23.553373 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:44:23.553400 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:44:23.553426 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:44:23.553456 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:44:23.553504 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:44:23.553533 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:44:23.553560 | controller | cd+++++++++ logs/apache/
2026-02-16 17:44:23.553586 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:44:23.553613 | controller | cd+++++++++ logs/etc/
2026-02-16 17:44:23.553682 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:44:23.553710 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:44:23.553736 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:44:23.553763 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:44:23.553789 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:44:23.553816 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:44:23.566543 | 
2026-02-16 17:44:23.566612 | TASK [Check if a tempest log exits]
2026-02-16 17:44:23.826994 | controller | ok
2026-02-16 17:44:23.833006 | 
2026-02-16 17:44:23.833074 | TASK [Link post-devstack tempest.log]
2026-02-16 17:44:23.857996 | controller | skipping: Conditional result was False
2026-02-16 17:44:23.863782 | 
2026-02-16 17:44:23.863843 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:44:24.103983 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:44:24.402597 | controller | ERROR
2026-02-16 17:44:24.402824 | controller | {
2026-02-16 17:44:24.402869 | controller |   "delta": "0:00:00.008277",
2026-02-16 17:44:24.402899 | controller |   "end": "2026-02-16 17:44:24.104719",
2026-02-16 17:44:24.402926 | controller |   "msg": "non-zero return code",
2026-02-16 17:44:24.402952 | controller |   "rc": 127,
2026-02-16 17:44:24.402979 | controller |   "start": "2026-02-16 17:44:24.096442"
2026-02-16 17:44:24.403004 | controller | }
2026-02-16 17:44:24.403037 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:24.404919 | 
2026-02-16 17:44:24.405002 | PLAY RECAP
2026-02-16 17:44:24.405066 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:44:24.405096 | 
2026-02-16 17:44:24.558399 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:44:24.563787 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:25.138270 | 
2026-02-16 17:44:25.138422 | PLAY [all]
2026-02-16 17:44:25.150199 | 
2026-02-16 17:44:25.150276 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:44:25.205571 | controller | skipping: Conditional result was False
2026-02-16 17:44:25.216705 | 
2026-02-16 17:44:25.216788 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:44:25.263301 | controller | ok
2026-02-16 17:44:25.269689 | 
2026-02-16 17:44:25.269760 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:44:25.681301 | controller -> localhost | ok: "/var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/work/logs"
2026-02-16 17:44:25.886982 | controller -> localhost | changed: "/var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/work/artifacts"
2026-02-16 17:44:26.102312 | controller -> localhost | changed: "/var/lib/zuul/builds/f4e847830b35456e983ae992522c161c/work/docs"
2026-02-16 17:44:26.120922 | 
2026-02-16 17:44:26.121141 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:44:26.785994 | controller | changed: .d..t...... ./
2026-02-16 17:44:26.786412 | controller | changed: All items complete
2026-02-16 17:44:26.786461 | 
2026-02-16 17:44:27.244357 | controller | changed: .d..t...... ./
2026-02-16 17:44:27.730664 | controller | changed: .d..t...... ./
2026-02-16 17:44:27.760433 | 
2026-02-16 17:44:27.760700 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:44:28.165707 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007605
2026-02-16 17:44:28.406728 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008496
2026-02-16 17:44:28.426979 | 
2026-02-16 17:44:28.427143 | PLAY [all]
2026-02-16 17:44:28.434921 | 
2026-02-16 17:44:28.434987 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:44:28.905360 | controller | changed
2026-02-16 17:44:28.912660 | 
2026-02-16 17:44:28.912715 | PLAY RECAP
2026-02-16 17:44:28.912767 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:44:28.912790 | 
2026-02-16 17:44:29.050306 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:29.058383 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:44:29.643128 | 
2026-02-16 17:44:29.643261 | PLAY [localhost]
2026-02-16 17:44:29.653767 | 
2026-02-16 17:44:29.653844 | TASK [Generate Zuul manifest]
2026-02-16 17:44:29.676495 | localhost | ok
2026-02-16 17:44:29.694197 | 
2026-02-16 17:44:29.694291 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:44:30.100809 | localhost | changed
2026-02-16 17:44:30.113971 | 
2026-02-16 17:44:30.114047 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:44:30.147441 | localhost | ok
2026-02-16 17:44:30.155554 | 
2026-02-16 17:44:30.155618 | TASK [Upload logs]
2026-02-16 17:44:30.177768 | localhost | ok
2026-02-16 17:44:30.237460 | 
2026-02-16 17:44:30.237569 | TASK [Set zuul-log-path fact]
2026-02-16 17:44:30.259525 | localhost | ok
2026-02-16 17:44:30.272041 | 
2026-02-16 17:44:30.272105 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:44:30.304285 | localhost | ok
2026-02-16 17:44:30.312408 | 
2026-02-16 17:44:30.312476 | TASK [upload-logs : Create log directories]
2026-02-16 17:44:30.750049 | localhost | changed
2026-02-16 17:44:30.756637 | 
2026-02-16 17:44:30.756727 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:44:31.200003 | localhost -> localhost | ok: Runtime: 0:00:00.006763
2026-02-16 17:44:31.204661 | 
2026-02-16 17:44:31.204724 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:44:31.651294 | localhost | Output suppressed because no_log was given
2026-02-16 17:44:31.656168 | 
2026-02-16 17:44:31.656252 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:44:31.701955 | localhost | skipping: Conditional result was False
2026-02-16 17:44:31.707627 | localhost | skipping: Conditional result was False
2026-02-16 17:44:31.721503 | 
2026-02-16 17:44:31.721688 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:44:31.763678 | localhost | skipping: Conditional result was False
2026-02-16 17:44:31.764084 | 
2026-02-16 17:44:31.766556 | localhost | skipping: Conditional result was False
2026-02-16 17:44:31.776527 | 
2026-02-16 17:44:31.776708 | LOOP [upload-logs : Upload console log and json output]