2026-02-16 17:21:47.749854 | Job console starting
2026-02-16 17:21:47.756012 | Updating git repos
2026-02-16 17:21:50.600255 | Cloning repos into workspace
2026-02-16 17:21:56.711821 | Restoring repo states
2026-02-16 17:22:00.107804 | Merging changes
2026-02-16 17:22:00.521047 | Checking out repos
2026-02-16 17:22:03.739286 | Preparing playbooks
2026-02-16 17:22:06.768088 | Running Ansible setup
2026-02-16 17:22:10.403674 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:22:11.063848 | 
2026-02-16 17:22:11.064018 | PLAY [localhost]
2026-02-16 17:22:11.072621 | 
2026-02-16 17:22:11.072708 | TASK [Gathering Facts]
2026-02-16 17:22:11.951774 | localhost | ok
2026-02-16 17:22:11.962457 | 
2026-02-16 17:22:11.962592 | TASK [Setup log path fact]
2026-02-16 17:22:11.987016 | localhost | ok
2026-02-16 17:22:12.000575 | 
2026-02-16 17:22:12.000687 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:22:12.031262 | localhost | ok
2026-02-16 17:22:12.039294 | 
2026-02-16 17:22:12.039364 | TASK [emit-job-header : Print job information]
2026-02-16 17:22:12.082952 | # Job Information
2026-02-16 17:22:12.083230 | Ansible Version: 2.16.15
2026-02-16 17:22:12.083299 | Job: magnum-cluster-api-sonobuoy-v1.35.1-calico
2026-02-16 17:22:12.083349 | Pipeline: check
2026-02-16 17:22:12.083395 | Executor: 3a2793d2bd32
2026-02-16 17:22:12.083438 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:22:12.083488 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:22:12.087824 | 
2026-02-16 17:22:12.087928 | LOOP [emit-job-header : Print node information]
2026-02-16 17:22:12.183295 | localhost | ok:
2026-02-16 17:22:12.183554 | localhost | # Node Information
2026-02-16 17:22:12.183628 | localhost | Inventory Hostname: controller
2026-02-16 17:22:12.183686 | localhost | Hostname: np0000155590
2026-02-16 17:22:12.183737 | localhost | Username: zuul
2026-02-16 17:22:12.183794 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:22:12.183845 | localhost | Provider: yul1
2026-02-16 17:22:12.183894 | localhost | Region: ca-ymq-1
2026-02-16 17:22:12.183942 | localhost | Label: ubuntu-noble-16
2026-02-16 17:22:12.183990 | localhost | Product Name: OpenStack Nova
2026-02-16 17:22:12.184037 | localhost | Interface IP: 199.204.45.242
2026-02-16 17:22:12.194592 | 
2026-02-16 17:22:12.194687 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:22:12.585322 | localhost -> localhost | changed
2026-02-16 17:22:12.595039 | 
2026-02-16 17:22:12.595109 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:22:13.428381 | localhost -> localhost | changed
2026-02-16 17:22:13.440582 | 
2026-02-16 17:22:13.440658 | PLAY [all]
2026-02-16 17:22:13.457751 | 
2026-02-16 17:22:13.457852 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:22:13.676189 | controller -> localhost | ok
2026-02-16 17:22:13.688274 | 
2026-02-16 17:22:13.688355 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:22:13.724622 | controller | ok
2026-02-16 17:22:13.740429 | controller | included: /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:22:13.746623 | 
2026-02-16 17:22:13.746686 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:22:16.512533 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:22:16.512710 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/work/8c355633aa2b407ba097ca6adbe88dd5_id_rsa
2026-02-16 17:22:16.512740 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/work/8c355633aa2b407ba097ca6adbe88dd5_id_rsa.pub
2026-02-16 17:22:16.512763 | controller -> localhost | The key fingerprint is:
2026-02-16 17:22:16.512784 | controller -> localhost | SHA256:Tcs3imy47F/q5fJtdJ/2N8w+qWY4xPVmSX8NM47W/jI zuul-build-sshkey
2026-02-16 17:22:16.512814 | controller -> localhost | The key's randomart image is:
2026-02-16 17:22:16.512835 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:22:16.512862 | controller -> localhost | |                 |
2026-02-16 17:22:16.512883 | controller -> localhost | |                 |
2026-02-16 17:22:16.512904 | controller -> localhost | |          .      |
2026-02-16 17:22:16.512924 | controller -> localhost | |         + . .+. |
2026-02-16 17:22:16.512943 | controller -> localhost | |        S = ++o++|
2026-02-16 17:22:16.512981 | controller -> localhost | |       o . =+.+=+|
2026-02-16 17:22:16.513002 | controller -> localhost | |      . + =o.o=.+|
2026-02-16 17:22:16.513022 | controller -> localhost | |     . o.= +.oEOo|
2026-02-16 17:22:16.513043 | controller -> localhost | |     .+o+oo.=.+=B|
2026-02-16 17:22:16.513063 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:22:16.513108 | controller -> localhost | ok: Runtime: 0:00:02.361053
2026-02-16 17:22:16.518737 | 
2026-02-16 17:22:16.520033 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:22:16.557527 | controller | ok
2026-02-16 17:22:16.566660 | controller | included: /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:22:16.574303 | 
2026-02-16 17:22:16.574361 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:22:16.598842 | controller | skipping: Conditional result was False
2026-02-16 17:22:16.610129 | 
2026-02-16 17:22:16.610220 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:22:17.123326 | controller | changed
2026-02-16 17:22:17.131530 | 
2026-02-16 17:22:17.131603 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:22:17.359824 | controller | ok
2026-02-16 17:22:17.365505 | 
2026-02-16 17:22:17.365572 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:22:17.947567 | controller | changed
2026-02-16 17:22:17.955349 | 
2026-02-16 17:22:17.955439 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:22:18.528587 | controller | changed
2026-02-16 17:22:18.536933 | 
2026-02-16 17:22:18.537024 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:22:18.561949 | controller | skipping: Conditional result was False
2026-02-16 17:22:18.572944 | 
2026-02-16 17:22:18.573028 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:22:18.921633 | controller -> localhost | changed
2026-02-16 17:22:18.938520 | 
2026-02-16 17:22:18.938596 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:22:19.274034 | controller -> localhost | Identity added: /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/work/8c355633aa2b407ba097ca6adbe88dd5_id_rsa (zuul-build-sshkey)
2026-02-16 17:22:19.274288 | controller -> localhost | ok: Runtime: 0:00:00.015108
2026-02-16 17:22:19.322352 | 
2026-02-16 17:22:19.322508 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:22:19.612041 | controller | ok
2026-02-16 17:22:19.617091 | 
2026-02-16 17:22:19.617152 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:22:19.641805 | controller | skipping: Conditional result was False
2026-02-16 17:22:19.659491 | 
2026-02-16 17:22:19.659577 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:22:19.990599 | controller | ok
2026-02-16 17:22:19.996564 | 
2026-02-16 17:22:19.996624 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:22:39.651302 | controller | Output suppressed because no_log was given
2026-02-16 17:22:39.662436 | 
2026-02-16 17:22:39.662501 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:22:39.899943 | controller | ok: "logs"
2026-02-16 17:22:39.900222 | controller | ok: All items complete
2026-02-16 17:22:39.900251 | 
2026-02-16 17:22:40.101803 | controller | ok: "artifacts"
2026-02-16 17:22:40.303109 | controller | ok: "docs"
2026-02-16 17:22:40.322817 | 
2026-02-16 17:22:40.323018 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:22:40.543947 | controller | changed: "logs"
2026-02-16 17:22:40.737975 | controller | changed: "artifacts"
2026-02-16 17:22:40.965956 | controller | changed: "docs"
2026-02-16 17:22:40.990459 | 
2026-02-16 17:22:40.990636 | PLAY RECAP
2026-02-16 17:22:40.990703 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:22:40.990743 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:22:40.990773 | 
2026-02-16 17:22:41.158868 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:22:41.167689 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:22:41.816229 | 
2026-02-16 17:22:41.816528 | PLAY [all]
2026-02-16 17:22:41.840463 | 
2026-02-16 17:22:41.840578 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:22:42.222157 | controller | changed
2026-02-16 17:22:42.230272 | 
2026-02-16 17:22:42.230353 | TASK [Gather minimum local MTU]
2026-02-16 17:22:42.306568 | controller | ok
2026-02-16 17:22:42.313798 | 
2026-02-16 17:22:42.313892 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:22:42.367632 | controller | ok
2026-02-16 17:22:42.375553 | 
2026-02-16 17:22:42.375650 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:22:42.402114 | controller | skipping: Conditional result was False
2026-02-16 17:22:42.415127 | 
2026-02-16 17:22:42.415262 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:22:42.955169 | controller | ok: Runtime: 0:00:00.010652
2026-02-16 17:22:42.960967 | 
2026-02-16 17:22:42.961056 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:22:42.986200 | controller | skipping: Conditional result was False
2026-02-16 17:22:42.997261 | 
2026-02-16 17:22:42.997337 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:22:43.023368 | controller | skipping: Conditional result was False
2026-02-16 17:22:43.032916 | 
2026-02-16 17:22:43.033040 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:22:43.089652 | controller | ok
2026-02-16 17:22:43.099631 | controller | included: /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:22:43.105678 | 
2026-02-16 17:22:43.105747 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:22:43.160995 | controller | ok
2026-02-16 17:22:43.166361 | 
2026-02-16 17:22:43.166423 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:22:43.443213 | controller | ext4
2026-02-16 17:22:43.754784 | controller | ok: Runtime: 0:00:00.010073
2026-02-16 17:22:43.760361 | 
2026-02-16 17:22:43.760425 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:22:43.795975 | controller | ok
2026-02-16 17:22:43.801477 | 
2026-02-16 17:22:43.801544 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:22:43.836893 | controller | ok:
2026-02-16 17:22:43.837057 | controller | {
2026-02-16 17:22:43.837084 | controller |   "root_filesystem": "ext4"
2026-02-16 17:22:43.837104 | controller | }
2026-02-16 17:22:43.842887 | 
2026-02-16 17:22:43.842951 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:22:53.280867 | controller | 8192+0 records in
2026-02-16 17:22:53.281005 | controller | 8192+0 records out
2026-02-16 17:22:53.281025 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.16991 s, 937 MB/s
2026-02-16 17:22:53.403229 | controller | ok: Runtime: 0:00:09.179368
2026-02-16 17:22:53.413853 | 
2026-02-16 17:22:53.414002 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:22:53.661713 | controller | changed
2026-02-16 17:22:53.667799 | 
2026-02-16 17:22:53.667859 | TASK [configure-swap : Make swapfile]
2026-02-16 17:23:17.764896 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:23:17.765292 | controller | no label, UUID=4d3fd76d-f3f7-4106-a992-c1e97e534a96
2026-02-16 17:23:18.229987 | controller | ok: Runtime: 0:00:23.856499
2026-02-16 17:23:18.237883 | 
2026-02-16 17:23:18.237953 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:23:18.649788 | controller | changed
2026-02-16 17:23:18.656275 | 
2026-02-16 17:23:18.656342 | TASK [configure-swap : Add all swap]
2026-02-16 17:23:19.195877 | controller | ok: Runtime: 0:00:00.018262
2026-02-16 17:23:19.203616 | 
2026-02-16 17:23:19.203706 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:23:19.250523 | controller | ok:
2026-02-16 17:23:19.250643 | controller | {
2026-02-16 17:23:19.250669 | controller |   "swap_required": "8192"
2026-02-16 17:23:19.250689 | controller | }
2026-02-16 17:23:19.255781 | 
2026-02-16 17:23:19.255845 | TASK [configure-swap : Set swappiness]
2026-02-16 17:23:19.670027 | controller | changed
2026-02-16 17:23:19.678832 | 
2026-02-16 17:23:19.678912 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:23:19.713478 | controller | ok:
2026-02-16 17:23:19.713637 | controller | {
2026-02-16 17:23:19.713673 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:23:19.713708 | controller | }
2026-02-16 17:23:19.726333 | 
2026-02-16 17:23:19.726405 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:23:20.176089 | controller | changed
2026-02-16 17:23:20.183371 | 
2026-02-16 17:23:20.183436 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:23:20.442096 | controller | changed
2026-02-16 17:23:20.449672 | 
2026-02-16 17:23:20.449744 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:23:21.021272 | controller | changed
2026-02-16 17:23:21.027828 | 
2026-02-16 17:23:21.027897 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:23:21.288383 | controller | changed
2026-02-16 17:23:21.297604 | 
2026-02-16 17:23:21.297714 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:23:22.428237 | controller | changed
2026-02-16 17:23:22.435369 | 
2026-02-16 17:23:22.435436 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:23:22.682343 | controller | changed
2026-02-16 17:23:22.696831 | 
2026-02-16 17:23:22.696907 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:23:22.968374 | controller | changed
2026-02-16 17:23:22.974411 | 
2026-02-16 17:23:22.974478 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:23:23.341127 | controller | changed
2026-02-16 17:23:23.413850 | 
2026-02-16 17:23:23.414030 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:23:24.177240 | controller | changed
2026-02-16 17:23:24.189346 | 
2026-02-16 17:23:24.189414 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:23:24.536109 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:23:24.550677 | 
2026-02-16 17:23:24.551067 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:23:25.046701 | controller | ok: Item: Runtime: 0:00:00.195142
2026-02-16 17:23:25.619418 | controller | ok: Item: Runtime: 0:00:00.355909
2026-02-16 17:23:26.362397 | controller | ok: Item: Runtime: 0:00:00.522147
2026-02-16 17:23:26.653557 | controller | ok: Item: Runtime: 0:00:00.063519
2026-02-16 17:23:27.139920 | controller | ok: Item: Runtime: 0:00:00.270770
2026-02-16 17:23:28.647690 | controller | ok: Item: Runtime: 0:00:01.286496
2026-02-16 17:23:32.161110 | controller | ok: Item: Runtime: 0:00:03.286084
2026-02-16 17:23:32.476807 | controller | ok: Item: Runtime: 0:00:00.092098
2026-02-16 17:23:32.878826 | controller | ok: Item: Runtime: 0:00:00.183854
2026-02-16 17:23:33.217236 | controller | ok: Item: Runtime: 0:00:00.119909
2026-02-16 17:23:33.949923 | controller | ok: Item: Runtime: 0:00:00.514087
2026-02-16 17:23:34.666063 | controller | ok: Item: Runtime: 0:00:00.498930
2026-02-16 17:23:35.146312 | controller | ok: Item: Runtime: 0:00:00.247901
2026-02-16 17:23:35.620898 | controller | ok: Item: Runtime: 0:00:00.239870
2026-02-16 17:23:36.219864 | controller | ok: Item: Runtime: 0:00:00.374002
2026-02-16 17:23:37.654544 | controller | ok: Item: Runtime: 0:00:01.220259
2026-02-16 17:23:37.674466 | 
2026-02-16 17:23:37.674586 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:23:37.903687 | controller | ok: All paths examined
2026-02-16 17:23:37.910136 | 
2026-02-16 17:23:37.910207 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:23:38.179488 | controller | ok: All paths examined
2026-02-16 17:23:38.186537 | 
2026-02-16 17:23:38.186615 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:23:38.643860 | controller | ok: Item: Runtime: 0:00:00.140613
2026-02-16 17:23:38.644117 | controller | changed: All items complete
2026-02-16 17:23:38.644147 | 
2026-02-16 17:23:39.044969 | controller | ok: Item: Runtime: 0:00:00.177597
2026-02-16 17:23:39.058185 | 
2026-02-16 17:23:39.058365 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:23:39.100387 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.102632 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.105316 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.108004 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.111099 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.114153 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.117245 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.120783 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.124054 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.126985 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.129994 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.132936 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.135546 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.138572 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.141560 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.144684 | controller | skipping: Conditional result was False
2026-02-16 17:23:39.171782 | 
2026-02-16 17:23:39.171943 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:23:41.663668 | controller | changed
2026-02-16 17:23:41.678788 | 
2026-02-16 17:23:41.679006 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:23:41.926086 | controller | changed
2026-02-16 17:23:41.983179 | 
2026-02-16 17:23:41.983318 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:23:42.246732 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:23:42.519084 | controller | ERROR
2026-02-16 17:23:42.519315 | controller | {
2026-02-16 17:23:42.519346 | controller |   "delta": "0:00:00.009816",
2026-02-16 17:23:42.519368 | controller |   "end": "2026-02-16 17:23:42.247474",
2026-02-16 17:23:42.519387 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:42.519406 | controller |   "rc": 1,
2026-02-16 17:23:42.519425 | controller |   "start": "2026-02-16 17:23:42.237658"
2026-02-16 17:23:42.519443 | controller | }
2026-02-16 17:23:42.519469 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:42.525108 | 
2026-02-16 17:23:42.525176 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:23:42.773277 | controller | ok
2026-02-16 17:23:42.786650 | 
2026-02-16 17:23:42.786720 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:23:43.028434 | controller | /usr/bin/journalctl
2026-02-16 17:23:43.329644 | controller | ok: Runtime: 0:00:00.008784
2026-02-16 17:23:43.335444 | 
2026-02-16 17:23:43.335517 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:23:43.590908 | controller | 2026-02-16 17:23:43
2026-02-16 17:23:43.872193 | controller | ok: Runtime: 0:00:00.007730
2026-02-16 17:23:43.877799 | 
2026-02-16 17:23:43.877865 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:23:44.458572 | controller | changed
2026-02-16 17:23:44.466472 | 
2026-02-16 17:23:44.466558 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:23:44.493791 | controller | skipping: Conditional result was False
2026-02-16 17:23:44.504189 | 
2026-02-16 17:23:44.504275 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:23:45.053313 | controller | skipping: Conditional result was False
2026-02-16 17:23:45.066396 | 
2026-02-16 17:23:45.066551 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:23:45.596445 | controller | skipping: Conditional result was False
2026-02-16 17:23:45.608638 | 
2026-02-16 17:23:45.608709 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:23:45.634161 | controller | skipping: Conditional result was False
2026-02-16 17:23:45.645347 | 
2026-02-16 17:23:45.645419 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:23:46.188094 | controller | skipping: Conditional result was False
2026-02-16 17:23:46.200669 | 
2026-02-16 17:23:46.200840 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:23:46.735030 | controller | skipping: Conditional result was False
2026-02-16 17:23:46.744152 | 
2026-02-16 17:23:46.744251 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:23:46.769843 | controller | skipping: Conditional result was False
2026-02-16 17:23:46.777953 | 
2026-02-16 17:23:46.778046 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:23:46.803415 | controller | skipping: Conditional result was False
2026-02-16 17:23:46.812299 | 
2026-02-16 17:23:46.812445 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:23:47.393109 | controller | skipping: Conditional result was False
2026-02-16 17:23:47.405498 | 
2026-02-16 17:23:47.405596 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:23:47.941593 | controller | skipping: Conditional result was False
2026-02-16 17:23:47.949510 | 
2026-02-16 17:23:47.949580 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:23:47.974882 | controller | skipping: Conditional result was False
2026-02-16 17:23:47.984695 | 
2026-02-16 17:23:47.984817 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:23:48.010517 | controller | skipping: Conditional result was False
2026-02-16 17:23:48.026020 | 
2026-02-16 17:23:48.026127 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:23:48.418377 | controller | ok
2026-02-16 17:23:48.554346 | 
2026-02-16 17:23:48.554454 | PLAY RECAP
2026-02-16 17:23:48.554500 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:23:48.554524 | 
2026-02-16 17:23:48.715284 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:23:48.720525 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:49.303673 | 
2026-02-16 17:23:49.304191 | PLAY [all]
2026-02-16 17:23:49.316559 | 
2026-02-16 17:23:49.316646 | TASK [ensure-rust : Use rustup]
2026-02-16 17:23:49.352740 | controller | ok
2026-02-16 17:23:49.359944 | controller | included: /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:23:49.365807 | 
2026-02-16 17:23:49.365887 | TASK [ensure-rust : Install Rust]
2026-02-16 17:23:49.754175 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:23:49.906934 | controller | ERROR
2026-02-16 17:23:49.907292 | controller | {
2026-02-16 17:23:49.907358 | controller |   "delta": "0:00:00.011547",
2026-02-16 17:23:49.907408 | controller |   "end": "2026-02-16 17:23:49.757716",
2026-02-16 17:23:49.907452 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:49.907493 | controller |   "rc": 127,
2026-02-16 17:23:49.907534 | controller |   "start": "2026-02-16 17:23:49.746169"
2026-02-16 17:23:49.907627 | controller | }
failure
2026-02-16 17:23:49.909776 | 
2026-02-16 17:23:49.909832 | PLAY RECAP
2026-02-16 17:23:49.909881 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:23:49.909903 | 
2026-02-16 17:23:50.043360 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:23:50.053404 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:50.640728 | 
2026-02-16 17:23:50.640863 | PLAY [all]
2026-02-16 17:23:50.657079 | 
2026-02-16 17:23:50.657160 | TASK [Return built artifacts to Zuul]
2026-02-16 17:23:50.692328 | controller | ok
2026-02-16 17:23:50.697642 | 
2026-02-16 17:23:50.697715 | TASK [Get the Docker volume ID]
2026-02-16 17:23:51.236938 | controller | ERROR
2026-02-16 17:23:51.237444 | controller | {
2026-02-16 17:23:51.237514 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:23:51.237565 | controller |   "rc": 2
2026-02-16 17:23:51.237611 | controller | }
2026-02-16 17:23:51.237669 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:51.246619 | 
2026-02-16 17:23:51.246690 | TASK [Copy all of the pod logs]
2026-02-16 17:23:51.283924 | controller | ERROR
2026-02-16 17:23:51.284190 | controller | {
2026-02-16 17:23:51.284221 | 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/8c355633aa2b407ba097ca6adbe88dd5/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:23:51.284245 | controller | }
2026-02-16 17:23:51.284272 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:51.285500 | 
2026-02-16 17:23:51.285546 | PLAY RECAP
2026-02-16 17:23:51.285592 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:23:51.285613 | 
2026-02-16 17:23:51.426173 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:23:51.434584 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:52.096080 | 
2026-02-16 17:23:52.096871 | PLAY [all]
2026-02-16 17:23:52.115514 | 
2026-02-16 17:23:52.136343 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:23:52.555321 | controller | changed
2026-02-16 17:23:52.562396 | 
2026-02-16 17:23:52.562477 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:23:53.604732 | controller | ok: Runtime: 0:00:00.452691
2026-02-16 17:23:53.633018 | 
2026-02-16 17:23:53.633098 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:23:54.170222 | controller | ok: Runtime: 0:00:00.021240
2026-02-16 17:23:54.176283 | 
2026-02-16 17:23:54.176353 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:23:54.712781 | controller | ok: Runtime: 0:00:00.022012
2026-02-16 17:23:54.721634 | 
2026-02-16 17:23:54.721707 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:23:55.606814 | controller | changed
2026-02-16 17:23:55.655552 | 
2026-02-16 17:23:55.655703 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:23:55.911606 | controller | changed
2026-02-16 17:23:55.918549 | 
2026-02-16 17:23:55.918621 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:56.262812 | controller | Output suppressed because no_log was given
2026-02-16 17:23:56.269065 | 
2026-02-16 17:23:56.269142 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:56.307932 | 
2026-02-16 17:23:56.308069 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:56.344131 | 
2026-02-16 17:23:56.344261 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:23:56.371534 | 
2026-02-16 17:23:56.371726 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:23:56.407039 | 
2026-02-16 17:23:56.407174 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:23:56.443921 | 
2026-02-16 17:23:56.444108 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:23:56.697513 | controller | changed
2026-02-16 17:23:56.705300 | 
2026-02-16 17:23:56.705374 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:23:56.742731 | controller | ok
2026-02-16 17:23:56.748554 | 
2026-02-16 17:23:56.748629 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:23:56.987790 | controller | Output suppressed because no_log was given
2026-02-16 17:23:56.994874 | 
2026-02-16 17:23:56.994964 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:23:57.031283 | 
2026-02-16 17:23:57.031489 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:23:57.074293 | 
2026-02-16 17:23:57.074457 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:23:57.741036 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:23:57.759433 | controller | Using python 3.12 to install setuptools
2026-02-16 17:23:57.796883 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:23:57.808954 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:23:58.116452 | controller | ERROR
2026-02-16 17:23:58.116703 | controller | {
2026-02-16 17:23:58.116737 | controller |   "delta": "0:00:00.487334",
2026-02-16 17:23:58.116758 | controller |   "end": "2026-02-16 17:23:57.811183",
2026-02-16 17:23:58.116776 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:58.116800 | controller |   "rc": 127,
2026-02-16 17:23:58.116818 | controller |   "start": "2026-02-16 17:23:57.323849"
2026-02-16 17:23:58.116835 | controller | }
2026-02-16 17:23:58.116860 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:58.128724 | 
2026-02-16 17:23:58.128803 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:23:58.379474 | controller | changed
2026-02-16 17:23:58.385665 | 
2026-02-16 17:23:58.385738 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:24:02.365151 | controller | Output suppressed because no_log was given
2026-02-16 17:24:02.379029 | 
2026-02-16 17:24:02.379100 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:24:02.411544 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.412033 | 
2026-02-16 17:24:02.414000 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.415690 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.417297 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.419896 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.422161 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.424684 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.426906 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.429223 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.431501 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.433903 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.436220 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.438578 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.440845 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.443182 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.445680 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.448007 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.451124 | controller | skipping: Conditional result was False
2026-02-16 17:24:02.481356 | 
2026-02-16 17:24:02.481498 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:24:02.736894 | controller | ok
2026-02-16 17:24:02.743711 | 
2026-02-16 17:24:02.743809 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:24:03.327890 | controller | skipping: Conditional result was False
2026-02-16 17:24:03.345104 | 
2026-02-16 17:24:03.345212 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:24:03.633336 | controller | sudo: iptables-save: command not found
2026-02-16 17:24:03.707664 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:24:05.093249 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:24:05.387262 | controller | ok: Runtime: 0:00:01.481975
2026-02-16 17:24:05.397727 | 
2026-02-16 17:24:05.397797 | LOOP [stage-output : Register sources]
2026-02-16 17:24:15.017788 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.071757 | 
2026-02-16 17:24:15.071924 | TASK [stage-output : Check sudo]
2026-02-16 17:24:15.611630 | controller | ok: Runtime: 0:00:00.020351
2026-02-16 17:24:15.617624 | 
2026-02-16 17:24:15.617701 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:24:15.713557 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.714174 | 
2026-02-16 17:24:15.716107 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.717054 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.718431 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.752885 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.753280 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.753625 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.755790 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.758119 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.760242 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.762399 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.764735 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.766884 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.768940 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.784345 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.784852 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.785356 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.786178 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.788277 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.790480 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.792693 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.795046 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.797188 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.821534 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.822052 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.906064 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.911040 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.916107 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.924770 | controller | Output suppressed because no_log was given
2026-02-16 17:24:15.940405 | 
2026-02-16 17:24:15.940561 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:24:16.016989 | controller | ok
2026-02-16 17:24:16.027436 | 
2026-02-16 17:24:16.027578 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:24:16.295612 | controller | changed: "docs"
2026-02-16 17:24:16.514023 | controller | changed: "artifacts"
2026-02-16 17:24:16.729365 | controller | ok: "logs"
2026-02-16 17:24:16.743046 | 
2026-02-16 17:24:16.743209 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:24:17.038755 | controller | ok: Item: Runtime: 0:00:00.010379
2026-02-16 17:24:17.039026 | controller | changed: All items complete
2026-02-16 17:24:17.039054 | 
2026-02-16 17:24:17.257790 | controller | ok: Item: Runtime: 0:00:00.008452
2026-02-16 17:24:17.481504 | controller | ok: Item: Runtime: 0:00:00.009254
2026-02-16 17:24:17.706319 | controller | ok: Item: Runtime: 0:00:00.007873
2026-02-16 17:24:17.934200 | controller | ok: Item: Runtime: 0:00:00.008888
2026-02-16 17:24:18.163885 | controller | ok: Item: Runtime: 0:00:00.008190
2026-02-16 17:24:18.394903 | controller | ok: Item: Runtime: 0:00:00.009535
2026-02-16 17:24:18.617820 | controller | ok: Item: Runtime: 0:00:00.008542
2026-02-16 17:24:18.852775 | controller | ok: Item: Runtime: 0:00:00.008557
2026-02-16 17:24:19.078531 | controller | ok: Item: Runtime: 0:00:00.007669
2026-02-16 17:24:19.304883 | controller | ok: Item: Runtime: 0:00:00.008741
2026-02-16 17:24:19.589861 | controller | ok: Item: Runtime: 0:00:00.008359
2026-02-16 17:24:19.753389 | controller | ok: Item: Runtime: 0:00:00.008202
2026-02-16 17:24:19.972325 | controller | ok: Item: Runtime: 0:00:00.007983
2026-02-16 17:24:20.200142 | controller | ok: Item: Runtime: 0:00:00.007761
2026-02-16 17:24:20.434264 | controller | ok: Item: Runtime: 0:00:00.008573
2026-02-16 17:24:20.460395 | 
2026-02-16 17:24:20.460615 | TASK [stage-output : Make all log files readable]
2026-02-16 17:24:20.717965 | controller | changed
2026-02-16 17:24:20.725654 | 
2026-02-16 17:24:20.725725 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:24:21.056690 | controller | changed: Renamed files for staging.
2026-02-16 17:24:21.063857 | 
2026-02-16 17:24:21.063929 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:24:21.079430 | controller | skipping: Conditional result was False
2026-02-16 17:24:21.087861 | 
2026-02-16 17:24:21.087934 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:24:21.120983 | 
2026-02-16 17:24:21.121164 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:24:21.743208 | controller | changed:
2026-02-16 17:24:21.743434 | controller | created directory /var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/work/logs/controller
2026-02-16 17:24:21.743474 | controller | cd+++++++++ logs/
2026-02-16 17:24:21.743504 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:24:21.743531 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:24:21.743557 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:24:21.743682 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:24:21.743718 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:24:21.743745 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:24:21.743771 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:24:21.743796 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:24:21.743821 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:24:21.743846 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:24:21.743871 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:24:21.743895 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:24:21.743926 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:24:21.743974 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:24:21.744000 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:24:21.744024 | controller | cd+++++++++ logs/apache/
2026-02-16 17:24:21.744049 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:24:21.744074 | controller | cd+++++++++ logs/etc/
2026-02-16 17:24:21.744099 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:24:21.744124 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:24:21.744148 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:24:21.744173 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:24:21.744197 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:24:21.744222 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:24:21.758498 | 
2026-02-16 17:24:21.758578 | TASK [Check if a tempest log exits]
2026-02-16 17:24:22.015989 | controller | ok
2026-02-16 17:24:22.021485 | 
2026-02-16 17:24:22.021550 | TASK [Link post-devstack tempest.log]
2026-02-16 17:24:22.039457 | controller | skipping: Conditional result was False
2026-02-16 17:24:22.050001 | 
2026-02-16 17:24:22.050090 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:24:22.317062 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:24:22.593245 | controller | ERROR
2026-02-16 17:24:22.593517 | controller | {
2026-02-16 17:24:22.593566 | controller |   "delta": "0:00:00.009433",
2026-02-16 17:24:22.593621 | controller |   "end": "2026-02-16 17:24:22.317798",
2026-02-16 17:24:22.593673 | controller |   "msg": "non-zero return code",
2026-02-16 17:24:22.593704 | controller |   "rc": 127,
2026-02-16 17:24:22.593736 | controller |   "start": "2026-02-16 17:24:22.308365"
2026-02-16 17:24:22.593763 | controller | }
2026-02-16 17:24:22.593798 | controller | ERROR: Ignoring Errors
2026-02-16 17:24:22.595827 | 
2026-02-16 17:24:22.595896 | PLAY RECAP
2026-02-16 17:24:22.595963 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:24:22.595995 | 
2026-02-16 17:24:22.740675 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:24:22.747263 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:24:23.392633 | 
2026-02-16 17:24:23.392779 | PLAY [all]
2026-02-16 17:24:23.404789 | 
2026-02-16 17:24:23.404864 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:24:23.450609 | controller | skipping: Conditional result was False
2026-02-16 17:24:23.461119 | 
2026-02-16 17:24:23.461209 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:24:23.506575 | controller | ok
2026-02-16 17:24:23.515314 | 
2026-02-16 17:24:23.515388 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:24:23.913972 | controller -> localhost | ok: "/var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/work/logs"
2026-02-16 17:24:24.125567 | controller -> localhost | changed: "/var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/work/artifacts"
2026-02-16 17:24:24.331940 | controller -> localhost | changed: "/var/lib/zuul/builds/8c355633aa2b407ba097ca6adbe88dd5/work/docs"
2026-02-16 17:24:24.350248 | 
2026-02-16 17:24:24.350396 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:24:24.993766 | controller | changed: .d..t...... ./
2026-02-16 17:24:24.994146 | controller | changed: All items complete
2026-02-16 17:24:24.994198 | 
2026-02-16 17:24:25.497004 | controller | changed: .d..t...... ./
2026-02-16 17:24:26.008443 | controller | changed: .d..t...... ./
2026-02-16 17:24:26.022956 | 
2026-02-16 17:24:26.023284 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:24:26.478829 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008193
2026-02-16 17:24:26.721824 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008127
2026-02-16 17:24:26.742173 | 
2026-02-16 17:24:26.742315 | PLAY [all]
2026-02-16 17:24:26.749768 | 
2026-02-16 17:24:26.749835 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:24:27.232377 | controller | changed
2026-02-16 17:24:27.241022 | 
2026-02-16 17:24:27.241078 | PLAY RECAP
2026-02-16 17:24:27.241139 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:24:27.241163 | 
2026-02-16 17:24:27.414808 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:24:27.430739 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:24:28.039474 | 
2026-02-16 17:24:28.039621 | PLAY [localhost]
2026-02-16 17:24:28.049914 | 
2026-02-16 17:24:28.049989 | TASK [Generate Zuul manifest]
2026-02-16 17:24:28.081982 | localhost | ok
2026-02-16 17:24:28.100150 | 
2026-02-16 17:24:28.100230 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:24:28.504571 | localhost | changed
2026-02-16 17:24:28.517170 | 
2026-02-16 17:24:28.517250 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:24:28.551710 | localhost | ok
2026-02-16 17:24:28.558465 | 
2026-02-16 17:24:28.558530 | TASK [Upload logs]
2026-02-16 17:24:28.592218 | localhost | ok
2026-02-16 17:24:28.655678 | 
2026-02-16 17:24:28.655829 | TASK [Set zuul-log-path fact]
2026-02-16 17:24:28.679920 | localhost | ok
2026-02-16 17:24:28.691327 | 
2026-02-16 17:24:28.691400 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:24:28.722751 | localhost | ok
2026-02-16 17:24:28.732220 | 
2026-02-16 17:24:28.732307 | TASK [upload-logs : Create log directories]
2026-02-16 17:24:29.166530 | localhost | changed
2026-02-16 17:24:29.172279 | 
2026-02-16 17:24:29.172376 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:24:29.624778 | localhost -> localhost | ok: Runtime: 0:00:00.006786
2026-02-16 17:24:29.629792 | 
2026-02-16 17:24:29.629858 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:24:30.077182 | localhost | Output suppressed because no_log was given
2026-02-16 17:24:30.082882 | 
2026-02-16 17:24:30.082969 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:24:30.124628 | localhost | skipping: Conditional result was False
2026-02-16 17:24:30.130632 | localhost | skipping: Conditional result was False
2026-02-16 17:24:30.147718 | 
2026-02-16 17:24:30.147904 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:24:30.192021 | localhost | skipping: Conditional result was False
2026-02-16 17:24:30.192414 | 
2026-02-16 17:24:30.195185 | localhost | skipping: Conditional result was False
2026-02-16 17:24:30.214077 | 
2026-02-16 17:24:30.214268 | LOOP [upload-logs : Upload console log and json output]