2026-02-16 17:24:25.963864 | Job console starting
2026-02-16 17:24:26.129670 | Updating git repos
2026-02-16 17:24:34.603661 | Cloning repos into workspace
2026-02-16 17:24:40.099769 | Restoring repo states
2026-02-16 17:24:40.449272 | Merging changes
2026-02-16 17:24:41.963494 | Checking out repos
2026-02-16 17:24:45.564828 | Preparing playbooks
2026-02-16 17:24:47.714451 | Running Ansible setup
2026-02-16 17:24:51.401872 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:24:51.999936 | 
2026-02-16 17:24:52.134053 | PLAY [localhost]
2026-02-16 17:24:52.148254 | 
2026-02-16 17:24:52.148355 | TASK [Gathering Facts]
2026-02-16 17:24:53.069192 | localhost | ok
2026-02-16 17:24:53.078996 | 
2026-02-16 17:24:53.079078 | TASK [Setup log path fact]
2026-02-16 17:24:53.098420 | localhost | ok
2026-02-16 17:24:53.111299 | 
2026-02-16 17:24:53.111370 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:24:53.141808 | localhost | ok
2026-02-16 17:24:53.150405 | 
2026-02-16 17:24:53.150474 | TASK [emit-job-header : Print job information]
2026-02-16 17:24:53.194103 | # Job Information
2026-02-16 17:24:53.194330 | Ansible Version: 2.16.15
2026-02-16 17:24:53.194374 | Job: magnum-cluster-api-sonobuoy-v1.35.1-cilium
2026-02-16 17:24:53.194405 | Pipeline: check
2026-02-16 17:24:53.194433 | Executor: 3a2793d2bd32
2026-02-16 17:24:53.194460 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:24:53.194491 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:24:53.198348 | 
2026-02-16 17:24:53.198439 | LOOP [emit-job-header : Print node information]
2026-02-16 17:24:53.298759 | localhost | ok:
2026-02-16 17:24:53.299072 | localhost | # Node Information
2026-02-16 17:24:53.299112 | localhost | Inventory Hostname: controller
2026-02-16 17:24:53.299142 | localhost | Hostname: np0000155594
2026-02-16 17:24:53.299169 | localhost | Username: zuul
2026-02-16 17:24:53.299200 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:24:53.299227 | localhost | Provider: yul1
2026-02-16 17:24:53.299254 | localhost | Region: ca-ymq-1
2026-02-16 17:24:53.299280 | localhost | Label: ubuntu-noble-16
2026-02-16 17:24:53.299305 | localhost | Product Name: OpenStack Nova
2026-02-16 17:24:53.299330 | localhost | Interface IP: 199.204.45.155
2026-02-16 17:24:53.317313 | 
2026-02-16 17:24:53.317473 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:24:53.730157 | localhost -> localhost | changed
2026-02-16 17:24:53.737248 | 
2026-02-16 17:24:53.737354 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:24:54.552650 | localhost -> localhost | changed
2026-02-16 17:24:54.565338 | 
2026-02-16 17:24:54.565472 | PLAY [all]
2026-02-16 17:24:54.577981 | 
2026-02-16 17:24:54.578070 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:24:54.816695 | controller -> localhost | ok
2026-02-16 17:24:54.827971 | 
2026-02-16 17:24:54.828038 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:24:54.861300 | controller | ok
2026-02-16 17:24:54.877650 | controller | included: /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:24:54.883963 | 
2026-02-16 17:24:54.884025 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:24:56.051555 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:24:56.052499 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/work/014913dae4554b259c6c1fbfae27ae7c_id_rsa
2026-02-16 17:24:56.052534 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/work/014913dae4554b259c6c1fbfae27ae7c_id_rsa.pub
2026-02-16 17:24:56.052557 | controller -> localhost | The key fingerprint is:
2026-02-16 17:24:56.052579 | controller -> localhost | SHA256:k7MPRoq6rOhKlEr0ez2V7Ph41gfYYbS5WxwX6m11678 zuul-build-sshkey
2026-02-16 17:24:56.052611 | controller -> localhost | The key's randomart image is:
2026-02-16 17:24:56.052633 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:24:56.052659 | controller -> localhost | |                 |
2026-02-16 17:24:56.052682 | controller -> localhost | |           .   . |
2026-02-16 17:24:56.052701 | controller -> localhost | | .        . o . .|
2026-02-16 17:24:56.052721 | controller -> localhost | |. o     ...= o .o|
2026-02-16 17:24:56.052740 | controller -> localhost | | + .    S++ = + +|
2026-02-16 17:24:56.052760 | controller -> localhost | |+   ...o+= + + + |
2026-02-16 17:24:56.052779 | controller -> localhost | |.. ....++.. + o  |
2026-02-16 17:24:56.052798 | controller -> localhost | |o. ..  .+= o . . |
2026-02-16 17:24:56.052820 | controller -> localhost | |*o+.   .o.. .  E+|
2026-02-16 17:24:56.052840 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:24:56.052890 | controller -> localhost | ok: Runtime: 0:00:00.777836
2026-02-16 17:24:56.058932 | 
2026-02-16 17:24:56.059004 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:24:56.090597 | controller | ok
2026-02-16 17:24:56.100205 | controller | included: /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:24:56.108004 | 
2026-02-16 17:24:56.108095 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:24:56.132315 | controller | skipping: Conditional result was False
2026-02-16 17:24:56.139118 | 
2026-02-16 17:24:56.139204 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:24:56.690875 | controller | changed
2026-02-16 17:24:56.698789 | 
2026-02-16 17:24:56.698906 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:24:56.929237 | controller | ok
2026-02-16 17:24:56.938792 | 
2026-02-16 17:24:56.938904 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:24:57.512185 | controller | changed
2026-02-16 17:24:57.518072 | 
2026-02-16 17:24:57.518137 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:24:58.080010 | controller | changed
2026-02-16 17:24:58.087337 | 
2026-02-16 17:24:58.087415 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:24:58.113210 | controller | skipping: Conditional result was False
2026-02-16 17:24:58.123694 | 
2026-02-16 17:24:58.123772 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:24:58.467520 | controller -> localhost | changed
2026-02-16 17:24:58.482309 | 
2026-02-16 17:24:58.482403 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:24:58.802459 | controller -> localhost | Identity added: /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/work/014913dae4554b259c6c1fbfae27ae7c_id_rsa (zuul-build-sshkey)
2026-02-16 17:24:58.802700 | controller -> localhost | ok: Runtime: 0:00:00.013049
2026-02-16 17:24:58.876363 | 
2026-02-16 17:24:58.876545 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:24:59.185928 | controller | ok
2026-02-16 17:24:59.192690 | 
2026-02-16 17:24:59.192756 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:24:59.217867 | controller | skipping: Conditional result was False
2026-02-16 17:24:59.235012 | 
2026-02-16 17:24:59.235074 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:24:59.573356 | controller | ok
2026-02-16 17:24:59.579825 | 
2026-02-16 17:24:59.579887 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:25:22.387006 | controller | Output suppressed because no_log was given
2026-02-16 17:25:22.397312 | 
2026-02-16 17:25:22.397377 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:25:22.665610 | controller | ok: "logs"
2026-02-16 17:25:23.299636 | controller | ok: All items complete
2026-02-16 17:25:23.301286 | 
2026-02-16 17:25:23.311850 | controller | ok: "artifacts"
2026-02-16 17:25:23.319541 | controller | ok: "docs"
2026-02-16 17:25:23.338904 | 
2026-02-16 17:25:23.339065 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:25:23.569018 | controller | changed: "logs"
2026-02-16 17:25:23.765855 | controller | changed: "artifacts"
2026-02-16 17:25:24.069207 | controller | changed: "docs"
2026-02-16 17:25:24.086531 | 
2026-02-16 17:25:24.086713 | PLAY RECAP
2026-02-16 17:25:24.086779 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:25:24.086819 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:25:24.086848 | 
2026-02-16 17:25:24.219078 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:25:24.230402 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:25:24.872102 | 
2026-02-16 17:25:24.872246 | PLAY [all]
2026-02-16 17:25:24.890307 | 
2026-02-16 17:25:24.890388 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:25:25.290246 | controller | changed
2026-02-16 17:25:25.298411 | 
2026-02-16 17:25:25.298488 | TASK [Gather minimum local MTU]
2026-02-16 17:25:25.374811 | controller | ok
2026-02-16 17:25:25.381041 | 
2026-02-16 17:25:25.381128 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:25:25.436319 | controller | ok
2026-02-16 17:25:25.442067 | 
2026-02-16 17:25:25.442133 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:25:25.476668 | controller | skipping: Conditional result was False
2026-02-16 17:25:26.226376 | 
2026-02-16 17:25:26.226523 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:25:26.764360 | controller | ok: Runtime: 0:00:00.009924
2026-02-16 17:25:26.772159 | 
2026-02-16 17:25:26.772248 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:25:26.797233 | controller | skipping: Conditional result was False
2026-02-16 17:25:26.803792 | 
2026-02-16 17:25:26.803867 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:25:26.847204 | controller | skipping: Conditional result was False
2026-02-16 17:25:26.858884 | 
2026-02-16 17:25:26.858954 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:25:26.915679 | controller | ok
2026-02-16 17:25:26.927504 | controller | included: /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:25:26.933603 | 
2026-02-16 17:25:26.933665 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:25:26.989085 | controller | ok
2026-02-16 17:25:26.994695 | 
2026-02-16 17:25:26.994755 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:25:27.293160 | controller | ext4
2026-02-16 17:25:27.574834 | controller | ok: Runtime: 0:00:00.012749
2026-02-16 17:25:27.580405 | 
2026-02-16 17:25:27.580474 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:25:27.616274 | controller | ok
2026-02-16 17:25:27.621698 | 
2026-02-16 17:25:27.621763 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:25:27.656462 | controller | ok:
2026-02-16 17:25:27.656625 | controller | {
2026-02-16 17:25:27.656650 | controller |   "root_filesystem": "ext4"
2026-02-16 17:25:27.656670 | controller | }
2026-02-16 17:25:27.662677 | 
2026-02-16 17:25:27.662740 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:25:37.752515 | controller | 8192+0 records in
2026-02-16 17:25:37.752712 | controller | 8192+0 records out
2026-02-16 17:25:37.752735 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.83257 s, 874 MB/s
2026-02-16 17:25:38.212794 | controller | ok: Runtime: 0:00:09.841600
2026-02-16 17:25:38.220228 | 
2026-02-16 17:25:38.220316 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:25:38.501086 | controller | changed
2026-02-16 17:25:38.507580 | 
2026-02-16 17:25:38.507651 | TASK [configure-swap : Make swapfile]
2026-02-16 17:26:23.895586 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:26:23.895840 | controller | no label, UUID=12ea0b2b-dfa9-4828-b1f3-526a558ed7b0
2026-02-16 17:26:24.409338 | controller | ok: Runtime: 0:00:45.112043
2026-02-16 17:26:24.416947 | 
2026-02-16 17:26:24.417048 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:26:24.797898 | controller | changed
2026-02-16 17:26:24.803755 | 
2026-02-16 17:26:24.803820 | TASK [configure-swap : Add all swap]
2026-02-16 17:26:25.342175 | controller | ok: Runtime: 0:00:00.024955
2026-02-16 17:26:25.350035 | 
2026-02-16 17:26:25.350129 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:26:25.383863 | controller | ok:
2026-02-16 17:26:25.384028 | controller | {
2026-02-16 17:26:25.384062 | controller |   "swap_required": "8192"
2026-02-16 17:26:25.384089 | controller | }
2026-02-16 17:26:25.391077 | 
2026-02-16 17:26:25.391160 | TASK [configure-swap : Set swappiness]
2026-02-16 17:26:25.832728 | controller | changed
2026-02-16 17:26:25.839806 | 
2026-02-16 17:26:25.839878 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:26:25.874853 | controller | ok:
2026-02-16 17:26:25.875012 | controller | {
2026-02-16 17:26:25.875046 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:26:25.875081 | controller | }
2026-02-16 17:26:25.886922 | 
2026-02-16 17:26:25.886985 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:26:26.349272 | controller | changed
2026-02-16 17:26:26.356444 | 
2026-02-16 17:26:26.356602 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:26:26.605523 | controller | changed
2026-02-16 17:26:26.613624 | 
2026-02-16 17:26:26.613731 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:26:27.160357 | controller | changed
2026-02-16 17:26:27.166626 | 
2026-02-16 17:26:27.166699 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:26:27.405971 | controller | changed
2026-02-16 17:26:27.412347 | 
2026-02-16 17:26:27.412415 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:26:28.511162 | controller | changed
2026-02-16 17:26:28.519405 | 
2026-02-16 17:26:28.519472 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:26:28.757204 | controller | changed
2026-02-16 17:26:28.770899 | 
2026-02-16 17:26:28.770968 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:26:29.050410 | controller | changed
2026-02-16 17:26:29.055730 | 
2026-02-16 17:26:29.055801 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:26:29.411769 | controller | changed
2026-02-16 17:26:29.482978 | 
2026-02-16 17:26:29.483183 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:26:30.287225 | controller | changed
2026-02-16 17:26:30.299584 | 
2026-02-16 17:26:30.299654 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:26:30.640421 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:26:30.654775 | 
2026-02-16 17:26:30.654847 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:26:31.130977 | controller | ok: Item: Runtime: 0:00:00.193493
2026-02-16 17:26:31.702904 | controller | ok: Item: Runtime: 0:00:00.334805
2026-02-16 17:26:32.461279 | controller | ok: Item: Runtime: 0:00:00.521765
2026-02-16 17:26:32.742616 | controller | ok: Item: Runtime: 0:00:00.062230
2026-02-16 17:26:33.224274 | controller | ok: Item: Runtime: 0:00:00.263051
2026-02-16 17:26:34.817019 | controller | ok: Item: Runtime: 0:00:01.382835
2026-02-16 17:26:37.911479 | controller | ok: Item: Runtime: 0:00:02.878920
2026-02-16 17:26:38.211778 | controller | ok: Item: Runtime: 0:00:00.096447
2026-02-16 17:26:38.616971 | controller | ok: Item: Runtime: 0:00:00.183637
2026-02-16 17:26:38.950501 | controller | ok: Item: Runtime: 0:00:00.119481
2026-02-16 17:26:39.703207 | controller | ok: Item: Runtime: 0:00:00.533305
2026-02-16 17:26:40.430092 | controller | ok: Item: Runtime: 0:00:00.515941
2026-02-16 17:26:40.865788 | controller | ok: Item: Runtime: 0:00:00.224103
2026-02-16 17:26:41.306740 | controller | ok: Item: Runtime: 0:00:00.213696
2026-02-16 17:26:41.899987 | controller | ok: Item: Runtime: 0:00:00.366540
2026-02-16 17:26:43.267548 | controller | ok: Item: Runtime: 0:00:01.141889
2026-02-16 17:26:43.289271 | 
2026-02-16 17:26:43.289415 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:26:43.501401 | controller | ok: All paths examined
2026-02-16 17:26:43.509757 | 
2026-02-16 17:26:43.509821 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:26:43.746628 | controller | ok: All paths examined
2026-02-16 17:26:43.754962 | 
2026-02-16 17:26:43.755050 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:26:44.175774 | controller | ok: Item: Runtime: 0:00:00.145893
2026-02-16 17:26:44.176164 | controller | changed: All items complete
2026-02-16 17:26:44.176200 | 
2026-02-16 17:26:44.708870 | controller | ok: Item: Runtime: 0:00:00.317223
2026-02-16 17:26:44.726106 | 
2026-02-16 17:26:44.726315 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:26:44.768192 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.770433 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.773199 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.775777 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.778747 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.781784 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.785214 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.788357 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.791521 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.794343 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.797224 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.800116 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.803106 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.806198 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.809125 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.812613 | controller | skipping: Conditional result was False
2026-02-16 17:26:44.838239 | 
2026-02-16 17:26:44.838417 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:26:47.190727 | controller | changed
2026-02-16 17:26:47.209589 | 
2026-02-16 17:26:47.209664 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:26:47.455468 | controller | changed
2026-02-16 17:26:47.526526 | 
2026-02-16 17:26:47.526661 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:26:47.769771 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:26:48.068397 | controller | ERROR
2026-02-16 17:26:48.068534 | controller | {
2026-02-16 17:26:48.068562 | controller |   "delta": "0:00:00.009205",
2026-02-16 17:26:48.068583 | controller |   "end": "2026-02-16 17:26:47.770384",
2026-02-16 17:26:48.068601 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:48.068620 | controller |   "rc": 1,
2026-02-16 17:26:48.068638 | controller |   "start": "2026-02-16 17:26:47.761179"
2026-02-16 17:26:48.068655 | controller | }
2026-02-16 17:26:48.068679 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:48.074392 | 
2026-02-16 17:26:48.074464 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:26:48.321809 | controller | ok
2026-02-16 17:26:48.335363 | 
2026-02-16 17:26:48.335431 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:26:48.568597 | controller | /usr/bin/journalctl
2026-02-16 17:26:48.876576 | controller | ok: Runtime: 0:00:00.007181
2026-02-16 17:26:48.884254 | 
2026-02-16 17:26:48.884342 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:26:49.134535 | controller | 2026-02-16 17:26:49
2026-02-16 17:26:49.468813 | controller | ok: Runtime: 0:00:00.008366
2026-02-16 17:26:49.474693 | 
2026-02-16 17:26:49.474755 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:26:50.036488 | controller | changed
2026-02-16 17:26:50.043207 | 
2026-02-16 17:26:50.043278 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:26:50.068422 | controller | skipping: Conditional result was False
2026-02-16 17:26:50.077713 | 
2026-02-16 17:26:50.077831 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:26:50.610181 | controller | skipping: Conditional result was False
2026-02-16 17:26:50.620747 | 
2026-02-16 17:26:50.620840 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:26:51.151300 | controller | skipping: Conditional result was False
2026-02-16 17:26:51.161802 | 
2026-02-16 17:26:51.161874 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:26:51.186959 | controller | skipping: Conditional result was False
2026-02-16 17:26:51.196793 | 
2026-02-16 17:26:51.196883 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:26:51.733318 | controller | skipping: Conditional result was False
2026-02-16 17:26:51.740680 | 
2026-02-16 17:26:51.740751 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:26:52.271874 | controller | skipping: Conditional result was False
2026-02-16 17:26:52.280065 | 
2026-02-16 17:26:52.280136 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:26:52.306291 | controller | skipping: Conditional result was False
2026-02-16 17:26:52.315854 | 
2026-02-16 17:26:52.315944 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:26:52.340695 | controller | skipping: Conditional result was False
2026-02-16 17:26:52.352924 | 
2026-02-16 17:26:52.352996 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:26:52.930055 | controller | skipping: Conditional result was False
2026-02-16 17:26:52.941037 | 
2026-02-16 17:26:52.941135 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:26:53.473482 | controller | skipping: Conditional result was False
2026-02-16 17:26:53.485080 | 
2026-02-16 17:26:53.485227 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:26:53.510621 | controller | skipping: Conditional result was False
2026-02-16 17:26:53.521590 | 
2026-02-16 17:26:53.521659 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:26:53.545970 | controller | skipping: Conditional result was False
2026-02-16 17:26:53.560159 | 
2026-02-16 17:26:53.560246 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:26:53.947294 | controller | ok
2026-02-16 17:26:53.956822 | 
2026-02-16 17:26:53.956875 | PLAY RECAP
2026-02-16 17:26:53.956927 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:26:53.956949 | 
2026-02-16 17:26:54.106639 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:26:54.114703 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:54.696178 | 
2026-02-16 17:26:54.696315 | PLAY [all]
2026-02-16 17:26:54.708493 | 
2026-02-16 17:26:54.708580 | TASK [ensure-rust : Use rustup]
2026-02-16 17:26:54.741907 | controller | ok
2026-02-16 17:26:54.749793 | controller | included: /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:26:54.755374 | 
2026-02-16 17:26:54.755446 | TASK [ensure-rust : Install Rust]
2026-02-16 17:26:55.150715 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:26:55.293703 | controller | ERROR
2026-02-16 17:26:55.294029 | controller | {
2026-02-16 17:26:55.294082 | controller |   "delta": "0:00:00.010955",
2026-02-16 17:26:55.294122 | controller |   "end": "2026-02-16 17:26:55.153358",
2026-02-16 17:26:55.294153 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:55.294180 | controller |   "rc": 127,
2026-02-16 17:26:55.294205 | controller |   "start": "2026-02-16 17:26:55.142403"
2026-02-16 17:26:55.294241 | controller | }
failure
2026-02-16 17:26:55.297052 | 
2026-02-16 17:26:55.297118 | PLAY RECAP
2026-02-16 17:26:55.297193 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:26:55.297233 | 
2026-02-16 17:26:55.430966 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:55.441652 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:56.048276 | 
2026-02-16 17:26:56.048426 | PLAY [all]
2026-02-16 17:26:56.065049 | 
2026-02-16 17:26:56.065153 | TASK [Return built artifacts to Zuul]
2026-02-16 17:26:56.101839 | controller | ok
2026-02-16 17:26:56.109022 | 
2026-02-16 17:26:56.109106 | TASK [Get the Docker volume ID]
2026-02-16 17:26:56.644803 | controller | ERROR
2026-02-16 17:26:56.645171 | controller | {
2026-02-16 17:26:56.645216 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:26:56.645247 | controller |   "rc": 2
2026-02-16 17:26:56.645275 | controller | }
2026-02-16 17:26:56.645312 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:56.652892 | 
2026-02-16 17:26:56.652959 | TASK [Copy all of the pod logs]
2026-02-16 17:26:56.705865 | controller | ERROR
2026-02-16 17:26:56.706147 | controller | {
2026-02-16 17:26:56.706193 | 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/014913dae4554b259c6c1fbfae27ae7c/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:26:56.706218 | controller | }
2026-02-16 17:26:56.706246 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:56.707775 | 
2026-02-16 17:26:56.707822 | PLAY RECAP
2026-02-16 17:26:56.707872 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:26:56.707900 | 
2026-02-16 17:26:56.862943 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:56.867860 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:26:57.537913 | 
2026-02-16 17:26:57.538088 | PLAY [all]
2026-02-16 17:26:57.557299 | 
2026-02-16 17:26:57.557390 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:26:57.948556 | controller | changed
2026-02-16 17:26:57.956604 | 
2026-02-16 17:26:57.956680 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:26:59.001972 | controller | ok: Runtime: 0:00:00.458297
2026-02-16 17:26:59.009215 | 
2026-02-16 17:26:59.009298 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:26:59.549401 | controller | ok: Runtime: 0:00:00.018814
2026-02-16 17:26:59.555300 | 
2026-02-16 17:26:59.555371 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:27:00.091818 | controller | ok: Runtime: 0:00:00.017993
2026-02-16 17:27:00.100462 | 
2026-02-16 17:27:00.100623 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:27:00.970797 | controller | changed
2026-02-16 17:27:01.027884 | 
2026-02-16 17:27:01.028036 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:27:01.266896 | controller | changed
2026-02-16 17:27:01.272299 | 
2026-02-16 17:27:01.272365 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:27:01.592417 | controller | Output suppressed because no_log was given
2026-02-16 17:27:01.599038 | 
2026-02-16 17:27:01.599108 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:27:01.635456 | 
2026-02-16 17:27:01.635583 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:27:01.670041 | 
2026-02-16 17:27:01.670205 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:27:01.706435 | 
2026-02-16 17:27:01.706592 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:27:01.741433 | 
2026-02-16 17:27:01.741598 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:27:01.777586 | 
2026-02-16 17:27:01.777758 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:27:02.018099 | controller | changed
2026-02-16 17:27:02.024153 | 
2026-02-16 17:27:02.024220 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:27:02.059691 | controller | ok
2026-02-16 17:27:02.066967 | 
2026-02-16 17:27:02.067035 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:27:02.296384 | controller | Output suppressed because no_log was given
2026-02-16 17:27:02.301705 | 
2026-02-16 17:27:02.301777 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:27:02.339527 | 
2026-02-16 17:27:02.340405 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:27:02.381376 | 
2026-02-16 17:27:02.381502 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:27:03.040007 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:27:03.056403 | controller | Using python 3.12 to install setuptools
2026-02-16 17:27:03.091065 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:27:03.101610 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:27:03.425940 | controller | ERROR
2026-02-16 17:27:03.426268 | controller | {
2026-02-16 17:27:03.426325 | controller |   "delta": "0:00:00.473603",
2026-02-16 17:27:03.426356 | controller |   "end": "2026-02-16 17:27:03.103795",
2026-02-16 17:27:03.426383 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:03.426418 | controller |   "rc": 127,
2026-02-16 17:27:03.426444 | controller |   "start": "2026-02-16 17:27:02.630192"
2026-02-16 17:27:03.426469 | controller | }
2026-02-16 17:27:03.426505 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:03.440851 | 
2026-02-16 17:27:03.440941 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:27:03.680644 | controller | changed
2026-02-16 17:27:03.687612 | 
2026-02-16 17:27:03.687678 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:27:07.371766 | controller | Output suppressed because no_log was given
2026-02-16 17:27:07.385687 | 
2026-02-16 17:27:07.385783 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:27:07.421167 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.422716 | 
2026-02-16 17:27:07.424630 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.426229 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.427754 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.429376 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.431738 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.434234 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.436609 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.439055 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.441362 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.443851 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.446146 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.448466 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.450763 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.453048 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.455321 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.457619 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.460819 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.487198 | 
2026-02-16 17:27:07.487330 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:27:07.728744 | controller | ok
2026-02-16 17:27:07.735167 | 
2026-02-16 17:27:07.735330 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:27:08.316717 | controller | skipping: Conditional result was False
2026-02-16 17:27:08.329874 | 
2026-02-16 17:27:08.329956 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:27:08.586587 | controller | sudo: iptables-save: command not found
2026-02-16 17:27:08.625540 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:27:10.053608 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:27:10.367994 | controller | ok: Runtime: 0:00:01.484757
2026-02-16 17:27:10.379106 | 
2026-02-16 17:27:10.379175 | LOOP [stage-output : Register sources]
2026-02-16 17:27:20.180781 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.239295 | 
2026-02-16 17:27:20.239457 | TASK [stage-output : Check sudo]
2026-02-16 17:27:20.779645 | controller | ok: Runtime: 0:00:00.042650
2026-02-16 17:27:20.785409 | 
2026-02-16 17:27:20.785475 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:27:20.878498 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.879018 | 
2026-02-16 17:27:20.881069 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.881993 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.883209 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.921785 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.922164 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.922497 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.922824 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.924759 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.926936 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.929279 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.931350 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.933479 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.935907 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.955845 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.956187 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.956522 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.956844 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.957185 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.957503 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.959722 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.962069 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.964304 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.988578 | controller | Output suppressed because no_log was given
2026-02-16 17:27:20.988919 | controller | Output suppressed because no_log was given
2026-02-16 17:27:21.062676 | controller | Output suppressed because no_log was given
2026-02-16 17:27:21.076339 | controller | Output suppressed because no_log was given
2026-02-16 17:27:21.084737 | controller | Output suppressed because no_log was given
2026-02-16 17:27:21.095666 | controller | Output suppressed because no_log was given
2026-02-16 17:27:21.109090 | 
2026-02-16 17:27:21.109162 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:27:21.172995 | controller | ok
2026-02-16 17:27:21.180645 | 
2026-02-16 17:27:21.180711 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:27:21.443049 | controller | changed: "docs"
2026-02-16 17:27:21.658880 | controller | changed: "artifacts"
2026-02-16 17:27:21.871838 | controller | ok: "logs"
2026-02-16 17:27:21.885484 | 
2026-02-16 17:27:21.885661 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:27:22.220060 | controller | ok: Item: Runtime: 0:00:00.034193
2026-02-16 17:27:22.220451 | controller | changed: All items complete
2026-02-16 17:27:22.220501 | 
2026-02-16 17:27:22.462146 | controller | ok: Item: Runtime: 0:00:00.009524
2026-02-16 17:27:22.689473 | controller | ok: Item: Runtime: 0:00:00.010711
2026-02-16 17:27:22.933283 | controller | ok: Item: Runtime: 0:00:00.007958
2026-02-16 17:27:23.148817 | controller | ok: Item: Runtime: 0:00:00.007168
2026-02-16 17:27:23.418770 | controller | ok: Item: Runtime: 0:00:00.015973
2026-02-16 17:27:23.642247 | controller | ok: Item: Runtime: 0:00:00.007668
2026-02-16 17:27:23.863216 | controller | ok: Item: Runtime: 0:00:00.005884
2026-02-16 17:27:24.093720 | controller | ok: Item: Runtime: 0:00:00.007285
2026-02-16 17:27:24.351914 | controller | ok: Item: Runtime: 0:00:00.009038
2026-02-16 17:27:24.581775 | controller | ok: Item: Runtime: 0:00:00.008191
2026-02-16 17:27:24.849185 | controller | ok: Item: Runtime: 0:00:00.007564
2026-02-16 17:27:25.016056 | controller | ok: Item: Runtime: 0:00:00.008047
2026-02-16 17:27:25.249790 | controller | ok: Item: Runtime: 0:00:00.007112
2026-02-16 17:27:25.477195 | controller | ok: Item: Runtime: 0:00:00.007400
2026-02-16 17:27:25.704656 | controller | ok: Item: Runtime: 0:00:00.008196
2026-02-16 17:27:25.733258 | 
2026-02-16 17:27:25.733430 | TASK [stage-output : Make all log files readable]
2026-02-16 17:27:25.994070 | controller | changed
2026-02-16 17:27:26.002497 | 
2026-02-16 17:27:26.002588 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:27:26.332307 | controller | changed: Renamed files for staging.
2026-02-16 17:27:26.337949 | 
2026-02-16 17:27:26.338021 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:27:26.363452 | controller | skipping: Conditional result was False
2026-02-16 17:27:26.377119 | 
2026-02-16 17:27:26.377247 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:27:26.422330 | 
2026-02-16 17:27:26.422534 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:27:27.056451 | controller | changed:
2026-02-16 17:27:27.056696 | controller | created directory /var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/work/logs/controller
2026-02-16 17:27:27.056738 | controller | cd+++++++++ logs/
2026-02-16 17:27:27.056768 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:27:27.056796 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:27:27.056822 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:27:27.056848 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:27:27.056874 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:27:27.056899 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:27:27.056929 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:27:27.056978 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:27:27.057015 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:27:27.057042 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:27:27.057067 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:27:27.057093 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:27:27.057129 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:27:27.057175 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:27:27.057203 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:27:27.057230 | controller | cd+++++++++ logs/apache/
2026-02-16 17:27:27.057255 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:27:27.057280 | controller | cd+++++++++ logs/etc/
2026-02-16 17:27:27.057306 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:27:27.057331 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:27:27.057356 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:27:27.057382 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:27:27.057406 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:27:27.057431 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:27:27.072058 | 
2026-02-16 17:27:27.072127 | TASK [Check if a tempest log exits]
2026-02-16 17:27:27.344694 | controller | ok
2026-02-16 17:27:27.352254 | 
2026-02-16 17:27:27.352334 | TASK [Link post-devstack tempest.log]
2026-02-16 17:27:27.380828 | controller | skipping: Conditional result was False
2026-02-16 17:27:27.392982 | 
2026-02-16 17:27:27.393057 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:27:27.653960 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:27:27.939329 | controller | ERROR
2026-02-16 17:27:27.939622 | controller | {
2026-02-16 17:27:27.939674 | controller |   "delta": "0:00:00.008125",
2026-02-16 17:27:27.939706 | controller |   "end": "2026-02-16 17:27:27.654433",
2026-02-16 17:27:27.939781 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:27.939822 | controller |   "rc": 127,
2026-02-16 17:27:27.939850 | controller |   "start": "2026-02-16 17:27:27.646308"
2026-02-16 17:27:27.939875 | controller | }
2026-02-16 17:27:27.939909 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:27.942016 | 
2026-02-16 17:27:27.942084 | PLAY RECAP
2026-02-16 17:27:27.942151 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:27:27.942183 | 
2026-02-16 17:27:28.112203 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:27:28.142038 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:27:28.759766 | 
2026-02-16 17:27:28.759902 | PLAY [all]
2026-02-16 17:27:28.773116 | 
2026-02-16 17:27:28.773202 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:27:28.819279 | controller | skipping: Conditional result was False
2026-02-16 17:27:28.829750 | 
2026-02-16 17:27:28.829841 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:27:28.875634 | controller | ok
2026-02-16 17:27:28.882428 | 
2026-02-16 17:27:28.882501 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:27:29.264068 | controller -> localhost | ok: "/var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/work/logs"
2026-02-16 17:27:29.486505 | controller -> localhost | changed: "/var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/work/artifacts"
2026-02-16 17:27:29.688323 | controller -> localhost | changed: "/var/lib/zuul/builds/014913dae4554b259c6c1fbfae27ae7c/work/docs"
2026-02-16 17:27:29.710099 | 
2026-02-16 17:27:29.710238 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:27:30.351570 | controller | changed: .d..t...... ./
2026-02-16 17:27:30.351835 | controller | changed: All items complete
2026-02-16 17:27:30.351873 | 
2026-02-16 17:27:30.833729 | controller | changed: .d..t...... ./
2026-02-16 17:27:31.325689 | controller | changed: .d..t...... ./
2026-02-16 17:27:31.349321 | 
2026-02-16 17:27:31.349505 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:27:31.834878 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.019778
2026-02-16 17:27:32.084581 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007802
2026-02-16 17:27:32.099233 | 
2026-02-16 17:27:32.099397 | PLAY [all]
2026-02-16 17:27:32.108009 | 
2026-02-16 17:27:32.108079 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:27:32.579438 | controller | changed
2026-02-16 17:27:32.585424 | 
2026-02-16 17:27:32.585476 | PLAY RECAP
2026-02-16 17:27:32.585525 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:27:32.585546 | 
2026-02-16 17:27:32.707196 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:27:32.717895 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:27:33.325834 | 
2026-02-16 17:27:33.325973 | PLAY [localhost]
2026-02-16 17:27:33.336606 | 
2026-02-16 17:27:33.336679 | TASK [Generate Zuul manifest]
2026-02-16 17:27:33.359743 | localhost | ok
2026-02-16 17:27:33.377758 | 
2026-02-16 17:27:33.377839 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:27:33.744147 | localhost | changed
2026-02-16 17:27:33.758257 | 
2026-02-16 17:27:33.758331 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:27:33.790275 | localhost | ok
2026-02-16 17:27:33.797089 | 
2026-02-16 17:27:33.797152 | TASK [Upload logs]
2026-02-16 17:27:33.818586 | localhost | ok
2026-02-16 17:27:33.907299 | 
2026-02-16 17:27:33.907440 | TASK [Set zuul-log-path fact]
2026-02-16 17:27:33.929401 | localhost | ok
2026-02-16 17:27:33.944525 | 
2026-02-16 17:27:33.944600 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:27:33.978917 | localhost | ok
2026-02-16 17:27:33.986075 | 
2026-02-16 17:27:33.986264 | TASK [upload-logs : Create log directories]
2026-02-16 17:27:34.403636 | localhost | changed
2026-02-16 17:27:34.410408 | 
2026-02-16 17:27:34.410504 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:27:34.852770 | localhost -> localhost | ok: Runtime: 0:00:00.005658
2026-02-16 17:27:34.857615 | 
2026-02-16 17:27:34.857679 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:27:35.274358 | localhost | Output suppressed because no_log was given
2026-02-16 17:27:35.278820 | 
2026-02-16 17:27:35.278887 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:27:35.325100 | localhost | skipping: Conditional result was False
2026-02-16 17:27:35.330724 | localhost | skipping: Conditional result was False
2026-02-16 17:27:35.345701 | 
2026-02-16 17:27:35.345904 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:27:35.387904 | localhost | skipping: Conditional result was False
2026-02-16 17:27:35.388472 | 
2026-02-16 17:27:35.390760 | localhost | skipping: Conditional result was False
2026-02-16 17:27:35.401431 | 
2026-02-16 17:27:35.401596 | LOOP [upload-logs : Upload console log and json output]