2026-02-16 17:42:11.676105 | Job console starting
2026-02-16 17:42:11.686171 | Updating git repos
2026-02-16 17:42:13.150367 | Cloning repos into workspace
2026-02-16 17:42:18.444779 | Restoring repo states
2026-02-16 17:42:21.441635 | Merging changes
2026-02-16 17:42:21.976186 | Checking out repos
2026-02-16 17:42:25.458889 | Preparing playbooks
2026-02-16 17:42:26.907320 | Running Ansible setup
2026-02-16 17:42:30.892169 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:31.516316 | 
2026-02-16 17:42:31.517234 | PLAY [localhost]
2026-02-16 17:42:31.526252 | 
2026-02-16 17:42:31.526329 | TASK [Gathering Facts]
2026-02-16 17:42:32.413967 | localhost | ok
2026-02-16 17:42:32.423433 | 
2026-02-16 17:42:32.423541 | TASK [Setup log path fact]
2026-02-16 17:42:32.444473 | localhost | ok
2026-02-16 17:42:32.458790 | 
2026-02-16 17:42:32.458858 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:42:32.489675 | localhost | ok
2026-02-16 17:42:32.497020 | 
2026-02-16 17:42:32.497089 | TASK [emit-job-header : Print job information]
2026-02-16 17:42:32.542212 | # Job Information
2026-02-16 17:42:32.542368 | Ansible Version: 2.16.15
2026-02-16 17:42:32.542415 | Job: magnum-cluster-api-sonobuoy-v1.34.4-calico
2026-02-16 17:42:32.542449 | Pipeline: check
2026-02-16 17:42:32.542479 | Executor: 3a2793d2bd32
2026-02-16 17:42:32.542508 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:42:32.542541 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:42:32.546558 | 
2026-02-16 17:42:32.546654 | LOOP [emit-job-header : Print node information]
2026-02-16 17:42:32.707776 | localhost | ok:
2026-02-16 17:42:32.708347 | localhost | # Node Information
2026-02-16 17:42:32.708434 | localhost | Inventory Hostname: controller
2026-02-16 17:42:32.708491 | localhost | Hostname: np0000155626
2026-02-16 17:42:32.708536 | localhost | Username: zuul
2026-02-16 17:42:32.708587 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:42:32.708638 | localhost | Provider: yul1
2026-02-16 17:42:32.708688 | localhost | Region: ca-ymq-1
2026-02-16 17:42:32.708729 | localhost | Label: ubuntu-noble-16
2026-02-16 17:42:32.708752 | localhost | Product Name: OpenStack Nova
2026-02-16 17:42:32.708775 | localhost | Interface IP: 199.204.45.44
2026-02-16 17:42:32.725274 | 
2026-02-16 17:42:32.725444 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:42:33.116479 | localhost -> localhost | changed
2026-02-16 17:42:33.127992 | 
2026-02-16 17:42:33.128073 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:42:33.952608 | localhost -> localhost | changed
2026-02-16 17:42:33.961152 | 
2026-02-16 17:42:33.961229 | PLAY [all]
2026-02-16 17:42:33.969552 | 
2026-02-16 17:42:33.969617 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:42:34.217555 | controller -> localhost | ok
2026-02-16 17:42:34.229139 | 
2026-02-16 17:42:34.229231 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:42:34.266160 | controller | ok
2026-02-16 17:42:34.283531 | controller | included: /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:42:34.290177 | 
2026-02-16 17:42:34.290243 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:42:35.297080 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:42:35.297280 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/work/16e8452ef9b94b35b2987ec00cfe122d_id_rsa
2026-02-16 17:42:35.297310 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/work/16e8452ef9b94b35b2987ec00cfe122d_id_rsa.pub
2026-02-16 17:42:35.297333 | controller -> localhost | The key fingerprint is:
2026-02-16 17:42:35.297354 | controller -> localhost | SHA256:oJU4H8ErdwtNzXIBpC3Ukq1RVWHl+bLm2f78AA+AGAU zuul-build-sshkey
2026-02-16 17:42:35.297394 | controller -> localhost | The key's randomart image is:
2026-02-16 17:42:35.297416 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:42:35.297439 | controller -> localhost | |     .EO==oo+o.  |
2026-02-16 17:42:35.297459 | controller -> localhost | |     o=B=.+. . . |
2026-02-16 17:42:35.297480 | controller -> localhost | |    o OBoo.   o  |
2026-02-16 17:42:35.297500 | controller -> localhost | |    .==+o  .   . |
2026-02-16 17:42:35.297519 | controller -> localhost | |    .o.oS.  o . .|
2026-02-16 17:42:35.297539 | controller -> localhost | |        .    + o |
2026-02-16 17:42:35.297558 | controller -> localhost | |              =  |
2026-02-16 17:42:35.297578 | controller -> localhost | |             o = |
2026-02-16 17:42:35.297600 | controller -> localhost | |              o.O|
2026-02-16 17:42:35.297621 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:42:35.297668 | controller -> localhost | ok: Runtime: 0:00:00.594759
2026-02-16 17:42:35.303320 | 
2026-02-16 17:42:35.303382 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:42:35.339229 | controller | ok
2026-02-16 17:42:35.350578 | controller | included: /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:42:35.358262 | 
2026-02-16 17:42:35.358321 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:42:35.383373 | controller | skipping: Conditional result was False
2026-02-16 17:42:35.392726 | 
2026-02-16 17:42:35.392814 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:42:35.917534 | controller | changed
2026-02-16 17:42:35.926473 | 
2026-02-16 17:42:35.926619 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:42:36.165330 | controller | ok
2026-02-16 17:42:36.172547 | 
2026-02-16 17:42:36.172617 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:42:36.736531 | controller | changed
2026-02-16 17:42:36.742114 | 
2026-02-16 17:42:36.742181 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:42:37.298901 | controller | changed
2026-02-16 17:42:37.306135 | 
2026-02-16 17:42:37.306207 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:42:37.331949 | controller | skipping: Conditional result was False
2026-02-16 17:42:37.344852 | 
2026-02-16 17:42:37.345025 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:42:37.715460 | controller -> localhost | changed
2026-02-16 17:42:37.731563 | 
2026-02-16 17:42:37.731626 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:42:38.061314 | controller -> localhost | Identity added: /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/work/16e8452ef9b94b35b2987ec00cfe122d_id_rsa (zuul-build-sshkey)
2026-02-16 17:42:38.061529 | controller -> localhost | ok: Runtime: 0:00:00.016061
2026-02-16 17:42:38.107735 | 
2026-02-16 17:42:38.107934 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:42:38.417217 | controller | ok
2026-02-16 17:42:38.424213 | 
2026-02-16 17:42:38.424346 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:42:38.450247 | controller | skipping: Conditional result was False
2026-02-16 17:42:38.465821 | 
2026-02-16 17:42:38.465884 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:42:38.787133 | controller | ok
2026-02-16 17:42:38.796612 | 
2026-02-16 17:42:38.796682 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:42:58.455713 | controller | Output suppressed because no_log was given
2026-02-16 17:42:58.466590 | 
2026-02-16 17:42:58.466661 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:42:58.700431 | controller | ok: "logs"
2026-02-16 17:42:58.700710 | controller | ok: All items complete
2026-02-16 17:42:58.700738 | 
2026-02-16 17:42:58.876593 | controller | ok: "artifacts"
2026-02-16 17:42:59.087306 | controller | ok: "docs"
2026-02-16 17:42:59.104340 | 
2026-02-16 17:42:59.104447 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:42:59.334372 | controller | changed: "logs"
2026-02-16 17:42:59.531858 | controller | changed: "artifacts"
2026-02-16 17:42:59.765432 | controller | changed: "docs"
2026-02-16 17:42:59.782421 | 
2026-02-16 17:42:59.782486 | PLAY RECAP
2026-02-16 17:42:59.782541 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:42:59.782579 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:42:59.782607 | 
2026-02-16 17:42:59.913838 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:42:59.923820 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:43:00.560220 | 
2026-02-16 17:43:00.560367 | PLAY [all]
2026-02-16 17:43:00.578494 | 
2026-02-16 17:43:00.578590 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:43:00.982357 | controller | changed
2026-02-16 17:43:00.989339 | 
2026-02-16 17:43:00.989417 | TASK [Gather minimum local MTU]
2026-02-16 17:43:01.066992 | controller | ok
2026-02-16 17:43:01.072218 | 
2026-02-16 17:43:01.072281 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:43:01.127905 | controller | ok
2026-02-16 17:43:01.133086 | 
2026-02-16 17:43:01.133152 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:43:01.160519 | controller | skipping: Conditional result was False
2026-02-16 17:43:01.173857 | 
2026-02-16 17:43:01.173945 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:43:01.716721 | controller | ok: Runtime: 0:00:00.019772
2026-02-16 17:43:01.729928 | 
2026-02-16 17:43:01.730105 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:43:01.757481 | controller | skipping: Conditional result was False
2026-02-16 17:43:01.766657 | 
2026-02-16 17:43:01.766744 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:43:01.792457 | controller | skipping: Conditional result was False
2026-02-16 17:43:01.802853 | 
2026-02-16 17:43:01.802922 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:43:01.860265 | controller | ok
2026-02-16 17:43:01.870649 | controller | included: /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:43:01.876737 | 
2026-02-16 17:43:01.876799 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:43:01.931035 | controller | ok
2026-02-16 17:43:01.936585 | 
2026-02-16 17:43:01.936645 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:43:02.218396 | controller | ext4
2026-02-16 17:43:02.518659 | controller | ok: Runtime: 0:00:00.013186
2026-02-16 17:43:02.524425 | 
2026-02-16 17:43:02.524489 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:43:02.558425 | controller | ok
2026-02-16 17:43:02.565011 | 
2026-02-16 17:43:02.565073 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:43:02.599217 | controller | ok:
2026-02-16 17:43:02.599374 | controller | {
2026-02-16 17:43:02.599408 | controller |   "root_filesystem": "ext4"
2026-02-16 17:43:02.599435 | controller | }
2026-02-16 17:43:02.606404 | 
2026-02-16 17:43:02.606467 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:43:12.785321 | controller | 8192+0 records in
2026-02-16 17:43:12.785608 | controller | 8192+0 records out
2026-02-16 17:43:12.785636 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.92539 s, 865 MB/s
2026-02-16 17:43:13.195053 | controller | ok: Runtime: 0:00:09.935518
2026-02-16 17:43:13.200473 | 
2026-02-16 17:43:13.200534 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:43:13.437141 | controller | changed
2026-02-16 17:43:13.446836 | 
2026-02-16 17:43:13.446925 | TASK [configure-swap : Make swapfile]
2026-02-16 17:43:35.454914 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:43:35.455124 | controller | no label, UUID=07df5435-4b1d-48fd-9df1-d44050e43bff
2026-02-16 17:43:35.522468 | controller | ok: Runtime: 0:00:21.769820
2026-02-16 17:43:35.529836 | 
2026-02-16 17:43:35.529900 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:43:35.924996 | controller | changed
2026-02-16 17:43:35.930611 | 
2026-02-16 17:43:35.930677 | TASK [configure-swap : Add all swap]
2026-02-16 17:43:36.473864 | controller | ok: Runtime: 0:00:00.015501
2026-02-16 17:43:36.479603 | 
2026-02-16 17:43:36.479671 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:43:36.514659 | controller | ok:
2026-02-16 17:43:36.514853 | controller | {
2026-02-16 17:43:36.514888 | controller |   "swap_required": "8192"
2026-02-16 17:43:36.514915 | controller | }
2026-02-16 17:43:36.521209 | 
2026-02-16 17:43:36.521274 | TASK [configure-swap : Set swappiness]
2026-02-16 17:43:36.918659 | controller | changed
2026-02-16 17:43:36.925318 | 
2026-02-16 17:43:36.925387 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:43:36.959147 | controller | ok:
2026-02-16 17:43:36.959288 | controller | {
2026-02-16 17:43:36.959314 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:43:36.959339 | controller | }
2026-02-16 17:43:36.968915 | 
2026-02-16 17:43:36.968996 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:43:37.420200 | controller | changed
2026-02-16 17:43:37.427603 | 
2026-02-16 17:43:37.427670 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:43:37.677117 | controller | changed
2026-02-16 17:43:37.683186 | 
2026-02-16 17:43:37.683256 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:43:38.226715 | controller | changed
2026-02-16 17:43:38.237017 | 
2026-02-16 17:43:38.237108 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:43:38.493770 | controller | changed
2026-02-16 17:43:38.500477 | 
2026-02-16 17:43:38.500543 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:43:39.590030 | controller | changed
2026-02-16 17:43:39.597492 | 
2026-02-16 17:43:39.597555 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:43:39.839382 | controller | changed
2026-02-16 17:43:39.852951 | 
2026-02-16 17:43:39.853035 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:43:40.151850 | controller | changed
2026-02-16 17:43:40.157451 | 
2026-02-16 17:43:40.157520 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:43:40.539574 | controller | changed
2026-02-16 17:43:40.594494 | 
2026-02-16 17:43:40.594647 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:43:41.391586 | controller | changed
2026-02-16 17:43:41.401572 | 
2026-02-16 17:43:41.401638 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:43:41.731740 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:43:41.743000 | 
2026-02-16 17:43:41.743063 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:43:42.235212 | controller | ok: Item: Runtime: 0:00:00.205220
2026-02-16 17:43:42.807427 | controller | ok: Item: Runtime: 0:00:00.346922
2026-02-16 17:43:43.545828 | controller | ok: Item: Runtime: 0:00:00.518830
2026-02-16 17:43:43.817287 | controller | ok: Item: Runtime: 0:00:00.062325
2026-02-16 17:43:44.292175 | controller | ok: Item: Runtime: 0:00:00.249731
2026-02-16 17:43:45.861735 | controller | ok: Item: Runtime: 0:00:01.359834
2026-02-16 17:43:49.030415 | controller | ok: Item: Runtime: 0:00:02.945219
2026-02-16 17:43:49.345278 | controller | ok: Item: Runtime: 0:00:00.095892
2026-02-16 17:43:49.753907 | controller | ok: Item: Runtime: 0:00:00.191815
2026-02-16 17:43:50.079643 | controller | ok: Item: Runtime: 0:00:00.116316
2026-02-16 17:43:50.836658 | controller | ok: Item: Runtime: 0:00:00.532571
2026-02-16 17:43:51.592463 | controller | ok: Item: Runtime: 0:00:00.531733
2026-02-16 17:43:52.105914 | controller | ok: Item: Runtime: 0:00:00.265674
2026-02-16 17:43:52.549793 | controller | ok: Item: Runtime: 0:00:00.223435
2026-02-16 17:43:53.132699 | controller | ok: Item: Runtime: 0:00:00.367321
2026-02-16 17:43:54.378121 | controller | ok: Item: Runtime: 0:00:01.031868
2026-02-16 17:43:54.408936 | 
2026-02-16 17:43:54.409152 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:43:54.625798 | controller | ok: All paths examined
2026-02-16 17:43:54.634272 | 
2026-02-16 17:43:54.634337 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:43:54.897370 | controller | ok: All paths examined
2026-02-16 17:43:54.903567 | 
2026-02-16 17:43:54.903633 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:43:55.348298 | controller | ok: Item: Runtime: 0:00:00.156085
2026-02-16 17:43:55.348612 | controller | changed: All items complete
2026-02-16 17:43:55.348654 | 
2026-02-16 17:43:55.766274 | controller | ok: Item: Runtime: 0:00:00.207257
2026-02-16 17:43:55.781262 | 
2026-02-16 17:43:55.781431 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:43:55.824042 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.826157 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.828985 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.832173 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.834913 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.837970 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.841087 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.844276 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.847453 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.850555 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.853579 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.856547 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.859804 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.863184 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.866202 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.869330 | controller | skipping: Conditional result was False
2026-02-16 17:43:55.901328 | 
2026-02-16 17:43:55.901461 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:43:58.219895 | controller | changed
2026-02-16 17:43:58.231139 | 
2026-02-16 17:43:58.231205 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:43:58.481582 | controller | changed
2026-02-16 17:43:58.538240 | 
2026-02-16 17:43:58.538388 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:43:58.782210 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:43:59.086497 | controller | ERROR
2026-02-16 17:43:59.087653 | controller | {
2026-02-16 17:43:59.087931 | controller |   "delta": "0:00:00.010860",
2026-02-16 17:43:59.088043 | controller |   "end": "2026-02-16 17:43:58.782866",
2026-02-16 17:43:59.088120 | controller |   "msg": "non-zero return code",
2026-02-16 17:43:59.088174 | controller |   "rc": 1,
2026-02-16 17:43:59.088310 | controller |   "start": "2026-02-16 17:43:58.772006"
2026-02-16 17:43:59.088342 | controller | }
2026-02-16 17:43:59.088439 | controller | ERROR: Ignoring Errors
2026-02-16 17:43:59.101342 | 
2026-02-16 17:43:59.101411 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:43:59.352214 | controller | ok
2026-02-16 17:43:59.364239 | 
2026-02-16 17:43:59.364327 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:43:59.595367 | controller | /usr/bin/journalctl
2026-02-16 17:43:59.906950 | controller | ok: Runtime: 0:00:00.007953
2026-02-16 17:43:59.913456 | 
2026-02-16 17:43:59.913527 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:44:00.171797 | controller | 2026-02-16 17:44:00
2026-02-16 17:44:00.453717 | controller | ok: Runtime: 0:00:00.008673
2026-02-16 17:44:00.459365 | 
2026-02-16 17:44:00.459430 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:44:01.022585 | controller | changed
2026-02-16 17:44:01.028567 | 
2026-02-16 17:44:01.028651 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:44:01.053992 | controller | skipping: Conditional result was False
2026-02-16 17:44:01.064007 | 
2026-02-16 17:44:01.064736 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:44:01.605428 | controller | skipping: Conditional result was False
2026-02-16 17:44:01.615702 | 
2026-02-16 17:44:01.615800 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:44:02.146726 | controller | skipping: Conditional result was False
2026-02-16 17:44:02.158006 | 
2026-02-16 17:44:02.158103 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:44:02.183659 | controller | skipping: Conditional result was False
2026-02-16 17:44:02.195848 | 
2026-02-16 17:44:02.195915 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:44:02.725814 | controller | skipping: Conditional result was False
2026-02-16 17:44:02.736090 | 
2026-02-16 17:44:02.736186 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:44:03.267757 | controller | skipping: Conditional result was False
2026-02-16 17:44:03.279818 | 
2026-02-16 17:44:03.279893 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:44:03.305635 | controller | skipping: Conditional result was False
2026-02-16 17:44:03.316570 | 
2026-02-16 17:44:03.316647 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:44:03.342285 | controller | skipping: Conditional result was False
2026-02-16 17:44:03.354152 | 
2026-02-16 17:44:03.354248 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:44:03.949025 | controller | skipping: Conditional result was False
2026-02-16 17:44:03.961016 | 
2026-02-16 17:44:03.961087 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:44:04.490866 | controller | skipping: Conditional result was False
2026-02-16 17:44:04.508030 | 
2026-02-16 17:44:04.508276 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:44:04.534584 | controller | skipping: Conditional result was False
2026-02-16 17:44:04.544928 | 
2026-02-16 17:44:04.545036 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:44:04.570233 | controller | skipping: Conditional result was False
2026-02-16 17:44:04.585983 | 
2026-02-16 17:44:04.586048 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:44:04.978279 | controller | ok
2026-02-16 17:44:04.987882 | 
2026-02-16 17:44:04.987926 | PLAY RECAP
2026-02-16 17:44:04.987972 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:44:04.987993 | 
2026-02-16 17:44:05.123303 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:44:05.132826 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:44:05.759651 | 
2026-02-16 17:44:05.760506 | PLAY [all]
2026-02-16 17:44:05.773220 | 
2026-02-16 17:44:05.773300 | TASK [ensure-rust : Use rustup]
2026-02-16 17:44:05.807718 | controller | ok
2026-02-16 17:44:05.816021 | controller | included: /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:44:05.821646 | 
2026-02-16 17:44:05.821720 | TASK [ensure-rust : Install Rust]
2026-02-16 17:44:06.217907 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:44:06.361937 | controller | ERROR
2026-02-16 17:44:06.362236 | controller | {
2026-02-16 17:44:06.362278 | controller |   "delta": "0:00:00.012191",
2026-02-16 17:44:06.362309 | controller |   "end": "2026-02-16 17:44:06.220373",
2026-02-16 17:44:06.362336 | controller |   "msg": "non-zero return code",
2026-02-16 17:44:06.362362 | controller |   "rc": 127,
2026-02-16 17:44:06.362387 | controller |   "start": "2026-02-16 17:44:06.208182"
2026-02-16 17:44:06.362416 | controller | }
failure
2026-02-16 17:44:06.364346 | 
2026-02-16 17:44:06.364412 | PLAY RECAP
2026-02-16 17:44:06.364479 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:44:06.364511 | 
2026-02-16 17:44:06.500539 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:44:06.512550 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:44:07.150077 | 
2026-02-16 17:44:07.150234 | PLAY [all]
2026-02-16 17:44:07.167832 | 
2026-02-16 17:44:07.167916 | TASK [Return built artifacts to Zuul]
2026-02-16 17:44:07.202159 | controller | ok
2026-02-16 17:44:07.207658 | 
2026-02-16 17:44:07.207732 | TASK [Get the Docker volume ID]
2026-02-16 17:44:07.747519 | controller | ERROR
2026-02-16 17:44:07.747989 | controller | {
2026-02-16 17:44:07.748059 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:44:07.748108 | controller |   "rc": 2
2026-02-16 17:44:07.748153 | controller | }
2026-02-16 17:44:07.748209 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:07.755255 | 
2026-02-16 17:44:07.755321 | TASK [Copy all of the pod logs]
2026-02-16 17:44:07.787648 | controller | ERROR
2026-02-16 17:44:07.787893 | controller | {
2026-02-16 17:44:07.787934 | 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/16e8452ef9b94b35b2987ec00cfe122d/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:44:07.787965 | controller | }
2026-02-16 17:44:07.788002 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:07.789351 | 
2026-02-16 17:44:07.789413 | PLAY RECAP
2026-02-16 17:44:07.789471 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:44:07.789501 | 
2026-02-16 17:44:07.930798 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:44:07.941235 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:44:08.539648 | 
2026-02-16 17:44:08.539782 | PLAY [all]
2026-02-16 17:44:08.557954 | 
2026-02-16 17:44:08.558038 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:44:08.946437 | controller | changed
2026-02-16 17:44:08.955604 | 
2026-02-16 17:44:08.955724 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:44:09.996123 | controller | ok: Runtime: 0:00:00.465407
2026-02-16 17:44:10.004487 | 
2026-02-16 17:44:10.004562 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:44:10.543289 | controller | ok: Runtime: 0:00:00.018542
2026-02-16 17:44:10.549131 | 
2026-02-16 17:44:10.549199 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:44:11.086875 | controller | ok: Runtime: 0:00:00.021485
2026-02-16 17:44:11.094239 | 
2026-02-16 17:44:11.094306 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:44:11.921886 | controller | changed
2026-02-16 17:44:11.965296 | 
2026-02-16 17:44:11.965451 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:44:12.212123 | controller | changed
2026-02-16 17:44:12.218762 | 
2026-02-16 17:44:12.218840 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:44:12.552782 | controller | Output suppressed because no_log was given
2026-02-16 17:44:12.558121 | 
2026-02-16 17:44:12.558185 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:44:12.590648 | 
2026-02-16 17:44:12.590802 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:44:12.620879 | 
2026-02-16 17:44:12.621015 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:44:12.656801 | 
2026-02-16 17:44:12.656926 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:44:12.691661 | 
2026-02-16 17:44:12.691834 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:44:12.730485 | 
2026-02-16 17:44:12.730740 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:44:12.979249 | controller | changed
2026-02-16 17:44:12.984988 | 
2026-02-16 17:44:12.985053 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:44:13.022592 | controller | ok
2026-02-16 17:44:13.028553 | 
2026-02-16 17:44:13.028612 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:44:13.262243 | controller | Output suppressed because no_log was given
2026-02-16 17:44:13.267479 | 
2026-02-16 17:44:13.267542 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:44:13.299988 | 
2026-02-16 17:44:13.300161 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:44:13.341825 | 
2026-02-16 17:44:13.341960 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:44:14.004126 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:44:14.023492 | controller | Using python 3.12 to install setuptools
2026-02-16 17:44:14.061615 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:44:14.072906 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:44:14.378180 | controller | ERROR
2026-02-16 17:44:14.378424 | controller | {
2026-02-16 17:44:14.378458 | controller |   "delta": "0:00:00.494507",
2026-02-16 17:44:14.378479 | controller |   "end": "2026-02-16 17:44:14.075298",
2026-02-16 17:44:14.378498 | controller |   "msg": "non-zero return code",
2026-02-16 17:44:14.378518 | controller |   "rc": 127,
2026-02-16 17:44:14.378536 | controller |   "start": "2026-02-16 17:44:13.580791"
2026-02-16 17:44:14.378553 | controller | }
2026-02-16 17:44:14.378578 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:14.390202 | 
2026-02-16 17:44:14.390273 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:44:14.638713 | controller | changed
2026-02-16 17:44:14.644259 | 
2026-02-16 17:44:14.644323 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:44:18.377422 | controller | Output suppressed because no_log was given
2026-02-16 17:44:18.390584 | 
2026-02-16 17:44:18.390659 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:44:18.423277 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.423664 | 
2026-02-16 17:44:18.424663 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.426719 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.429139 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.431592 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.433963 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.436430 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.438830 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.441106 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.443523 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.446014 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.448340 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.450737 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.453168 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.455521 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.457795 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.460070 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.464119 | controller | skipping: Conditional result was False
2026-02-16 17:44:18.487498 | 
2026-02-16 17:44:18.487665 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:44:18.726739 | controller | ok
2026-02-16 17:44:18.733777 | 
2026-02-16 17:44:18.733841 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:44:19.336466 | controller | skipping: Conditional result was False
2026-02-16 17:44:19.355822 | 
2026-02-16 17:44:19.355908 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:44:19.638899 | controller | sudo: iptables-save: command not found
2026-02-16 17:44:19.681430 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:44:21.186790 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:44:21.390557 | controller | ok: Runtime: 0:00:01.573109
2026-02-16 17:44:21.401177 | 
2026-02-16 17:44:21.401243 | LOOP [stage-output : Register sources]
2026-02-16 17:44:30.802378 | controller | Output suppressed because no_log was given
2026-02-16 17:44:30.847751 | 
2026-02-16 17:44:30.847899 | TASK [stage-output : Check sudo]
2026-02-16 17:44:31.386047 | controller | ok: Runtime: 0:00:00.021784
2026-02-16 17:44:31.393961 | 
2026-02-16 17:44:31.394050 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:44:31.502955 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.503398 | 
2026-02-16 17:44:31.505361 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.506238 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.508379 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.543676 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.544116 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.544476 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.545918 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.548286 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.550408 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.552510 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.554969 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.557130 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.559331 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.589698 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.590098 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.590545 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.591028 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.591479 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.591840 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.592213 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.592762 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.593156 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.611384 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.611888 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.685766 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.696780 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.705976 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.721179 | controller | Output suppressed because no_log was given
2026-02-16 17:44:31.735391 | 
2026-02-16 17:44:31.735470 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:44:31.801691 | controller | ok
2026-02-16 17:44:31.811080 | 
2026-02-16 17:44:31.811151 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:44:32.070661 | controller | changed: "docs"
2026-02-16 17:44:32.304089 | controller | changed: "artifacts"
2026-02-16 17:44:32.545929 | controller | ok: "logs"
2026-02-16 17:44:32.559014 | 
2026-02-16 17:44:32.559186 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:44:32.858889 | controller | ok: Item: Runtime: 0:00:00.011626
2026-02-16 17:44:32.859113 | controller | changed: All items complete
2026-02-16 17:44:32.859143 | 
2026-02-16 17:44:33.090292 | controller | ok: Item: Runtime: 0:00:00.009523
2026-02-16 17:44:33.324472 | controller | ok: Item: Runtime: 0:00:00.009350
2026-02-16 17:44:33.568000 | controller | ok: Item: Runtime: 0:00:00.008111
2026-02-16 17:44:33.794587 | controller | ok: Item: Runtime: 0:00:00.007733
2026-02-16 17:44:34.030474 | controller | ok: Item: Runtime: 0:00:00.009740
2026-02-16 17:44:34.262454 | controller | ok: Item: Runtime: 0:00:00.008429
2026-02-16 17:44:34.496369 | controller | ok: Item: Runtime: 0:00:00.009647
2026-02-16 17:44:34.723043 | controller | ok: Item: Runtime: 0:00:00.010664
2026-02-16 17:44:34.964406 | controller | ok: Item: Runtime: 0:00:00.008844
2026-02-16 17:44:35.200626 | controller | ok: Item: Runtime: 0:00:00.007531
2026-02-16 17:44:35.488957 | controller | ok: Item: Runtime: 0:00:00.009212
2026-02-16 17:44:35.678411 | controller | ok: Item: Runtime: 0:00:00.008328
2026-02-16 17:44:35.922914 | controller | ok: Item: Runtime: 0:00:00.009406
2026-02-16 17:44:36.150255 | controller | ok: Item: Runtime: 0:00:00.008622
2026-02-16 17:44:36.374937 | controller | ok: Item: Runtime: 0:00:00.008401
2026-02-16 17:44:36.401056 | 
2026-02-16 17:44:36.401824 | TASK [stage-output : Make all log files readable]
2026-02-16 17:44:36.651103 | controller | changed
2026-02-16 17:44:36.657172 | 
2026-02-16 17:44:36.657236 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:44:36.999787 | controller | changed: Renamed files for staging.
2026-02-16 17:44:37.005354 | 
2026-02-16 17:44:37.005421 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:44:37.030732 | controller | skipping: Conditional result was False
2026-02-16 17:44:37.042682 | 
2026-02-16 17:44:37.042756 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:44:37.083821 | 
2026-02-16 17:44:37.083956 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:44:37.701047 | controller | changed:
2026-02-16 17:44:37.701231 | controller | created directory /var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/work/logs/controller
2026-02-16 17:44:37.701258 | controller | cd+++++++++ logs/
2026-02-16 17:44:37.701279 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:44:37.701298 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:44:37.701317 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:44:37.701335 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:44:37.701353 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:44:37.701372 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:44:37.701390 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:44:37.701408 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:44:37.701425 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:44:37.701443 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:44:37.701461 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:44:37.701479 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:44:37.701499 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:44:37.701531 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:44:37.701550 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:44:37.701568 | controller | cd+++++++++ logs/apache/
2026-02-16 17:44:37.701586 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:44:37.701604 | controller | cd+++++++++ logs/etc/
2026-02-16 17:44:37.701621 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:44:37.701639 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:44:37.701657 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:44:37.701675 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:44:37.701692 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:44:37.701710 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:44:37.713644 | 
2026-02-16 17:44:37.713718 | TASK [Check if a tempest log exits]
2026-02-16 17:44:37.960907 | controller | ok
2026-02-16 17:44:37.966307 | 
2026-02-16 17:44:37.966381 | TASK [Link post-devstack tempest.log]
2026-02-16 17:44:37.989327 | controller | skipping: Conditional result was False
2026-02-16 17:44:37.998949 | 
2026-02-16 17:44:37.999037 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:44:38.249246 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:44:38.542328 | controller | ERROR
2026-02-16 17:44:38.542552 | controller | {
2026-02-16 17:44:38.542585 | controller |   "delta": "0:00:00.007888",
2026-02-16 17:44:38.542605 | controller |   "end": "2026-02-16 17:44:38.249872",
2026-02-16 17:44:38.542627 | controller |   "msg": "non-zero return code",
2026-02-16 17:44:38.542646 | controller |   "rc": 127,
2026-02-16 17:44:38.542664 | controller |   "start": "2026-02-16 17:44:38.241984"
2026-02-16 17:44:38.542681 | controller | }
2026-02-16 17:44:38.542704 | controller | ERROR: Ignoring Errors
2026-02-16 17:44:38.544487 | 
2026-02-16 17:44:38.544535 | PLAY RECAP
2026-02-16 17:44:38.544585 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:44:38.544606 | 
2026-02-16 17:44:38.684588 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:44:38.696539 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:39.272611 | 
2026-02-16 17:44:39.272743 | PLAY [all]
2026-02-16 17:44:39.284207 | 
2026-02-16 17:44:39.284279 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:44:39.329336 | controller | skipping: Conditional result was False
2026-02-16 17:44:39.336890 | 
2026-02-16 17:44:39.337012 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:44:39.379683 | controller | ok
2026-02-16 17:44:39.386639 | 
2026-02-16 17:44:39.386706 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:44:39.755915 | controller -> localhost | ok: "/var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/work/logs"
2026-02-16 17:44:39.958189 | controller -> localhost | changed: "/var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/work/artifacts"
2026-02-16 17:44:40.166625 | controller -> localhost | changed: "/var/lib/zuul/builds/16e8452ef9b94b35b2987ec00cfe122d/work/docs"
2026-02-16 17:44:40.188085 | 
2026-02-16 17:44:40.188257 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:44:40.815771 | controller | changed: .d..t...... ./
2026-02-16 17:44:40.816060 | controller | changed: All items complete
2026-02-16 17:44:40.816098 | 
2026-02-16 17:44:41.279615 | controller | changed: .d..t...... ./
2026-02-16 17:44:41.746760 | controller | changed: .d..t...... ./
2026-02-16 17:44:41.769227 | 
2026-02-16 17:44:41.769387 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:44:42.175084 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008253
2026-02-16 17:44:42.395259 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007743
2026-02-16 17:44:42.407398 | 
2026-02-16 17:44:42.407498 | PLAY [all]
2026-02-16 17:44:42.414837 | 
2026-02-16 17:44:42.414898 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:44:42.876754 | controller | changed
2026-02-16 17:44:42.883728 | 
2026-02-16 17:44:42.883784 | PLAY RECAP
2026-02-16 17:44:42.883830 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:44:42.883851 | 
2026-02-16 17:44:43.067059 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:44:43.103774 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:44:43.721826 | 
2026-02-16 17:44:43.721965 | PLAY [localhost]
2026-02-16 17:44:43.732159 | 
2026-02-16 17:44:43.732231 | TASK [Generate Zuul manifest]
2026-02-16 17:44:43.751311 | localhost | ok
2026-02-16 17:44:43.767180 | 
2026-02-16 17:44:43.767255 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:44:44.153387 | localhost | changed
2026-02-16 17:44:44.165154 | 
2026-02-16 17:44:44.165225 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:44:44.196878 | localhost | ok
2026-02-16 17:44:44.205418 | 
2026-02-16 17:44:44.205480 | TASK [Upload logs]
2026-02-16 17:44:44.225918 | localhost | ok
2026-02-16 17:44:44.282826 | 
2026-02-16 17:44:44.282903 | TASK [Set zuul-log-path fact]
2026-02-16 17:44:44.300468 | localhost | ok
2026-02-16 17:44:44.312955 | 
2026-02-16 17:44:44.313038 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:44:44.344766 | localhost | ok
2026-02-16 17:44:44.351638 | 
2026-02-16 17:44:44.351730 | TASK [upload-logs : Create log directories]
2026-02-16 17:44:44.738381 | localhost | changed
2026-02-16 17:44:44.745463 | 
2026-02-16 17:44:44.745529 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:44:45.178369 | localhost -> localhost | ok: Runtime: 0:00:00.005686
2026-02-16 17:44:45.183053 | 
2026-02-16 17:44:45.183118 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:44:45.611011 | localhost | Output suppressed because no_log was given
2026-02-16 17:44:45.615783 | 
2026-02-16 17:44:45.615868 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:44:45.659428 | localhost | skipping: Conditional result was False
2026-02-16 17:44:45.665169 | localhost | skipping: Conditional result was False
2026-02-16 17:44:45.678321 | 
2026-02-16 17:44:45.678459 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:44:45.720932 | localhost | skipping: Conditional result was False
2026-02-16 17:44:45.721582 | 
2026-02-16 17:44:45.723985 | localhost | skipping: Conditional result was False
2026-02-16 17:44:45.742769 | 
2026-02-16 17:44:45.742971 | LOOP [upload-logs : Upload console log and json output]