2026-02-16 17:38:02.889099 | Job console starting
2026-02-16 17:38:02.899650 | Updating git repos
2026-02-16 17:38:05.834794 | Cloning repos into workspace
2026-02-16 17:38:15.348474 | Restoring repo states
2026-02-16 17:38:18.293323 | Merging changes
2026-02-16 17:38:19.097794 | Checking out repos
2026-02-16 17:38:23.659156 | Preparing playbooks
2026-02-16 17:38:26.634714 | Running Ansible setup
2026-02-16 17:38:31.091648 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:38:31.755324 | 
2026-02-16 17:38:31.755626 | PLAY [localhost]
2026-02-16 17:38:31.769757 | 
2026-02-16 17:38:31.770037 | TASK [Gathering Facts]
2026-02-16 17:38:33.906319 | localhost | ok
2026-02-16 17:38:33.916676 | 
2026-02-16 17:38:33.916787 | TASK [Setup log path fact]
2026-02-16 17:38:33.937956 | localhost | ok
2026-02-16 17:38:33.949805 | 
2026-02-16 17:38:33.949873 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:38:33.980159 | localhost | ok
2026-02-16 17:38:33.990974 | 
2026-02-16 17:38:33.991047 | TASK [emit-job-header : Print job information]
2026-02-16 17:38:34.033199 | # Job Information
2026-02-16 17:38:34.033365 | Ansible Version: 2.16.15
2026-02-16 17:38:34.033409 | Job: magnum-cluster-api-sonobuoy-v1.35.1-cilium
2026-02-16 17:38:34.033439 | Pipeline: check
2026-02-16 17:38:34.033467 | Executor: 3a2793d2bd32
2026-02-16 17:38:34.033493 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:38:34.033523 | Event ID: 2685a480-0b5e-11f1-90f6-a235114455cc
2026-02-16 17:38:34.037364 | 
2026-02-16 17:38:34.037705 | LOOP [emit-job-header : Print node information]
2026-02-16 17:38:34.143482 | localhost | ok:
2026-02-16 17:38:34.143928 | localhost | # Node Information
2026-02-16 17:38:34.143997 | localhost | Inventory Hostname: controller
2026-02-16 17:38:34.144047 | localhost | Hostname: np0000155616
2026-02-16 17:38:34.144091 | localhost | Username: zuul
2026-02-16 17:38:34.144139 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:38:34.144183 | localhost | Provider: yul1
2026-02-16 17:38:34.144225 | localhost | Region: ca-ymq-1
2026-02-16 17:38:34.144268 | localhost | Label: ubuntu-noble-16
2026-02-16 17:38:34.144309 | localhost | Product Name: OpenStack Nova
2026-02-16 17:38:34.144350 | localhost | Interface IP: 199.204.45.109
2026-02-16 17:38:34.164174 | 
2026-02-16 17:38:34.164338 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:38:34.645068 | localhost -> localhost | changed
2026-02-16 17:38:34.651555 | 
2026-02-16 17:38:34.651626 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:38:35.586631 | localhost -> localhost | changed
2026-02-16 17:38:35.593260 | 
2026-02-16 17:38:35.593324 | PLAY [all]
2026-02-16 17:38:35.602134 | 
2026-02-16 17:38:35.602213 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:38:35.819223 | controller -> localhost | ok
2026-02-16 17:38:35.826597 | 
2026-02-16 17:38:35.826679 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:38:35.872390 | controller | ok
2026-02-16 17:38:35.888330 | controller | included: /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:38:35.894604 | 
2026-02-16 17:38:35.894666 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:38:36.715041 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:38:36.715224 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/work/4fa4babed87045638450e93a5b072cd6_id_rsa
2026-02-16 17:38:36.715253 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/work/4fa4babed87045638450e93a5b072cd6_id_rsa.pub
2026-02-16 17:38:36.715276 | controller -> localhost | The key fingerprint is:
2026-02-16 17:38:36.715297 | controller -> localhost | SHA256:l/KWhDaZeeJbdokbH5JBm2mso8F+Ani9odVD33j1Tes zuul-build-sshkey
2026-02-16 17:38:36.715326 | controller -> localhost | The key's randomart image is:
2026-02-16 17:38:36.715348 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:38:36.715373 | controller -> localhost | |                 |
2026-02-16 17:38:36.715394 | controller -> localhost | |                 |
2026-02-16 17:38:36.715414 | controller -> localhost | |          .      |
2026-02-16 17:38:36.715433 | controller -> localhost | |        .B =  . .|
2026-02-16 17:38:36.715452 | controller -> localhost | |   . . oS.%o . oo|
2026-02-16 17:38:36.715471 | controller -> localhost | |  . o.+oo@o=o. .o|
2026-02-16 17:38:36.715490 | controller -> localhost | |   . +oo+.@.+ .  |
2026-02-16 17:38:36.715509 | controller -> localhost | |    ..oo.* * . E |
2026-02-16 17:38:36.715531 | controller -> localhost | |      oo. . .    |
2026-02-16 17:38:36.715551 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:38:36.715600 | controller -> localhost | ok: Runtime: 0:00:00.315030
2026-02-16 17:38:36.721619 | 
2026-02-16 17:38:36.721682 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:38:36.765720 | controller | ok
2026-02-16 17:38:36.775699 | controller | included: /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:38:36.783741 | 
2026-02-16 17:38:36.783808 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:38:36.809310 | controller | skipping: Conditional result was False
2026-02-16 17:38:36.819711 | 
2026-02-16 17:38:36.819800 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:38:37.666200 | controller | changed
2026-02-16 17:38:37.674294 | 
2026-02-16 17:38:37.674356 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:38:37.905790 | controller | ok
2026-02-16 17:38:37.913450 | 
2026-02-16 17:38:37.913519 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:38:38.503571 | controller | changed
2026-02-16 17:38:38.510334 | 
2026-02-16 17:38:38.510402 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:38:39.087233 | controller | changed
2026-02-16 17:38:39.095889 | 
2026-02-16 17:38:39.095958 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:38:39.120494 | controller | skipping: Conditional result was False
2026-02-16 17:38:39.126682 | 
2026-02-16 17:38:39.126747 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:38:39.526245 | controller -> localhost | changed
2026-02-16 17:38:39.541125 | 
2026-02-16 17:38:39.541213 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:38:39.890334 | controller -> localhost | Identity added: /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/work/4fa4babed87045638450e93a5b072cd6_id_rsa (zuul-build-sshkey)
2026-02-16 17:38:39.890603 | controller -> localhost | ok: Runtime: 0:00:00.017249
2026-02-16 17:38:39.943706 | 
2026-02-16 17:38:39.943876 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:38:40.244765 | controller | ok
2026-02-16 17:38:40.251450 | 
2026-02-16 17:38:40.251522 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:38:40.276823 | controller | skipping: Conditional result was False
2026-02-16 17:38:40.294751 | 
2026-02-16 17:38:40.294820 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:38:40.637169 | controller | ok
2026-02-16 17:38:40.676974 | 
2026-02-16 17:38:40.677086 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:39:05.236281 | controller | Output suppressed because no_log was given
2026-02-16 17:39:05.250876 | 
2026-02-16 17:39:05.250954 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:39:05.485986 | controller | ok: "logs"
2026-02-16 17:39:05.486299 | controller | ok: All items complete
2026-02-16 17:39:05.486327 | 
2026-02-16 17:39:05.670998 | controller | ok: "artifacts"
2026-02-16 17:39:05.848790 | controller | ok: "docs"
2026-02-16 17:39:05.859467 | 
2026-02-16 17:39:05.859613 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:39:06.105473 | controller | changed: "logs"
2026-02-16 17:39:06.331418 | controller | changed: "artifacts"
2026-02-16 17:39:06.574430 | controller | changed: "docs"
2026-02-16 17:39:06.593806 | 
2026-02-16 17:39:06.593950 | PLAY RECAP
2026-02-16 17:39:06.594002 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:39:06.594045 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:39:06.594070 | 
2026-02-16 17:39:06.759669 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:39:06.771685 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:39:07.461238 | 
2026-02-16 17:39:07.461472 | PLAY [all]
2026-02-16 17:39:07.480168 | 
2026-02-16 17:39:07.480289 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:39:07.865661 | controller | changed
2026-02-16 17:39:07.872878 | 
2026-02-16 17:39:07.872974 | TASK [Gather minimum local MTU]
2026-02-16 17:39:07.969401 | controller | ok
2026-02-16 17:39:07.976857 | 
2026-02-16 17:39:07.976927 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:39:08.032383 | controller | ok
2026-02-16 17:39:08.039974 | 
2026-02-16 17:39:08.040059 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:39:08.075886 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.090437 | 
2026-02-16 17:39:08.090583 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:39:08.634823 | controller | ok: Runtime: 0:00:00.010201
2026-02-16 17:39:08.640652 | 
2026-02-16 17:39:08.640717 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:39:08.684776 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.692381 | 
2026-02-16 17:39:08.692451 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:39:08.716995 | controller | skipping: Conditional result was False
2026-02-16 17:39:08.735525 | 
2026-02-16 17:39:08.735601 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:39:08.807795 | controller | ok
2026-02-16 17:39:08.817636 | controller | included: /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:39:08.824103 | 
2026-02-16 17:39:08.824166 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:39:08.891473 | controller | ok
2026-02-16 17:39:08.898632 | 
2026-02-16 17:39:08.898708 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:39:09.199508 | controller | ext4
2026-02-16 17:39:09.479088 | controller | ok: Runtime: 0:00:00.013363
2026-02-16 17:39:09.484678 | 
2026-02-16 17:39:09.484742 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:39:09.523669 | controller | ok
2026-02-16 17:39:09.530038 | 
2026-02-16 17:39:09.530103 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:39:09.564399 | controller | ok:
2026-02-16 17:39:09.564554 | controller | {
2026-02-16 17:39:09.564584 | controller |   "root_filesystem": "ext4"
2026-02-16 17:39:09.564605 | controller | }
2026-02-16 17:39:09.571335 | 
2026-02-16 17:39:09.571400 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:39:19.163707 | controller | 8192+0 records in
2026-02-16 17:39:19.164714 | controller | 8192+0 records out
2026-02-16 17:39:19.164752 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.33761 s, 920 MB/s
2026-02-16 17:39:19.614497 | controller | ok: Runtime: 0:00:09.348061
2026-02-16 17:39:19.621925 | 
2026-02-16 17:39:19.621987 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:39:19.850344 | controller | changed
2026-02-16 17:39:19.856679 | 
2026-02-16 17:39:19.856747 | TASK [configure-swap : Make swapfile]
2026-02-16 17:40:13.391506 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:40:13.391832 | controller | no label, UUID=a2f9cb44-84ac-46af-9546-30c931fa1a06
2026-02-16 17:40:13.449210 | controller | ok: Runtime: 0:00:53.291563
2026-02-16 17:40:13.454791 | 
2026-02-16 17:40:13.454855 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:40:13.853614 | controller | changed
2026-02-16 17:40:13.859019 | 
2026-02-16 17:40:13.859082 | TASK [configure-swap : Add all swap]
2026-02-16 17:40:14.402681 | controller | ok: Runtime: 0:00:00.039546
2026-02-16 17:40:14.409794 | 
2026-02-16 17:40:14.410194 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:40:14.445374 | controller | ok:
2026-02-16 17:40:14.445497 | controller | {
2026-02-16 17:40:14.445521 | controller |   "swap_required": "8192"
2026-02-16 17:40:14.445541 | controller | }
2026-02-16 17:40:14.450552 | 
2026-02-16 17:40:14.450618 | TASK [configure-swap : Set swappiness]
2026-02-16 17:40:14.880734 | controller | changed
2026-02-16 17:40:14.890374 | 
2026-02-16 17:40:14.890518 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:40:14.926276 | controller | ok:
2026-02-16 17:40:14.926708 | controller | {
2026-02-16 17:40:14.926747 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:40:14.926777 | controller | }
2026-02-16 17:40:14.937570 | 
2026-02-16 17:40:14.937714 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:40:15.602269 | controller | changed
2026-02-16 17:40:15.608901 | 
2026-02-16 17:40:15.608986 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:40:15.844978 | controller | changed
2026-02-16 17:40:15.852577 | 
2026-02-16 17:40:15.852646 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:40:16.913891 | controller | changed
2026-02-16 17:40:16.919843 | 
2026-02-16 17:40:16.919912 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:40:17.151784 | controller | changed
2026-02-16 17:40:17.158682 | 
2026-02-16 17:40:17.158766 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:40:18.244499 | controller | changed
2026-02-16 17:40:18.250395 | 
2026-02-16 17:40:18.250459 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:40:18.513975 | controller | changed
2026-02-16 17:40:18.524983 | 
2026-02-16 17:40:18.525050 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:40:18.814812 | controller | changed
2026-02-16 17:40:18.822278 | 
2026-02-16 17:40:18.822351 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:40:19.210963 | controller | changed
2026-02-16 17:40:19.260778 | 
2026-02-16 17:40:19.260938 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:40:20.025556 | controller | changed
2026-02-16 17:40:20.036930 | 
2026-02-16 17:40:20.037025 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:40:20.367675 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:40:20.380056 | 
2026-02-16 17:40:20.380146 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:40:20.848079 | controller | ok: Item: Runtime: 0:00:00.187702
2026-02-16 17:40:21.407488 | controller | ok: Item: Runtime: 0:00:00.325329
2026-02-16 17:40:22.144565 | controller | ok: Item: Runtime: 0:00:00.514238
2026-02-16 17:40:22.422718 | controller | ok: Item: Runtime: 0:00:00.066455
2026-02-16 17:40:22.904913 | controller | ok: Item: Runtime: 0:00:00.256021
2026-02-16 17:40:24.427068 | controller | ok: Item: Runtime: 0:00:01.294134
2026-02-16 17:40:27.351593 | controller | ok: Item: Runtime: 0:00:02.710675
2026-02-16 17:40:27.680493 | controller | ok: Item: Runtime: 0:00:00.102704
2026-02-16 17:40:28.080100 | controller | ok: Item: Runtime: 0:00:00.183845
2026-02-16 17:40:28.414871 | controller | ok: Item: Runtime: 0:00:00.115591
2026-02-16 17:40:29.128270 | controller | ok: Item: Runtime: 0:00:00.486450
2026-02-16 17:40:29.863737 | controller | ok: Item: Runtime: 0:00:00.514131
2026-02-16 17:40:30.370466 | controller | ok: Item: Runtime: 0:00:00.259416
2026-02-16 17:40:30.836894 | controller | ok: Item: Runtime: 0:00:00.231801
2026-02-16 17:40:31.471907 | controller | ok: Item: Runtime: 0:00:00.408367
2026-02-16 17:40:32.844442 | controller | ok: Item: Runtime: 0:00:01.122002
2026-02-16 17:40:32.878738 | 
2026-02-16 17:40:32.878903 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:40:33.106739 | controller | ok: All paths examined
2026-02-16 17:40:33.114480 | 
2026-02-16 17:40:33.114550 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:40:33.386086 | controller | ok: All paths examined
2026-02-16 17:40:33.392676 | 
2026-02-16 17:40:33.392744 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:40:33.827334 | controller | ok: Item: Runtime: 0:00:00.147927
2026-02-16 17:40:33.827581 | controller | changed: All items complete
2026-02-16 17:40:33.827609 | 
2026-02-16 17:40:34.289683 | controller | ok: Item: Runtime: 0:00:00.186107
2026-02-16 17:40:34.309009 | 
2026-02-16 17:40:34.309186 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:40:34.352723 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.356469 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.360098 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.363176 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.366320 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.369477 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.372829 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.376039 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.379272 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.382016 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.385136 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.388185 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.391165 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.394238 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.397268 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.400878 | controller | skipping: Conditional result was False
2026-02-16 17:40:34.431085 | 
2026-02-16 17:40:34.431277 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:40:36.968827 | controller | changed
2026-02-16 17:40:36.979735 | 
2026-02-16 17:40:36.979802 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:40:37.228776 | controller | changed
2026-02-16 17:40:37.284455 | 
2026-02-16 17:40:37.284607 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:40:37.537926 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:40:37.824734 | controller | ERROR
2026-02-16 17:40:37.824989 | controller | {
2026-02-16 17:40:37.825033 | controller |   "delta": "0:00:00.009098",
2026-02-16 17:40:37.825062 | controller |   "end": "2026-02-16 17:40:37.538430",
2026-02-16 17:40:37.825089 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:37.825115 | controller |   "rc": 1,
2026-02-16 17:40:37.825140 | controller |   "start": "2026-02-16 17:40:37.529332"
2026-02-16 17:40:37.825164 | controller | }
2026-02-16 17:40:37.825205 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:37.832858 | 
2026-02-16 17:40:37.832945 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:40:38.080012 | controller | ok
2026-02-16 17:40:38.091088 | 
2026-02-16 17:40:38.091151 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:40:38.329341 | controller | /usr/bin/journalctl
2026-02-16 17:40:38.630393 | controller | ok: Runtime: 0:00:00.007471
2026-02-16 17:40:38.637969 | 
2026-02-16 17:40:38.638055 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:40:38.883605 | controller | 2026-02-16 17:40:38
2026-02-16 17:40:39.177715 | controller | ok: Runtime: 0:00:00.008578
2026-02-16 17:40:39.187166 | 
2026-02-16 17:40:39.187236 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:40:39.779747 | controller | changed
2026-02-16 17:40:39.790305 | 
2026-02-16 17:40:39.790629 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:40:39.818032 | controller | skipping: Conditional result was False
2026-02-16 17:40:39.829831 | 
2026-02-16 17:40:39.829907 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:40:40.368898 | controller | skipping: Conditional result was False
2026-02-16 17:40:40.382353 | 
2026-02-16 17:40:40.382437 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:40:40.911399 | controller | skipping: Conditional result was False
2026-02-16 17:40:40.923906 | 
2026-02-16 17:40:40.923981 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:40:40.961187 | controller | skipping: Conditional result was False
2026-02-16 17:40:40.970115 | 
2026-02-16 17:40:40.970223 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:40:41.499539 | controller | skipping: Conditional result was False
2026-02-16 17:40:41.510879 | 
2026-02-16 17:40:41.510976 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:40:42.042845 | controller | skipping: Conditional result was False
2026-02-16 17:40:42.053588 | 
2026-02-16 17:40:42.053666 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:40:42.080023 | controller | skipping: Conditional result was False
2026-02-16 17:40:42.090735 | 
2026-02-16 17:40:42.090855 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:40:42.118431 | controller | skipping: Conditional result was False
2026-02-16 17:40:42.127423 | 
2026-02-16 17:40:42.127497 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:40:42.720330 | controller | skipping: Conditional result was False
2026-02-16 17:40:42.732231 | 
2026-02-16 17:40:42.732304 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:40:43.261488 | controller | skipping: Conditional result was False
2026-02-16 17:40:43.272974 | 
2026-02-16 17:40:43.273047 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:40:43.298582 | controller | skipping: Conditional result was False
2026-02-16 17:40:43.309072 | 
2026-02-16 17:40:43.309142 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:40:43.334625 | controller | skipping: Conditional result was False
2026-02-16 17:40:43.349579 | 
2026-02-16 17:40:43.349648 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:40:43.744092 | controller | ok
2026-02-16 17:40:43.753835 | 
2026-02-16 17:40:43.753891 | PLAY RECAP
2026-02-16 17:40:43.753942 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:40:43.753964 | 
2026-02-16 17:40:43.901052 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:40:43.910373 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:44.530606 | 
2026-02-16 17:40:44.530797 | PLAY [all]
2026-02-16 17:40:44.544593 | 
2026-02-16 17:40:44.544724 | TASK [ensure-rust : Use rustup]
2026-02-16 17:40:44.580574 | controller | ok
2026-02-16 17:40:44.591608 | controller | included: /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:40:44.597703 | 
2026-02-16 17:40:44.597783 | TASK [ensure-rust : Install Rust]
2026-02-16 17:40:44.997386 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:40:45.143921 | controller | ERROR
2026-02-16 17:40:45.144259 | controller | {
2026-02-16 17:40:45.144325 | controller |   "delta": "0:00:00.011753",
2026-02-16 17:40:45.144502 | controller |   "end": "2026-02-16 17:40:44.999888",
2026-02-16 17:40:45.144555 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:45.144597 | controller |   "rc": 127,
2026-02-16 17:40:45.144637 | controller |   "start": "2026-02-16 17:40:44.988135"
2026-02-16 17:40:45.144686 | controller | }
failure
2026-02-16 17:40:45.147457 | 
2026-02-16 17:40:45.147511 | PLAY RECAP
2026-02-16 17:40:45.147560 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:40:45.147582 | 
2026-02-16 17:40:45.270659 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:40:45.279372 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:45.876301 | 
2026-02-16 17:40:45.876476 | PLAY [all]
2026-02-16 17:40:45.899952 | 
2026-02-16 17:40:45.900135 | TASK [Return built artifacts to Zuul]
2026-02-16 17:40:45.934381 | controller | ok
2026-02-16 17:40:45.941806 | 
2026-02-16 17:40:45.941911 | TASK [Get the Docker volume ID]
2026-02-16 17:40:46.481410 | controller | ERROR
2026-02-16 17:40:46.481768 | controller | {
2026-02-16 17:40:46.481813 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:40:46.481844 | controller |   "rc": 2
2026-02-16 17:40:46.481872 | controller | }
2026-02-16 17:40:46.481909 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:46.490108 | 
2026-02-16 17:40:46.490247 | TASK [Copy all of the pod logs]
2026-02-16 17:40:46.522528 | controller | ERROR
2026-02-16 17:40:46.522724 | controller | {
2026-02-16 17:40:46.522765 | 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/4fa4babed87045638450e93a5b072cd6/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:40:46.522796 | controller | }
2026-02-16 17:40:46.522833 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:46.524044 | 
2026-02-16 17:40:46.524105 | PLAY RECAP
2026-02-16 17:40:46.524163 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:40:46.524193 | 
2026-02-16 17:40:46.640657 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:40:46.649916 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:40:47.269163 | 
2026-02-16 17:40:47.269326 | PLAY [all]
2026-02-16 17:40:47.290032 | 
2026-02-16 17:40:47.290142 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:40:47.734438 | controller | changed
2026-02-16 17:40:47.742140 | 
2026-02-16 17:40:47.742227 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:40:48.920551 | controller | ok: Runtime: 0:00:00.504053
2026-02-16 17:40:48.926250 | 
2026-02-16 17:40:48.926318 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:40:49.467117 | controller | ok: Runtime: 0:00:00.015548
2026-02-16 17:40:49.473308 | 
2026-02-16 17:40:49.473380 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:40:50.011087 | controller | ok: Runtime: 0:00:00.018176
2026-02-16 17:40:50.018011 | 
2026-02-16 17:40:50.018078 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:40:50.877851 | controller | changed
2026-02-16 17:40:50.928785 | 
2026-02-16 17:40:50.928926 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:40:51.164969 | controller | changed
2026-02-16 17:40:51.171592 | 
2026-02-16 17:40:51.171660 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:51.493609 | controller | Output suppressed because no_log was given
2026-02-16 17:40:51.501453 | 
2026-02-16 17:40:51.501526 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:51.537736 | 
2026-02-16 17:40:51.537919 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:51.572950 | 
2026-02-16 17:40:51.573105 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:40:51.609342 | 
2026-02-16 17:40:51.609514 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:40:51.647196 | 
2026-02-16 17:40:51.647424 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:40:51.684151 | 
2026-02-16 17:40:51.684333 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:40:51.936332 | controller | changed
2026-02-16 17:40:51.942792 | 
2026-02-16 17:40:51.942861 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:40:51.967308 | controller | ok
2026-02-16 17:40:51.972469 | 
2026-02-16 17:40:51.972532 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:40:52.202843 | controller | Output suppressed because no_log was given
2026-02-16 17:40:52.208980 | 
2026-02-16 17:40:52.209057 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:40:52.246013 | 
2026-02-16 17:40:52.246139 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:40:52.280711 | 
2026-02-16 17:40:52.280866 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:40:52.958735 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:40:52.977241 | controller | Using python 3.12 to install setuptools
2026-02-16 17:40:53.013367 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:40:53.025157 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:40:53.319006 | controller | ERROR
2026-02-16 17:40:53.319226 | controller | {
2026-02-16 17:40:53.319260 | controller |   "delta": "0:00:00.501309",
2026-02-16 17:40:53.319282 | controller |   "end": "2026-02-16 17:40:53.027457",
2026-02-16 17:40:53.319301 | controller |   "msg": "non-zero return code",
2026-02-16 17:40:53.319325 | controller |   "rc": 127,
2026-02-16 17:40:53.319344 | controller |   "start": "2026-02-16 17:40:52.526148"
2026-02-16 17:40:53.319362 | controller | }
2026-02-16 17:40:53.319387 | controller | ERROR: Ignoring Errors
2026-02-16 17:40:53.337312 | 
2026-02-16 17:40:53.337395 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:40:53.574990 | controller | changed
2026-02-16 17:40:53.581549 | 
2026-02-16 17:40:53.581614 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:40:57.400456 | controller | Output suppressed because no_log was given
2026-02-16 17:40:57.417954 | 
2026-02-16 17:40:57.418555 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:40:57.459601 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.460019 | 
2026-02-16 17:40:57.462320 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.465789 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.468793 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.471865 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.474681 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.477729 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.480687 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.483668 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.486467 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.489469 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.492209 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.495098 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.497887 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.500682 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.503833 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.523480 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.524279 | controller | skipping: Conditional result was False
2026-02-16 17:40:57.552291 | 
2026-02-16 17:40:57.552449 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:40:57.783092 | controller | ok
2026-02-16 17:40:57.791463 | 
2026-02-16 17:40:57.791539 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:40:58.371889 | controller | skipping: Conditional result was False
2026-02-16 17:40:58.383504 | 
2026-02-16 17:40:58.383598 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:40:58.697307 | controller | sudo: iptables-save: command not found
2026-02-16 17:40:58.735157 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:40:59.881161 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:40:59.958809 | controller | ok: Runtime: 0:00:01.205660
2026-02-16 17:40:59.969095 | 
2026-02-16 17:40:59.969164 | LOOP [stage-output : Register sources]
2026-02-16 17:41:09.369589 | controller | Output suppressed because no_log was given
2026-02-16 17:41:09.416731 | 
2026-02-16 17:41:09.416881 | TASK [stage-output : Check sudo]
2026-02-16 17:41:09.958329 | controller | ok: Runtime: 0:00:00.022230
2026-02-16 17:41:09.964431 | 
2026-02-16 17:41:09.964517 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:41:10.061787 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.062293 | 
2026-02-16 17:41:10.064042 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.065064 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.066527 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.101001 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.101457 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.101809 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.104090 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.106383 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.108618 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.110945 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.113222 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.115315 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.117567 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.133862 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.134342 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.134831 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.135679 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.137952 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.141386 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.142510 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.144702 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.147136 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.175947 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.176360 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.250782 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.262225 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.267316 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.279305 | controller | Output suppressed because no_log was given
2026-02-16 17:41:10.294718 | 
2026-02-16 17:41:10.294832 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:41:10.364409 | controller | ok
2026-02-16 17:41:10.371409 | 
2026-02-16 17:41:10.371489 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:41:10.612979 | controller | changed: "docs"
2026-02-16 17:41:10.833212 | controller | changed: "artifacts"
2026-02-16 17:41:11.054426 | controller | ok: "logs"
2026-02-16 17:41:11.087206 | 
2026-02-16 17:41:11.087350 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:41:11.365640 | controller | ok: Item: Runtime: 0:00:00.007943
2026-02-16 17:41:11.365884 | controller | changed: All items complete
2026-02-16 17:41:11.365912 | 
2026-02-16 17:41:11.585893 | controller | ok: Item: Runtime: 0:00:00.008924
2026-02-16 17:41:11.850239 | controller | ok: Item: Runtime: 0:00:00.007538
2026-02-16 17:41:12.072284 | controller | ok: Item: Runtime: 0:00:00.008034
2026-02-16 17:41:12.301372 | controller | ok: Item: Runtime: 0:00:00.006023
2026-02-16 17:41:12.516096 | controller | ok: Item: Runtime: 0:00:00.005319
2026-02-16 17:41:12.731570 | controller | ok: Item: Runtime: 0:00:00.005516
2026-02-16 17:41:12.969228 | controller | ok: Item: Runtime: 0:00:00.007572
2026-02-16 17:41:13.187486 | controller | ok: Item: Runtime: 0:00:00.007090
2026-02-16 17:41:13.433499 | controller | ok: Item: Runtime: 0:00:00.006323
2026-02-16 17:41:13.655652 | controller | ok: Item: Runtime: 0:00:00.005329
2026-02-16 17:41:13.945290 | controller | ok: Item: Runtime: 0:00:00.007887
2026-02-16 17:41:14.096919 | controller | ok: Item: Runtime: 0:00:00.008878
2026-02-16 17:41:14.348036 | controller | ok: Item: Runtime: 0:00:00.007946
2026-02-16 17:41:14.574817 | controller | ok: Item: Runtime: 0:00:00.006601
2026-02-16 17:41:14.806786 | controller | ok: Item: Runtime: 0:00:00.008582
2026-02-16 17:41:14.826227 | 
2026-02-16 17:41:14.826299 | TASK [stage-output : Make all log files readable]
2026-02-16 17:41:15.085509 | controller | changed
2026-02-16 17:41:15.091267 | 
2026-02-16 17:41:15.091329 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:41:15.441418 | controller | changed: Renamed files for staging.
2026-02-16 17:41:15.447233 | 
2026-02-16 17:41:15.447300 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:41:15.472818 | controller | skipping: Conditional result was False
2026-02-16 17:41:15.485001 | 
2026-02-16 17:41:15.485075 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:41:15.538658 | 
2026-02-16 17:41:15.538786 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:41:16.185352 | controller | changed:
2026-02-16 17:41:16.185546 | controller | created directory /var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/work/logs/controller
2026-02-16 17:41:16.185574 | controller | cd+++++++++ logs/
2026-02-16 17:41:16.185595 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:41:16.185615 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:41:16.185633 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:41:16.185652 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:41:16.185670 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:41:16.185687 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:41:16.185705 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:41:16.185723 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:41:16.185741 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:41:16.185758 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:41:16.185776 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:41:16.185793 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:41:16.185817 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:41:16.185849 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:41:16.185868 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:41:16.185886 | controller | cd+++++++++ logs/apache/
2026-02-16 17:41:16.185904 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:41:16.185921 | controller | cd+++++++++ logs/etc/
2026-02-16 17:41:16.185939 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:41:16.185956 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:41:16.185974 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:41:16.185991 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:41:16.186008 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:41:16.186026 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:41:16.209658 | 
2026-02-16 17:41:16.209872 | TASK [Check if a tempest log exits]
2026-02-16 17:41:16.469512 | controller | ok
2026-02-16 17:41:16.476464 | 
2026-02-16 17:41:16.476557 | TASK [Link post-devstack tempest.log]
2026-02-16 17:41:16.502522 | controller | skipping: Conditional result was False
2026-02-16 17:41:16.512715 | 
2026-02-16 17:41:16.512832 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:41:16.766178 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:41:17.055758 | controller | ERROR
2026-02-16 17:41:17.055998 | controller | {
2026-02-16 17:41:17.056045 | controller |   "delta": "0:00:00.008490",
2026-02-16 17:41:17.056074 | controller |   "end": "2026-02-16 17:41:16.766883",
2026-02-16 17:41:17.056101 | controller |   "msg": "non-zero return code",
2026-02-16 17:41:17.056126 | controller |   "rc": 127,
2026-02-16 17:41:17.056150 | controller |   "start": "2026-02-16 17:41:16.758393"
2026-02-16 17:41:17.056175 | controller | }
2026-02-16 17:41:17.056207 | controller | ERROR: Ignoring Errors
2026-02-16 17:41:17.058778 | 
2026-02-16 17:41:17.058845 | PLAY RECAP
2026-02-16 17:41:17.058910 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:41:17.058939 | 
2026-02-16 17:41:17.215737 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:41:17.224327 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:17.842876 | 
2026-02-16 17:41:17.843030 | PLAY [all]
2026-02-16 17:41:17.856494 | 
2026-02-16 17:41:17.856580 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:41:17.911772 | controller | skipping: Conditional result was False
2026-02-16 17:41:17.922598 | 
2026-02-16 17:41:17.922687 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:41:17.966332 | controller | ok
2026-02-16 17:41:17.974332 | 
2026-02-16 17:41:17.974428 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:41:18.373512 | controller -> localhost | ok: "/var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/work/logs"
2026-02-16 17:41:18.603523 | controller -> localhost | changed: "/var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/work/artifacts"
2026-02-16 17:41:18.825949 | controller -> localhost | changed: "/var/lib/zuul/builds/4fa4babed87045638450e93a5b072cd6/work/docs"
2026-02-16 17:41:18.843212 | 
2026-02-16 17:41:18.843404 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:41:19.555118 | controller | changed: .d..t...... ./
2026-02-16 17:41:19.555380 | controller | changed: All items complete
2026-02-16 17:41:19.555416 | 
2026-02-16 17:41:20.036406 | controller | changed: .d..t...... ./
2026-02-16 17:41:20.546212 | controller | changed: .d..t...... ./
2026-02-16 17:41:20.574439 | 
2026-02-16 17:41:20.574538 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:41:21.035190 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008480
2026-02-16 17:41:21.263333 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006824
2026-02-16 17:41:21.280226 | 
2026-02-16 17:41:21.280355 | PLAY [all]
2026-02-16 17:41:21.288212 | 
2026-02-16 17:41:21.288283 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:41:21.759391 | controller | changed
2026-02-16 17:41:21.767436 | 
2026-02-16 17:41:21.767488 | PLAY RECAP
2026-02-16 17:41:21.767537 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:41:21.767559 | 
2026-02-16 17:41:21.899369 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:41:21.907410 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:41:22.517922 | 
2026-02-16 17:41:22.518081 | PLAY [localhost]
2026-02-16 17:41:22.529402 | 
2026-02-16 17:41:22.529485 | TASK [Generate Zuul manifest]
2026-02-16 17:41:22.548675 | localhost | ok
2026-02-16 17:41:22.562829 | 
2026-02-16 17:41:22.562906 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:41:22.944875 | localhost | changed
2026-02-16 17:41:22.956581 | 
2026-02-16 17:41:22.956657 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:41:22.987964 | localhost | ok
2026-02-16 17:41:22.994686 | 
2026-02-16 17:41:22.994750 | TASK [Upload logs]
2026-02-16 17:41:23.028708 | localhost | ok
2026-02-16 17:41:23.091378 | 
2026-02-16 17:41:23.091519 | TASK [Set zuul-log-path fact]
2026-02-16 17:41:23.113880 | localhost | ok
2026-02-16 17:41:23.129291 | 
2026-02-16 17:41:23.129380 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:41:23.162621 | localhost | ok
2026-02-16 17:41:23.172218 | 
2026-02-16 17:41:23.172309 | TASK [upload-logs : Create log directories]
2026-02-16 17:41:23.565465 | localhost | changed
2026-02-16 17:41:23.570269 | 
2026-02-16 17:41:23.570340 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:41:24.049273 | localhost -> localhost | ok: Runtime: 0:00:00.004891
2026-02-16 17:41:24.056409 | 
2026-02-16 17:41:24.056478 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:41:24.485831 | localhost | Output suppressed because no_log was given
2026-02-16 17:41:24.496672 | 
2026-02-16 17:41:24.496770 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:41:24.543956 | localhost | skipping: Conditional result was False
2026-02-16 17:41:24.549820 | localhost | skipping: Conditional result was False
2026-02-16 17:41:24.564494 | 
2026-02-16 17:41:24.564682 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:41:24.607992 | localhost | skipping: Conditional result was False
2026-02-16 17:41:24.608999 | 
2026-02-16 17:41:24.611101 | localhost | skipping: Conditional result was False
2026-02-16 17:41:24.622164 | 
2026-02-16 17:41:24.622334 | LOOP [upload-logs : Upload console log and json output]