2026-02-16 17:27:59.337667 | Job console starting
2026-02-16 17:27:59.348856 | Updating git repos
2026-02-16 17:28:00.658878 | Cloning repos into workspace
2026-02-16 17:28:06.797290 | Restoring repo states
2026-02-16 17:28:09.714636 | Merging changes
2026-02-16 17:28:10.294822 | Checking out repos
2026-02-16 17:28:13.388869 | Preparing playbooks
2026-02-16 17:28:14.983830 | Running Ansible setup
2026-02-16 17:28:19.033582 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:28:19.635029 | 
2026-02-16 17:28:19.635174 | PLAY [localhost]
2026-02-16 17:28:19.643004 | 
2026-02-16 17:28:19.643079 | TASK [Gathering Facts]
2026-02-16 17:28:20.513740 | localhost | ok
2026-02-16 17:28:20.520844 | 
2026-02-16 17:28:20.520923 | TASK [Setup log path fact]
2026-02-16 17:28:20.539548 | localhost | ok
2026-02-16 17:28:20.550014 | 
2026-02-16 17:28:20.550080 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:28:20.578082 | localhost | ok
2026-02-16 17:28:20.583800 | 
2026-02-16 17:28:20.583867 | TASK [emit-job-header : Print job information]
2026-02-16 17:28:20.623491 | # Job Information
2026-02-16 17:28:20.623607 | Ansible Version: 2.16.15
2026-02-16 17:28:20.623638 | Job: magnum-cluster-api-sonobuoy-v1.35.1-cilium
2026-02-16 17:28:20.623660 | Pipeline: check
2026-02-16 17:28:20.623679 | Executor: 3a2793d2bd32
2026-02-16 17:28:20.623699 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/884
2026-02-16 17:28:20.623720 | Event ID: 942d9220-0b5b-11f1-9435-38659876bbc5
2026-02-16 17:28:20.626398 | 
2026-02-16 17:28:20.626460 | LOOP [emit-job-header : Print node information]
2026-02-16 17:28:20.723441 | localhost | ok:
2026-02-16 17:28:20.723843 | localhost | # Node Information
2026-02-16 17:28:20.723913 | localhost | Inventory Hostname: controller
2026-02-16 17:28:20.723961 | localhost | Hostname: np0000155604
2026-02-16 17:28:20.724003 | localhost | Username: zuul
2026-02-16 17:28:20.724052 | localhost | Distro: Ubuntu 24.04
2026-02-16 17:28:20.724095 | localhost | Provider: yul1
2026-02-16 17:28:20.724137 | localhost | Region: ca-ymq-1
2026-02-16 17:28:20.724177 | localhost | Label: ubuntu-noble-16
2026-02-16 17:28:20.724216 | localhost | Product Name: OpenStack Nova
2026-02-16 17:28:20.724256 | localhost | Interface IP: 199.204.45.242
2026-02-16 17:28:20.744786 | 
2026-02-16 17:28:20.744916 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-16 17:28:21.135710 | localhost -> localhost | changed
2026-02-16 17:28:21.144472 | 
2026-02-16 17:28:21.144568 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-16 17:28:21.971144 | localhost -> localhost | changed
2026-02-16 17:28:21.983175 | 
2026-02-16 17:28:21.983292 | PLAY [all]
2026-02-16 17:28:21.995104 | 
2026-02-16 17:28:21.995172 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-16 17:28:22.226070 | controller -> localhost | ok
2026-02-16 17:28:22.237370 | 
2026-02-16 17:28:22.237440 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-16 17:28:22.274892 | controller | ok
2026-02-16 17:28:22.289837 | controller | included: /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-16 17:28:22.295984 | 
2026-02-16 17:28:22.296046 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-16 17:28:23.626347 | controller -> localhost | Generating public/private rsa key pair.
2026-02-16 17:28:23.627243 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/work/051bc51c0bc946449742f22de3a7ca86_id_rsa
2026-02-16 17:28:23.627288 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/work/051bc51c0bc946449742f22de3a7ca86_id_rsa.pub
2026-02-16 17:28:23.627321 | controller -> localhost | The key fingerprint is:
2026-02-16 17:28:23.627352 | controller -> localhost | SHA256:mMIVoOkuxUMqM735hOtut71ZePW061urIgTNdG+OMAc zuul-build-sshkey
2026-02-16 17:28:23.627395 | controller -> localhost | The key's randomart image is:
2026-02-16 17:28:23.627426 | controller -> localhost | +---[RSA 3072]----+
2026-02-16 17:28:23.627460 | controller -> localhost | |    ...          |
2026-02-16 17:28:23.627490 | controller -> localhost | |   o   .E .      |
2026-02-16 17:28:23.627518 | controller -> localhost | |  +   .+ o .     |
2026-02-16 17:28:23.627555 | controller -> localhost | | * . ..o= . o    |
2026-02-16 17:28:23.627591 | controller -> localhost | |= * o o.S+.+.    |
2026-02-16 17:28:23.627619 | controller -> localhost | |.= = . ....o..   |
2026-02-16 17:28:23.627646 | controller -> localhost | |. = . ..o   o .  |
2026-02-16 17:28:23.627673 | controller -> localhost | | ..+.. +. .  o . |
2026-02-16 17:28:23.627704 | controller -> localhost | | ++.o.+. . o=o.  |
2026-02-16 17:28:23.627732 | controller -> localhost | +----[SHA256]-----+
2026-02-16 17:28:23.627798 | controller -> localhost | ok: Runtime: 0:00:00.930911
2026-02-16 17:28:23.634771 | 
2026-02-16 17:28:23.634838 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-16 17:28:23.671018 | controller | ok
2026-02-16 17:28:23.681411 | controller | included: /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-16 17:28:23.689183 | 
2026-02-16 17:28:23.689242 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-16 17:28:23.704304 | controller | skipping: Conditional result was False
2026-02-16 17:28:23.715029 | 
2026-02-16 17:28:23.715116 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-16 17:28:24.282258 | controller | changed
2026-02-16 17:28:24.290891 | 
2026-02-16 17:28:24.290955 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-16 17:28:24.521882 | controller | ok
2026-02-16 17:28:24.527580 | 
2026-02-16 17:28:24.527654 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-16 17:28:25.110239 | controller | changed
2026-02-16 17:28:25.115803 | 
2026-02-16 17:28:25.115866 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-16 17:28:25.685662 | controller | changed
2026-02-16 17:28:25.694742 | 
2026-02-16 17:28:25.694813 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-16 17:28:25.720144 | controller | skipping: Conditional result was False
2026-02-16 17:28:25.731742 | 
2026-02-16 17:28:25.731808 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-16 17:28:26.081143 | controller -> localhost | changed
2026-02-16 17:28:26.093979 | 
2026-02-16 17:28:26.094052 | TASK [add-build-sshkey : Add back temp key]
2026-02-16 17:28:26.414081 | controller -> localhost | Identity added: /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/work/051bc51c0bc946449742f22de3a7ca86_id_rsa (zuul-build-sshkey)
2026-02-16 17:28:26.414301 | controller -> localhost | ok: Runtime: 0:00:00.015097
2026-02-16 17:28:26.467799 | 
2026-02-16 17:28:26.467950 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-16 17:28:26.779543 | controller | ok
2026-02-16 17:28:26.785672 | 
2026-02-16 17:28:26.785740 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-16 17:28:26.813687 | controller | skipping: Conditional result was False
2026-02-16 17:28:26.829466 | 
2026-02-16 17:28:26.829547 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-16 17:28:27.161359 | controller | ok
2026-02-16 17:28:27.170203 | 
2026-02-16 17:28:27.170270 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-16 17:28:46.500398 | controller | Output suppressed because no_log was given
2026-02-16 17:28:46.520435 | 
2026-02-16 17:28:46.520507 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-16 17:28:46.758361 | controller | ok: "logs"
2026-02-16 17:28:46.758648 | controller | ok: All items complete
2026-02-16 17:28:46.758679 | 
2026-02-16 17:28:46.940900 | controller | ok: "artifacts"
2026-02-16 17:28:47.138077 | controller | ok: "docs"
2026-02-16 17:28:47.147181 | 
2026-02-16 17:28:47.147337 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-16 17:28:47.374635 | controller | changed: "logs"
2026-02-16 17:28:47.565869 | controller | changed: "artifacts"
2026-02-16 17:28:47.805461 | controller | changed: "docs"
2026-02-16 17:28:47.827776 | 
2026-02-16 17:28:47.827930 | PLAY RECAP
2026-02-16 17:28:47.827981 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-16 17:28:47.828010 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:28:47.828032 | 
2026-02-16 17:28:48.006758 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main]
2026-02-16 17:28:48.015613 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:28:48.618197 | 
2026-02-16 17:28:48.618323 | PLAY [all]
2026-02-16 17:28:48.635397 | 
2026-02-16 17:28:48.635473 | TASK [Fix the permissions of the zuul home directory]
2026-02-16 17:28:49.014784 | controller | changed
2026-02-16 17:28:49.021523 | 
2026-02-16 17:28:49.021598 | TASK [Gather minimum local MTU]
2026-02-16 17:28:49.098674 | controller | ok
2026-02-16 17:28:49.103650 | 
2026-02-16 17:28:49.103714 | TASK [Calculate external_bridge_mtu]
2026-02-16 17:28:49.159215 | controller | ok
2026-02-16 17:28:49.164242 | 
2026-02-16 17:28:49.164310 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-16 17:28:49.189455 | controller | skipping: Conditional result was False
2026-02-16 17:28:49.201431 | 
2026-02-16 17:28:49.201536 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-16 17:28:49.739069 | controller | ok: Runtime: 0:00:00.011254
2026-02-16 17:28:49.746588 | 
2026-02-16 17:28:49.746677 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-16 17:28:49.782062 | controller | skipping: Conditional result was False
2026-02-16 17:28:49.793868 | 
2026-02-16 17:28:49.793932 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-16 17:28:49.828515 | controller | skipping: Conditional result was False
2026-02-16 17:28:49.838847 | 
2026-02-16 17:28:49.838912 | TASK [configure-swap : Setup swap file on root device]
2026-02-16 17:28:49.895509 | controller | ok
2026-02-16 17:28:49.907740 | controller | included: /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-16 17:28:49.913682 | 
2026-02-16 17:28:49.913742 | TASK [configure-swap : Calculate required swap]
2026-02-16 17:28:49.967854 | controller | ok
2026-02-16 17:28:49.975113 | 
2026-02-16 17:28:49.975195 | TASK [configure-swap : Get root filesystem]
2026-02-16 17:28:50.259081 | controller | ext4
2026-02-16 17:28:50.555336 | controller | ok: Runtime: 0:00:00.013519
2026-02-16 17:28:50.561015 | 
2026-02-16 17:28:50.561083 | TASK [configure-swap : Save root filesystem]
2026-02-16 17:28:50.595211 | controller | ok
2026-02-16 17:28:50.602576 | 
2026-02-16 17:28:50.602658 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-16 17:28:50.639616 | controller | ok:
2026-02-16 17:28:50.639841 | controller | {
2026-02-16 17:28:50.639897 | controller |   "root_filesystem": "ext4"
2026-02-16 17:28:50.639940 | controller | }
2026-02-16 17:28:50.647540 | 
2026-02-16 17:28:50.647602 | TASK [configure-swap : Create swap backing file]
2026-02-16 17:29:00.453566 | controller | 8192+0 records in
2026-02-16 17:29:00.454086 | controller | 8192+0 records out
2026-02-16 17:29:00.454115 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.54045 s, 900 MB/s
2026-02-16 17:29:00.704950 | controller | ok: Runtime: 0:00:09.551048
2026-02-16 17:29:00.711919 | 
2026-02-16 17:29:00.711984 | TASK [configure-swap : Ensure swapfile perms]
2026-02-16 17:29:00.959847 | controller | changed
2026-02-16 17:29:00.966588 | 
2026-02-16 17:29:00.966656 | TASK [configure-swap : Make swapfile]
2026-02-16 17:29:28.793455 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-16 17:29:28.793594 | controller | no label, UUID=5597afb8-5ce3-43e8-84d7-82a9246a1b1f
2026-02-16 17:29:29.048177 | controller | ok: Runtime: 0:00:27.588257
2026-02-16 17:29:29.055614 | 
2026-02-16 17:29:29.055687 | TASK [configure-swap : Write swap to fstab]
2026-02-16 17:29:29.449343 | controller | changed
2026-02-16 17:29:29.456922 | 
2026-02-16 17:29:29.457009 | TASK [configure-swap : Add all swap]
2026-02-16 17:29:29.993577 | controller | ok: Runtime: 0:00:00.018478
2026-02-16 17:29:30.050082 | 
2026-02-16 17:29:30.050226 | TASK [configure-swap : Debug the swap_required variable]
2026-02-16 17:29:30.087354 | controller | ok:
2026-02-16 17:29:30.087629 | controller | {
2026-02-16 17:29:30.087689 | controller |   "swap_required": "8192"
2026-02-16 17:29:30.087733 | controller | }
2026-02-16 17:29:30.095266 | 
2026-02-16 17:29:30.095330 | TASK [configure-swap : Set swappiness]
2026-02-16 17:29:30.535129 | controller | changed
2026-02-16 17:29:30.542631 | 
2026-02-16 17:29:30.542702 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-16 17:29:30.586992 | controller | ok:
2026-02-16 17:29:30.587161 | controller | {
2026-02-16 17:29:30.587197 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-16 17:29:30.587232 | controller | }
2026-02-16 17:29:30.598202 | 
2026-02-16 17:29:30.598266 | TASK [setup-stack-user : Create stack group]
2026-02-16 17:29:31.038567 | controller | changed
2026-02-16 17:29:31.046324 | 
2026-02-16 17:29:31.046392 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-16 17:29:31.291919 | controller | changed
2026-02-16 17:29:31.297570 | 
2026-02-16 17:29:31.297634 | TASK [setup-stack-user : Create stack user]
2026-02-16 17:29:31.820641 | controller | changed
2026-02-16 17:29:31.830267 | 
2026-02-16 17:29:31.830338 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-16 17:29:32.100984 | controller | changed
2026-02-16 17:29:32.107363 | 
2026-02-16 17:29:32.107432 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-16 17:29:33.186887 | controller | changed
2026-02-16 17:29:33.194752 | 
2026-02-16 17:29:33.194871 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-16 17:29:33.432935 | controller | changed
2026-02-16 17:29:33.447032 | 
2026-02-16 17:29:33.447093 | TASK [setup-tempest-user : Create tempest group]
2026-02-16 17:29:33.736084 | controller | changed
2026-02-16 17:29:33.743779 | 
2026-02-16 17:29:33.743891 | TASK [setup-tempest-user : Create tempest user]
2026-02-16 17:29:34.136989 | controller | changed
2026-02-16 17:29:34.196090 | 
2026-02-16 17:29:34.196330 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-16 17:29:34.986409 | controller | changed
2026-02-16 17:29:34.996849 | 
2026-02-16 17:29:34.996921 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-16 17:29:35.328589 | controller | ok: Not all paths examined, check warnings for details
2026-02-16 17:29:35.343082 | 
2026-02-16 17:29:35.343151 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-16 17:29:35.825617 | controller | ok: Item: Runtime: 0:00:00.192274
2026-02-16 17:29:36.382061 | controller | ok: Item: Runtime: 0:00:00.342420
2026-02-16 17:29:37.148132 | controller | ok: Item: Runtime: 0:00:00.547070
2026-02-16 17:29:37.444471 | controller | ok: Item: Runtime: 0:00:00.063873
2026-02-16 17:29:37.937332 | controller | ok: Item: Runtime: 0:00:00.257235
2026-02-16 17:29:39.456029 | controller | ok: Item: Runtime: 0:00:01.298688
2026-02-16 17:29:42.623697 | controller | ok: Item: Runtime: 0:00:02.945522
2026-02-16 17:29:42.944229 | controller | ok: Item: Runtime: 0:00:00.098374
2026-02-16 17:29:43.374301 | controller | ok: Item: Runtime: 0:00:00.190454
2026-02-16 17:29:43.722341 | controller | ok: Item: Runtime: 0:00:00.126586
2026-02-16 17:29:44.442038 | controller | ok: Item: Runtime: 0:00:00.501013
2026-02-16 17:29:45.147738 | controller | ok: Item: Runtime: 0:00:00.499800
2026-02-16 17:29:45.604562 | controller | ok: Item: Runtime: 0:00:00.238230
2026-02-16 17:29:46.051795 | controller | ok: Item: Runtime: 0:00:00.224775
2026-02-16 17:29:46.648080 | controller | ok: Item: Runtime: 0:00:00.376699
2026-02-16 17:29:48.078417 | controller | ok: Item: Runtime: 0:00:01.222804
2026-02-16 17:29:48.111444 | 
2026-02-16 17:29:48.111632 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-16 17:29:48.331723 | controller | ok: All paths examined
2026-02-16 17:29:48.340690 | 
2026-02-16 17:29:48.340757 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-16 17:29:48.612042 | controller | ok: All paths examined
2026-02-16 17:29:48.618968 | 
2026-02-16 17:29:48.619039 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-16 17:29:49.058048 | controller | ok: Item: Runtime: 0:00:00.149816
2026-02-16 17:29:49.058499 | controller | changed: All items complete
2026-02-16 17:29:49.058546 | 
2026-02-16 17:29:49.455198 | controller | ok: Item: Runtime: 0:00:00.185689
2026-02-16 17:29:49.475962 | 
2026-02-16 17:29:49.476146 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-16 17:29:49.521085 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.523339 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.525695 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.528781 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.531890 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.534939 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.537954 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.541105 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.544721 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.547893 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.550525 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.553621 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.556686 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.560013 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.563089 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.566386 | controller | skipping: Conditional result was False
2026-02-16 17:29:49.600197 | 
2026-02-16 17:29:49.600445 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-16 17:29:51.969631 | controller | changed
2026-02-16 17:29:51.981560 | 
2026-02-16 17:29:51.981637 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-16 17:29:52.230794 | controller | changed
2026-02-16 17:29:52.326630 | 
2026-02-16 17:29:52.326814 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-16 17:29:52.587008 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-16 17:29:52.867407 | controller | ERROR
2026-02-16 17:29:52.867683 | controller | {
2026-02-16 17:29:52.867729 | controller |   "delta": "0:00:00.012959",
2026-02-16 17:29:52.867759 | controller |   "end": "2026-02-16 17:29:52.587740",
2026-02-16 17:29:52.867785 | controller |   "msg": "non-zero return code",
2026-02-16 17:29:52.867811 | controller |   "rc": 1,
2026-02-16 17:29:52.867835 | controller |   "start": "2026-02-16 17:29:52.574781"
2026-02-16 17:29:52.867891 | controller | }
2026-02-16 17:29:52.867928 | controller | ERROR: Ignoring Errors
2026-02-16 17:29:52.876934 | 
2026-02-16 17:29:52.877046 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-16 17:29:53.124249 | controller | ok
2026-02-16 17:29:53.136894 | 
2026-02-16 17:29:53.137016 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-16 17:29:53.367446 | controller | /usr/bin/journalctl
2026-02-16 17:29:53.677325 | controller | ok: Runtime: 0:00:00.007571
2026-02-16 17:29:53.684876 | 
2026-02-16 17:29:53.684979 | TASK [start-fresh-logging : Get current date]
2026-02-16 17:29:53.947595 | controller | 2026-02-16 17:29:53
2026-02-16 17:29:54.225925 | controller | ok: Runtime: 0:00:00.007750
2026-02-16 17:29:54.234111 | 
2026-02-16 17:29:54.234198 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-16 17:29:54.819297 | controller | changed
2026-02-16 17:29:54.825800 | 
2026-02-16 17:29:54.825863 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-16 17:29:54.851114 | controller | skipping: Conditional result was False
2026-02-16 17:29:54.864058 | 
2026-02-16 17:29:54.864146 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-16 17:29:55.401272 | controller | skipping: Conditional result was False
2026-02-16 17:29:55.411337 | 
2026-02-16 17:29:55.411408 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-16 17:29:55.942506 | controller | skipping: Conditional result was False
2026-02-16 17:29:55.955008 | 
2026-02-16 17:29:55.955100 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-16 17:29:55.980244 | controller | skipping: Conditional result was False
2026-02-16 17:29:55.989233 | 
2026-02-16 17:29:55.989342 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-16 17:29:56.525565 | controller | skipping: Conditional result was False
2026-02-16 17:29:56.537880 | 
2026-02-16 17:29:56.537977 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-16 17:29:57.069701 | controller | skipping: Conditional result was False
2026-02-16 17:29:57.081518 | 
2026-02-16 17:29:57.081641 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-16 17:29:57.107187 | controller | skipping: Conditional result was False
2026-02-16 17:29:57.117770 | 
2026-02-16 17:29:57.117857 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-16 17:29:57.143573 | controller | skipping: Conditional result was False
2026-02-16 17:29:57.154324 | 
2026-02-16 17:29:57.154404 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-16 17:29:57.744276 | controller | skipping: Conditional result was False
2026-02-16 17:29:57.754565 | 
2026-02-16 17:29:57.754656 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-16 17:29:58.285406 | controller | skipping: Conditional result was False
2026-02-16 17:29:58.297411 | 
2026-02-16 17:29:58.297505 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-16 17:29:58.323220 | controller | skipping: Conditional result was False
2026-02-16 17:29:58.335923 | 
2026-02-16 17:29:58.336068 | TASK [start-fresh-logging : Start rsyslog]
2026-02-16 17:29:58.361998 | controller | skipping: Conditional result was False
2026-02-16 17:29:58.379147 | 
2026-02-16 17:29:58.379220 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-16 17:29:58.782703 | controller | ok
2026-02-16 17:29:58.797323 | 
2026-02-16 17:29:58.797975 | PLAY RECAP
2026-02-16 17:29:58.798060 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-16 17:29:58.798084 | 
2026-02-16 17:29:58.994188 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-16 17:29:59.004326 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:29:59.604758 | 
2026-02-16 17:29:59.604988 | PLAY [all]
2026-02-16 17:29:59.618671 | 
2026-02-16 17:29:59.618771 | TASK [ensure-rust : Use rustup]
2026-02-16 17:29:59.654259 | controller | ok
2026-02-16 17:29:59.661359 | controller | included: /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-02-16 17:29:59.666863 | 
2026-02-16 17:29:59.666940 | TASK [ensure-rust : Install Rust]
2026-02-16 17:30:00.067046 | controller | /bin/bash: line 2: curl: command not found
2026-02-16 17:30:00.207271 | controller | ERROR
2026-02-16 17:30:00.207555 | controller | {
2026-02-16 17:30:00.207624 | controller |   "delta": "0:00:00.013095",
2026-02-16 17:30:00.207677 | controller |   "end": "2026-02-16 17:30:00.070024",
2026-02-16 17:30:00.207722 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:00.207762 | controller |   "rc": 127,
2026-02-16 17:30:00.207804 | controller |   "start": "2026-02-16 17:30:00.056929"
2026-02-16 17:30:00.207854 | controller | }
failure
2026-02-16 17:30:00.210316 | 
2026-02-16 17:30:00.210365 | PLAY RECAP
2026-02-16 17:30:00.210415 | controller | ok: 1 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-16 17:30:00.210437 | 
2026-02-16 17:30:00.334606 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/pre.yml@main]
2026-02-16 17:30:00.344941 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:30:00.940547 | 
2026-02-16 17:30:00.940734 | PLAY [all]
2026-02-16 17:30:00.957182 | 
2026-02-16 17:30:00.957258 | TASK [Return built artifacts to Zuul]
2026-02-16 17:30:00.992228 | controller | ok
2026-02-16 17:30:00.998203 | 
2026-02-16 17:30:00.998274 | TASK [Get the Docker volume ID]
2026-02-16 17:30:01.537006 | controller | ERROR
2026-02-16 17:30:01.537485 | controller | {
2026-02-16 17:30:01.537556 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-02-16 17:30:01.537611 | controller |   "rc": 2
2026-02-16 17:30:01.537662 | controller | }
2026-02-16 17:30:01.537727 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:01.544236 | 
2026-02-16 17:30:01.544325 | TASK [Copy all of the pod logs]
2026-02-16 17:30:01.577559 | controller | ERROR
2026-02-16 17:30:01.577769 | controller | {
2026-02-16 17:30:01.577810 | 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/051bc51c0bc946449742f22de3a7ca86/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:30:01.577842 | controller | }
2026-02-16 17:30:01.577878 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:01.578979 | 
2026-02-16 17:30:01.579038 | PLAY RECAP
2026-02-16 17:30:01.579097 | controller | ok: 3 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 2
2026-02-16 17:30:01.579126 | 
2026-02-16 17:30:01.732721 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/sonobuoy/post.yml@main]
2026-02-16 17:30:01.742254 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:30:02.380820 | 
2026-02-16 17:30:02.380981 | PLAY [all]
2026-02-16 17:30:02.399349 | 
2026-02-16 17:30:02.399433 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-16 17:30:02.786518 | controller | changed
2026-02-16 17:30:02.794227 | 
2026-02-16 17:30:02.794332 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-16 17:30:03.837933 | controller | ok: Runtime: 0:00:00.459024
2026-02-16 17:30:03.845810 | 
2026-02-16 17:30:03.845892 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-16 17:30:04.386201 | controller | ok: Runtime: 0:00:00.018521
2026-02-16 17:30:04.392337 | 
2026-02-16 17:30:04.392410 | TASK [export-devstack-journal : Export journal]
2026-02-16 17:30:04.929504 | controller | ok: Runtime: 0:00:00.022888
2026-02-16 17:30:04.938688 | 
2026-02-16 17:30:04.938763 | TASK [export-devstack-journal : Save journal README]
2026-02-16 17:30:05.806291 | controller | changed
2026-02-16 17:30:05.862236 | 
2026-02-16 17:30:05.862393 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-16 17:30:06.110690 | controller | changed
2026-02-16 17:30:06.117289 | 
2026-02-16 17:30:06.117352 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:30:06.425537 | controller | Output suppressed because no_log was given
2026-02-16 17:30:06.430942 | 
2026-02-16 17:30:06.431008 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:30:06.468332 | 
2026-02-16 17:30:06.468496 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:30:06.505211 | 
2026-02-16 17:30:06.505372 | TASK [apache-logs-conf : Find logs]
2026-02-16 17:30:06.541071 | 
2026-02-16 17:30:06.541197 | LOOP [apache-logs-conf : Dereference files]
2026-02-16 17:30:06.585539 | 
2026-02-16 17:30:06.585675 | LOOP [apache-logs-conf : Create hard links]
2026-02-16 17:30:06.624500 | 
2026-02-16 17:30:06.624636 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-16 17:30:06.862492 | controller | changed
2026-02-16 17:30:06.868126 | 
2026-02-16 17:30:06.868194 | TASK [apache-logs-conf : Define config paths]
2026-02-16 17:30:06.901867 | controller | ok
2026-02-16 17:30:06.909009 | 
2026-02-16 17:30:06.909093 | TASK [apache-logs-conf : Discover configurations]
2026-02-16 17:30:07.148196 | controller | Output suppressed because no_log was given
2026-02-16 17:30:07.153226 | 
2026-02-16 17:30:07.153287 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-16 17:30:07.190243 | 
2026-02-16 17:30:07.190384 | LOOP [apache-logs-conf : Link configurations]
2026-02-16 17:30:07.232528 | 
2026-02-16 17:30:07.232696 | TASK [capture-performance-data : Generate statistics]
2026-02-16 17:30:07.879574 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-16 17:30:07.896994 | controller | Using python 3.12 to install setuptools
2026-02-16 17:30:07.933868 | controller | /usr/bin/python3.12: No module named pip
2026-02-16 17:30:07.945215 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-16 17:30:08.275962 | controller | ERROR
2026-02-16 17:30:08.276331 | controller | {
2026-02-16 17:30:08.276405 | controller |   "delta": "0:00:00.477437",
2026-02-16 17:30:08.276452 | controller |   "end": "2026-02-16 17:30:07.947749",
2026-02-16 17:30:08.276494 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:08.276543 | controller |   "rc": 127,
2026-02-16 17:30:08.276586 | controller |   "start": "2026-02-16 17:30:07.470312"
2026-02-16 17:30:08.276626 | controller | }
2026-02-16 17:30:08.276680 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:08.290322 | 
2026-02-16 17:30:08.290401 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-16 17:30:08.530880 | controller | changed
2026-02-16 17:30:08.538796 | 
2026-02-16 17:30:08.538886 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-16 17:30:12.221163 | controller | Output suppressed because no_log was given
2026-02-16 17:30:12.235740 | 
2026-02-16 17:30:12.235836 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-16 17:30:12.272295 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.272928 | 
2026-02-16 17:30:12.275245 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.276951 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.278742 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.279895 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.282246 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.284627 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.287171 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.289565 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.292022 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.294509 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.296775 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.299139 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.301481 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.303874 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.306311 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.308669 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.312181 | controller | skipping: Conditional result was False
2026-02-16 17:30:12.349038 | 
2026-02-16 17:30:12.349208 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-16 17:30:12.576838 | controller | ok
2026-02-16 17:30:12.584430 | 
2026-02-16 17:30:12.584501 | TASK [devstack-project-conf : Copy configuration files]
2026-02-16 17:30:13.183469 | controller | skipping: Conditional result was False
2026-02-16 17:30:13.203284 | 
2026-02-16 17:30:13.203397 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-16 17:30:13.470604 | controller | sudo: iptables-save: command not found
2026-02-16 17:30:13.511939 | controller | /usr/bin/python3: No module named pip
2026-02-16 17:30:14.866345 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-16 17:30:15.245849 | controller | ok: Runtime: 0:00:01.415027
2026-02-16 17:30:15.256622 | 
2026-02-16 17:30:15.256691 | LOOP [stage-output : Register sources]
2026-02-16 17:30:24.532159 | controller | Output suppressed because no_log was given
2026-02-16 17:30:24.580320 | 
2026-02-16 17:30:24.580469 | TASK [stage-output : Check sudo]
2026-02-16 17:30:25.120687 | controller | ok: Runtime: 0:00:00.021088
2026-02-16 17:30:25.126802 | 
2026-02-16 17:30:25.126868 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-16 17:30:25.224227 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.224784 | 
2026-02-16 17:30:25.226559 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.227491 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.229322 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.265786 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.266274 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.266734 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.267187 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.268870 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.270973 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.273110 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.275180 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.277591 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.279736 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.304048 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.304495 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.304827 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.305916 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.308831 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.311861 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.313489 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.315738 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.317930 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.342792 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.343148 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.417900 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.428489 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.439219 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.446946 | controller | Output suppressed because no_log was given
2026-02-16 17:30:25.461235 | 
2026-02-16 17:30:25.461328 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-16 17:30:25.528868 | controller | ok
2026-02-16 17:30:25.535821 | 
2026-02-16 17:30:25.535888 | LOOP [stage-output : Ensure target folders exist]
2026-02-16 17:30:25.789038 | controller | changed: "docs"
2026-02-16 17:30:26.002984 | controller | changed: "artifacts"
2026-02-16 17:30:26.220921 | controller | ok: "logs"
2026-02-16 17:30:26.233361 | 
2026-02-16 17:30:26.233455 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-16 17:30:26.514826 | controller | ok: Item: Runtime: 0:00:00.007799
2026-02-16 17:30:26.515082 | controller | changed: All items complete
2026-02-16 17:30:26.515109 | 
2026-02-16 17:30:26.741413 | controller | ok: Item: Runtime: 0:00:00.008133
2026-02-16 17:30:26.967952 | controller | ok: Item: Runtime: 0:00:00.009953
2026-02-16 17:30:27.194414 | controller | ok: Item: Runtime: 0:00:00.007734
2026-02-16 17:30:27.418976 | controller | ok: Item: Runtime: 0:00:00.007676
2026-02-16 17:30:27.646112 | controller | ok: Item: Runtime: 0:00:00.007628
2026-02-16 17:30:27.865332 | controller | ok: Item: Runtime: 0:00:00.007795
2026-02-16 17:30:28.079220 | controller | ok: Item: Runtime: 0:00:00.007663
2026-02-16 17:30:28.305596 | controller | ok: Item: Runtime: 0:00:00.008006
2026-02-16 17:30:28.521305 | controller | ok: Item: Runtime: 0:00:00.008470
2026-02-16 17:30:28.745585 | controller | ok: Item: Runtime: 0:00:00.006798
2026-02-16 17:30:29.021498 | controller | ok: Item: Runtime: 0:00:00.007781
2026-02-16 17:30:29.197807 | controller | ok: Item: Runtime: 0:00:00.007726
2026-02-16 17:30:29.426641 | controller | ok: Item: Runtime: 0:00:00.007690
2026-02-16 17:30:29.660765 | controller | ok: Item: Runtime: 0:00:00.008611
2026-02-16 17:30:29.882986 | controller | ok: Item: Runtime: 0:00:00.008319
2026-02-16 17:30:29.905440 | 
2026-02-16 17:30:29.905620 | TASK [stage-output : Make all log files readable]
2026-02-16 17:30:30.144228 | controller | changed
2026-02-16 17:30:30.152124 | 
2026-02-16 17:30:30.152209 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-16 17:30:30.472272 | controller | changed: Renamed files for staging.
2026-02-16 17:30:30.479455 | 
2026-02-16 17:30:30.479521 | TASK [stage-output : Discover log files for compression]
2026-02-16 17:30:30.505404 | controller | skipping: Conditional result was False
2026-02-16 17:30:30.517179 | 
2026-02-16 17:30:30.517275 | LOOP [stage-output : Archive everything from logs]
2026-02-16 17:30:30.559245 | 
2026-02-16 17:30:30.559380 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-16 17:30:31.177236 | controller | changed:
2026-02-16 17:30:31.177411 | controller | created directory /var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/work/logs/controller
2026-02-16 17:30:31.177440 | controller | cd+++++++++ logs/
2026-02-16 17:30:31.177462 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-16 17:30:31.177483 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-16 17:30:31.177502 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-16 17:30:31.177520 | controller | >f+++++++++ logs/df.txt
2026-02-16 17:30:31.177539 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-16 17:30:31.177557 | controller | >f+++++++++ logs/iptables.txt
2026-02-16 17:30:31.177575 | controller | >f+++++++++ logs/listen53.txt
2026-02-16 17:30:31.177593 | controller | >f+++++++++ logs/local_conf.txt
2026-02-16 17:30:31.177612 | controller | >f+++++++++ logs/mount.txt
2026-02-16 17:30:31.177630 | controller | >f+++++++++ logs/performance.json
2026-02-16 17:30:31.177648 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-16 17:30:31.177666 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-16 17:30:31.177687 | controller | >f+++++++++ logs/services.txt
2026-02-16 17:30:31.177719 | controller | >f+++++++++ logs/sudoers
2026-02-16 17:30:31.177738 | controller | >f+++++++++ logs/syslog.txt
2026-02-16 17:30:31.177757 | controller | cd+++++++++ logs/apache/
2026-02-16 17:30:31.177775 | controller | cd+++++++++ logs/apache_config/
2026-02-16 17:30:31.177794 | controller | cd+++++++++ logs/etc/
2026-02-16 17:30:31.177813 | controller | cd+++++++++ logs/sudoers.d/
2026-02-16 17:30:31.177831 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-16 17:30:31.177849 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-16 17:30:31.177867 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-16 17:30:31.177886 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-16 17:30:31.177903 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-16 17:30:31.189378 | 
2026-02-16 17:30:31.189473 | TASK [Check if a tempest log exits]
2026-02-16 17:30:31.441957 | controller | ok
2026-02-16 17:30:31.447997 | 
2026-02-16 17:30:31.448091 | TASK [Link post-devstack tempest.log]
2026-02-16 17:30:31.473592 | controller | skipping: Conditional result was False
2026-02-16 17:30:31.484818 | 
2026-02-16 17:30:31.484931 | TASK [Capture most recent qemu crash dump, if any]
2026-02-16 17:30:31.742300 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-16 17:30:32.025670 | controller | ERROR
2026-02-16 17:30:32.025883 | controller | {
2026-02-16 17:30:32.025915 | controller |   "delta": "0:00:00.007257",
2026-02-16 17:30:32.025936 | controller |   "end": "2026-02-16 17:30:31.742745",
2026-02-16 17:30:32.025955 | controller |   "msg": "non-zero return code",
2026-02-16 17:30:32.025976 | controller |   "rc": 127,
2026-02-16 17:30:32.026017 | controller |   "start": "2026-02-16 17:30:31.735488"
2026-02-16 17:30:32.026045 | controller | }
2026-02-16 17:30:32.026073 | controller | ERROR: Ignoring Errors
2026-02-16 17:30:32.029065 | 
2026-02-16 17:30:32.029116 | PLAY RECAP
2026-02-16 17:30:32.029172 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-16 17:30:32.029196 | 
2026-02-16 17:30:32.182616 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-16 17:30:32.192791 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:30:32.791400 | 
2026-02-16 17:30:32.791599 | PLAY [all]
2026-02-16 17:30:32.803243 | 
2026-02-16 17:30:32.803316 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-16 17:30:32.849410 | controller | skipping: Conditional result was False
2026-02-16 17:30:32.858753 | 
2026-02-16 17:30:32.858873 | TASK [fetch-output : Set log path for single node]
2026-02-16 17:30:32.902884 | controller | ok
2026-02-16 17:30:32.911620 | 
2026-02-16 17:30:32.911690 | LOOP [fetch-output : Ensure local output dirs]
2026-02-16 17:30:33.314312 | controller -> localhost | ok: "/var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/work/logs"
2026-02-16 17:30:33.524354 | controller -> localhost | changed: "/var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/work/artifacts"
2026-02-16 17:30:33.730761 | controller -> localhost | changed: "/var/lib/zuul/builds/051bc51c0bc946449742f22de3a7ca86/work/docs"
2026-02-16 17:30:33.754674 | 
2026-02-16 17:30:33.754851 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-16 17:30:34.404054 | controller | changed: .d..t...... ./
2026-02-16 17:30:34.404333 | controller | changed: All items complete
2026-02-16 17:30:34.404370 | 
2026-02-16 17:30:34.873605 | controller | changed: .d..t...... ./
2026-02-16 17:30:35.351499 | controller | changed: .d..t...... ./
2026-02-16 17:30:35.374361 | 
2026-02-16 17:30:35.374534 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-16 17:30:35.804845 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008342
2026-02-16 17:30:36.039811 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007289
2026-02-16 17:30:36.054971 | 
2026-02-16 17:30:36.055112 | PLAY [all]
2026-02-16 17:30:36.063921 | 
2026-02-16 17:30:36.064024 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-16 17:30:36.560455 | controller | changed
2026-02-16 17:30:36.566476 | 
2026-02-16 17:30:36.566528 | PLAY RECAP
2026-02-16 17:30:36.566574 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-16 17:30:36.566595 | 
2026-02-16 17:30:36.684101 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main]
2026-02-16 17:30:36.694926 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-16 17:30:37.318509 | 
2026-02-16 17:30:37.318676 | PLAY [localhost]
2026-02-16 17:30:37.330863 | 
2026-02-16 17:30:37.330950 | TASK [Generate Zuul manifest]
2026-02-16 17:30:37.354500 | localhost | ok
2026-02-16 17:30:37.371922 | 
2026-02-16 17:30:37.372018 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-16 17:30:37.739876 | localhost | changed
2026-02-16 17:30:37.752587 | 
2026-02-16 17:30:37.752670 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-16 17:30:37.785515 | localhost | ok
2026-02-16 17:30:37.794763 | 
2026-02-16 17:30:37.794828 | TASK [Upload logs]
2026-02-16 17:30:37.815031 | localhost | ok
2026-02-16 17:30:37.883567 | 
2026-02-16 17:30:37.883724 | TASK [Set zuul-log-path fact]
2026-02-16 17:30:37.907014 | localhost | ok
2026-02-16 17:30:37.919631 | 
2026-02-16 17:30:37.919704 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-16 17:30:37.951668 | localhost | ok
2026-02-16 17:30:37.961462 | 
2026-02-16 17:30:37.961560 | TASK [upload-logs : Create log directories]
2026-02-16 17:30:38.379977 | localhost | changed
2026-02-16 17:30:38.385815 | 
2026-02-16 17:30:38.385887 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-16 17:30:38.824036 | localhost -> localhost | ok: Runtime: 0:00:00.006196
2026-02-16 17:30:38.828972 | 
2026-02-16 17:30:38.829041 | TASK [upload-logs : Upload logs to log server]
2026-02-16 17:30:39.277461 | localhost | Output suppressed because no_log was given
2026-02-16 17:30:39.281478 | 
2026-02-16 17:30:39.281547 | LOOP [upload-logs : Compress console log and json output]
2026-02-16 17:30:39.330165 | localhost | skipping: Conditional result was False
2026-02-16 17:30:39.336239 | localhost | skipping: Conditional result was False
2026-02-16 17:30:39.345181 | 
2026-02-16 17:30:39.345329 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-16 17:30:39.385171 | localhost | skipping: Conditional result was False
2026-02-16 17:30:39.385570 | 
2026-02-16 17:30:39.388381 | localhost | skipping: Conditional result was False
2026-02-16 17:30:39.400343 | 
2026-02-16 17:30:39.400528 | LOOP [upload-logs : Upload console log and json output]