2026-02-16 17:25:05.877708 | Job console starting
2026-02-16 17:25:06.027026 | Updating git repos
2026-02-16 17:25:08.701850 | Cloning repos into workspace
2026-02-16 17:25:15.763254 | Restoring repo states
2026-02-16 17:25:18.497480 | Merging changes
2026-02-16 17:25:18.965394 | Checking out repos
2026-02-16 17:25:26.717934 | Preparing playbooks
2026-02-16 17:25:32.934424 | Running Ansible setup
2026-02-16 17:25:37.236558 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:25:37.828126 | 
2026-02-16 17:25:37.883024 | PLAY [localhost]
2026-02-16 17:25:37.896003 | 
2026-02-16 17:25:37.896103 | TASK [Gathering Facts]
2026-02-16 17:25:38.778445 | localhost | ok
2026-02-16 17:25:38.788903 | 
2026-02-16 17:25:38.789015 | TASK [Setup log path fact]
2026-02-16 17:25:38.810815 | localhost | ok
2026-02-16 17:25:38.839168 | 
2026-02-16 17:25:38.839254 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:25:38.868265 | localhost | ok
2026-02-16 17:25:38.876825 | 
2026-02-16 17:25:38.876929 | TASK [emit-job-header : Print job information]
2026-02-16 17:25:38.921858 | # Job Information
2026-02-16 17:25:39.217039 | Ansible Version: 2.16.15
2026-02-16 17:25:39.217297 | Job: magnum-cluster-api-sonobuoy-v1.35.1-calico
2026-02-16 17:25:39.217363 | Pipeline: check
2026-02-16 17:25:39.217415 | Executor: 3a2793d2bd32
2026-02-16 17:25:39.217463 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:25:39.217519 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:25:39.224474 | 
2026-02-16 17:25:39.224620 | LOOP [emit-job-header : Print node information]
2026-02-16 17:25:39.323806 | localhost | ok:
2026-02-16 17:25:39.324138 | localhost | # Node Information
2026-02-16 17:25:39.324189 | localhost | Inventory Hostname: controller
2026-02-16 17:25:39.324220 | localhost | Hostname: np0000155600
2026-02-16 17:25:39.324245 | localhost | Username: zuul
2026-02-16 17:25:39.324272 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:25:39.324299 | localhost | Provider: yul1
2026-02-16 17:25:39.324323 | localhost | Region: ca-ymq-1
2026-02-16 17:25:39.324348 | localhost | Label: ubuntu-noble-16
2026-02-16 17:25:39.324374 | localhost | Product Name: OpenStack Nova
2026-02-16 17:25:39.324406 | localhost | Interface IP: 199.204.45.153
2026-02-16 17:25:39.336972 | 
2026-02-16 17:25:39.337129 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:25:39.736342 | localhost -> localhost | changed
2026-02-16 17:25:39.746356 | 
2026-02-16 17:25:39.746604 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:25:40.547361 | localhost -> localhost | changed
2026-02-16 17:25:40.558212 | 
2026-02-16 17:25:40.558298 | PLAY [all]
2026-02-16 17:25:40.568014 | 
2026-02-16 17:25:40.568081 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:25:40.784992 | controller -> localhost | ok
2026-02-16 17:25:40.796287 | 
2026-02-16 17:25:40.796358 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:25:40.831252 | controller | ok
2026-02-16 17:25:40.847391 | controller | included: /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:25:40.854792 | 
2026-02-16 17:25:40.854854 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:25:45.914633 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:25:45.915068 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/work/f2a44494f3744b6d8c4be7e705d10ad2_id_rsa
2026-02-16 17:25:45.915100 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/work/f2a44494f3744b6d8c4be7e705d10ad2_id_rsa.pub
2026-02-16 17:25:45.915123 | controller -> localhost | The key fingerprint is:
2026-02-16 17:25:45.915145 | controller -> localhost | SHA256:pL6k3WLLYMFl4ozSj8vtWXPG0fe0Ud59+54hioS9q3o zuul-build-sshkey
2026-02-16 17:25:45.915183 | controller -> localhost | The key's randomart image is:
2026-02-16 17:25:45.915204 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:25:45.915227 | controller -> localhost | |                 |
2026-02-16 17:25:45.915249 | controller -> localhost | |                 |
2026-02-16 17:25:45.915270 | controller -> localhost | |    . o .       .|
2026-02-16 17:25:45.915290 | controller -> localhost | | . = + o .     oo|
2026-02-16 17:25:45.915310 | controller -> localhost | |. o = . S . . o =|
2026-02-16 17:25:45.915329 | controller -> localhost | | . o o .o. . o oo|
2026-02-16 17:25:45.915349 | controller -> localhost | |  . + =.+o   .oo |
2026-02-16 17:25:45.915369 | controller -> localhost | | . + OoE. o . . +|
2026-02-16 17:25:45.915391 | controller -> localhost | |  o.=oB+o+..   oo|
2026-02-16 17:25:45.915412 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:25:45.915458 | controller -> localhost | ok: Runtime: 0:00:04.624573
2026-02-16 17:25:45.921441 | 
2026-02-16 17:25:45.921508 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:25:45.956453 | controller | ok
2026-02-16 17:25:46.039463 | controller | included: /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:25:46.047555 | 
2026-02-16 17:25:46.047615 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:25:46.072451 | controller | skipping: Conditional result was False
2026-02-16 17:25:46.082937 | 
2026-02-16 17:25:46.083027 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:25:46.624687 | controller | changed
2026-02-16 17:25:46.633893 | 
2026-02-16 17:25:46.633975 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:25:46.858739 | controller | ok
2026-02-16 17:25:46.864382 | 
2026-02-16 17:25:46.864454 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:25:47.411606 | controller | changed
2026-02-16 17:25:47.417243 | 
2026-02-16 17:25:47.417309 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:25:47.960996 | controller | changed
2026-02-16 17:25:47.970209 | 
2026-02-16 17:25:47.970293 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:25:47.993378 | controller | skipping: Conditional result was False
2026-02-16 17:25:48.003930 | 
2026-02-16 17:25:48.004016 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:25:49.349568 | controller -> localhost | changed
2026-02-16 17:25:49.365522 | 
2026-02-16 17:25:49.365589 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:25:49.669592 | controller -> localhost | Identity added: /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/work/f2a44494f3744b6d8c4be7e705d10ad2_id_rsa (zuul-build-sshkey)
2026-02-16 17:25:49.669798 | controller -> localhost | ok: Runtime: 0:00:00.010052
2026-02-16 17:25:49.723026 | 
2026-02-16 17:25:49.723281 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:25:50.034114 | controller | ok
2026-02-16 17:25:50.250219 | 
2026-02-16 17:25:50.250349 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:25:50.276328 | controller | skipping: Conditional result was False
2026-02-16 17:25:50.295318 | 
2026-02-16 17:25:50.295404 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:25:50.621531 | controller | ok
2026-02-16 17:25:50.628840 | 
2026-02-16 17:25:50.628909 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:26:16.648748 | controller | Output suppressed because no_log was given
2026-02-16 17:26:16.659167 | 
2026-02-16 17:26:16.659259 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:26:16.889512 | controller | ok: "logs"
2026-02-16 17:26:16.889767 | controller | ok: All items complete
2026-02-16 17:26:16.889795 | 
2026-02-16 17:26:17.091329 | controller | ok: "artifacts"
2026-02-16 17:26:17.289696 | controller | ok: "docs"
2026-02-16 17:26:17.300662 | 
2026-02-16 17:26:17.300831 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:26:17.515778 | controller | changed: "logs"
2026-02-16 17:26:17.722408 | controller | changed: "artifacts"
2026-02-16 17:26:17.962387 | controller | changed: "docs"
2026-02-16 17:26:17.996752 | 
2026-02-16 17:26:17.996915 | PLAY RECAP
2026-02-16 17:26:17.997011 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:26:17.997042 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:26:17.997065 | 
2026-02-16 17:26:18.161242 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:26:18.171484 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:26:18.828397 | 
2026-02-16 17:26:18.828539 | PLAY [all]
2026-02-16 17:26:18.846297 | 
2026-02-16 17:26:18.846402 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:26:19.250715 | controller | changed
2026-02-16 17:26:19.257497 | 
2026-02-16 17:26:19.257595 | TASK [Gather minimum local MTU]
2026-02-16 17:26:19.335012 | controller | ok
2026-02-16 17:26:19.340457 | 
2026-02-16 17:26:19.340529 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:26:19.392922 | controller | ok
2026-02-16 17:26:19.398322 | 
2026-02-16 17:26:19.398393 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:26:19.427353 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.440739 | 
2026-02-16 17:26:19.440829 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:26:19.988622 | controller | ok: Runtime: 0:00:00.008360
2026-02-16 17:26:19.995052 | 
2026-02-16 17:26:19.995189 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:26:20.022455 | controller | skipping: Conditional result was False
2026-02-16 17:26:20.032133 | 
2026-02-16 17:26:20.032225 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:26:20.058864 | controller | skipping: Conditional result was False
2026-02-16 17:26:20.070247 | 
2026-02-16 17:26:20.070371 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:26:20.129032 | controller | ok
2026-02-16 17:26:20.141358 | controller | included: /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:26:20.147505 | 
2026-02-16 17:26:20.147569 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:26:20.203240 | controller | ok
2026-02-16 17:26:20.208751 | 
2026-02-16 17:26:20.208817 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:26:20.535215 | controller | ext4
2026-02-16 17:26:20.785225 | controller | ok: Runtime: 0:00:00.012455
2026-02-16 17:26:20.790803 | 
2026-02-16 17:26:20.790866 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:26:20.825523 | controller | ok
2026-02-16 17:26:20.833125 | 
2026-02-16 17:26:20.833213 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:26:20.878026 | controller | ok:
2026-02-16 17:26:20.878247 | controller | {
2026-02-16 17:26:20.878282 | controller |   "root_filesystem": "ext4"
2026-02-16 17:26:20.878310 | controller | }
2026-02-16 17:26:20.886599 | 
2026-02-16 17:26:20.886666 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:26:31.037554 | controller | 8192+0 records in
2026-02-16 17:26:31.037700 | controller | 8192+0 records out
2026-02-16 17:26:31.037715 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.8767 s, 870 MB/s
2026-02-16 17:26:31.434086 | controller | ok: Runtime: 0:00:09.886139
2026-02-16 17:26:31.439661 | 
2026-02-16 17:26:31.439728 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:26:31.728357 | controller | changed
2026-02-16 17:26:31.737169 | 
2026-02-16 17:26:31.737237 | TASK [configure-swap : Make swapfile]
2026-02-16 17:26:39.741350 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:26:39.741565 | controller | no label, UUID=f6ebd83e-327b-4f5d-96c0-4bcd8285a0a5
2026-02-16 17:26:39.796862 | controller | ok: Runtime: 0:00:07.742962
2026-02-16 17:26:39.802448 | 
2026-02-16 17:26:39.802508 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:26:40.231681 | controller | changed
2026-02-16 17:26:40.240705 | 
2026-02-16 17:26:40.240848 | TASK [configure-swap : Add all swap]
2026-02-16 17:26:40.780476 | controller | ok: Runtime: 0:00:00.015784
2026-02-16 17:26:40.788866 | 
2026-02-16 17:26:40.788983 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:26:40.824402 | controller | ok:
2026-02-16 17:26:40.824576 | controller | {
2026-02-16 17:26:40.824611 | controller |   "swap_required": "8192"
2026-02-16 17:26:40.824647 | controller | }
2026-02-16 17:26:40.832055 | 
2026-02-16 17:26:40.832119 | TASK [configure-swap : Set swappiness]
2026-02-16 17:26:41.252295 | controller | changed
2026-02-16 17:26:41.261598 | 
2026-02-16 17:26:41.261665 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:26:41.309010 | controller | ok:
2026-02-16 17:26:41.309252 | controller | {
2026-02-16 17:26:41.309283 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:26:41.309314 | controller | }
2026-02-16 17:26:41.319025 | 
2026-02-16 17:26:41.319088 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:26:41.758360 | controller | changed
2026-02-16 17:26:41.765523 | 
2026-02-16 17:26:41.765592 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:26:42.013535 | controller | changed
2026-02-16 17:26:42.020010 | 
2026-02-16 17:26:42.020077 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:26:42.552741 | controller | changed
2026-02-16 17:26:42.559768 | 
2026-02-16 17:26:42.559841 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:26:42.808517 | controller | changed
2026-02-16 17:26:42.814846 | 
2026-02-16 17:26:42.814910 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:26:43.897177 | controller | changed
2026-02-16 17:26:43.904675 | 
2026-02-16 17:26:43.904738 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:26:44.152422 | controller | changed
2026-02-16 17:26:44.163178 | 
2026-02-16 17:26:44.163241 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:26:44.452050 | controller | changed
2026-02-16 17:26:44.457422 | 
2026-02-16 17:26:44.457484 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:26:45.243264 | controller | changed
2026-02-16 17:26:45.296838 | 
2026-02-16 17:26:45.297020 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:26:46.076046 | controller | changed
2026-02-16 17:26:46.088825 | 
2026-02-16 17:26:46.088896 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:26:46.417066 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:26:46.432484 | 
2026-02-16 17:26:46.432599 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:26:46.915510 | controller | ok: Item: Runtime: 0:00:00.193379
2026-02-16 17:26:47.481841 | controller | ok: Item: Runtime: 0:00:00.337547
2026-02-16 17:26:48.230705 | controller | ok: Item: Runtime: 0:00:00.528420
2026-02-16 17:26:48.517799 | controller | ok: Item: Runtime: 0:00:00.062437
2026-02-16 17:26:49.015166 | controller | ok: Item: Runtime: 0:00:00.271116
2026-02-16 17:26:50.649814 | controller | ok: Item: Runtime: 0:00:01.410661
2026-02-16 17:26:53.852538 | controller | ok: Item: Runtime: 0:00:02.980525
2026-02-16 17:26:54.165975 | controller | ok: Item: Runtime: 0:00:00.097995
2026-02-16 17:26:54.591795 | controller | ok: Item: Runtime: 0:00:00.195253
2026-02-16 17:26:54.935055 | controller | ok: Item: Runtime: 0:00:00.125062
2026-02-16 17:26:55.709842 | controller | ok: Item: Runtime: 0:00:00.552605
2026-02-16 17:26:56.492988 | controller | ok: Item: Runtime: 0:00:00.557397
2026-02-16 17:26:56.959628 | controller | ok: Item: Runtime: 0:00:00.241409
2026-02-16 17:26:57.391137 | controller | ok: Item: Runtime: 0:00:00.209434
2026-02-16 17:26:57.993888 | controller | ok: Item: Runtime: 0:00:00.384462
2026-02-16 17:26:59.469438 | controller | ok: Item: Runtime: 0:00:01.261762
2026-02-16 17:26:59.500492 | 
2026-02-16 17:26:59.501544 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:26:59.717124 | controller | ok: All paths examined
2026-02-16 17:26:59.724038 | 
2026-02-16 17:26:59.724107 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:26:59.973829 | controller | ok: All paths examined
2026-02-16 17:26:59.980306 | 
2026-02-16 17:26:59.980373 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:27:00.407980 | controller | ok: Item: Runtime: 0:00:00.149321
2026-02-16 17:27:00.408221 | controller | changed: All items complete
2026-02-16 17:27:00.408251 | 
2026-02-16 17:27:00.810372 | controller | ok: Item: Runtime: 0:00:00.189859
2026-02-16 17:27:00.825525 | 
2026-02-16 17:27:00.825635 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:27:00.866923 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.869234 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.872063 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.875086 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.878182 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.881312 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.884859 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.888251 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.891327 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.894324 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.897402 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.900459 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.903757 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.906773 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.910089 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.913098 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.937674 | 
2026-02-16 17:27:00.937748 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:27:03.263151 | controller | changed
2026-02-16 17:27:03.274792 | 
2026-02-16 17:27:03.274925 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:27:03.514099 | controller | changed
2026-02-16 17:27:03.572763 | 
2026-02-16 17:27:03.572911 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:27:03.827406 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:27:04.113329 | controller | ERROR
2026-02-16 17:27:04.113589 | controller | {
2026-02-16 17:27:04.113633 | controller |   "delta": "0:00:00.009989",
2026-02-16 17:27:04.113656 | controller |   "end": "2026-02-16 17:27:03.827973",
2026-02-16 17:27:04.113675 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:04.113693 | controller |   "rc": 1,
2026-02-16 17:27:04.113712 | controller |   "start": "2026-02-16 17:27:03.817984"
2026-02-16 17:27:04.113729 | controller | }
2026-02-16 17:27:04.113755 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:04.119754 | 
2026-02-16 17:27:04.119827 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:27:04.370243 | controller | ok
2026-02-16 17:27:04.382536 | 
2026-02-16 17:27:04.382604 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:27:04.616104 | controller | /usr/bin/journalctl
2026-02-16 17:27:04.921520 | controller | ok: Runtime: 0:00:00.008105
2026-02-16 17:27:04.927995 | 
2026-02-16 17:27:04.928064 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:27:05.177762 | controller | 2026-02-16 17:27:05
2026-02-16 17:27:05.467918 | controller | ok: Runtime: 0:00:00.008638
2026-02-16 17:27:05.475699 | 
2026-02-16 17:27:05.475811 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:27:06.051070 | controller | changed
2026-02-16 17:27:06.059490 | 
2026-02-16 17:27:06.059582 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:27:06.085721 | controller | skipping: Conditional result was False
2026-02-16 17:27:06.095096 | 
2026-02-16 17:27:06.095190 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:27:06.627032 | controller | skipping: Conditional result was False
2026-02-16 17:27:06.639742 | 
2026-02-16 17:27:06.639814 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:27:07.169128 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.181964 | 
2026-02-16 17:27:07.182031 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:27:07.207412 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.217460 | 
2026-02-16 17:27:07.217549 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:27:07.751360 | controller | skipping: Conditional result was False
2026-02-16 17:27:07.761593 | 
2026-02-16 17:27:07.761665 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:27:08.291538 | controller | skipping: Conditional result was False
2026-02-16 17:27:08.303195 | 
2026-02-16 17:27:08.303268 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:27:08.329195 | controller | skipping: Conditional result was False
2026-02-16 17:27:08.338636 | 
2026-02-16 17:27:08.338727 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:27:08.364575 | controller | skipping: Conditional result was False
2026-02-16 17:27:08.374723 | 
2026-02-16 17:27:08.374789 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:27:08.960677 | controller | skipping: Conditional result was False
2026-02-16 17:27:08.971015 | 
2026-02-16 17:27:08.971128 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:27:09.501123 | controller | skipping: Conditional result was False
2026-02-16 17:27:09.512574 | 
2026-02-16 17:27:09.512668 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:27:09.538225 | controller | skipping: Conditional result was False
2026-02-16 17:27:09.550019 | 
2026-02-16 17:27:09.550085 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:27:09.575365 | controller | skipping: Conditional result was False
2026-02-16 17:27:09.591189 | 
2026-02-16 17:27:09.591256 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:27:09.993041 | controller | ok
2026-02-16 17:27:10.002981 | 
2026-02-16 17:27:10.003035 | PLAY RECAP
2026-02-16 17:27:10.003098 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:27:10.003131 | 
2026-02-16 17:27:10.140685 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:27:10.149195 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:27:10.719100 | 
2026-02-16 17:27:10.719833 | PLAY [all]
2026-02-16 17:27:10.731972 | 
2026-02-16 17:27:10.732051 | TASK [ensure-rust : Use rustup]
2026-02-16 17:27:10.768035 | controller | ok
2026-02-16 17:27:10.775110 | controller | included: /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:27:10.781706 | 
2026-02-16 17:27:10.781781 | TASK [ensure-rust : Install Rust]
2026-02-16 17:27:11.212037 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:27:11.322656 | controller | ERROR
2026-02-16 17:27:11.322844 | controller | {
2026-02-16 17:27:11.322886 | controller |   "delta": "0:00:00.012220",
2026-02-16 17:27:11.322918 | controller |   "end": "2026-02-16 17:27:11.215135",
2026-02-16 17:27:11.322947 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:11.322973 | controller |   "rc": 127,
2026-02-16 17:27:11.322999 | controller |   "start": "2026-02-16 17:27:11.202915"
2026-02-16 17:27:11.323030 | controller | }
failure
2026-02-16 17:27:11.325133 | 
2026-02-16 17:27:11.325203 | PLAY RECAP
2026-02-16 17:27:11.325267 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:27:11.325296 | 
2026-02-16 17:27:11.444133 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:27:11.454207 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:27:12.069596 | 
2026-02-16 17:27:12.070136 | PLAY [all]
2026-02-16 17:27:12.087976 | 
2026-02-16 17:27:12.088063 | TASK [Return built artifacts to Zuul]
2026-02-16 17:27:12.122561 | controller | ok
2026-02-16 17:27:12.129353 | 
2026-02-16 17:27:12.129430 | TASK [Get the Docker volume ID]
2026-02-16 17:27:12.673812 | controller | ERROR
2026-02-16 17:27:12.674282 | controller | {
2026-02-16 17:27:12.674528 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:27:12.674580 | controller |   "rc": 2
2026-02-16 17:27:12.674626 | controller | }
2026-02-16 17:27:12.674685 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:12.681785 | 
2026-02-16 17:27:12.681854 | TASK [Copy all of the pod logs]
2026-02-16 17:27:12.716875 | controller | ERROR
2026-02-16 17:27:12.717095 | controller | {
2026-02-16 17:27:12.717127 | 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/f2a44494f3744b6d8c4be7e705d10ad2/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:27:12.717150 | controller | }
2026-02-16 17:27:12.717178 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:12.718265 | 
2026-02-16 17:27:12.718311 | PLAY RECAP
2026-02-16 17:27:12.718357 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:27:12.718378 | 
2026-02-16 17:27:12.840458 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:27:12.849902 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:27:13.478592 | 
2026-02-16 17:27:13.478739 | PLAY [all]
2026-02-16 17:27:13.497493 | 
2026-02-16 17:27:13.497579 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:27:13.900377 | controller | changed
2026-02-16 17:27:13.907930 | 
2026-02-16 17:27:13.908014 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:27:14.948820 | controller | ok: Runtime: 0:00:00.455013
2026-02-16 17:27:14.956170 | 
2026-02-16 17:27:14.956238 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:27:15.502762 | controller | ok: Runtime: 0:00:00.021198
2026-02-16 17:27:15.509099 | 
2026-02-16 17:27:15.509175 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:27:16.056870 | controller | ok: Runtime: 0:00:00.021451
2026-02-16 17:27:16.063501 | 
2026-02-16 17:27:16.063566 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:27:16.980261 | controller | changed
2026-02-16 17:27:17.029012 | 
2026-02-16 17:27:17.029160 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:27:17.267893 | controller | changed
2026-02-16 17:27:17.273628 | 
2026-02-16 17:27:17.273702 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:27:17.591452 | controller | Output suppressed because no_log was given
2026-02-16 17:27:17.598133 | 
2026-02-16 17:27:17.598260 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:27:17.634269 | 
2026-02-16 17:27:17.634426 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:27:17.670440 | 
2026-02-16 17:27:17.670614 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:27:17.705824 | 
2026-02-16 17:27:17.705989 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:27:17.741896 | 
2026-02-16 17:27:17.742032 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:27:17.779121 | 
2026-02-16 17:27:17.779303 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:27:18.028896 | controller | changed
2026-02-16 17:27:18.035067 | 
2026-02-16 17:27:18.035139 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:27:18.069913 | controller | ok
2026-02-16 17:27:18.075120 | 
2026-02-16 17:27:18.075184 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:27:18.314503 | controller | Output suppressed because no_log was given
2026-02-16 17:27:18.320705 | 
2026-02-16 17:27:18.320773 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:27:18.357806 | 
2026-02-16 17:27:18.357967 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:27:18.398765 | 
2026-02-16 17:27:18.398901 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:27:19.056228 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:27:19.077162 | controller | Using python 3.12 to install setuptools
2026-02-16 17:27:19.114053 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:27:19.126244 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:27:19.447070 | controller | ERROR
2026-02-16 17:27:19.447330 | controller | {
2026-02-16 17:27:19.447367 | controller |   "delta": "0:00:00.482467",
2026-02-16 17:27:19.447388 | controller |   "end": "2026-02-16 17:27:19.128955",
2026-02-16 17:27:19.447407 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:19.447432 | controller |   "rc": 127,
2026-02-16 17:27:19.447451 | controller |   "start": "2026-02-16 17:27:18.646488"
2026-02-16 17:27:19.447469 | controller | }
2026-02-16 17:27:19.447495 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:19.459496 | 
2026-02-16 17:27:19.459574 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:27:19.702559 | controller | changed
2026-02-16 17:27:19.708538 | 
2026-02-16 17:27:19.708610 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:27:23.414859 | controller | Output suppressed because no_log was given
2026-02-16 17:27:23.427768 | 
2026-02-16 17:27:23.427832 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:27:23.462431 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.462897 | 
2026-02-16 17:27:23.464893 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.466547 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.468121 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.470549 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.472884 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.475222 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.477666 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.479913 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.482245 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.484663 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.486970 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.489279 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.491497 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.493839 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.496080 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.498423 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.501438 | controller | skipping: Conditional result was False
2026-02-16 17:27:23.530932 | 
2026-02-16 17:27:23.531002 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:27:23.768848 | controller | ok
2026-02-16 17:27:23.774768 | 
2026-02-16 17:27:23.774837 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:27:24.355913 | controller | skipping: Conditional result was False
2026-02-16 17:27:24.374552 | 
2026-02-16 17:27:24.374637 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:27:24.634808 | controller | sudo: iptables-save: command not found
2026-02-16 17:27:24.673291 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:27:25.744191 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:27:25.918651 | controller | ok: Runtime: 0:00:01.132468
2026-02-16 17:27:25.931869 | 
2026-02-16 17:27:25.931945 | LOOP [stage-output : Register sources]
2026-02-16 17:27:35.466620 | controller | Output suppressed because no_log was given
2026-02-16 17:27:35.549299 | 
2026-02-16 17:27:35.549423 | TASK [stage-output : Check sudo]
2026-02-16 17:27:36.089726 | controller | ok: Runtime: 0:00:00.018345
2026-02-16 17:27:36.096226 | 
2026-02-16 17:27:36.096295 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:27:36.201903 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.202461 | 
2026-02-16 17:27:36.204179 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.205016 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.206932 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.244314 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.244890 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.245440 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.245831 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.247023 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.249324 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.251270 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.253378 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.255464 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.257914 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.281437 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.282642 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.283659 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.286809 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.287184 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.287541 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.287996 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.288348 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.288712 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.320211 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.320592 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.407928 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.416331 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.432222 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.439651 | controller | Output suppressed because no_log was given
2026-02-16 17:27:36.454101 | 
2026-02-16 17:27:36.454253 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:27:36.521475 | controller | ok
2026-02-16 17:27:36.529207 | 
2026-02-16 17:27:36.529280 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:27:36.783058 | controller | changed: "docs"
2026-02-16 17:27:36.985884 | controller | changed: "artifacts"
2026-02-16 17:27:37.213618 | controller | ok: "logs"
2026-02-16 17:27:37.234372 | 
2026-02-16 17:27:37.235617 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:27:37.516779 | controller | ok: Item: Runtime: 0:00:00.009603
2026-02-16 17:27:37.521065 | controller | changed: All items complete
2026-02-16 17:27:37.521158 | 
2026-02-16 17:27:37.770526 | controller | ok: Item: Runtime: 0:00:00.008606
2026-02-16 17:27:37.998221 | controller | ok: Item: Runtime: 0:00:00.009122
2026-02-16 17:27:38.226204 | controller | ok: Item: Runtime: 0:00:00.008017
2026-02-16 17:27:38.450711 | controller | ok: Item: Runtime: 0:00:00.008760
2026-02-16 17:27:38.683803 | controller | ok: Item: Runtime: 0:00:00.009153
2026-02-16 17:27:38.914657 | controller | ok: Item: Runtime: 0:00:00.008235
2026-02-16 17:27:39.163440 | controller | ok: Item: Runtime: 0:00:00.008921
2026-02-16 17:27:39.384296 | controller | ok: Item: Runtime: 0:00:00.007418
2026-02-16 17:27:39.611144 | controller | ok: Item: Runtime: 0:00:00.008640
2026-02-16 17:27:39.844035 | controller | ok: Item: Runtime: 0:00:00.009125
2026-02-16 17:27:40.119323 | controller | ok: Item: Runtime: 0:00:00.006173
2026-02-16 17:27:40.302449 | controller | ok: Item: Runtime: 0:00:00.008396
2026-02-16 17:27:40.524438 | controller | ok: Item: Runtime: 0:00:00.008023
2026-02-16 17:27:40.756665 | controller | ok: Item: Runtime: 0:00:00.009261
2026-02-16 17:27:40.988235 | controller | ok: Item: Runtime: 0:00:00.008446
2026-02-16 17:27:41.034950 | 
2026-02-16 17:27:41.035646 | TASK [stage-output : Make all log files readable]
2026-02-16 17:27:41.288976 | controller | changed
2026-02-16 17:27:41.298288 | 
2026-02-16 17:27:41.298370 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:27:41.628621 | controller | changed: Renamed files for staging.
2026-02-16 17:27:41.636531 | 
2026-02-16 17:27:41.636621 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:27:41.662841 | controller | skipping: Conditional result was False
2026-02-16 17:27:41.674475 | 
2026-02-16 17:27:41.674568 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:27:41.719546 | 
2026-02-16 17:27:41.719671 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:27:42.368931 | controller | changed:
2026-02-16 17:27:42.369201 | controller | created directory /var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/work/logs/controller
2026-02-16 17:27:42.369247 | controller | cd+++++++++ logs/
2026-02-16 17:27:42.369279 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:27:42.369309 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:27:42.369338 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:27:42.369367 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:27:42.369395 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:27:42.369422 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:27:42.369449 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:27:42.369475 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:27:42.369502 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:27:42.369529 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:27:42.369555 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:27:42.369582 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:27:42.369613 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:27:42.369652 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:27:42.369680 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:27:42.369706 | controller | cd+++++++++ logs/apache/
2026-02-16 17:27:42.369732 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:27:42.369759 | controller | cd+++++++++ logs/etc/
2026-02-16 17:27:42.369785 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:27:42.369811 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:27:42.369837 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:27:42.369865 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:27:42.369892 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:27:42.369919 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:27:42.388426 | 
2026-02-16 17:27:42.388613 | TASK [Check if a tempest log exits]
2026-02-16 17:27:42.646596 | controller | ok
2026-02-16 17:27:42.651993 | 
2026-02-16 17:27:42.652058 | TASK [Link post-devstack tempest.log]
2026-02-16 17:27:42.676743 | controller | skipping: Conditional result was False
2026-02-16 17:27:42.688286 | 
2026-02-16 17:27:42.688375 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:27:42.930881 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:27:43.233985 | controller | ERROR
2026-02-16 17:27:43.234225 | controller | {
2026-02-16 17:27:43.234259 | controller |   "delta": "0:00:00.008972",
2026-02-16 17:27:43.234280 | controller |   "end": "2026-02-16 17:27:42.931640",
2026-02-16 17:27:43.234299 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:43.234317 | controller |   "rc": 127,
2026-02-16 17:27:43.234336 | controller |   "start": "2026-02-16 17:27:42.922668"
2026-02-16 17:27:43.234354 | controller | }
2026-02-16 17:27:43.234378 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:43.236237 | 
2026-02-16 17:27:43.236289 | PLAY RECAP
2026-02-16 17:27:43.236342 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:27:43.236365 | 
2026-02-16 17:27:43.397548 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:27:43.408268 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:27:44.020156 | 
2026-02-16 17:27:44.021071 | PLAY [all]
2026-02-16 17:27:44.034226 | 
2026-02-16 17:27:44.034303 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:27:44.080234 | controller | skipping: Conditional result was False
2026-02-16 17:27:44.092018 | 
2026-02-16 17:27:44.092181 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:27:44.137052 | controller | ok
2026-02-16 17:27:44.145430 | 
2026-02-16 17:27:44.145519 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:27:44.535013 | controller -> localhost | ok: "/var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/work/logs"
2026-02-16 17:27:44.744663 | controller -> localhost | changed: "/var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/work/artifacts"
2026-02-16 17:27:45.014515 | controller -> localhost | changed: "/var/lib/zuul/builds/f2a44494f3744b6d8c4be7e705d10ad2/work/docs"
2026-02-16 17:27:45.035356 | 
2026-02-16 17:27:45.035524 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:27:45.705773 | controller | changed: .d..t...... ./
2026-02-16 17:27:45.706235 | controller | changed: All items complete
2026-02-16 17:27:45.706320 | 
2026-02-16 17:27:46.168926 | controller | changed: .d..t...... ./
2026-02-16 17:27:46.637308 | controller | changed: .d..t...... ./
2026-02-16 17:27:46.664274 | 
2026-02-16 17:27:46.664420 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:27:47.079096 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008430
2026-02-16 17:27:47.305673 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009077
2026-02-16 17:27:47.321966 | 
2026-02-16 17:27:47.322064 | PLAY [all]
2026-02-16 17:27:47.331627 | 
2026-02-16 17:27:47.331688 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:27:47.814513 | controller | changed
2026-02-16 17:27:47.827834 | 
2026-02-16 17:27:47.828010 | PLAY RECAP
2026-02-16 17:27:47.828115 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:27:47.828214 | 
2026-02-16 17:27:47.958250 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:27:47.969201 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:27:48.561876 | 
2026-02-16 17:27:48.562023 | PLAY [localhost]
2026-02-16 17:27:48.572779 | 
2026-02-16 17:27:48.572897 | TASK [Generate Zuul manifest]
2026-02-16 17:27:48.596563 | localhost | ok
2026-02-16 17:27:48.614445 | 
2026-02-16 17:27:48.614526 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:27:48.992824 | localhost | changed
2026-02-16 17:27:49.009134 | 
2026-02-16 17:27:49.009266 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:27:49.042326 | localhost | ok
2026-02-16 17:27:49.052759 | 
2026-02-16 17:27:49.052846 | TASK [Upload logs]
2026-02-16 17:27:49.076078 | localhost | ok
2026-02-16 17:27:49.139195 | 
2026-02-16 17:27:49.139361 | TASK [Set zuul-log-path fact]
2026-02-16 17:27:49.162202 | localhost | ok
2026-02-16 17:27:49.176013 | 
2026-02-16 17:27:49.176080 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:27:49.220123 | localhost | ok
2026-02-16 17:27:49.229571 | 
2026-02-16 17:27:49.229646 | TASK [upload-logs : Create log directories]
2026-02-16 17:27:49.618040 | localhost | changed
2026-02-16 17:27:49.623604 | 
2026-02-16 17:27:49.623673 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:27:50.086442 | localhost -> localhost | ok: Runtime: 0:00:00.005600
2026-02-16 17:27:50.091354 | 
2026-02-16 17:27:50.091423 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:27:50.509208 | localhost | Output suppressed because no_log was given
2026-02-16 17:27:50.514751 | 
2026-02-16 17:27:50.514839 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:27:50.562485 | localhost | skipping: Conditional result was False
2026-02-16 17:27:50.568590 | localhost | skipping: Conditional result was False
2026-02-16 17:27:50.582243 | 
2026-02-16 17:27:50.582474 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:27:50.623174 | localhost | skipping: Conditional result was False
2026-02-16 17:27:50.623584 | 
2026-02-16 17:27:50.626715 | localhost | skipping: Conditional result was False
2026-02-16 17:27:50.636867 | 
2026-02-16 17:27:50.637079 | LOOP [upload-logs : Upload console log and json output]