2026-02-16 17:20:41.808916 | Job console starting
2026-02-16 17:20:41.986815 | Updating git repos
2026-02-16 17:20:49.046294 | Cloning repos into workspace
2026-02-16 17:20:56.429303 | Restoring repo states
2026-02-16 17:20:56.783350 | Merging changes
2026-02-16 17:20:57.417661 | Checking out repos
2026-02-16 17:21:01.930769 | Preparing playbooks
2026-02-16 17:21:03.975929 | Running Ansible setup
2026-02-16 17:21:08.195373 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:08.836331 | 
2026-02-16 17:21:08.836824 | PLAY [localhost]
2026-02-16 17:21:08.845989 | 
2026-02-16 17:21:08.846095 | TASK [Gathering Facts]
2026-02-16 17:21:09.784756 | localhost | ok
2026-02-16 17:21:09.794293 | 
2026-02-16 17:21:09.794403 | TASK [Setup log path fact]
2026-02-16 17:21:09.814565 | localhost | ok
2026-02-16 17:21:09.827789 | 
2026-02-16 17:21:09.827862 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:21:09.869496 | localhost | ok
2026-02-16 17:21:09.947439 | 
2026-02-16 17:21:09.947617 | TASK [emit-job-header : Print job information]
2026-02-16 17:21:09.991185 | # Job Information
2026-02-16 17:21:09.991332 | Ansible Version: 2.16.15
2026-02-16 17:21:09.991376 | Job: magnum-cluster-api-sonobuoy-v1.34.4-cilium
2026-02-16 17:21:09.991406 | Pipeline: check
2026-02-16 17:21:09.991433 | Executor: 3a2793d2bd32
2026-02-16 17:21:09.991459 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:21:09.991489 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:21:09.995350 | 
2026-02-16 17:21:09.995439 | LOOP [emit-job-header : Print node information]
2026-02-16 17:21:10.094833 | localhost | ok:
2026-02-16 17:21:10.095468 | localhost | # Node Information
2026-02-16 17:21:10.095520 | localhost | Inventory Hostname: controller
2026-02-16 17:21:10.095596 | localhost | Hostname: np0000155589
2026-02-16 17:21:10.095633 | localhost | Username: zuul
2026-02-16 17:21:10.095666 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:21:10.095695 | localhost | Provider: yul1
2026-02-16 17:21:10.095721 | localhost | Region: ca-ymq-1
2026-02-16 17:21:10.095746 | localhost | Label: ubuntu-noble-16
2026-02-16 17:21:10.095771 | localhost | Product Name: OpenStack Nova
2026-02-16 17:21:10.095797 | localhost | Interface IP: 199.204.45.53
2026-02-16 17:21:10.114651 | 
2026-02-16 17:21:10.114787 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:21:10.562352 | localhost -> localhost | changed
2026-02-16 17:21:10.572349 | 
2026-02-16 17:21:10.572480 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:21:11.578138 | localhost -> localhost | changed
2026-02-16 17:21:11.586523 | 
2026-02-16 17:21:11.586602 | PLAY [all]
2026-02-16 17:21:11.598692 | 
2026-02-16 17:21:11.598760 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:21:11.900112 | controller -> localhost | ok
2026-02-16 17:21:11.911178 | 
2026-02-16 17:21:11.911300 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:21:11.947080 | controller | ok
2026-02-16 17:21:11.964934 | controller | included: /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:21:11.971225 | 
2026-02-16 17:21:11.971292 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:21:12.840598 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:21:12.840904 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/work/592dad0332fa4d81ab5680373143afe5_id_rsa
2026-02-16 17:21:12.840998 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/work/592dad0332fa4d81ab5680373143afe5_id_rsa.pub
2026-02-16 17:21:12.841054 | controller -> localhost | The key fingerprint is:
2026-02-16 17:21:12.841103 | controller -> localhost | SHA256:8W3PWn2Gj3+xWjW1UKmtCA6/p1IcaNIiloStUbHTEgQ zuul-build-sshkey
2026-02-16 17:21:12.841170 | controller -> localhost | The key's randomart image is:
2026-02-16 17:21:12.841262 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:21:12.841317 | controller -> localhost | | E*=.          ..|
2026-02-16 17:21:12.841365 | controller -> localhost | | o o+         .. |
2026-02-16 17:21:12.841410 | controller -> localhost | |  ++... o    .o .|
2026-02-16 17:21:12.841454 | controller -> localhost | | . +oo = = . ...o|
2026-02-16 17:21:12.841498 | controller -> localhost | |  . . + S + + .o.|
2026-02-16 17:21:12.841541 | controller -> localhost | |         = o + +o|
2026-02-16 17:21:12.841584 | controller -> localhost | |        . .   =.*|
2026-02-16 17:21:12.841628 | controller -> localhost | |       . . . o.=o|
2026-02-16 17:21:12.841671 | controller -> localhost | |        ..o ..o.+|
2026-02-16 17:21:12.841695 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:21:12.841769 | controller -> localhost | ok: Runtime: 0:00:00.434633
2026-02-16 17:21:12.848067 | 
2026-02-16 17:21:12.848161 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:21:12.884882 | controller | ok
2026-02-16 17:21:12.896118 | controller | included: /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:21:12.904005 | 
2026-02-16 17:21:12.904066 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:21:12.929091 | controller | skipping: Conditional result was False
2026-02-16 17:21:12.940784 | 
2026-02-16 17:21:12.940888 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:21:13.514580 | controller | changed
2026-02-16 17:21:13.523616 | 
2026-02-16 17:21:13.523684 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:21:13.754135 | controller | ok
2026-02-16 17:21:13.760598 | 
2026-02-16 17:21:13.760669 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:21:14.350538 | controller | changed
2026-02-16 17:21:14.358665 | 
2026-02-16 17:21:14.358735 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:21:14.923689 | controller | changed
2026-02-16 17:21:14.933677 | 
2026-02-16 17:21:14.933773 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:21:14.959047 | controller | skipping: Conditional result was False
2026-02-16 17:21:14.968648 | 
2026-02-16 17:21:14.968738 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:21:15.339615 | controller -> localhost | changed
2026-02-16 17:21:15.358377 | 
2026-02-16 17:21:15.358449 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:21:15.712996 | controller -> localhost | Identity added: /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/work/592dad0332fa4d81ab5680373143afe5_id_rsa (zuul-build-sshkey)
2026-02-16 17:21:15.713251 | controller -> localhost | ok: Runtime: 0:00:00.014720
2026-02-16 17:21:15.759901 | 
2026-02-16 17:21:15.760100 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:21:16.078582 | controller | ok
2026-02-16 17:21:16.084001 | 
2026-02-16 17:21:16.084073 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:21:16.109689 | controller | skipping: Conditional result was False
2026-02-16 17:21:16.127088 | 
2026-02-16 17:21:16.127177 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:21:16.476380 | controller | ok
2026-02-16 17:21:16.484540 | 
2026-02-16 17:21:16.484672 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:21:45.284117 | controller | Output suppressed because no_log was given
2026-02-16 17:21:45.295421 | 
2026-02-16 17:21:45.295484 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:21:45.525964 | controller | ok: "logs"
2026-02-16 17:21:45.526217 | controller | ok: All items complete
2026-02-16 17:21:45.526245 | 
2026-02-16 17:21:45.718366 | controller | ok: "artifacts"
2026-02-16 17:21:45.908284 | controller | ok: "docs"
2026-02-16 17:21:45.928546 | 
2026-02-16 17:21:45.928735 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:21:46.153291 | controller | changed: "logs"
2026-02-16 17:21:46.344072 | controller | changed: "artifacts"
2026-02-16 17:21:46.563306 | controller | changed: "docs"
2026-02-16 17:21:46.584289 | 
2026-02-16 17:21:46.584427 | PLAY RECAP
2026-02-16 17:21:46.584475 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:21:46.584503 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:21:46.584524 | 
2026-02-16 17:21:46.738235 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:21:46.743290 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:21:47.362857 | 
2026-02-16 17:21:47.362985 | PLAY [all]
2026-02-16 17:21:47.380219 | 
2026-02-16 17:21:47.380294 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:21:47.765392 | controller | changed
2026-02-16 17:21:47.772390 | 
2026-02-16 17:21:47.772531 | TASK [Gather minimum local MTU]
2026-02-16 17:21:47.845656 | controller | ok
2026-02-16 17:21:47.851193 | 
2026-02-16 17:21:47.851290 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:21:47.905363 | controller | ok
2026-02-16 17:21:47.912034 | 
2026-02-16 17:21:47.912102 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:21:47.947315 | controller | skipping: Conditional result was False
2026-02-16 17:21:47.957743 | 
2026-02-16 17:21:47.957836 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:21:48.498050 | controller | ok: Runtime: 0:00:00.009753
2026-02-16 17:21:48.504754 | 
2026-02-16 17:21:48.504827 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:21:48.530561 | controller | skipping: Conditional result was False
2026-02-16 17:21:48.542002 | 
2026-02-16 17:21:48.542106 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:21:48.568926 | controller | skipping: Conditional result was False
2026-02-16 17:21:48.580669 | 
2026-02-16 17:21:48.580742 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:21:48.649663 | controller | ok
2026-02-16 17:21:48.663379 | controller | included: /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:21:48.671597 | 
2026-02-16 17:21:48.671663 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:21:48.738976 | controller | ok
2026-02-16 17:21:48.745374 | 
2026-02-16 17:21:48.745438 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:21:49.035635 | controller | ext4
2026-02-16 17:21:49.329129 | controller | ok: Runtime: 0:00:00.011825
2026-02-16 17:21:49.334558 | 
2026-02-16 17:21:49.334621 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:21:49.369012 | controller | ok
2026-02-16 17:21:49.376741 | 
2026-02-16 17:21:49.376843 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:21:49.416660 | controller | ok:
2026-02-16 17:21:49.416985 | controller | {
2026-02-16 17:21:49.417038 | controller |   "root_filesystem": "ext4"
2026-02-16 17:21:49.417223 | controller | }
2026-02-16 17:21:49.428713 | 
2026-02-16 17:21:49.428914 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:21:59.451477 | controller | 8192+0 records in
2026-02-16 17:21:59.451608 | controller | 8192+0 records out
2026-02-16 17:21:59.451628 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.75476 s, 881 MB/s
2026-02-16 17:21:59.498913 | controller | ok: Runtime: 0:00:09.763474
2026-02-16 17:21:59.504394 | 
2026-02-16 17:21:59.504456 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:21:59.742920 | controller | changed
2026-02-16 17:21:59.752703 | 
2026-02-16 17:21:59.752795 | TASK [configure-swap : Make swapfile]
2026-02-16 17:22:11.009277 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:22:11.009482 | controller | no label, UUID=6afc4f6e-0f33-498d-870f-b367c204d0fa
2026-02-16 17:22:11.298848 | controller | ok: Runtime: 0:00:11.023311
2026-02-16 17:22:11.306889 | 
2026-02-16 17:22:11.306979 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:22:11.737937 | controller | changed
2026-02-16 17:22:11.743574 | 
2026-02-16 17:22:11.743639 | TASK [configure-swap : Add all swap]
2026-02-16 17:22:12.284128 | controller | ok: Runtime: 0:00:00.022409
2026-02-16 17:22:12.292044 | 
2026-02-16 17:22:12.292139 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:22:12.337988 | controller | ok:
2026-02-16 17:22:12.338142 | controller | {
2026-02-16 17:22:12.338177 | controller |   "swap_required": "8192"
2026-02-16 17:22:12.338204 | controller | }
2026-02-16 17:22:12.345025 | 
2026-02-16 17:22:12.345110 | TASK [configure-swap : Set swappiness]
2026-02-16 17:22:12.744738 | controller | changed
2026-02-16 17:22:12.751503 | 
2026-02-16 17:22:12.751568 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:22:12.786301 | controller | ok:
2026-02-16 17:22:12.786457 | controller | {
2026-02-16 17:22:12.786493 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:22:12.786529 | controller | }
2026-02-16 17:22:12.799071 | 
2026-02-16 17:22:12.799135 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:22:13.248164 | controller | changed
2026-02-16 17:22:13.257071 | 
2026-02-16 17:22:13.257166 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:22:13.517122 | controller | changed
2026-02-16 17:22:13.524083 | 
2026-02-16 17:22:13.524149 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:22:14.069700 | controller | changed
2026-02-16 17:22:14.076165 | 
2026-02-16 17:22:14.076460 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:22:14.328601 | controller | changed
2026-02-16 17:22:14.337077 | 
2026-02-16 17:22:14.337164 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:22:15.384742 | controller | changed
2026-02-16 17:22:15.392806 | 
2026-02-16 17:22:15.392870 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:22:15.632486 | controller | changed
2026-02-16 17:22:15.645082 | 
2026-02-16 17:22:15.645185 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:22:15.932184 | controller | changed
2026-02-16 17:22:15.939276 | 
2026-02-16 17:22:15.939362 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:22:16.328878 | controller | changed
2026-02-16 17:22:16.379955 | 
2026-02-16 17:22:16.380116 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:22:17.146471 | controller | changed
2026-02-16 17:22:17.160254 | 
2026-02-16 17:22:17.160354 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:22:17.512089 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:22:17.526707 | 
2026-02-16 17:22:17.526785 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:22:18.021984 | controller | ok: Item: Runtime: 0:00:00.193268
2026-02-16 17:22:18.593140 | controller | ok: Item: Runtime: 0:00:00.352751
2026-02-16 17:22:19.342619 | controller | ok: Item: Runtime: 0:00:00.536252
2026-02-16 17:22:19.631797 | controller | ok: Item: Runtime: 0:00:00.062250
2026-02-16 17:22:20.121620 | controller | ok: Item: Runtime: 0:00:00.270264
2026-02-16 17:22:21.680756 | controller | ok: Item: Runtime: 0:00:01.317203
2026-02-16 17:22:25.196291 | controller | ok: Item: Runtime: 0:00:03.288086
2026-02-16 17:22:25.506904 | controller | ok: Item: Runtime: 0:00:00.097079
2026-02-16 17:22:25.912409 | controller | ok: Item: Runtime: 0:00:00.187292
2026-02-16 17:22:26.249118 | controller | ok: Item: Runtime: 0:00:00.120343
2026-02-16 17:22:27.000315 | controller | ok: Item: Runtime: 0:00:00.529064
2026-02-16 17:22:27.702311 | controller | ok: Item: Runtime: 0:00:00.488314
2026-02-16 17:22:28.159686 | controller | ok: Item: Runtime: 0:00:00.241885
2026-02-16 17:22:28.593349 | controller | ok: Item: Runtime: 0:00:00.218377
2026-02-16 17:22:29.165082 | controller | ok: Item: Runtime: 0:00:00.355812
2026-02-16 17:22:30.529466 | controller | ok: Item: Runtime: 0:00:01.147169
2026-02-16 17:22:30.561711 | 
2026-02-16 17:22:30.561870 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:22:30.782888 | controller | ok: All paths examined
2026-02-16 17:22:30.790187 | 
2026-02-16 17:22:30.790276 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:22:31.030221 | controller | ok: All paths examined
2026-02-16 17:22:31.036783 | 
2026-02-16 17:22:31.036863 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:22:31.492078 | controller | ok: Item: Runtime: 0:00:00.149742
2026-02-16 17:22:31.492419 | controller | changed: All items complete
2026-02-16 17:22:31.492454 | 
2026-02-16 17:22:31.880681 | controller | ok: Item: Runtime: 0:00:00.167974
2026-02-16 17:22:31.890887 | 
2026-02-16 17:22:31.891068 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:22:31.934002 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.936128 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.939278 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.942308 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.945389 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.948502 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.951777 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.955017 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.958372 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.961443 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.964444 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.967510 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.970698 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.973787 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.976778 | controller | skipping: Conditional result was False
2026-02-16 17:22:31.980148 | controller | skipping: Conditional result was False
2026-02-16 17:22:32.010179 | 
2026-02-16 17:22:32.010359 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:22:34.377973 | controller | changed
2026-02-16 17:22:34.388510 | 
2026-02-16 17:22:34.388574 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:22:34.643862 | controller | changed
2026-02-16 17:22:34.696983 | 
2026-02-16 17:22:34.697133 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:22:34.956593 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:22:35.241124 | controller | ERROR
2026-02-16 17:22:35.241387 | controller | {
2026-02-16 17:22:35.241416 | controller |   "delta": "0:00:00.009560",
2026-02-16 17:22:35.241438 | controller |   "end": "2026-02-16 17:22:34.957251",
2026-02-16 17:22:35.241457 | controller |   "msg": "non-zero return code",
2026-02-16 17:22:35.241475 | controller |   "rc": 1,
2026-02-16 17:22:35.241494 | controller |   "start": "2026-02-16 17:22:34.947691"
2026-02-16 17:22:35.241512 | controller | }
2026-02-16 17:22:35.241537 | controller | ERROR: Ignoring Errors
2026-02-16 17:22:35.247337 | 
2026-02-16 17:22:35.247409 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:22:35.504809 | controller | ok
2026-02-16 17:22:35.517151 | 
2026-02-16 17:22:35.517251 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:22:35.743405 | controller | /usr/bin/journalctl
2026-02-16 17:22:36.063600 | controller | ok: Runtime: 0:00:00.007226
2026-02-16 17:22:36.070229 | 
2026-02-16 17:22:36.070302 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:22:36.327984 | controller | 2026-02-16 17:22:36
2026-02-16 17:22:36.612394 | controller | ok: Runtime: 0:00:00.013399
2026-02-16 17:22:36.619390 | 
2026-02-16 17:22:36.619493 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:22:37.179816 | controller | changed
2026-02-16 17:22:37.187949 | 
2026-02-16 17:22:37.188018 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:22:37.215779 | controller | skipping: Conditional result was False
2026-02-16 17:22:37.222410 | 
2026-02-16 17:22:37.222477 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:22:37.756782 | controller | skipping: Conditional result was False
2026-02-16 17:22:37.768174 | 
2026-02-16 17:22:37.768243 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:22:38.300798 | controller | skipping: Conditional result was False
2026-02-16 17:22:38.313499 | 
2026-02-16 17:22:38.313569 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:22:38.338464 | controller | skipping: Conditional result was False
2026-02-16 17:22:38.349293 | 
2026-02-16 17:22:38.349387 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:22:38.883894 | controller | skipping: Conditional result was False
2026-02-16 17:22:38.893288 | 
2026-02-16 17:22:38.893387 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:22:39.428626 | controller | skipping: Conditional result was False
2026-02-16 17:22:39.441334 | 
2026-02-16 17:22:39.441407 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:22:39.467073 | controller | skipping: Conditional result was False
2026-02-16 17:22:39.478238 | 
2026-02-16 17:22:39.478322 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:22:39.503499 | controller | skipping: Conditional result was False
2026-02-16 17:22:39.513711 | 
2026-02-16 17:22:39.513824 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:22:40.120606 | controller | skipping: Conditional result was False
2026-02-16 17:22:40.133214 | 
2026-02-16 17:22:40.133290 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:22:40.663293 | controller | skipping: Conditional result was False
2026-02-16 17:22:40.674115 | 
2026-02-16 17:22:40.674188 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:22:40.699126 | controller | skipping: Conditional result was False
2026-02-16 17:22:40.708576 | 
2026-02-16 17:22:40.708663 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:22:40.733624 | controller | skipping: Conditional result was False
2026-02-16 17:22:40.749585 | 
2026-02-16 17:22:40.749710 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:22:41.152757 | controller | ok
2026-02-16 17:22:41.161466 | 
2026-02-16 17:22:41.161874 | PLAY RECAP
2026-02-16 17:22:41.161920 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:22:41.161943 | 
2026-02-16 17:22:41.306112 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:22:41.315898 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:22:41.913848 | 
2026-02-16 17:22:41.913994 | PLAY [all]
2026-02-16 17:22:41.925800 | 
2026-02-16 17:22:41.925879 | TASK [ensure-rust : Use rustup]
2026-02-16 17:22:41.955540 | controller | ok
2026-02-16 17:22:41.962663 | controller | included: /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:22:41.968415 | 
2026-02-16 17:22:41.968492 | TASK [ensure-rust : Install Rust]
2026-02-16 17:22:42.357891 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:22:42.508405 | controller | ERROR
2026-02-16 17:22:42.508668 | controller | {
2026-02-16 17:22:42.508710 | controller |   "delta": "0:00:00.012040",
2026-02-16 17:22:42.508741 | controller |   "end": "2026-02-16 17:22:42.360821",
2026-02-16 17:22:42.508768 | controller |   "msg": "non-zero return code",
2026-02-16 17:22:42.508795 | controller |   "rc": 127,
2026-02-16 17:22:42.508819 | controller |   "start": "2026-02-16 17:22:42.348781"
2026-02-16 17:22:42.508848 | controller | }
failure
2026-02-16 17:22:42.510742 | 
2026-02-16 17:22:42.510813 | PLAY RECAP
2026-02-16 17:22:42.510874 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:22:42.510903 | 
2026-02-16 17:22:42.629277 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:22:42.635376 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:22:43.213457 | 
2026-02-16 17:22:43.213602 | PLAY [all]
2026-02-16 17:22:43.229536 | 
2026-02-16 17:22:43.229621 | TASK [Return built artifacts to Zuul]
2026-02-16 17:22:43.262307 | controller | ok
2026-02-16 17:22:43.268316 | 
2026-02-16 17:22:43.268390 | TASK [Get the Docker volume ID]
2026-02-16 17:22:43.811248 | controller | ERROR
2026-02-16 17:22:43.811641 | controller | {
2026-02-16 17:22:43.811689 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:22:43.811723 | controller |   "rc": 2
2026-02-16 17:22:43.811752 | controller | }
2026-02-16 17:22:43.811790 | controller | ERROR: Ignoring Errors
2026-02-16 17:22:43.819408 | 
2026-02-16 17:22:43.819476 | TASK [Copy all of the pod logs]
2026-02-16 17:22:43.854397 | controller | ERROR
2026-02-16 17:22:43.854621 | controller | {
2026-02-16 17:22:43.854663 | 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/592dad0332fa4d81ab5680373143afe5/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:22:43.854694 | controller | }
2026-02-16 17:22:43.854732 | controller | ERROR: Ignoring Errors
2026-02-16 17:22:43.856071 | 
2026-02-16 17:22:43.856139 | PLAY RECAP
2026-02-16 17:22:43.856203 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:22:43.856233 | 
2026-02-16 17:22:44.039554 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:22:44.048421 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:22:44.662708 | 
2026-02-16 17:22:44.662870 | PLAY [all]
2026-02-16 17:22:44.681552 | 
2026-02-16 17:22:44.681641 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:22:45.082175 | controller | changed
2026-02-16 17:22:45.090421 | 
2026-02-16 17:22:45.090500 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:22:46.132461 | controller | ok: Runtime: 0:00:00.473296
2026-02-16 17:22:46.142122 | 
2026-02-16 17:22:46.142213 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:22:46.683228 | controller | ok: Runtime: 0:00:00.023529
2026-02-16 17:22:46.689892 | 
2026-02-16 17:22:46.689962 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:22:47.230277 | controller | ok: Runtime: 0:00:00.023065
2026-02-16 17:22:47.237025 | 
2026-02-16 17:22:47.237091 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:22:48.094918 | controller | changed
2026-02-16 17:22:48.141617 | 
2026-02-16 17:22:48.141770 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:22:48.390507 | controller | changed
2026-02-16 17:22:48.397346 | 
2026-02-16 17:22:48.397413 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:22:48.732771 | controller | Output suppressed because no_log was given
2026-02-16 17:22:48.738618 | 
2026-02-16 17:22:48.738687 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:22:48.774477 | 
2026-02-16 17:22:48.774648 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:22:48.810513 | 
2026-02-16 17:22:48.810648 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:22:48.839218 | 
2026-02-16 17:22:48.839393 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:22:48.877433 | 
2026-02-16 17:22:48.877576 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:22:48.915014 | 
2026-02-16 17:22:48.915193 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:22:49.165804 | controller | changed
2026-02-16 17:22:49.171782 | 
2026-02-16 17:22:49.171853 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:22:49.218012 | controller | ok
2026-02-16 17:22:49.223566 | 
2026-02-16 17:22:49.223630 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:22:49.479940 | controller | Output suppressed because no_log was given
2026-02-16 17:22:49.486216 | 
2026-02-16 17:22:49.486284 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:22:49.524834 | 
2026-02-16 17:22:49.525054 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:22:49.567884 | 
2026-02-16 17:22:49.568106 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:22:50.252319 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:22:50.271988 | controller | Using python 3.12 to install setuptools
2026-02-16 17:22:50.314028 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:22:50.326289 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:22:50.610083 | controller | ERROR
2026-02-16 17:22:50.610347 | controller | {
2026-02-16 17:22:50.610402 | controller |   "delta": "0:00:00.506255",
2026-02-16 17:22:50.610435 | controller |   "end": "2026-02-16 17:22:50.328879",
2026-02-16 17:22:50.610462 | controller |   "msg": "non-zero return code",
2026-02-16 17:22:50.610491 | controller |   "rc": 127,
2026-02-16 17:22:50.610519 | controller |   "start": "2026-02-16 17:22:49.822624"
2026-02-16 17:22:50.610545 | controller | }
2026-02-16 17:22:50.610582 | controller | ERROR: Ignoring Errors
2026-02-16 17:22:50.623070 | 
2026-02-16 17:22:50.623144 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:22:50.871528 | controller | changed
2026-02-16 17:22:50.877467 | 
2026-02-16 17:22:50.877543 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:22:54.613798 | controller | Output suppressed because no_log was given
2026-02-16 17:22:54.625964 | 
2026-02-16 17:22:54.626032 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:22:54.658623 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.659965 | 
2026-02-16 17:22:54.662216 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.663288 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.664324 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.666516 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.669123 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.671309 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.673944 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.676445 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.678988 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.681664 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.684182 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.686545 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.688818 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.691414 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.693966 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.708419 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.709213 | controller | skipping: Conditional result was False
2026-02-16 17:22:54.739163 | 
2026-02-16 17:22:54.739315 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:22:54.975997 | controller | ok
2026-02-16 17:22:54.982280 | 
2026-02-16 17:22:54.982360 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:22:55.558792 | controller | skipping: Conditional result was False
2026-02-16 17:22:55.576286 | 
2026-02-16 17:22:55.576370 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:22:55.838418 | controller | sudo: iptables-save: command not found
2026-02-16 17:22:55.880688 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:22:56.974913 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:22:57.117227 | controller | ok: Runtime: 0:00:01.160617
2026-02-16 17:22:57.127565 | 
2026-02-16 17:22:57.127631 | LOOP [stage-output : Register sources]
2026-02-16 17:23:06.357462 | controller | Output suppressed because no_log was given
2026-02-16 17:23:06.423975 | 
2026-02-16 17:23:06.424142 | TASK [stage-output : Check sudo]
2026-02-16 17:23:06.979049 | controller | ok: Runtime: 0:00:00.021050
2026-02-16 17:23:06.986145 | 
2026-02-16 17:23:06.986217 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:23:07.087531 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.087858 | 
2026-02-16 17:23:07.088826 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.091155 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.093471 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.129964 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.130340 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.130909 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.133175 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.135543 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.137829 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.140046 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.142642 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.144812 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.147115 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.164784 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.165386 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.165855 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.166305 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.167091 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.169265 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.171552 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.173917 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.176087 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.201193 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.201673 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.276629 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.288510 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.300085 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.310902 | controller | Output suppressed because no_log was given
2026-02-16 17:23:07.323860 | 
2026-02-16 17:23:07.323933 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:23:07.389924 | controller | ok
2026-02-16 17:23:07.398981 | 
2026-02-16 17:23:07.399071 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:23:07.642744 | controller | changed: "docs"
2026-02-16 17:23:07.847119 | controller | changed: "artifacts"
2026-02-16 17:23:08.044087 | controller | ok: "logs"
2026-02-16 17:23:08.057653 | 
2026-02-16 17:23:08.057808 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:23:08.335624 | controller | ok: Item: Runtime: 0:00:00.011847
2026-02-16 17:23:08.335864 | controller | changed: All items complete
2026-02-16 17:23:08.335892 | 
2026-02-16 17:23:08.559195 | controller | ok: Item: Runtime: 0:00:00.008855
2026-02-16 17:23:08.780539 | controller | ok: Item: Runtime: 0:00:00.008126
2026-02-16 17:23:09.002369 | controller | ok: Item: Runtime: 0:00:00.007643
2026-02-16 17:23:09.229068 | controller | ok: Item: Runtime: 0:00:00.008671
2026-02-16 17:23:09.446668 | controller | ok: Item: Runtime: 0:00:00.007224
2026-02-16 17:23:09.656271 | controller | ok: Item: Runtime: 0:00:00.007171
2026-02-16 17:23:09.874349 | controller | ok: Item: Runtime: 0:00:00.007333
2026-02-16 17:23:10.123917 | controller | ok: Item: Runtime: 0:00:00.008096
2026-02-16 17:23:10.353273 | controller | ok: Item: Runtime: 0:00:00.007749
2026-02-16 17:23:10.577290 | controller | ok: Item: Runtime: 0:00:00.007768
2026-02-16 17:23:10.843895 | controller | ok: Item: Runtime: 0:00:00.007204
2026-02-16 17:23:11.020535 | controller | ok: Item: Runtime: 0:00:00.007985
2026-02-16 17:23:11.241963 | controller | ok: Item: Runtime: 0:00:00.007997
2026-02-16 17:23:11.458997 | controller | ok: Item: Runtime: 0:00:00.008290
2026-02-16 17:23:11.685890 | controller | ok: Item: Runtime: 0:00:00.007522
2026-02-16 17:23:11.717608 | 
2026-02-16 17:23:11.717770 | TASK [stage-output : Make all log files readable]
2026-02-16 17:23:11.955345 | controller | changed
2026-02-16 17:23:11.962690 | 
2026-02-16 17:23:11.962757 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:23:12.295466 | controller | changed: Renamed files for staging.
2026-02-16 17:23:12.301192 | 
2026-02-16 17:23:12.301257 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:23:12.327197 | controller | skipping: Conditional result was False
2026-02-16 17:23:12.339031 | 
2026-02-16 17:23:12.339128 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:23:12.388296 | 
2026-02-16 17:23:12.388527 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:23:13.018622 | controller | changed:
2026-02-16 17:23:13.018829 | controller | created directory /var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/work/logs/controller
2026-02-16 17:23:13.018868 | controller | cd+++++++++ logs/
2026-02-16 17:23:13.018897 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:23:13.018924 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:23:13.018950 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:23:13.018976 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:23:13.019001 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:23:13.019026 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:23:13.019051 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:23:13.019075 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:23:13.019100 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:23:13.019125 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:23:13.019149 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:23:13.019173 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:23:13.019201 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:23:13.019244 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:23:13.019271 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:23:13.019296 | controller | cd+++++++++ logs/apache/
2026-02-16 17:23:13.019320 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:23:13.019345 | controller | cd+++++++++ logs/etc/
2026-02-16 17:23:13.019369 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:23:13.019394 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:23:13.019419 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:23:13.019443 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:23:13.019467 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:23:13.019491 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:23:13.047213 | 
2026-02-16 17:23:13.047397 | TASK [Check if a tempest log exits]
2026-02-16 17:23:13.316870 | controller | ok
2026-02-16 17:23:13.326713 | 
2026-02-16 17:23:13.326811 | TASK [Link post-devstack tempest.log]
2026-02-16 17:23:13.352709 | controller | skipping: Conditional result was False
2026-02-16 17:23:13.364047 | 
2026-02-16 17:23:13.364157 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:23:13.615209 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:23:13.904697 | controller | ERROR
2026-02-16 17:23:13.905172 | controller | {
2026-02-16 17:23:13.905212 | controller |   "delta": "0:00:00.008270",
2026-02-16 17:23:13.905233 | controller |   "end": "2026-02-16 17:23:13.615872",
2026-02-16 17:23:13.905251 | controller |   "msg": "non-zero return code",
2026-02-16 17:23:13.905269 | controller |   "rc": 127,
2026-02-16 17:23:13.905286 | controller |   "start": "2026-02-16 17:23:13.607602"
2026-02-16 17:23:13.905304 | controller | }
2026-02-16 17:23:13.905328 | controller | ERROR: Ignoring Errors
2026-02-16 17:23:13.907142 | 
2026-02-16 17:23:13.907191 | PLAY RECAP
2026-02-16 17:23:13.907241 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:23:13.907262 | 
2026-02-16 17:23:14.101542 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:23:14.112379 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:14.725870 | 
2026-02-16 17:23:14.726003 | PLAY [all]
2026-02-16 17:23:14.737509 | 
2026-02-16 17:23:14.737585 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:23:14.783100 | controller | skipping: Conditional result was False
2026-02-16 17:23:14.792365 | 
2026-02-16 17:23:14.792468 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:23:14.837013 | controller | ok
2026-02-16 17:23:14.845564 | 
2026-02-16 17:23:14.845654 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:23:15.233733 | controller -> localhost | ok: "/var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/work/logs"
2026-02-16 17:23:15.457537 | controller -> localhost | changed: "/var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/work/artifacts"
2026-02-16 17:23:15.682146 | controller -> localhost | changed: "/var/lib/zuul/builds/592dad0332fa4d81ab5680373143afe5/work/docs"
2026-02-16 17:23:15.699424 | 
2026-02-16 17:23:15.699594 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:23:16.366131 | controller | changed: .d..t...... ./
2026-02-16 17:23:16.366498 | controller | changed: All items complete
2026-02-16 17:23:16.366536 | 
2026-02-16 17:23:16.857005 | controller | changed: .d..t...... ./
2026-02-16 17:23:17.326280 | controller | changed: .d..t...... ./
2026-02-16 17:23:17.358081 | 
2026-02-16 17:23:17.358278 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:23:17.804309 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008225
2026-02-16 17:23:18.058374 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009923
2026-02-16 17:23:18.081825 | 
2026-02-16 17:23:18.081973 | PLAY [all]
2026-02-16 17:23:18.089970 | 
2026-02-16 17:23:18.090036 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:23:18.577847 | controller | changed
2026-02-16 17:23:18.584088 | 
2026-02-16 17:23:18.584141 | PLAY RECAP
2026-02-16 17:23:18.584188 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:23:18.584209 | 
2026-02-16 17:23:18.702749 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:23:18.710762 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:23:19.372062 | 
2026-02-16 17:23:19.372240 | PLAY [localhost]
2026-02-16 17:23:19.384025 | 
2026-02-16 17:23:19.384102 | TASK [Generate Zuul manifest]
2026-02-16 17:23:19.406555 | localhost | ok
2026-02-16 17:23:19.422290 | 
2026-02-16 17:23:19.422369 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:23:19.813830 | localhost | changed
2026-02-16 17:23:19.828530 | 
2026-02-16 17:23:19.828607 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:23:19.869100 | localhost | ok
2026-02-16 17:23:19.875784 | 
2026-02-16 17:23:19.875858 | TASK [Upload logs]
2026-02-16 17:23:19.898463 | localhost | ok
2026-02-16 17:23:19.961297 | 
2026-02-16 17:23:19.961465 | TASK [Set zuul-log-path fact]
2026-02-16 17:23:19.984626 | localhost | ok
2026-02-16 17:23:19.996560 | 
2026-02-16 17:23:19.996632 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:23:20.029115 | localhost | ok
2026-02-16 17:23:20.038503 | 
2026-02-16 17:23:20.038593 | TASK [upload-logs : Create log directories]
2026-02-16 17:23:20.487816 | localhost | changed
2026-02-16 17:23:20.494289 | 
2026-02-16 17:23:20.494378 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:23:20.928425 | localhost -> localhost | ok: Runtime: 0:00:00.005145
2026-02-16 17:23:20.933190 | 
2026-02-16 17:23:20.933252 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:23:21.390251 | localhost | Output suppressed because no_log was given
2026-02-16 17:23:21.395349 | 
2026-02-16 17:23:21.395442 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:23:21.445566 | localhost | skipping: Conditional result was False
2026-02-16 17:23:21.452386 | localhost | skipping: Conditional result was False
2026-02-16 17:23:21.479040 | 
2026-02-16 17:23:21.479400 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:23:21.529511 | localhost | skipping: Conditional result was False
2026-02-16 17:23:21.529964 | 
2026-02-16 17:23:21.533002 | localhost | skipping: Conditional result was False
2026-02-16 17:23:21.542108 | 
2026-02-16 17:23:21.542240 | LOOP [upload-logs : Upload console log and json output]