2026-02-16 17:24:25.415055 | Job console starting
2026-02-16 17:24:25.593322 | Updating git repos
2026-02-16 17:24:31.567450 | Cloning repos into workspace
2026-02-16 17:24:37.435286 | Restoring repo states
2026-02-16 17:24:40.172851 | Merging changes
2026-02-16 17:24:41.893271 | Checking out repos
2026-02-16 17:24:45.591706 | Preparing playbooks
2026-02-16 17:24:47.738020 | Running Ansible setup
2026-02-16 17:24:51.490660 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:24:52.088787 | 
2026-02-16 17:24:52.133851 | PLAY [localhost]
2026-02-16 17:24:52.146301 | 
2026-02-16 17:24:52.146396 | TASK [Gathering Facts]
2026-02-16 17:24:52.995600 | localhost | ok
2026-02-16 17:24:53.006106 | 
2026-02-16 17:24:53.006188 | TASK [Setup log path fact]
2026-02-16 17:24:53.028350 | localhost | ok
2026-02-16 17:24:53.041538 | 
2026-02-16 17:24:53.041607 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:24:53.071255 | localhost | ok
2026-02-16 17:24:53.079550 | 
2026-02-16 17:24:53.079616 | TASK [emit-job-header : Print job information]
2026-02-16 17:24:53.123519 | # Job Information
2026-02-16 17:24:53.123664 | Ansible Version: 2.16.15
2026-02-16 17:24:53.123699 | Job: magnum-cluster-api-sonobuoy-v1.34.4-cilium
2026-02-16 17:24:53.123721 | Pipeline: check
2026-02-16 17:24:53.123741 | Executor: 3a2793d2bd32
2026-02-16 17:24:53.123760 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:24:53.123781 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:24:53.126865 | 
2026-02-16 17:24:53.126929 | LOOP [emit-job-header : Print node information]
2026-02-16 17:24:53.227529 | localhost | ok:
2026-02-16 17:24:53.227863 | localhost | # Node Information
2026-02-16 17:24:53.227934 | localhost | Inventory Hostname: controller
2026-02-16 17:24:53.228000 | localhost | Hostname: np0000155593
2026-02-16 17:24:53.228050 | localhost | Username: zuul
2026-02-16 17:24:53.228100 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:24:53.228146 | localhost | Provider: yul1
2026-02-16 17:24:53.228206 | localhost | Region: ca-ymq-1
2026-02-16 17:24:53.228268 | localhost | Label: ubuntu-noble-16
2026-02-16 17:24:53.228326 | localhost | Product Name: OpenStack Nova
2026-02-16 17:24:53.228371 | localhost | Interface IP: 199.204.45.156
2026-02-16 17:24:53.247877 | 
2026-02-16 17:24:53.248047 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:24:53.620907 | localhost -> localhost | changed
2026-02-16 17:24:53.631153 | 
2026-02-16 17:24:53.631227 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:24:54.468484 | localhost -> localhost | changed
2026-02-16 17:24:54.481407 | 
2026-02-16 17:24:54.481465 | PLAY [all]
2026-02-16 17:24:54.490946 | 
2026-02-16 17:24:54.491013 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:24:54.748499 | controller -> localhost | ok
2026-02-16 17:24:54.759452 | 
2026-02-16 17:24:54.759522 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:24:54.794122 | controller | ok
2026-02-16 17:24:54.810605 | controller | included: /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:24:54.816835 | 
2026-02-16 17:24:54.816893 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:24:55.688113 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:24:55.688304 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/work/c0cfec077d55467195a960017818f5ee_id_rsa
2026-02-16 17:24:55.688338 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/work/c0cfec077d55467195a960017818f5ee_id_rsa.pub
2026-02-16 17:24:55.688363 | controller -> localhost | The key fingerprint is:
2026-02-16 17:24:55.688385 | controller -> localhost | SHA256:3GlxwIhn4IvftRdkUc+uurA8XkLTN8KlM/iIDjiuRJ8 zuul-build-sshkey
2026-02-16 17:24:55.688419 | controller -> localhost | The key's randomart image is:
2026-02-16 17:24:55.688442 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:24:55.688467 | controller -> localhost | |      .o o. ...  |
2026-02-16 17:24:55.688489 | controller -> localhost | |     .. + .. . o |
2026-02-16 17:24:55.688510 | controller -> localhost | |      .o  . + . o|
2026-02-16 17:24:55.688530 | controller -> localhost | |     . o . X o . |
2026-02-16 17:24:55.688549 | controller -> localhost | |  . . . S O O o .|
2026-02-16 17:24:55.688570 | controller -> localhost | | . . + . = = * o |
2026-02-16 17:24:55.688590 | controller -> localhost | |  . E o o = + .  |
2026-02-16 17:24:55.688610 | controller -> localhost | | . . . o ..* .   |
2026-02-16 17:24:55.688633 | controller -> localhost | |  ...   ..+.o.   |
2026-02-16 17:24:55.688652 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:24:55.688702 | controller -> localhost | ok: Runtime: 0:00:00.461673
2026-02-16 17:24:55.695005 | 
2026-02-16 17:24:55.695069 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:24:55.732477 | controller | ok
2026-02-16 17:24:55.742953 | controller | included: /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:24:55.751047 | 
2026-02-16 17:24:55.751119 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:24:55.776352 | controller | skipping: Conditional result was False
2026-02-16 17:24:55.786280 | 
2026-02-16 17:24:55.786528 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:24:56.354326 | controller | changed
2026-02-16 17:24:56.361811 | 
2026-02-16 17:24:56.361928 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:24:56.611441 | controller | ok
2026-02-16 17:24:56.618476 | 
2026-02-16 17:24:56.618578 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:24:57.204525 | controller | changed
2026-02-16 17:24:57.210374 | 
2026-02-16 17:24:57.210455 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:24:57.821474 | controller | changed
2026-02-16 17:24:57.831162 | 
2026-02-16 17:24:57.831266 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:24:57.855727 | controller | skipping: Conditional result was False
2026-02-16 17:24:57.866475 | 
2026-02-16 17:24:57.866571 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:24:58.226606 | controller -> localhost | changed
2026-02-16 17:24:58.243060 | 
2026-02-16 17:24:58.243128 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:24:58.581398 | controller -> localhost | Identity added: /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/work/c0cfec077d55467195a960017818f5ee_id_rsa (zuul-build-sshkey)
2026-02-16 17:24:58.581711 | controller -> localhost | ok: Runtime: 0:00:00.013292
2026-02-16 17:24:58.635901 | 
2026-02-16 17:24:58.636048 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:24:58.945108 | controller | ok
2026-02-16 17:24:58.952465 | 
2026-02-16 17:24:58.952584 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:24:58.977337 | controller | skipping: Conditional result was False
2026-02-16 17:24:58.994961 | 
2026-02-16 17:24:58.995030 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:24:59.343491 | controller | ok
2026-02-16 17:24:59.352047 | 
2026-02-16 17:24:59.352114 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:25:19.527050 | controller | Output suppressed because no_log was given
2026-02-16 17:25:19.579891 | 
2026-02-16 17:25:19.579989 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:25:19.834358 | controller | ok: "logs"
2026-02-16 17:25:19.834639 | controller | ok: All items complete
2026-02-16 17:25:19.834666 | 
2026-02-16 17:25:20.010653 | controller | ok: "artifacts"
2026-02-16 17:25:20.204323 | controller | ok: "docs"
2026-02-16 17:25:20.220973 | 
2026-02-16 17:25:20.221254 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:25:20.439923 | controller | changed: "logs"
2026-02-16 17:25:20.623293 | controller | changed: "artifacts"
2026-02-16 17:25:20.876864 | controller | changed: "docs"
2026-02-16 17:25:20.907245 | 
2026-02-16 17:25:20.907379 | PLAY RECAP
2026-02-16 17:25:20.907430 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:25:20.907459 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:25:20.907481 | 
2026-02-16 17:25:21.090977 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:25:21.097240 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:25:21.704550 | 
2026-02-16 17:25:21.704732 | PLAY [all]
2026-02-16 17:25:21.723258 | 
2026-02-16 17:25:21.723340 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:25:22.113005 | controller | changed
2026-02-16 17:25:22.120007 | 
2026-02-16 17:25:22.120084 | TASK [Gather minimum local MTU]
2026-02-16 17:25:22.207995 | controller | ok
2026-02-16 17:25:22.215126 | 
2026-02-16 17:25:22.215218 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:25:22.270169 | controller | ok
2026-02-16 17:25:22.277074 | 
2026-02-16 17:25:22.277167 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:25:22.303580 | controller | skipping: Conditional result was False
2026-02-16 17:25:22.316295 | 
2026-02-16 17:25:22.316390 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:25:22.855684 | controller | ok: Runtime: 0:00:00.011040
2026-02-16 17:25:23.310822 | 
2026-02-16 17:25:23.311005 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:25:23.336419 | controller | skipping: Conditional result was False
2026-02-16 17:25:23.348048 | 
2026-02-16 17:25:23.348274 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:25:23.374403 | controller | skipping: Conditional result was False
2026-02-16 17:25:23.381610 | 
2026-02-16 17:25:23.381691 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:25:23.448451 | controller | ok
2026-02-16 17:25:23.460521 | controller | included: /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:25:23.467107 | 
2026-02-16 17:25:23.467179 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:25:23.532397 | controller | ok
2026-02-16 17:25:23.538081 | 
2026-02-16 17:25:23.538143 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:25:23.851424 | controller | ext4
2026-02-16 17:25:24.113239 | controller | ok: Runtime: 0:00:00.012354
2026-02-16 17:25:24.119211 | 
2026-02-16 17:25:24.119273 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:25:24.152215 | controller | ok
2026-02-16 17:25:24.159495 | 
2026-02-16 17:25:24.159583 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:25:24.194312 | controller | ok:
2026-02-16 17:25:24.194424 | controller | {
2026-02-16 17:25:24.194449 | controller |   "root_filesystem": "ext4"
2026-02-16 17:25:24.194469 | controller | }
2026-02-16 17:25:24.200054 | 
2026-02-16 17:25:24.200114 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:25:34.605326 | controller | 8192+0 records in
2026-02-16 17:25:34.605450 | controller | 8192+0 records out
2026-02-16 17:25:34.605470 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.1362 s, 847 MB/s
2026-02-16 17:25:34.759009 | controller | ok: Runtime: 0:00:10.146460
2026-02-16 17:25:34.767075 | 
2026-02-16 17:25:34.767164 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:25:35.044926 | controller | changed
2026-02-16 17:25:35.053438 | 
2026-02-16 17:25:35.053527 | TASK [configure-swap : Make swapfile]
2026-02-16 17:25:58.380199 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:25:58.380331 | controller | no label, UUID=0e97215b-9338-4c8b-9fd6-a46a30ff2f9b
2026-02-16 17:25:58.611649 | controller | ok: Runtime: 0:00:23.076083
2026-02-16 17:25:58.619326 | 
2026-02-16 17:25:58.619396 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:25:59.039273 | controller | changed
2026-02-16 17:25:59.045502 | 
2026-02-16 17:25:59.045568 | TASK [configure-swap : Add all swap]
2026-02-16 17:25:59.581498 | controller | ok: Runtime: 0:00:00.021705
2026-02-16 17:25:59.588118 | 
2026-02-16 17:25:59.588223 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:25:59.623809 | controller | ok:
2026-02-16 17:25:59.624090 | controller | {
2026-02-16 17:25:59.624130 | controller |   "swap_required": "8192"
2026-02-16 17:25:59.624179 | controller | }
2026-02-16 17:25:59.631003 | 
2026-02-16 17:25:59.631071 | TASK [configure-swap : Set swappiness]
2026-02-16 17:26:00.075617 | controller | changed
2026-02-16 17:26:00.082502 | 
2026-02-16 17:26:00.082597 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:26:00.127111 | controller | ok:
2026-02-16 17:26:00.127259 | controller | {
2026-02-16 17:26:00.127294 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:26:00.127329 | controller | }
2026-02-16 17:26:00.138433 | 
2026-02-16 17:26:00.138494 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:26:00.623536 | controller | changed
2026-02-16 17:26:00.630473 | 
2026-02-16 17:26:00.630542 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:26:00.871621 | controller | changed
2026-02-16 17:26:00.877236 | 
2026-02-16 17:26:00.877304 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:26:01.419117 | controller | changed
2026-02-16 17:26:01.425682 | 
2026-02-16 17:26:01.425754 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:26:01.670938 | controller | changed
2026-02-16 17:26:01.677262 | 
2026-02-16 17:26:01.677329 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:26:02.752160 | controller | changed
2026-02-16 17:26:02.758316 | 
2026-02-16 17:26:02.758384 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:26:03.008371 | controller | changed
2026-02-16 17:26:03.020098 | 
2026-02-16 17:26:03.020168 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:26:03.308342 | controller | changed
2026-02-16 17:26:03.315509 | 
2026-02-16 17:26:03.315597 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:26:03.681923 | controller | changed
2026-02-16 17:26:03.736718 | 
2026-02-16 17:26:03.736869 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:26:04.608932 | controller | changed
2026-02-16 17:26:04.619837 | 
2026-02-16 17:26:04.619916 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:26:04.951649 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:26:04.964953 | 
2026-02-16 17:26:04.965050 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:26:05.437154 | controller | ok: Item: Runtime: 0:00:00.189598
2026-02-16 17:26:06.025475 | controller | ok: Item: Runtime: 0:00:00.363336
2026-02-16 17:26:06.781799 | controller | ok: Item: Runtime: 0:00:00.533281
2026-02-16 17:26:07.062703 | controller | ok: Item: Runtime: 0:00:00.064632
2026-02-16 17:26:07.551250 | controller | ok: Item: Runtime: 0:00:00.269149
2026-02-16 17:26:09.086193 | controller | ok: Item: Runtime: 0:00:01.313401
2026-02-16 17:26:12.467662 | controller | ok: Item: Runtime: 0:00:03.161703
2026-02-16 17:26:12.785482 | controller | ok: Item: Runtime: 0:00:00.095753
2026-02-16 17:26:13.210934 | controller | ok: Item: Runtime: 0:00:00.198132
2026-02-16 17:26:13.551002 | controller | ok: Item: Runtime: 0:00:00.122162
2026-02-16 17:26:14.298270 | controller | ok: Item: Runtime: 0:00:00.524637
2026-02-16 17:26:15.034603 | controller | ok: Item: Runtime: 0:00:00.529068
2026-02-16 17:26:15.516708 | controller | ok: Item: Runtime: 0:00:00.243956
2026-02-16 17:26:15.986394 | controller | ok: Item: Runtime: 0:00:00.229926
2026-02-16 17:26:16.583826 | controller | ok: Item: Runtime: 0:00:00.375227
2026-02-16 17:26:17.998813 | controller | ok: Item: Runtime: 0:00:01.187910
2026-02-16 17:26:18.031185 | 
2026-02-16 17:26:18.031414 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:26:18.251163 | controller | ok: All paths examined
2026-02-16 17:26:18.258132 | 
2026-02-16 17:26:18.258205 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:26:18.498416 | controller | ok: All paths examined
2026-02-16 17:26:18.504679 | 
2026-02-16 17:26:18.504752 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:26:18.972135 | controller | ok: Item: Runtime: 0:00:00.178452
2026-02-16 17:26:18.972535 | controller | changed: All items complete
2026-02-16 17:26:18.972576 | 
2026-02-16 17:26:19.402672 | controller | ok: Item: Runtime: 0:00:00.196211
2026-02-16 17:26:19.419292 | 
2026-02-16 17:26:19.419467 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:26:19.463115 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.465923 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.469099 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.472314 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.475484 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.478923 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.482027 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.485308 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.488584 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.492097 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.495236 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.498338 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.501456 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.504505 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.507576 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.510796 | controller | skipping: Conditional result was False
2026-02-16 17:26:19.534399 | 
2026-02-16 17:26:19.534572 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:26:21.934783 | controller | changed
2026-02-16 17:26:21.955903 | 
2026-02-16 17:26:21.955984 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:26:22.204362 | controller | changed
2026-02-16 17:26:22.256690 | 
2026-02-16 17:26:22.256834 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:26:22.511124 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:26:22.798142 | controller | ERROR
2026-02-16 17:26:22.798386 | controller | {
2026-02-16 17:26:22.798449 | controller |   "delta": "0:00:00.010615",
2026-02-16 17:26:22.798494 | controller |   "end": "2026-02-16 17:26:22.511568",
2026-02-16 17:26:22.798535 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:22.798575 | controller |   "rc": 1,
2026-02-16 17:26:22.798596 | controller |   "start": "2026-02-16 17:26:22.500953"
2026-02-16 17:26:22.798616 | controller | }
2026-02-16 17:26:22.798641 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:22.804285 | 
2026-02-16 17:26:22.804356 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:26:23.070317 | controller | ok
2026-02-16 17:26:23.085017 | 
2026-02-16 17:26:23.085094 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:26:23.320292 | controller | /usr/bin/journalctl
2026-02-16 17:26:23.626201 | controller | ok: Runtime: 0:00:00.007809
2026-02-16 17:26:23.631647 | 
2026-02-16 17:26:23.631710 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:26:23.885106 | controller | 2026-02-16 17:26:23
2026-02-16 17:26:24.171172 | controller | ok: Runtime: 0:00:00.010252
2026-02-16 17:26:24.176613 | 
2026-02-16 17:26:24.176676 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:26:24.769526 | controller | changed
2026-02-16 17:26:24.775425 | 
2026-02-16 17:26:24.775490 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:26:24.800879 | controller | skipping: Conditional result was False
2026-02-16 17:26:24.815142 | 
2026-02-16 17:26:24.815300 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:26:25.348628 | controller | skipping: Conditional result was False
2026-02-16 17:26:25.360904 | 
2026-02-16 17:26:25.361000 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:26:25.891551 | controller | skipping: Conditional result was False
2026-02-16 17:26:25.903328 | 
2026-02-16 17:26:25.903420 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:26:25.928670 | controller | skipping: Conditional result was False
2026-02-16 17:26:25.936806 | 
2026-02-16 17:26:25.936895 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:26:26.469762 | controller | skipping: Conditional result was False
2026-02-16 17:26:26.481289 | 
2026-02-16 17:26:26.481364 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:26:27.010947 | controller | skipping: Conditional result was False
2026-02-16 17:26:27.018369 | 
2026-02-16 17:26:27.018435 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:26:27.044128 | controller | skipping: Conditional result was False
2026-02-16 17:26:27.054112 | 
2026-02-16 17:26:27.054203 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:26:27.080151 | controller | skipping: Conditional result was False
2026-02-16 17:26:27.090898 | 
2026-02-16 17:26:27.090988 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:26:27.669715 | controller | skipping: Conditional result was False
2026-02-16 17:26:27.683825 | 
2026-02-16 17:26:27.683968 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:26:28.226576 | controller | skipping: Conditional result was False
2026-02-16 17:26:28.239515 | 
2026-02-16 17:26:28.239596 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:26:28.264672 | controller | skipping: Conditional result was False
2026-02-16 17:26:28.277817 | 
2026-02-16 17:26:28.277889 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:26:28.301053 | controller | skipping: Conditional result was False
2026-02-16 17:26:28.318627 | 
2026-02-16 17:26:28.318722 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:26:28.711135 | controller | ok
2026-02-16 17:26:28.720434 | 
2026-02-16 17:26:28.720542 | PLAY RECAP
2026-02-16 17:26:28.720593 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:26:28.720615 | 
2026-02-16 17:26:28.900187 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:26:28.909817 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:29.530297 | 
2026-02-16 17:26:29.530428 | PLAY [all]
2026-02-16 17:26:29.542515 | 
2026-02-16 17:26:29.542602 | TASK [ensure-rust : Use rustup]
2026-02-16 17:26:29.572433 | controller | ok
2026-02-16 17:26:29.579133 | controller | included: /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:26:29.584441 | 
2026-02-16 17:26:29.584514 | TASK [ensure-rust : Install Rust]
2026-02-16 17:26:30.002844 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:26:30.120522 | controller | ERROR
2026-02-16 17:26:30.120791 | controller | {
2026-02-16 17:26:30.120831 | controller |   "delta": "0:00:00.009882",
2026-02-16 17:26:30.120866 | controller |   "end": "2026-02-16 17:26:30.005807",
2026-02-16 17:26:30.120895 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:30.120920 | controller |   "rc": 127,
2026-02-16 17:26:30.120996 | controller |   "start": "2026-02-16 17:26:29.995925"
2026-02-16 17:26:30.121032 | controller | }
failure
2026-02-16 17:26:30.122982 | 
2026-02-16 17:26:30.123044 | PLAY RECAP
2026-02-16 17:26:30.123108 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:26:30.123136 | 
2026-02-16 17:26:30.258514 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:26:30.269782 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:30.850807 | 
2026-02-16 17:26:30.850984 | PLAY [all]
2026-02-16 17:26:30.867974 | 
2026-02-16 17:26:30.868108 | TASK [Return built artifacts to Zuul]
2026-02-16 17:26:30.898968 | controller | ok
2026-02-16 17:26:30.904490 | 
2026-02-16 17:26:30.904568 | TASK [Get the Docker volume ID]
2026-02-16 17:26:31.440317 | controller | ERROR
2026-02-16 17:26:31.440611 | controller | {
2026-02-16 17:26:31.440652 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:26:31.440675 | controller |   "rc": 2
2026-02-16 17:26:31.440695 | controller | }
2026-02-16 17:26:31.440721 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:31.447288 | 
2026-02-16 17:26:31.447356 | TASK [Copy all of the pod logs]
2026-02-16 17:26:31.482367 | controller | ERROR
2026-02-16 17:26:31.482663 | controller | {
2026-02-16 17:26:31.482726 | 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/c0cfec077d55467195a960017818f5ee/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml': line 21, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-02-16 17:26:31.482773 | controller | }
2026-02-16 17:26:31.482830 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:31.484290 | 
2026-02-16 17:26:31.484384 | PLAY RECAP
2026-02-16 17:26:31.484470 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:26:31.484515 | 
2026-02-16 17:26:31.626511 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:26:31.636642 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:26:32.259340 | 
2026-02-16 17:26:32.260076 | PLAY [all]
2026-02-16 17:26:32.279285 | 
2026-02-16 17:26:32.279364 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:26:32.679415 | controller | changed
2026-02-16 17:26:32.686758 | 
2026-02-16 17:26:32.686842 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:26:33.723678 | controller | ok: Runtime: 0:00:00.488921
2026-02-16 17:26:33.731144 | 
2026-02-16 17:26:33.731234 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:26:34.270226 | controller | ok: Runtime: 0:00:00.022951
2026-02-16 17:26:34.278476 | 
2026-02-16 17:26:34.278577 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:26:34.818266 | controller | ok: Runtime: 0:00:00.022880
2026-02-16 17:26:34.824448 | 
2026-02-16 17:26:34.824517 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:26:35.689249 | controller | changed
2026-02-16 17:26:35.737295 | 
2026-02-16 17:26:35.737436 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:26:35.974948 | controller | changed
2026-02-16 17:26:35.982675 | 
2026-02-16 17:26:35.982767 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:26:36.326163 | controller | Output suppressed because no_log was given
2026-02-16 17:26:36.333481 | 
2026-02-16 17:26:36.333574 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:26:36.370434 | 
2026-02-16 17:26:36.370597 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:26:36.406841 | 
2026-02-16 17:26:36.407006 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:26:36.443637 | 
2026-02-16 17:26:36.443793 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:26:36.476892 | 
2026-02-16 17:26:36.477074 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:26:36.513683 | 
2026-02-16 17:26:36.513820 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:26:36.775019 | controller | changed
2026-02-16 17:26:36.782195 | 
2026-02-16 17:26:36.782267 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:26:36.817459 | controller | ok
2026-02-16 17:26:36.824844 | 
2026-02-16 17:26:36.824938 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:26:37.072793 | controller | Output suppressed because no_log was given
2026-02-16 17:26:37.080668 | 
2026-02-16 17:26:37.080763 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:26:37.115387 | 
2026-02-16 17:26:37.115558 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:26:37.159143 | 
2026-02-16 17:26:37.159342 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:26:37.826358 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:26:37.846363 | controller | Using python 3.12 to install setuptools
2026-02-16 17:26:37.886075 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:26:37.898550 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:26:38.197828 | controller | ERROR
2026-02-16 17:26:38.197976 | controller | {
2026-02-16 17:26:38.198009 | controller |   "delta": "0:00:00.489578",
2026-02-16 17:26:38.198029 | controller |   "end": "2026-02-16 17:26:37.901052",
2026-02-16 17:26:38.198048 | controller |   "msg": "non-zero return code",
2026-02-16 17:26:38.198073 | controller |   "rc": 127,
2026-02-16 17:26:38.198091 | controller |   "start": "2026-02-16 17:26:37.411474"
2026-02-16 17:26:38.198109 | controller | }
2026-02-16 17:26:38.198134 | controller | ERROR: Ignoring Errors
2026-02-16 17:26:38.210110 | 
2026-02-16 17:26:38.210184 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:26:38.458086 | controller | changed
2026-02-16 17:26:38.463813 | 
2026-02-16 17:26:38.463882 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:26:42.263409 | controller | Output suppressed because no_log was given
2026-02-16 17:26:42.277196 | 
2026-02-16 17:26:42.277312 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:26:42.313394 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.313906 | 
2026-02-16 17:26:42.316126 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.317863 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.319479 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.321196 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.323421 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.325700 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.328168 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.330511 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.332721 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.335154 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.337510 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.339784 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.342122 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.344450 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.346714 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.349066 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.352279 | controller | skipping: Conditional result was False
2026-02-16 17:26:42.384546 | 
2026-02-16 17:26:42.384739 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:26:42.624044 | controller | ok
2026-02-16 17:26:42.629667 | 
2026-02-16 17:26:42.629747 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:26:43.206991 | controller | skipping: Conditional result was False
2026-02-16 17:26:43.223992 | 
2026-02-16 17:26:43.224082 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:26:43.508424 | controller | sudo: iptables-save: command not found
2026-02-16 17:26:43.546620 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:26:44.981479 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:26:45.264839 | controller | ok: Runtime: 0:00:01.493512
2026-02-16 17:26:45.275955 | 
2026-02-16 17:26:45.276032 | LOOP [stage-output : Register sources]
2026-02-16 17:26:54.637161 | controller | Output suppressed because no_log was given
2026-02-16 17:26:54.685688 | 
2026-02-16 17:26:54.685828 | TASK [stage-output : Check sudo]
2026-02-16 17:26:55.225001 | controller | ok: Runtime: 0:00:00.023354
2026-02-16 17:26:55.231371 | 
2026-02-16 17:26:55.231440 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:26:55.355719 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.356796 | 
2026-02-16 17:26:55.358686 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.359629 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.360538 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.396321 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.396670 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.397005 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.398782 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.401073 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.403542 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.405728 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.408055 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.410302 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.412902 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.431614 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.432200 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.432707 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.433215 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.433685 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.434745 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.436882 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.439120 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.441311 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.465547 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.465895 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.553360 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.568260 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.583387 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.591257 | controller | Output suppressed because no_log was given
2026-02-16 17:26:55.604699 | 
2026-02-16 17:26:55.604765 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:26:55.668579 | controller | ok
2026-02-16 17:26:55.676159 | 
2026-02-16 17:26:55.676224 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:26:55.936115 | controller | changed: "docs"
2026-02-16 17:26:56.153637 | controller | changed: "artifacts"
2026-02-16 17:26:56.390182 | controller | ok: "logs"
2026-02-16 17:26:56.402108 | 
2026-02-16 17:26:56.402259 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:26:56.725069 | controller | ok: Item: Runtime: 0:00:00.012878
2026-02-16 17:26:56.725310 | controller | changed: All items complete
2026-02-16 17:26:56.725348 | 
2026-02-16 17:26:56.924319 | controller | ok: Item: Runtime: 0:00:00.007864
2026-02-16 17:26:57.144902 | controller | ok: Item: Runtime: 0:00:00.007575
2026-02-16 17:26:57.371380 | controller | ok: Item: Runtime: 0:00:00.008184
2026-02-16 17:26:57.597138 | controller | ok: Item: Runtime: 0:00:00.011531
2026-02-16 17:26:57.822429 | controller | ok: Item: Runtime: 0:00:00.008731
2026-02-16 17:26:58.055510 | controller | ok: Item: Runtime: 0:00:00.008844
2026-02-16 17:26:58.277660 | controller | ok: Item: Runtime: 0:00:00.008334
2026-02-16 17:26:58.504191 | controller | ok: Item: Runtime: 0:00:00.010241
2026-02-16 17:26:58.720640 | controller | ok: Item: Runtime: 0:00:00.005970
2026-02-16 17:26:58.946660 | controller | ok: Item: Runtime: 0:00:00.007682
2026-02-16 17:26:59.223946 | controller | ok: Item: Runtime: 0:00:00.008050
2026-02-16 17:26:59.395029 | controller | ok: Item: Runtime: 0:00:00.008261
2026-02-16 17:26:59.618222 | controller | ok: Item: Runtime: 0:00:00.008824
2026-02-16 17:26:59.856006 | controller | ok: Item: Runtime: 0:00:00.008274
2026-02-16 17:27:00.077924 | controller | ok: Item: Runtime: 0:00:00.007946
2026-02-16 17:27:00.099380 | 
2026-02-16 17:27:00.099513 | TASK [stage-output : Make all log files readable]
2026-02-16 17:27:00.339087 | controller | changed
2026-02-16 17:27:00.345198 | 
2026-02-16 17:27:00.345259 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:27:00.662068 | controller | changed: Renamed files for staging.
2026-02-16 17:27:00.668647 | 
2026-02-16 17:27:00.668710 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:27:00.693335 | controller | skipping: Conditional result was False
2026-02-16 17:27:00.705239 | 
2026-02-16 17:27:00.705306 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:27:00.749209 | 
2026-02-16 17:27:00.749370 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:27:01.393860 | controller | changed:
2026-02-16 17:27:01.394050 | controller | created directory /var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/work/logs/controller
2026-02-16 17:27:01.394078 | controller | cd+++++++++ logs/
2026-02-16 17:27:01.394099 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:27:01.394119 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:27:01.394137 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:27:01.394156 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:27:01.394174 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:27:01.394191 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:27:01.394209 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:27:01.394226 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:27:01.394243 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:27:01.394261 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:27:01.394278 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:27:01.394295 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:27:01.394317 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:27:01.394350 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:27:01.394369 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:27:01.394386 | controller | cd+++++++++ logs/apache/
2026-02-16 17:27:01.394403 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:27:01.394421 | controller | cd+++++++++ logs/etc/
2026-02-16 17:27:01.394438 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:27:01.394455 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:27:01.394473 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:27:01.394490 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:27:01.394508 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:27:01.394525 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:27:01.406228 | 
2026-02-16 17:27:01.406289 | TASK [Check if a tempest log exits]
2026-02-16 17:27:01.678507 | controller | ok
2026-02-16 17:27:01.683543 | 
2026-02-16 17:27:01.683602 | TASK [Link post-devstack tempest.log]
2026-02-16 17:27:01.708265 | controller | skipping: Conditional result was False
2026-02-16 17:27:01.719201 | 
2026-02-16 17:27:01.719265 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:27:01.969233 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:27:02.259909 | controller | ERROR
2026-02-16 17:27:02.260168 | controller | {
2026-02-16 17:27:02.260214 | controller |   "delta": "0:00:00.008161",
2026-02-16 17:27:02.260244 | controller |   "end": "2026-02-16 17:27:01.969751",
2026-02-16 17:27:02.260272 | controller |   "msg": "non-zero return code",
2026-02-16 17:27:02.260299 | controller |   "rc": 127,
2026-02-16 17:27:02.260327 | controller |   "start": "2026-02-16 17:27:01.961590"
2026-02-16 17:27:02.260353 | controller | }
2026-02-16 17:27:02.260387 | controller | ERROR: Ignoring Errors
2026-02-16 17:27:02.262611 | 
2026-02-16 17:27:02.262680 | PLAY RECAP
2026-02-16 17:27:02.262747 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:27:02.262778 | 
2026-02-16 17:27:02.429494 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:27:02.435175 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:27:03.034452 | 
2026-02-16 17:27:03.034596 | PLAY [all]
2026-02-16 17:27:03.046318 | 
2026-02-16 17:27:03.046400 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:27:03.091788 | controller | skipping: Conditional result was False
2026-02-16 17:27:03.100613 | 
2026-02-16 17:27:03.100718 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:27:03.145853 | controller | ok
2026-02-16 17:27:03.152236 | 
2026-02-16 17:27:03.152307 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:27:03.563731 | controller -> localhost | ok: "/var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/work/logs"
2026-02-16 17:27:03.771358 | controller -> localhost | changed: "/var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/work/artifacts"
2026-02-16 17:27:03.980357 | controller -> localhost | changed: "/var/lib/zuul/builds/c0cfec077d55467195a960017818f5ee/work/docs"
2026-02-16 17:27:03.999809 | 
2026-02-16 17:27:03.999995 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:27:04.663502 | controller | changed: .d..t...... ./
2026-02-16 17:27:04.663877 | controller | changed: All items complete
2026-02-16 17:27:04.663924 | 
2026-02-16 17:27:05.140867 | controller | changed: .d..t...... ./
2026-02-16 17:27:05.599578 | controller | changed: .d..t...... ./
2026-02-16 17:27:05.626589 | 
2026-02-16 17:27:05.626668 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:27:06.057824 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007504
2026-02-16 17:27:06.276857 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.009433
2026-02-16 17:27:06.290314 | 
2026-02-16 17:27:06.290458 | PLAY [all]
2026-02-16 17:27:06.297903 | 
2026-02-16 17:27:06.297967 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:27:06.789798 | controller | changed
2026-02-16 17:27:06.795884 | 
2026-02-16 17:27:06.795937 | PLAY RECAP
2026-02-16 17:27:06.795982 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:27:06.796002 | 
2026-02-16 17:27:06.933757 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:27:06.943040 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:27:07.538460 | 
2026-02-16 17:27:07.538667 | PLAY [localhost]
2026-02-16 17:27:07.549869 | 
2026-02-16 17:27:07.549979 | TASK [Generate Zuul manifest]
2026-02-16 17:27:07.583511 | localhost | ok
2026-02-16 17:27:07.601652 | 
2026-02-16 17:27:07.601746 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:27:07.978002 | localhost | changed
2026-02-16 17:27:07.991157 | 
2026-02-16 17:27:07.991246 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:27:08.023178 | localhost | ok
2026-02-16 17:27:08.030221 | 
2026-02-16 17:27:08.030290 | TASK [Upload logs]
2026-02-16 17:27:08.052226 | localhost | ok
2026-02-16 17:27:08.115196 | 
2026-02-16 17:27:08.115424 | TASK [Set zuul-log-path fact]
2026-02-16 17:27:08.139189 | localhost | ok
2026-02-16 17:27:08.153124 | 
2026-02-16 17:27:08.153196 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:27:08.185565 | localhost | ok
2026-02-16 17:27:08.194032 | 
2026-02-16 17:27:08.194099 | TASK [upload-logs : Create log directories]
2026-02-16 17:27:08.671255 | localhost | changed
2026-02-16 17:27:08.677760 | 
2026-02-16 17:27:08.677862 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:27:09.123225 | localhost -> localhost | ok: Runtime: 0:00:00.006423
2026-02-16 17:27:09.128180 | 
2026-02-16 17:27:09.128245 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:27:09.545963 | localhost | Output suppressed because no_log was given
2026-02-16 17:27:09.551836 | 
2026-02-16 17:27:09.551942 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:27:09.597572 | localhost | skipping: Conditional result was False
2026-02-16 17:27:09.603545 | localhost | skipping: Conditional result was False
2026-02-16 17:27:09.616819 | 
2026-02-16 17:27:09.617031 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:27:09.658431 | localhost | skipping: Conditional result was False
2026-02-16 17:27:09.658823 | 
2026-02-16 17:27:09.661656 | localhost | skipping: Conditional result was False
2026-02-16 17:27:09.671911 | 
2026-02-16 17:27:09.672104 | LOOP [upload-logs : Upload console log and json output]