2026-05-20 01:15:19.570024 | Job console starting
2026-05-20 01:15:19.619600 | Updating git repos
2026-05-20 01:15:25.980777 | Cloning repos into workspace
2026-05-20 01:15:42.256211 | Restoring repo states
2026-05-20 01:15:44.248117 | Merging changes
2026-05-20 01:15:45.387670 | Checking out repos
2026-05-20 01:15:55.473221 | Preparing playbooks
2026-05-20 01:16:02.804276 | Running Ansible setup
2026-05-20 01:16:07.196813 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:16:07.943199 | 
2026-05-20 01:16:07.943334 | PLAY [localhost]
2026-05-20 01:16:07.950924 | 
2026-05-20 01:16:07.951008 | TASK [Gathering Facts]
2026-05-20 01:16:08.898610 | localhost | ok
2026-05-20 01:16:08.906778 | 
2026-05-20 01:16:08.906867 | TASK [Setup log path fact]
2026-05-20 01:16:08.935801 | localhost | ok
2026-05-20 01:16:09.018800 | 
2026-05-20 01:16:09.018900 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:16:09.050214 | localhost | ok
2026-05-20 01:16:09.059014 | 
2026-05-20 01:16:09.059080 | TASK [emit-job-header : Print job information]
2026-05-20 01:16:09.106523 | # Job Information
2026-05-20 01:16:09.106645 | Ansible Version: 2.16.18
2026-05-20 01:16:09.106677 | Job: magnum-cluster-api-hydrophone-v1.34.7-cilium
2026-05-20 01:16:09.106699 | Pipeline: check
2026-05-20 01:16:09.106718 | Executor: 2d72f0692154
2026-05-20 01:16:09.106737 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/996
2026-05-20 01:16:09.106759 | Event ID: 2acb06b0-53e9-11f1-9ac5-3449499046b8
2026-05-20 01:16:09.109551 | 
2026-05-20 01:16:09.109613 | LOOP [emit-job-header : Print node information]
2026-05-20 01:16:09.246664 | localhost | ok:
2026-05-20 01:16:09.371530 | localhost | # Node Information
2026-05-20 01:16:09.371643 | localhost | Inventory Hostname: controller
2026-05-20 01:16:09.371679 | localhost | Hostname: np0000175147
2026-05-20 01:16:09.371707 | localhost | Username: zuul
2026-05-20 01:16:09.371739 | localhost | Distro: Ubuntu 24.04
2026-05-20 01:16:09.371767 | localhost | Provider: yul1
2026-05-20 01:16:09.371793 | localhost | Region: ca-ymq-1
2026-05-20 01:16:09.371819 | localhost | Label: ubuntu-noble-16
2026-05-20 01:16:09.371845 | localhost | Product Name: OpenStack Nova
2026-05-20 01:16:09.371870 | localhost | Interface IP: 199.204.45.250
2026-05-20 01:16:09.400226 | 
2026-05-20 01:16:09.400878 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 01:16:09.825745 | localhost -> localhost | changed
2026-05-20 01:16:09.836287 | 
2026-05-20 01:16:09.836409 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 01:16:10.967760 | localhost -> localhost | changed
2026-05-20 01:16:10.980124 | 
2026-05-20 01:16:10.980655 | PLAY [all]
2026-05-20 01:16:11.002406 | 
2026-05-20 01:16:11.002573 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 01:16:11.241152 | controller -> localhost | ok
2026-05-20 01:16:11.251873 | 
2026-05-20 01:16:11.251975 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 01:16:11.291892 | controller | ok
2026-05-20 01:16:11.311591 | controller | included: /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 01:16:11.320785 | 
2026-05-20 01:16:11.320858 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 01:16:14.804315 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 01:16:14.804570 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/work/ce5fb8f0fe76471cb4327f72873e850a_id_rsa
2026-05-20 01:16:14.804638 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/work/ce5fb8f0fe76471cb4327f72873e850a_id_rsa.pub
2026-05-20 01:16:14.804691 | controller -> localhost | The key fingerprint is:
2026-05-20 01:16:14.804740 | controller -> localhost | SHA256:kw1qOP0HS6cCZEOdbwchiPQRRKyhajd3d2xFipSuL7k zuul-build-sshkey
2026-05-20 01:16:14.804808 | controller -> localhost | The key's randomart image is:
2026-05-20 01:16:14.804858 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 01:16:14.804912 | controller -> localhost | | ..=*+.....      |
2026-05-20 01:16:14.804975 | controller -> localhost | |  ooo.o..o   .   |
2026-05-20 01:16:14.805357 | controller -> localhost | | . o=  .+.. o    |
2026-05-20 01:16:14.805428 | controller -> localhost | |. .o + .o*.. .   |
2026-05-20 01:16:14.805477 | controller -> localhost | |.   + +.S.+ .    |
2026-05-20 01:16:14.805521 | controller -> localhost | |.. o = = B +     |
2026-05-20 01:16:14.805565 | controller -> localhost | |. . o o B +      |
2026-05-20 01:16:14.805608 | controller -> localhost | |       + o       |
2026-05-20 01:16:14.805658 | controller -> localhost | |       Eo        |
2026-05-20 01:16:14.805701 | controller -> localhost | +----[SHA256]-----+
2026-05-20 01:16:14.805794 | controller -> localhost | ok: Runtime: 0:00:03.028734
2026-05-20 01:16:14.811977 | 
2026-05-20 01:16:14.812041 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 01:16:14.840741 | controller | ok
2026-05-20 01:16:14.850441 | controller | included: /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 01:16:14.858185 | 
2026-05-20 01:16:14.858243 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 01:16:14.881882 | controller | skipping: Conditional result was False
2026-05-20 01:16:14.891452 | 
2026-05-20 01:16:14.891538 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 01:16:18.477804 | controller | changed
2026-05-20 01:16:18.507616 | 
2026-05-20 01:16:18.507724 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 01:16:19.757564 | controller | ok
2026-05-20 01:16:19.767197 | 
2026-05-20 01:16:19.767340 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 01:16:20.473961 | controller | changed
2026-05-20 01:16:20.493229 | 
2026-05-20 01:16:20.493429 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 01:16:21.144209 | controller | changed
2026-05-20 01:16:21.213349 | 
2026-05-20 01:16:21.213462 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 01:16:21.239099 | controller | skipping: Conditional result was False
2026-05-20 01:16:21.249727 | 
2026-05-20 01:16:21.249842 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 01:16:21.720447 | controller -> localhost | changed
2026-05-20 01:16:21.737189 | 
2026-05-20 01:16:21.737273 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 01:16:22.221917 | controller -> localhost | Identity added: /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/work/ce5fb8f0fe76471cb4327f72873e850a_id_rsa (zuul-build-sshkey)
2026-05-20 01:16:22.222141 | controller -> localhost | ok: Runtime: 0:00:00.013822
2026-05-20 01:16:22.228049 | 
2026-05-20 01:16:22.228114 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 01:16:22.556974 | controller | ok
2026-05-20 01:16:22.562982 | 
2026-05-20 01:16:22.563468 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 01:16:22.589036 | controller | skipping: Conditional result was False
2026-05-20 01:16:22.604917 | 
2026-05-20 01:16:22.605015 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 01:16:22.933990 | controller | ok
2026-05-20 01:16:22.942091 | 
2026-05-20 01:16:22.942170 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 01:17:58.839493 | controller | Output suppressed because no_log was given
2026-05-20 01:17:58.864407 | 
2026-05-20 01:17:58.864526 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 01:17:59.701467 | controller | ok: "logs"
2026-05-20 01:17:59.701768 | controller | ok: All items complete
2026-05-20 01:17:59.701798 | 
2026-05-20 01:17:59.841971 | controller | ok: "artifacts"
2026-05-20 01:18:00.047053 | controller | ok: "docs"
2026-05-20 01:18:00.063892 | 
2026-05-20 01:18:00.064034 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 01:18:00.329183 | controller | changed: "logs"
2026-05-20 01:18:00.517902 | controller | changed: "artifacts"
2026-05-20 01:18:00.706125 | controller | changed: "docs"
2026-05-20 01:18:00.779201 | 
2026-05-20 01:18:00.779310 | PLAY RECAP
2026-05-20 01:18:00.779392 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 01:18:00.779424 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:18:00.779446 | 
2026-05-20 01:18:00.981490 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:18:01.016209 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:18:02.239402 | 
2026-05-20 01:18:02.239602 | PLAY [all]
2026-05-20 01:18:02.287138 | 
2026-05-20 01:18:02.287312 | TASK [Fix the permissions of the zuul home directory]
2026-05-20 01:18:03.214172 | controller | changed
2026-05-20 01:18:03.226491 | 
2026-05-20 01:18:03.226628 | TASK [Gather minimum local MTU]
2026-05-20 01:18:03.313822 | controller | ok
2026-05-20 01:18:03.319230 | 
2026-05-20 01:18:03.319304 | TASK [Calculate external_bridge_mtu]
2026-05-20 01:18:03.359344 | controller | ok
2026-05-20 01:18:03.365618 | 
2026-05-20 01:18:03.365706 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-20 01:18:03.423087 | controller | skipping: Conditional result was False
2026-05-20 01:18:03.430703 | 
2026-05-20 01:18:03.430788 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-20 01:18:04.473579 | controller | ok: Runtime: 0:00:00.012660
2026-05-20 01:18:04.485214 | 
2026-05-20 01:18:04.485307 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-20 01:18:04.518131 | controller | skipping: Conditional result was False
2026-05-20 01:18:04.525734 | 
2026-05-20 01:18:04.525856 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-20 01:18:04.571035 | controller | skipping: Conditional result was False
2026-05-20 01:18:04.580010 | 
2026-05-20 01:18:04.580149 | TASK [configure-swap : Setup swap file on root device]
2026-05-20 01:18:04.662607 | controller | ok
2026-05-20 01:18:04.695217 | controller | included: /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-20 01:18:04.717251 | 
2026-05-20 01:18:04.717392 | TASK [configure-swap : Calculate required swap]
2026-05-20 01:18:04.917633 | controller | ok
2026-05-20 01:18:05.020041 | 
2026-05-20 01:18:05.020160 | TASK [configure-swap : Get root filesystem]
2026-05-20 01:18:05.310508 | controller | ext4
2026-05-20 01:18:05.597496 | controller | ok: Runtime: 0:00:00.013126
2026-05-20 01:18:05.607355 | 
2026-05-20 01:18:05.607432 | TASK [configure-swap : Save root filesystem]
2026-05-20 01:18:05.643054 | controller | ok
2026-05-20 01:18:05.649934 | 
2026-05-20 01:18:05.650009 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-20 01:18:05.690492 | controller | ok:
2026-05-20 01:18:05.690642 | controller | {
2026-05-20 01:18:05.690682 | controller |   "root_filesystem": "ext4"
2026-05-20 01:18:05.690704 | controller | }
2026-05-20 01:18:05.696241 | 
2026-05-20 01:18:05.696309 | TASK [configure-swap : Create swap backing file]
2026-05-20 01:18:15.072513 | controller | 8192+0 records in
2026-05-20 01:18:15.072633 | controller | 8192+0 records out
2026-05-20 01:18:15.072654 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.02658 s, 952 MB/s
2026-05-20 01:18:15.415593 | controller | ok: Runtime: 0:00:09.034140
2026-05-20 01:18:15.422338 | 
2026-05-20 01:18:15.422431 | TASK [configure-swap : Ensure swapfile perms]
2026-05-20 01:18:16.067429 | controller | changed
2026-05-20 01:18:16.234804 | 
2026-05-20 01:18:16.235015 | TASK [configure-swap : Make swapfile]
2026-05-20 01:19:03.865635 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-20 01:19:03.865912 | controller | no label, UUID=2b5c3943-9304-4a06-88a0-4df6048a85cf
2026-05-20 01:19:04.431084 | controller | ok: Runtime: 0:00:47.314630
2026-05-20 01:19:04.442183 | 
2026-05-20 01:19:04.442301 | TASK [configure-swap : Write swap to fstab]
2026-05-20 01:19:04.864311 | controller | changed
2026-05-20 01:19:04.876304 | 
2026-05-20 01:19:04.876390 | TASK [configure-swap : Add all swap]
2026-05-20 01:19:05.424412 | controller | ok: Runtime: 0:00:00.019172
2026-05-20 01:19:05.595524 | 
2026-05-20 01:19:05.595813 | TASK [configure-swap : Debug the swap_required variable]
2026-05-20 01:19:05.655152 | controller | ok:
2026-05-20 01:19:05.655358 | controller | {
2026-05-20 01:19:05.655386 | controller |   "swap_required": "8192"
2026-05-20 01:19:05.655407 | controller | }
2026-05-20 01:19:05.669746 | 
2026-05-20 01:19:05.670008 | TASK [configure-swap : Set swappiness]
2026-05-20 01:19:07.028622 | controller | changed
2026-05-20 01:19:07.036526 | 
2026-05-20 01:19:07.036640 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-20 01:19:07.076543 | controller | ok:
2026-05-20 01:19:07.076713 | controller | {
2026-05-20 01:19:07.076741 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-20 01:19:07.076766 | controller | }
2026-05-20 01:19:07.091704 | 
2026-05-20 01:19:07.091845 | TASK [setup-stack-user : Create stack group]
2026-05-20 01:19:07.553371 | controller | changed
2026-05-20 01:19:07.560920 | 
2026-05-20 01:19:07.561057 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-20 01:19:07.823881 | controller | changed
2026-05-20 01:19:07.834819 | 
2026-05-20 01:19:07.835297 | TASK [setup-stack-user : Create stack user]
2026-05-20 01:19:08.439106 | controller | changed
2026-05-20 01:19:08.446520 | 
2026-05-20 01:19:08.446640 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-20 01:19:08.709231 | controller | changed
2026-05-20 01:19:08.801125 | 
2026-05-20 01:19:08.801220 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-20 01:19:10.087810 | controller | changed
2026-05-20 01:19:10.168054 | 
2026-05-20 01:19:10.168184 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-20 01:19:10.510991 | controller | changed
2026-05-20 01:19:10.531085 | 
2026-05-20 01:19:10.531199 | TASK [setup-tempest-user : Create tempest group]
2026-05-20 01:19:10.847900 | controller | changed
2026-05-20 01:19:10.854615 | 
2026-05-20 01:19:10.854713 | TASK [setup-tempest-user : Create tempest user]
2026-05-20 01:19:11.225462 | controller | changed
2026-05-20 01:19:11.232035 | 
2026-05-20 01:19:11.232121 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-20 01:19:12.077960 | controller | changed
2026-05-20 01:19:12.102835 | 
2026-05-20 01:19:12.102992 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-20 01:19:12.448759 | controller | ok: Not all paths examined, check warnings for details
2026-05-20 01:19:12.460802 | 
2026-05-20 01:19:12.460904 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-20 01:19:12.941999 | controller | ok: Item: Runtime: 0:00:00.191197
2026-05-20 01:19:13.636398 | controller | ok: Item: Runtime: 0:00:00.132771
2026-05-20 01:19:14.388473 | controller | ok: Item: Runtime: 0:00:00.063822
2026-05-20 01:19:14.905205 | controller | ok: Item: Runtime: 0:00:00.272456
2026-05-20 01:19:15.284567 | controller | ok: Item: Runtime: 0:00:00.144544
2026-05-20 01:19:15.822996 | controller | ok: Item: Runtime: 0:00:00.302472
2026-05-20 01:19:16.564264 | controller | ok: Item: Runtime: 0:00:00.517258
2026-05-20 01:19:17.334941 | controller | ok: Item: Runtime: 0:00:00.554352
2026-05-20 01:19:18.077655 | controller | ok: Item: Runtime: 0:00:00.509451
2026-05-20 01:19:19.399393 | controller | ok: Item: Runtime: 0:00:01.069338
2026-05-20 01:19:19.744332 | controller | ok: Item: Runtime: 0:00:00.102985
2026-05-20 01:19:20.201570 | controller | ok: Item: Runtime: 0:00:00.208984
2026-05-20 01:19:20.764145 | controller | ok: Item: Runtime: 0:00:00.352434
2026-05-20 01:19:24.609484 | controller | ok: Item: Runtime: 0:00:03.085773
2026-05-20 01:19:26.060274 | controller | ok: Item: Runtime: 0:00:01.212068
2026-05-20 01:19:26.588348 | controller | ok: Item: Runtime: 0:00:00.290958
2026-05-20 01:19:26.636961 | 
2026-05-20 01:19:26.637179 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-20 01:19:26.852189 | controller | ok: All paths examined
2026-05-20 01:19:27.065783 | 
2026-05-20 01:19:27.065880 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-20 01:19:27.374241 | controller | ok: All paths examined
2026-05-20 01:19:27.381663 | 
2026-05-20 01:19:27.381768 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-20 01:19:27.831979 | controller | ok: Item: Runtime: 0:00:00.144950
2026-05-20 01:19:27.832260 | controller | changed: All items complete
2026-05-20 01:19:27.832291 | 
2026-05-20 01:19:28.295592 | controller | ok: Item: Runtime: 0:00:00.220075
2026-05-20 01:19:28.321025 | 
2026-05-20 01:19:28.321280 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-20 01:19:28.444762 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.448687 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.457430 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.458849 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.462019 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.468125 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.482923 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.483720 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.484413 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.489872 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.492323 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.499795 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.503231 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.504126 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.508921 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.512477 | controller | skipping: Conditional result was False
2026-05-20 01:19:28.592002 | 
2026-05-20 01:19:28.592125 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-20 01:19:30.985990 | controller | changed
2026-05-20 01:19:31.024286 | 
2026-05-20 01:19:31.025336 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-20 01:19:31.269162 | controller | changed
2026-05-20 01:19:31.390414 | 
2026-05-20 01:19:31.390555 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-20 01:19:31.736035 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-20 01:19:32.032403 | controller | ERROR
2026-05-20 01:19:32.032593 | controller | {
2026-05-20 01:19:32.032637 | controller |   "delta": "0:00:00.040476",
2026-05-20 01:19:32.032668 | controller |   "end": "2026-05-20 01:19:31.736915",
2026-05-20 01:19:32.032695 | controller |   "msg": "non-zero return code",
2026-05-20 01:19:32.032724 | controller |   "rc": 1,
2026-05-20 01:19:32.032750 | controller |   "start": "2026-05-20 01:19:31.696439"
2026-05-20 01:19:32.032777 | controller | }
2026-05-20 01:19:32.032813 | controller | ERROR: Ignoring Errors
2026-05-20 01:19:32.056713 | 
2026-05-20 01:19:32.057087 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-20 01:19:32.380368 | controller | ok
2026-05-20 01:19:32.405808 | 
2026-05-20 01:19:32.405944 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-20 01:19:32.694692 | controller | /usr/bin/journalctl
2026-05-20 01:19:33.122609 | controller | ok: Runtime: 0:00:00.010972
2026-05-20 01:19:33.139859 | 
2026-05-20 01:19:33.139970 | TASK [start-fresh-logging : Get current date]
2026-05-20 01:19:33.496552 | controller | 2026-05-20 01:19:33
2026-05-20 01:19:33.738870 | controller | ok: Runtime: 0:00:00.035684
2026-05-20 01:19:33.745504 | 
2026-05-20 01:19:33.745579 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-20 01:19:34.391657 | controller | changed
2026-05-20 01:19:34.406113 | 
2026-05-20 01:19:34.406229 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-20 01:19:34.437813 | controller | skipping: Conditional result was False
2026-05-20 01:19:34.445051 | 
2026-05-20 01:19:34.445144 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-20 01:19:34.986423 | controller | skipping: Conditional result was False
2026-05-20 01:19:35.001480 | 
2026-05-20 01:19:35.002218 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-20 01:19:35.554227 | controller | skipping: Conditional result was False
2026-05-20 01:19:35.695427 | 
2026-05-20 01:19:35.695612 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-20 01:19:35.721965 | controller | skipping: Conditional result was False
2026-05-20 01:19:35.730510 | 
2026-05-20 01:19:35.730637 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-20 01:19:36.313882 | controller | skipping: Conditional result was False
2026-05-20 01:19:36.376809 | 
2026-05-20 01:19:36.376977 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-20 01:19:36.999832 | controller | skipping: Conditional result was False
2026-05-20 01:19:37.077853 | 
2026-05-20 01:19:37.077981 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-20 01:19:37.102600 | controller | skipping: Conditional result was False
2026-05-20 01:19:37.111138 | 
2026-05-20 01:19:37.111302 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-20 01:19:37.171163 | controller | skipping: Conditional result was False
2026-05-20 01:19:37.195198 | 
2026-05-20 01:19:37.195357 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-20 01:19:37.752389 | controller | skipping: Conditional result was False
2026-05-20 01:19:37.764792 | 
2026-05-20 01:19:37.764931 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-20 01:19:38.315658 | controller | skipping: Conditional result was False
2026-05-20 01:19:38.322501 | 
2026-05-20 01:19:38.322605 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-20 01:19:38.347166 | controller | skipping: Conditional result was False
2026-05-20 01:19:38.353329 | 
2026-05-20 01:19:38.353399 | TASK [start-fresh-logging : Start rsyslog]
2026-05-20 01:19:38.388010 | controller | skipping: Conditional result was False
2026-05-20 01:19:38.406109 | 
2026-05-20 01:19:38.406234 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-20 01:19:38.959876 | controller | ok
2026-05-20 01:19:38.976541 | 
2026-05-20 01:19:38.976676 | PLAY RECAP
2026-05-20 01:19:38.976734 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-20 01:19:38.976758 | 
2026-05-20 01:19:39.123435 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:19:39.247418 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:19:40.111987 | 
2026-05-20 01:19:40.112112 | PLAY [all]
2026-05-20 01:19:40.127702 | 
2026-05-20 01:19:40.127786 | TASK [Install curl]
2026-05-20 01:20:19.021249 | controller | ok
2026-05-20 01:20:19.193408 | 
2026-05-20 01:20:19.193642 | TASK [ensure-rust : Use rustup]
2026-05-20 01:20:19.223781 | controller | ok
2026-05-20 01:20:19.267753 | controller | included: /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-05-20 01:20:19.281832 | 
2026-05-20 01:20:19.282047 | TASK [ensure-rust : Install Rust]
2026-05-20 01:20:19.815680 | controller | info: downloading installer
2026-05-20 01:20:20.089425 | controller | warn: It looks like you have an existing rustup settings file at:
2026-05-20 01:20:20.089484 | controller | warn: /opt/rust/settings.toml
2026-05-20 01:20:20.089496 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-05-20 01:20:20.089513 | controller | warn: instead of the one inferred from the default host triple.
2026-05-20 01:20:20.118048 | controller | info: profile set to default
2026-05-20 01:20:20.118104 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-05-20 01:20:20.119421 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-05-20 01:20:20.379175 | controller | info: latest update on 2026-04-16 for version 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:20:20.379229 | controller | info: downloading 6 components
2026-05-20 01:20:33.630518 | controller | info: default toolchain set to stable-x86_64-unknown-linux-gnu
2026-05-20 01:20:33.630616 | controller |
2026-05-20 01:20:33.650046 | controller |   stable-x86_64-unknown-linux-gnu installed - rustc 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:20:33.650082 | controller |
2026-05-20 01:20:33.650092 | controller |
2026-05-20 01:20:33.650118 | controller | Rust is installed now. Great!
2026-05-20 01:20:33.650127 | controller |
2026-05-20 01:20:33.650136 | controller | To get started you need Cargo's bin directory (/opt/rust/bin) in your PATH
2026-05-20 01:20:33.650145 | controller | environment variable. This has not been done automatically.
2026-05-20 01:20:33.650154 | controller |
2026-05-20 01:20:33.650162 | controller | To configure your current shell, you need to source
2026-05-20 01:20:33.650170 | controller | the corresponding env file under /opt/rust.
2026-05-20 01:20:33.650179 | controller |
2026-05-20 01:20:33.650202 | controller | This is usually done by running one of the following (note the leading DOT):
2026-05-20 01:20:33.650211 | controller | . "/opt/rust/env"            # For sh/bash/zsh/ash/dash/pdksh
2026-05-20 01:20:33.650219 | controller | source "/opt/rust/env.fish"  # For fish
2026-05-20 01:20:33.650229 | controller | source "/opt/rust/env.nu"  # For nushell
2026-05-20 01:20:33.650237 | controller | source "/opt/rust/env.tcsh"  # For tcsh
2026-05-20 01:20:33.650250 | controller | . "/opt/rust/env.ps1"        # For pwsh
2026-05-20 01:20:33.650258 | controller | source "/opt/rust/env.xsh"   # For xonsh
2026-05-20 01:20:33.650266 | controller | warn: no default linker (`cc`) was found in your PATH
2026-05-20 01:20:33.650274 | controller | warn: many Rust crates require a system C toolchain to build
2026-05-20 01:20:36.039265 | controller | ok: Runtime: 0:00:13.974594
2026-05-20 01:20:36.045907 | 
2026-05-20 01:20:36.045999 | TASK [ensure-rust : Install wrapper helper script]
2026-05-20 01:20:39.518159 | controller | changed
2026-05-20 01:20:39.525173 | 
2026-05-20 01:20:39.525269 | TASK [ensure-rust : Run wrapper installation]
2026-05-20 01:20:39.835194 | controller | Install link for cargo
2026-05-20 01:20:39.840707 | controller | Install link for cargo-clippy
2026-05-20 01:20:39.846650 | controller | Install link for cargo-fmt
2026-05-20 01:20:39.852842 | controller | Install link for cargo-miri
2026-05-20 01:20:39.859078 | controller | Install link for clippy-driver
2026-05-20 01:20:39.864456 | controller | Install link for rls
2026-05-20 01:20:39.869590 | controller | Install link for rust-analyzer
2026-05-20 01:20:39.874665 | controller | Install link for rust-gdb
2026-05-20 01:20:39.879280 | controller | Install link for rust-gdbgui
2026-05-20 01:20:39.885813 | controller | Install link for rust-lldb
2026-05-20 01:20:39.892273 | controller | Install link for rustc
2026-05-20 01:20:39.898101 | controller | Install link for rustdoc
2026-05-20 01:20:39.903514 | controller | Install link for rustfmt
2026-05-20 01:20:39.907571 | controller | Install link for rustup
2026-05-20 01:20:40.086828 | controller | ok: Runtime: 0:00:00.097164
2026-05-20 01:20:40.096966 | 
2026-05-20 01:20:40.097066 | TASK [ensure-rust : Install packages]
2026-05-20 01:20:40.124404 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.185120 | 
2026-05-20 01:20:40.185281 | PLAY RECAP
2026-05-20 01:20:40.185332 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:20:40.185355 | 
2026-05-20 01:20:40.348406 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:20:40.406304 | RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:20:41.697066 | 
2026-05-20 01:20:41.697337 | PLAY [all]
2026-05-20 01:20:41.728416 | 
2026-05-20 01:20:41.728589 | TASK [Install DevStack]
2026-05-20 01:20:41.766766 | controller | ok
2026-05-20 01:20:41.814716 | 
2026-05-20 01:20:41.814913 | TASK [Run devstack on the controller]
2026-05-20 01:20:41.858413 | controller | ok
2026-05-20 01:20:41.977701 | 
2026-05-20 01:20:42.016052 | TASK [run-devstack : Run devstack]
2026-05-20 01:20:42.763965 | controller | + unset GREP_OPTIONS
2026-05-20 01:20:42.764188 | controller | + unset LANG
2026-05-20 01:20:42.764204 | controller | + unset LANGUAGE
2026-05-20 01:20:42.764214 | controller | + LC_ALL=en_US.utf8
2026-05-20 01:20:42.770893 | controller | + export LC_ALL
2026-05-20 01:20:42.772462 | controller | ++ env
2026-05-20 01:20:42.772814 | controller | ++ grep -E '^OS_'
2026-05-20 01:20:42.773333 | controller | ++ cut -d = -f 1
2026-05-20 01:20:42.778412 | controller | + unset
2026-05-20 01:20:42.778445 | controller | + umask 022
2026-05-20 01:20:42.778477 | controller | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/usr/local/bin:/usr/local/sbin:/usr/sbin:/sbin
2026-05-20 01:20:42.780029 | controller | +++ dirname ./stack.sh
2026-05-20 01:20:42.783024 | controller | ++ cd .
2026-05-20 01:20:42.783053 | controller | ++ pwd
2026-05-20 01:20:42.783657 | controller | + TOP_DIR=/opt/stack/devstack
2026-05-20 01:20:42.783676 | controller | + NOUNSET=
2026-05-20 01:20:42.783688 | controller | + [[ -n '' ]]
2026-05-20 01:20:42.784456 | controller | ++ date +%s
2026-05-20 01:20:42.786493 | controller | + DEVSTACK_START_TIME=1779240042
2026-05-20 01:20:42.786521 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-05-20 01:20:42.786589 | controller | + FILES=/opt/stack/devstack/files
2026-05-20 01:20:42.786603 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-05-20 01:20:42.786686 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-05-20 01:20:42.786700 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-05-20 01:20:42.786758 | controller | + [[ '' == \y ]]
2026-05-20 01:20:42.786847 | controller | + [[ 1002 -eq 0 ]]
2026-05-20 01:20:42.786926 | controller | + [[ -n '' ]]
2026-05-20 01:20:42.786940 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-05-20 01:20:42.786991 | controller | + LAST_SPINNER_PID=
2026-05-20 01:20:42.787004 | controller | + source /opt/stack/devstack/functions
2026-05-20 01:20:42.787168 | controller | ++ [[ -z '' ]]
2026-05-20 01:20:42.787178 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-05-20 01:20:42.788880 | controller | ++++ dirname /opt/stack/devstack/functions
2026-05-20 01:20:42.790961 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:20:42.790986 | controller | +++ pwd
2026-05-20 01:20:42.791562 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-05-20 01:20:42.791580 | controller | ++ source /opt/stack/devstack/functions-common
2026-05-20 01:20:42.793257 | controller | ++++ set +o
2026-05-20 01:20:42.793465 | controller | ++++ grep xtrace
2026-05-20 01:20:42.796287 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-05-20 01:20:42.796324 | controller | +++ set +o xtrace
2026-05-20 01:20:42.810693 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-05-20 01:20:42.811898 | controller | ++++ set +o
2026-05-20 01:20:42.812380 | controller | ++++ grep xtrace
2026-05-20 01:20:42.815858 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-05-20 01:20:42.815882 | controller | +++ set +o xtrace
2026-05-20 01:20:42.817225 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-05-20 01:20:42.818944 | controller | ++++ set +o
2026-05-20 01:20:42.819391 | controller | ++++ grep xtrace
2026-05-20 01:20:42.822959 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-05-20 01:20:42.822983 | controller | +++ set +o xtrace
2026-05-20 01:20:42.823467 | controller | ++ source /opt/stack/devstack/inc/python
2026-05-20 01:20:42.825185 | controller | ++++ set +o
2026-05-20 01:20:42.825426 | controller | ++++ grep xtrace
2026-05-20 01:20:42.828064 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-05-20 01:20:42.828126 | controller | +++ set +o xtrace
2026-05-20 01:20:42.830184 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-05-20 01:20:42.831609 | controller | ++++ set +o
2026-05-20 01:20:42.831857 | controller | ++++ grep xtrace
2026-05-20 01:20:42.835076 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-05-20 01:20:42.835105 | controller | +++ set +o xtrace
2026-05-20 01:20:42.835433 | controller | ++ source /opt/stack/devstack/inc/async
2026-05-20 01:20:42.836315 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-05-20 01:20:42.836449 | controller | ++++ local xtrace
2026-05-20 01:20:42.837997 | controller | +++++ set +o
2026-05-20 01:20:42.838531 | controller | +++++ grep xtrace
2026-05-20 01:20:42.842217 | controller | ++++ xtrace='set -o xtrace'
2026-05-20 01:20:42.842240 | controller | ++++ set +o xtrace
2026-05-20 01:20:42.843213 | controller | +++ DEVSTACK_PARALLEL=True
2026-05-20 01:20:42.843237 | controller | +++ _ASYNC_BG_TIME=0
2026-05-20 01:20:42.846129 | controller | +++ set +o
2026-05-20 01:20:42.846454 | controller | +++ grep xtrace
2026-05-20 01:20:42.850309 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-05-20 01:20:42.850343 | controller | ++ set +o xtrace
2026-05-20 01:20:42.854889 | controller | + source /opt/stack/devstack/lib/stack
2026-05-20 01:20:42.855090 | controller | + GetDistro
2026-05-20 01:20:42.855147 | controller | + GetOSVersion
2026-05-20 01:20:42.855160 | controller | + source /etc/os-release
2026-05-20 01:20:42.855173 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-05-20 01:20:42.855185 | controller | ++ NAME=Ubuntu
2026-05-20 01:20:42.855197 | controller | ++ VERSION_ID=24.04
2026-05-20 01:20:42.855209 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-05-20 01:20:42.855221 | controller | ++ VERSION_CODENAME=noble
2026-05-20 01:20:42.855231 | controller | ++ ID=ubuntu
2026-05-20 01:20:42.855243 | controller | ++ ID_LIKE=debian
2026-05-20 01:20:42.855254 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-05-20 01:20:42.855266 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-05-20 01:20:42.855279 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-05-20 01:20:42.855296 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-05-20 01:20:42.855308 | controller | ++ UBUNTU_CODENAME=noble
2026-05-20 01:20:42.855325 | controller | ++ LOGO=ubuntu-logo
2026-05-20 01:20:42.855334 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-05-20 01:20:42.855411 | controller | + _ensure_lsb_release
2026-05-20 01:20:42.856309 | controller | ++ command -v lsb_release
2026-05-20 01:20:42.856839 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-05-20 01:20:42.856857 | controller | + return
2026-05-20 01:20:42.857756 | controller | ++ lsb_release -r -s
2026-05-20 01:20:42.877749 | controller | + os_RELEASE=24.04
2026-05-20 01:20:42.878906 | controller | ++ lsb_release -c -s
2026-05-20 01:20:42.898729 | controller | + os_CODENAME=noble
2026-05-20 01:20:42.899656 | controller | ++ lsb_release -i -s
2026-05-20 01:20:42.917504 | controller | + os_VENDOR=Ubuntu
2026-05-20 01:20:42.917541 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:20:42.917652 | controller | + os_PACKAGE=deb
2026-05-20 01:20:42.917661 | controller | + typeset -xr os_VENDOR
2026-05-20 01:20:42.917689 | controller | + typeset -xr os_RELEASE
2026-05-20 01:20:42.917697 | controller | + typeset -xr os_PACKAGE
2026-05-20 01:20:42.917718 | controller | + typeset -xr os_CODENAME
2026-05-20 01:20:42.917838 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-05-20 01:20:42.917878 | controller | + DISTRO=noble
2026-05-20 01:20:42.917887 | controller | + typeset -xr DISTRO
2026-05-20 01:20:42.917999 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:42.920724 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:42.920846 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-05-20 01:20:42.920868 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-05-20 01:20:42.920874 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-05-20 01:20:42.920881 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:42.921985 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-05-20 01:20:42.922115 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-05-20 01:20:42.922124 | controller | ++ local matchgroup=local
2026-05-20 01:20:42.922133 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:42.922220 | controller | ++ awk -v matchgroup=local '
2026-05-20 01:20:42.922226 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:20:42.922231 | controller |             gsub("[][]", "", $1);
2026-05-20 01:20:42.922236 | controller |             split($1, a, "|");
2026-05-20 01:20:42.922241 | controller |             if (a[1] == matchgroup)
2026-05-20 01:20:42.922246 | controller |                 print a[2]
2026-05-20 01:20:42.922251 | controller |         }
2026-05-20 01:20:42.922257 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:20:42.924889 | controller | + LRC=localrc
2026-05-20 01:20:42.924912 | controller | + for lfile in $LRC
2026-05-20 01:20:42.924919 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-05-20 01:20:42.924947 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-05-20 01:20:42.925003 | controller | + echo '# Generated file, do not edit'
2026-05-20 01:20:42.925189 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-05-20 01:20:42.925272 | controller | + local file=/opt/stack/devstack/local.conf
2026-05-20 01:20:42.925280 | controller | + local matchgroup=local
2026-05-20 01:20:42.925287 | controller | + local configfile=localrc
2026-05-20 01:20:42.925296 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:42.925317 | controller | + [[ -z localrc ]]
2026-05-20 01:20:42.925408 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-05-20 01:20:42.925417 | controller |         BEGIN { group = "" }
2026-05-20 01:20:42.925423 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:20:42.925445 | controller |             gsub("[][]", "", $1);
2026-05-20 01:20:42.925451 | controller |             split($1, a, "|");
2026-05-20 01:20:42.925457 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-05-20 01:20:42.925462 | controller |                 group=a[1]
2026-05-20 01:20:42.925467 | controller |             } else {
2026-05-20 01:20:42.925472 | controller |                 group=""
2026-05-20 01:20:42.925477 | controller |             }
2026-05-20 01:20:42.925482 | controller |             next
2026-05-20 01:20:42.925487 | controller |         }
2026-05-20 01:20:42.925492 | controller |         {
2026-05-20 01:20:42.925497 | controller |             if (group != "")
2026-05-20 01:20:42.925502 | controller |                 print $0
2026-05-20 01:20:42.925507 | controller |         }
2026-05-20 01:20:42.925512 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:20:42.928805 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-05-20 01:20:42.928828 | controller | + source /opt/stack/devstack/stackrc
2026-05-20 01:20:42.928994 | controller | ++ [[ -z '' ]]
2026-05-20 01:20:42.929003 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-05-20 01:20:42.931189 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-05-20 01:20:42.933367 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:20:42.933433 | controller | +++ pwd
2026-05-20 01:20:42.934094 | controller | ++ RC_DIR=/opt/stack/devstack
2026-05-20 01:20:42.934118 | controller | ++ source /opt/stack/devstack/functions
2026-05-20 01:20:42.934245 | controller | +++ [[ -z 1 ]]
2026-05-20 01:20:42.934255 | controller | +++ return 0
2026-05-20 01:20:42.934277 | controller | ++ TARGET_BRANCH=master
2026-05-20 01:20:42.934290 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-05-20 01:20:42.934299 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-05-20 01:20:42.934308 | controller | ++ DEST=/opt/stack
2026-05-20 01:20:42.934316 | controller | ++ DATA_DIR=/opt/stack/data
2026-05-20 01:20:42.934325 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-05-20 01:20:42.934334 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-05-20 01:20:42.934346 | controller | ++ [[ 1002 -eq 0 ]]
2026-05-20 01:20:42.935199 | controller | +++ whoami
2026-05-20 01:20:42.938962 | controller | ++ STACK_USER=stack
2026-05-20 01:20:42.939011 | controller | ++ REGION_NAME=RegionOne
2026-05-20 01:20:42.939029 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-05-20 01:20:42.939151 | controller | ++ isset ENABLED_SERVICES
2026-05-20 01:20:42.939163 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-05-20 01:20:42.939175 | controller | ++ ENABLED_SERVICES=key
2026-05-20 01:20:42.939184 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-05-20 01:20:42.939193 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-05-20 01:20:42.939206 | controller | ++ ENABLED_SERVICES+=,g-api
2026-05-20 01:20:42.939214 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-05-20 01:20:42.939224 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-05-20 01:20:42.939233 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-05-20 01:20:42.939242 | controller | ++ ENABLED_SERVICES+=,horizon
2026-05-20 01:20:42.939254 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-05-20 01:20:42.939263 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-05-20 01:20:42.939272 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-05-20 01:20:42.939284 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-05-20 01:20:42.939296 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-05-20 01:20:42.939308 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:42.939394 | controller | +++ disable_all_services
2026-05-20 01:20:42.939405 | controller | +++ ENABLED_SERVICES=
2026-05-20 01:20:42.939417 | controller | +++ enable_service c-api
2026-05-20 01:20:42.939454 | controller | +++ local xtrace
2026-05-20 01:20:42.941071 | controller | ++++ set +o
2026-05-20 01:20:42.941299 | controller | ++++ grep xtrace
2026-05-20 01:20:42.944627 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:42.944655 | controller | +++ set +o xtrace
2026-05-20 01:20:42.984895 | controller | +++ enable_service c-bak
2026-05-20 01:20:42.984956 | controller | +++ local xtrace
2026-05-20 01:20:42.986743 | controller | ++++ set +o
2026-05-20 01:20:42.987119 | controller | ++++ grep xtrace
2026-05-20 01:20:42.990541 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:42.990571 | controller | +++ set +o xtrace
2026-05-20 01:20:43.031755 | controller | +++ enable_service c-sch
2026-05-20 01:20:43.031851 | controller | +++ local xtrace
2026-05-20 01:20:43.033274 | controller | ++++ set +o
2026-05-20 01:20:43.033640 | controller | ++++ grep xtrace
2026-05-20 01:20:43.036851 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.036872 | controller | +++ set +o xtrace
2026-05-20 01:20:43.077579 | controller | +++ enable_service c-vol
2026-05-20 01:20:43.077632 | controller | +++ local xtrace
2026-05-20 01:20:43.079434 | controller | ++++ set +o
2026-05-20 01:20:43.079861 | controller | ++++ grep xtrace
2026-05-20 01:20:43.082964 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.082996 | controller | +++ set +o xtrace
2026-05-20 01:20:43.122447 | controller | +++ disable_service dstat
2026-05-20 01:20:43.122504 | controller | +++ local xtrace
2026-05-20 01:20:43.124013 | controller | ++++ set +o
2026-05-20 01:20:43.124292 | controller | ++++ grep xtrace
2026-05-20 01:20:43.126789 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.126811 | controller | +++ set +o xtrace
2026-05-20 01:20:43.154894 | controller | +++ enable_service etcd3
2026-05-20 01:20:43.154927 | controller | +++ local xtrace
2026-05-20 01:20:43.156830 | controller | ++++ set +o
2026-05-20 01:20:43.157539 | controller | ++++ grep xtrace
2026-05-20 01:20:43.161001 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.161032 | controller | +++ set +o xtrace
2026-05-20 01:20:43.200602 | controller | +++ enable_service file_tracker
2026-05-20 01:20:43.200646 | controller | +++ local xtrace
2026-05-20 01:20:43.202166 | controller | ++++ set +o
2026-05-20 01:20:43.202182 | controller | ++++ grep xtrace
2026-05-20 01:20:43.204336 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.204354 | controller | +++ set +o xtrace
2026-05-20 01:20:43.244191 | controller | +++ enable_service g-api
2026-05-20 01:20:43.244243 | controller | +++ local xtrace
2026-05-20 01:20:43.245698 | controller | ++++ set +o
2026-05-20 01:20:43.246006 | controller | ++++ grep xtrace
2026-05-20 01:20:43.249231 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.249257 | controller | +++ set +o xtrace
2026-05-20 01:20:43.290388 | controller | +++ disable_service horizon
2026-05-20 01:20:43.290442 | controller | +++ local xtrace
2026-05-20 01:20:43.292061 | controller | ++++ set +o
2026-05-20 01:20:43.292521 | controller | ++++ grep xtrace
2026-05-20 01:20:43.296521 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.296551 | controller | +++ set +o xtrace
2026-05-20 01:20:43.325725 | controller | +++ enable_service key
2026-05-20 01:20:43.325813 | controller | +++ local xtrace
2026-05-20 01:20:43.327366 | controller | ++++ set +o
2026-05-20 01:20:43.327807 | controller | ++++ grep xtrace
2026-05-20 01:20:43.330533 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.330569 | controller | +++ set +o xtrace
2026-05-20 01:20:43.370703 | controller | +++ enable_service memory_tracker
2026-05-20 01:20:43.370756 | controller | +++ local xtrace
2026-05-20 01:20:43.372418 | controller | ++++ set +o
2026-05-20 01:20:43.372915 | controller | ++++ grep xtrace
2026-05-20 01:20:43.376685 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.376721 | controller | +++ set +o xtrace
2026-05-20 01:20:43.417542 | controller | +++ enable_service mysql
2026-05-20 01:20:43.417596 | controller | +++ local xtrace
2026-05-20 01:20:43.419245 | controller | ++++ set +o
2026-05-20 01:20:43.419657 | controller | ++++ grep xtrace
2026-05-20 01:20:43.424085 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.424168 | controller | +++ set +o xtrace
2026-05-20 01:20:43.461797 | controller | +++ enable_service n-api
2026-05-20 01:20:43.461855 | controller | +++ local xtrace
2026-05-20 01:20:43.463899 | controller | ++++ set +o
2026-05-20 01:20:43.463927 | controller | ++++ grep xtrace
2026-05-20 01:20:43.467473 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.467488 | controller | +++ set +o xtrace
2026-05-20 01:20:43.506245 | controller | +++ enable_service n-api-meta
2026-05-20 01:20:43.506279 | controller | +++ local xtrace
2026-05-20 01:20:43.507992 | controller | ++++ set +o
2026-05-20 01:20:43.508375 | controller | ++++ grep xtrace
2026-05-20 01:20:43.511943 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.511967 | controller | +++ set +o xtrace
2026-05-20 01:20:43.553606 | controller | +++ enable_service n-cond
2026-05-20 01:20:43.553641 | controller | +++ local xtrace
2026-05-20 01:20:43.555202 | controller | ++++ set +o
2026-05-20 01:20:43.555802 | controller | ++++ grep xtrace
2026-05-20 01:20:43.559459 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.559490 | controller | +++ set +o xtrace
2026-05-20 01:20:43.599059 | controller | +++ enable_service n-cpu
2026-05-20 01:20:43.599083 | controller | +++ local xtrace
2026-05-20 01:20:43.600872 | controller | ++++ set +o
2026-05-20 01:20:43.601542 | controller | ++++ grep xtrace
2026-05-20 01:20:43.605141 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.605164 | controller | +++ set +o xtrace
2026-05-20 01:20:43.642966 | controller | +++ enable_service n-novnc
2026-05-20 01:20:43.642989 | controller | +++ local xtrace
2026-05-20 01:20:43.644599 | controller | ++++ set +o
2026-05-20 01:20:43.645040 | controller | ++++ grep xtrace
2026-05-20 01:20:43.648513 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.648561 | controller | +++ set +o xtrace
2026-05-20 01:20:43.684633 | controller | +++ enable_service n-sch
2026-05-20 01:20:43.684665 | controller | +++ local xtrace
2026-05-20 01:20:43.686441 | controller | ++++ set +o
2026-05-20 01:20:43.686701 | controller | ++++ grep xtrace
2026-05-20 01:20:43.689548 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.689573 | controller | +++ set +o xtrace
2026-05-20 01:20:43.729387 | controller | +++ enable_service o-api
2026-05-20 01:20:43.729453 | controller | +++ local xtrace
2026-05-20 01:20:43.731247 | controller | ++++ set +o
2026-05-20 01:20:43.731540 | controller | ++++ grep xtrace
2026-05-20 01:20:43.734946 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.734977 | controller | +++ set +o xtrace
2026-05-20 01:20:43.775621 | controller | +++ enable_service o-da
2026-05-20 01:20:43.775657 | controller | +++ local xtrace
2026-05-20 01:20:43.777144 | controller | ++++ set +o
2026-05-20 01:20:43.777642 | controller | ++++ grep xtrace
2026-05-20 01:20:43.780935 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.780970 | controller | +++ set +o xtrace
2026-05-20 01:20:43.824080 | controller | +++ enable_service o-hk
2026-05-20 01:20:43.824115 | controller | +++ local xtrace
2026-05-20 01:20:43.825587 | controller | ++++ set +o
2026-05-20 01:20:43.826386 | controller | ++++ grep xtrace
2026-05-20 01:20:43.829491 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.829518 | controller | +++ set +o xtrace
2026-05-20 01:20:43.868916 | controller | +++ enable_service octavia
2026-05-20 01:20:43.868941 | controller | +++ local xtrace
2026-05-20 01:20:43.870601 | controller | ++++ set +o
2026-05-20 01:20:43.871047 | controller | ++++ grep xtrace
2026-05-20 01:20:43.874793 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.874865 | controller | +++ set +o xtrace
2026-05-20 01:20:43.916553 | controller | +++ enable_service openstack-cli-server
2026-05-20 01:20:43.916605 | controller | +++ local xtrace
2026-05-20 01:20:43.918072 | controller | ++++ set +o
2026-05-20 01:20:43.918386 | controller | ++++ grep xtrace
2026-05-20 01:20:43.921709 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.921754 | controller | +++ set +o xtrace
2026-05-20 01:20:43.959697 | controller | +++ enable_service ovn-controller
2026-05-20 01:20:43.959746 | controller | +++ local xtrace
2026-05-20 01:20:43.961750 | controller | ++++ set +o
2026-05-20 01:20:43.962194 | controller | ++++ grep xtrace
2026-05-20 01:20:43.965038 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:43.965082 | controller | +++ set +o xtrace
2026-05-20 01:20:44.005303 | controller | +++ enable_service ovn-northd
2026-05-20 01:20:44.005361 | controller | +++ local xtrace
2026-05-20 01:20:44.007163 | controller | ++++ set +o
2026-05-20 01:20:44.007500 | controller | ++++ grep xtrace
2026-05-20 01:20:44.010941 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.010979 | controller | +++ set +o xtrace
2026-05-20 01:20:44.050049 | controller | +++ enable_service ovs-vswitchd
2026-05-20 01:20:44.050110 | controller | +++ local xtrace
2026-05-20 01:20:44.052525 | controller | ++++ set +o
2026-05-20 01:20:44.052553 | controller | ++++ grep xtrace
2026-05-20 01:20:44.055875 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.055910 | controller | +++ set +o xtrace
2026-05-20 01:20:44.097383 | controller | +++ enable_service ovsdb-server
2026-05-20 01:20:44.097439 | controller | +++ local xtrace
2026-05-20 01:20:44.098864 | controller | ++++ set +o
2026-05-20 01:20:44.099039 | controller | ++++ grep xtrace
2026-05-20 01:20:44.102108 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.102128 | controller | +++ set +o xtrace
2026-05-20 01:20:44.142704 | controller | +++ enable_service placement-api
2026-05-20 01:20:44.142737 | controller | +++ local xtrace
2026-05-20 01:20:44.144329 | controller | ++++ set +o
2026-05-20 01:20:44.144689 | controller | ++++ grep xtrace
2026-05-20 01:20:44.147566 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.147593 | controller | +++ set +o xtrace
2026-05-20 01:20:44.188424 | controller | +++ enable_service q-ovn-agent
2026-05-20 01:20:44.188473 | controller | +++ local xtrace
2026-05-20 01:20:44.189740 | controller | ++++ set +o
2026-05-20 01:20:44.189830 | controller | ++++ grep xtrace
2026-05-20 01:20:44.192519 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.192535 | controller | +++ set +o xtrace
2026-05-20 01:20:44.234251 | controller | +++ enable_service q-svc
2026-05-20 01:20:44.234293 | controller | +++ local xtrace
2026-05-20 01:20:44.236075 | controller | ++++ set +o
2026-05-20 01:20:44.236333 | controller | ++++ grep xtrace
2026-05-20 01:20:44.238981 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.239026 | controller | +++ set +o xtrace
2026-05-20 01:20:44.278466 | controller | +++ enable_service rabbit
2026-05-20 01:20:44.278524 | controller | +++ local xtrace
2026-05-20 01:20:44.280356 | controller | ++++ set +o
2026-05-20 01:20:44.280582 | controller | ++++ grep xtrace
2026-05-20 01:20:44.284155 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.284184 | controller | +++ set +o xtrace
2026-05-20 01:20:44.325275 | controller | +++ disable_service s-account
2026-05-20 01:20:44.325328 | controller | +++ local xtrace
2026-05-20 01:20:44.326865 | controller | ++++ set +o
2026-05-20 01:20:44.327484 | controller | ++++ grep xtrace
2026-05-20 01:20:44.331372 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.331397 | controller | +++ set +o xtrace
2026-05-20 01:20:44.358922 | controller | +++ disable_service s-container
2026-05-20 01:20:44.358955 | controller | +++ local xtrace
2026-05-20 01:20:44.360629 | controller | ++++ set +o
2026-05-20 01:20:44.361102 | controller | ++++ grep xtrace
2026-05-20 01:20:44.364419 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.364447 | controller | +++ set +o xtrace
2026-05-20 01:20:44.394872 | controller | +++ disable_service s-object
2026-05-20 01:20:44.394897 | controller | +++ local xtrace
2026-05-20 01:20:44.396630 | controller | ++++ set +o
2026-05-20 01:20:44.397069 | controller | ++++ grep xtrace
2026-05-20 01:20:44.400664 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.400703 | controller | +++ set +o xtrace
2026-05-20 01:20:44.427432 | controller | +++ disable_service s-proxy
2026-05-20 01:20:44.427484 | controller | +++ local xtrace
2026-05-20 01:20:44.429277 | controller | ++++ set +o
2026-05-20 01:20:44.429681 | controller | ++++ grep xtrace
2026-05-20 01:20:44.433120 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.433155 | controller | +++ set +o xtrace
2026-05-20 01:20:44.459170 | controller | +++ disable_service tempest
2026-05-20 01:20:44.459230 | controller | +++ local xtrace
2026-05-20 01:20:44.460730 | controller | ++++ set +o
2026-05-20 01:20:44.461148 | controller | ++++ grep xtrace
2026-05-20 01:20:44.464617 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.464650 | controller | +++ set +o xtrace
2026-05-20 01:20:44.492959 | controller | +++ enable_service tls-proxy
2026-05-20 01:20:44.492990 | controller | +++ local xtrace
2026-05-20 01:20:44.494875 | controller | ++++ set +o
2026-05-20 01:20:44.495195 | controller | ++++ grep xtrace
2026-05-20 01:20:44.498758 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.498828 | controller | +++ set +o xtrace
2026-05-20 01:20:44.536845 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-05-20 01:20:44.536879 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-05-20 01:20:44.536889 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-05-20 01:20:44.536897 | controller | +++ DISABLE_AMP_IMAGE_BUILD=True
2026-05-20 01:20:44.536905 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:20:44.536914 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:20:44.536922 | controller | +++ ENABLE_ZSWAP=True
2026-05-20 01:20:44.536930 | controller | +++ ERROR_ON_CLONE=True
2026-05-20 01:20:44.536938 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-05-20 01:20:44.536946 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:20:44.536960 | controller | +++ GIT_BASE=https://github.com
2026-05-20 01:20:44.536968 | controller | +++ HOST_IP=199.204.45.250
2026-05-20 01:20:44.536976 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:20:44.536984 | controller | +++ LIBVIRT_TYPE=qemu
2026-05-20 01:20:44.536993 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-05-20 01:20:44.537001 | controller | +++ LOG_COLOR=False
2026-05-20 01:20:44.537009 | controller | +++ MAGNUM_GUEST_IMAGE_URL=https://github.com/vexxhost/capo-image-elements/releases/latest/download/ubuntu-22.04-v1.34.7.qcow2
2026-05-20 01:20:44.537018 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS='snapshot_support=True create_share_from_snapshot_support=True'
2026-05-20 01:20:44.537026 | controller | +++ MANILA_ENABLED_BACKENDS=generic
2026-05-20 01:20:44.537035 | controller | +++ MANILA_USE_SERVICE_INSTANCE_PASSWORD=True
2026-05-20 01:20:44.537043 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-05-20 01:20:44.537051 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-05-20 01:20:44.537060 | controller | +++ NOVA_VNC_ENABLED=True
2026-05-20 01:20:44.537068 | controller | +++ OCTAVIA_NODE=api
2026-05-20 01:20:44.537076 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-05-20 01:20:44.537084 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-05-20 01:20:44.537093 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-05-20 01:20:44.537101 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-05-20 01:20:44.537109 | controller | +++ SERVICE_HOST=199.204.45.250
2026-05-20 01:20:44.537117 | controller | +++ SERVICE_PASSWORD=secretservice
2026-05-20 01:20:44.537129 | controller | +++ SWIFT_HASH=1234123412341234
2026-05-20 01:20:44.537137 | controller | +++ SWIFT_REPLICAS=1
2026-05-20 01:20:44.537145 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-05-20 01:20:44.537153 | controller | +++ VERBOSE=True
2026-05-20 01:20:44.537179 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:20:44.537189 | controller | +++ LIBS_FROM_GIT=novnc,barbican,cinder,devstack,glance,keystone,magnum,manila,neutron,nova,octavia,os-test-images,ovn-octavia-provider,placement,python-magnumclient,requirements,swift,magnum-cluster-api
2026-05-20 01:20:44.537197 | controller | +++ enable_plugin barbican https://github.com/openstack/barbican
2026-05-20 01:20:44.537206 | controller | +++ local name=barbican
2026-05-20 01:20:44.537214 | controller | +++ local url=https://github.com/openstack/barbican
2026-05-20 01:20:44.537222 | controller | +++ local branch=master
2026-05-20 01:20:44.537230 | controller | +++ is_plugin_enabled barbican
2026-05-20 01:20:44.537238 | controller | +++ local name=barbican
2026-05-20 01:20:44.537246 | controller | +++ [[ ,, =~ ,barbican, ]]
2026-05-20 01:20:44.537258 | controller | +++ return 1
2026-05-20 01:20:44.537266 | controller | +++ DEVSTACK_PLUGINS+=,barbican
2026-05-20 01:20:44.537275 | controller | +++ GITREPO[$name]=https://github.com/openstack/barbican
2026-05-20 01:20:44.537283 | controller | +++ GITDIR[$name]=/opt/stack/barbican
2026-05-20 01:20:44.537291 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:44.537299 | controller | +++ enable_plugin magnum https://review.opendev.org/openstack/magnum
2026-05-20 01:20:44.537308 | controller | +++ local name=magnum
2026-05-20 01:20:44.537316 | controller | +++ local url=https://review.opendev.org/openstack/magnum
2026-05-20 01:20:44.537324 | controller | +++ local branch=master
2026-05-20 01:20:44.537332 | controller | +++ is_plugin_enabled magnum
2026-05-20 01:20:44.537340 | controller | +++ local name=magnum
2026-05-20 01:20:44.537348 | controller | +++ [[ ,,barbican, =~ ,magnum, ]]
2026-05-20 01:20:44.537360 | controller | +++ return 1
2026-05-20 01:20:44.537368 | controller | +++ DEVSTACK_PLUGINS+=,magnum
2026-05-20 01:20:44.537376 | controller | +++ GITREPO[$name]=https://review.opendev.org/openstack/magnum
2026-05-20 01:20:44.537384 | controller | +++ GITDIR[$name]=/opt/stack/magnum
2026-05-20 01:20:44.537392 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:44.537448 | controller | +++ enable_plugin manila https://github.com/openstack/manila
2026-05-20 01:20:44.537458 | controller | +++ local name=manila
2026-05-20 01:20:44.537466 | controller | +++ local url=https://github.com/openstack/manila
2026-05-20 01:20:44.537474 | controller | +++ local branch=master
2026-05-20 01:20:44.537482 | controller | +++ is_plugin_enabled manila
2026-05-20 01:20:44.537490 | controller | +++ local name=manila
2026-05-20 01:20:44.537498 | controller | +++ [[ ,,barbican,magnum, =~ ,manila, ]]
2026-05-20 01:20:44.537511 | controller | +++ return 1
2026-05-20 01:20:44.537520 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-05-20 01:20:44.537528 | controller | +++ GITREPO[$name]=https://github.com/openstack/manila
2026-05-20 01:20:44.537536 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-05-20 01:20:44.537544 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:44.537552 | controller | +++ enable_plugin octavia https://github.com/openstack/octavia
2026-05-20 01:20:44.537561 | controller | +++ local name=octavia
2026-05-20 01:20:44.537569 | controller | +++ local url=https://github.com/openstack/octavia
2026-05-20 01:20:44.537577 | controller | +++ local branch=master
2026-05-20 01:20:44.537585 | controller | +++ is_plugin_enabled octavia
2026-05-20 01:20:44.537593 | controller | +++ local name=octavia
2026-05-20 01:20:44.537601 | controller | +++ [[ ,,barbican,magnum,manila, =~ ,octavia, ]]
2026-05-20 01:20:44.537609 | controller | +++ return 1
2026-05-20 01:20:44.537617 | controller | +++ DEVSTACK_PLUGINS+=,octavia
2026-05-20 01:20:44.537625 | controller | +++ GITREPO[$name]=https://github.com/openstack/octavia
2026-05-20 01:20:44.537635 | controller | +++ GITDIR[$name]=/opt/stack/octavia
2026-05-20 01:20:44.537647 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:44.537656 | controller | +++ enable_plugin magnum-cluster-api https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:44.537664 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:20:44.537672 | controller | +++ local url=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:44.537680 | controller | +++ local branch=master
2026-05-20 01:20:44.537688 | controller | +++ is_plugin_enabled magnum-cluster-api
2026-05-20 01:20:44.537696 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:20:44.537704 | controller | +++ [[ ,,barbican,magnum,manila,octavia, =~ ,magnum-cluster-api, ]]
2026-05-20 01:20:44.537713 | controller | +++ return 1
2026-05-20 01:20:44.537721 | controller | +++ DEVSTACK_PLUGINS+=,magnum-cluster-api
2026-05-20 01:20:44.537729 | controller | +++ GITREPO[$name]=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:44.537746 | controller | +++ GITDIR[$name]=/opt/stack/magnum-cluster-api
2026-05-20 01:20:44.537755 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:44.537807 | controller | +++ enable_plugin ovn-octavia-provider https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:44.537824 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:20:44.537833 | controller | +++ local url=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:44.537841 | controller | +++ local branch=master
2026-05-20 01:20:44.537849 | controller | +++ is_plugin_enabled ovn-octavia-provider
2026-05-20 01:20:44.537857 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:20:44.537865 | controller | +++ [[ ,,barbican,magnum,manila,octavia,magnum-cluster-api, =~ ,ovn-octavia-provider, ]]
2026-05-20 01:20:44.537874 | controller | +++ return 1
2026-05-20 01:20:44.537882 | controller | +++ DEVSTACK_PLUGINS+=,ovn-octavia-provider
2026-05-20 01:20:44.537890 | controller | +++ GITREPO[$name]=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:44.537898 | controller | +++ GITDIR[$name]=/opt/stack/ovn-octavia-provider
2026-05-20 01:20:44.537906 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:44.537917 | controller | ++ CELLSV2_SETUP=superconductor
2026-05-20 01:20:44.537925 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-05-20 01:20:44.538819 | controller | +++ trueorfalse False USER_UNITS
2026-05-20 01:20:44.539056 | controller | +++ local xtrace
2026-05-20 01:20:44.540494 | controller | ++++ set +o
2026-05-20 01:20:44.540807 | controller | ++++ grep xtrace
2026-05-20 01:20:44.544921 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.544944 | controller | +++ set +o xtrace
2026-05-20 01:20:44.545913 | controller | ++ USER_UNITS=False
2026-05-20 01:20:44.546060 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:20:44.546072 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-05-20 01:20:44.546082 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-05-20 01:20:44.546094 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-05-20 01:20:44.547225 | controller | +++ _get_python_version python3
2026-05-20 01:20:44.547376 | controller | +++ local interp=python3
2026-05-20 01:20:44.547388 | controller | +++ local version
2026-05-20 01:20:44.548455 | controller | ++++ which python3
2026-05-20 01:20:44.552197 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-05-20 01:20:44.553301 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-05-20 01:20:44.570009 | controller | +++ version=3.12
2026-05-20 01:20:44.570038 | controller | +++ echo 3.12
2026-05-20 01:20:44.570863 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-05-20 01:20:44.570897 | controller | ++ export PYTHON3_VERSION=3.12
2026-05-20 01:20:44.570906 | controller | ++ PYTHON3_VERSION=3.12
2026-05-20 01:20:44.570917 | controller | ++ export 'VIRTUALENV_CMD=python3.12 -m venv'
2026-05-20 01:20:44.570926 | controller | ++ VIRTUALENV_CMD='python3.12 -m venv'
2026-05-20 01:20:44.570937 | controller | ++ '[' -t 1 ']'
2026-05-20 01:20:44.570949 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-05-20 01:20:44.572056 | controller | +++ trueorfalse False LOG_COLOR
2026-05-20 01:20:44.572247 | controller | +++ local xtrace
2026-05-20 01:20:44.573634 | controller | ++++ set +o
2026-05-20 01:20:44.573949 | controller | ++++ grep xtrace
2026-05-20 01:20:44.577309 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:44.577342 | controller | +++ set +o xtrace
2026-05-20 01:20:44.578312 | controller | ++ LOG_COLOR=False
2026-05-20 01:20:44.578429 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:20:44.578440 | controller | ++ export 'PS4=+ $(short_source):   '
2026-05-20 01:20:44.578448 | controller | ++ PS4='+ $(short_source):   '
2026-05-20 01:20:44.582344 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-05-20 01:20:44.585219 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:44.590511 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:44.590536 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:44.597746 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:44.602173 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:44.607324 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-05-20 01:20:44.611814 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-05-20 01:20:44.614461 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-05-20 01:20:44.620389 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-05-20 01:20:44.624529 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:44.630689 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:44.630815 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:44.637892 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:44.641696 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:44.645514 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-05-20 01:20:44.650003 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-05-20 01:20:44.654555 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:44.660843 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:44.660861 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:44.668661 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:44.671966 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:44.677737 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-05-20 01:20:44.681749 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-05-20 01:20:44.686860 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-05-20 01:20:44.690796 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:44.697467 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:44.698086 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:44.706739 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:44.710649 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:44.715724 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-05-20 01:20:44.720810 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-05-20 01:20:44.724942 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:44.730843 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:44.731518 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:44.738881 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:44.743007 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:44.748075 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-05-20 01:20:44.753611 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-05-20 01:20:44.757685 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:44.763669 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:44.764641 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:44.771683 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:44.775483 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:44.779821 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-05-20 01:20:44.784362 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-05-20 01:20:44.788658 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-05-20 01:20:44.792460 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-05-20 01:20:44.796564 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://github.com
2026-05-20 01:20:44.800691 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-05-20 01:20:44.805412 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.2
2026-05-20 01:20:44.809490 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://github.com/openstack/cinder.git
2026-05-20 01:20:44.812999 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-05-20 01:20:44.817371 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://github.com/openstack/glance.git
2026-05-20 01:20:44.820421 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-05-20 01:20:44.824183 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://github.com/openstack/horizon.git
2026-05-20 01:20:44.828273 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-05-20 01:20:44.831809 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://github.com/openstack/keystone.git
2026-05-20 01:20:44.835822 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-05-20 01:20:44.839421 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://github.com/openstack/neutron.git
2026-05-20 01:20:44.843704 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-05-20 01:20:44.847663 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://github.com/openstack/nova.git
2026-05-20 01:20:44.852606 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-05-20 01:20:44.856026 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://github.com/openstack/swift.git
2026-05-20 01:20:44.860267 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-05-20 01:20:44.864238 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://github.com/openstack/placement.git
2026-05-20 01:20:44.868216 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-05-20 01:20:44.872635 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://github.com/openstack/requirements.git
2026-05-20 01:20:44.877581 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-05-20 01:20:44.881313 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://github.com/openstack/tempest.git
2026-05-20 01:20:44.884785 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-05-20 01:20:44.888823 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-05-20 01:20:44.892208 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://github.com/openstack/os-test-images.git
2026-05-20 01:20:44.895413 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-05-20 01:20:44.899051 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-05-20 01:20:44.903115 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://github.com/openstack/python-cinderclient.git
2026-05-20 01:20:44.907216 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-05-20 01:20:44.911240 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://github.com/openstack/python-brick-cinderclient-ext.git
2026-05-20 01:20:44.915222 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-05-20 01:20:44.919306 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://github.com/openstack/python-barbicanclient.git
2026-05-20 01:20:44.922884 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-05-20 01:20:44.926817 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-05-20 01:20:44.930627 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://github.com/openstack/python-glanceclient.git
2026-05-20 01:20:44.934785 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-05-20 01:20:44.939175 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://github.com/openstack/python-ironicclient.git
2026-05-20 01:20:44.942913 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-05-20 01:20:44.946265 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-05-20 01:20:44.950332 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://github.com/openstack/keystoneauth.git
2026-05-20 01:20:44.953990 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-05-20 01:20:44.956703 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://github.com/openstack/python-keystoneclient.git
2026-05-20 01:20:44.959419 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-05-20 01:20:44.962815 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://github.com/openstack/python-neutronclient.git
2026-05-20 01:20:44.966815 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-05-20 01:20:44.969414 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://github.com/openstack/python-novaclient.git
2026-05-20 01:20:44.972243 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-05-20 01:20:44.976246 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://github.com/openstack/python-swiftclient.git
2026-05-20 01:20:44.980165 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-05-20 01:20:44.984319 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://github.com/openstack/python-openstackclient.git
2026-05-20 01:20:44.988108 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-05-20 01:20:44.990922 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-05-20 01:20:44.994395 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://github.com/openstack/osc-placement.git
2026-05-20 01:20:44.998258 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-05-20 01:20:45.001645 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://github.com/openstack/castellan.git
2026-05-20 01:20:45.004192 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-05-20 01:20:45.007948 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://github.com/openstack/cliff.git
2026-05-20 01:20:45.012147 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-05-20 01:20:45.016234 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://github.com/openstack/futurist.git
2026-05-20 01:20:45.019549 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-05-20 01:20:45.023823 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://github.com/openstack/debtcollector.git
2026-05-20 01:20:45.027800 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-05-20 01:20:45.031691 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://github.com/openstack/etcd3gw.git
2026-05-20 01:20:45.035447 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-05-20 01:20:45.038817 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://github.com/openstack/automaton.git
2026-05-20 01:20:45.042884 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-05-20 01:20:45.046352 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://github.com/openstack/oslo.cache.git
2026-05-20 01:20:45.050647 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-05-20 01:20:45.055387 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://github.com/openstack/oslo.concurrency.git
2026-05-20 01:20:45.059248 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-05-20 01:20:45.063298 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://github.com/openstack/oslo.config.git
2026-05-20 01:20:45.067148 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-05-20 01:20:45.071158 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://github.com/openstack/oslo.context.git
2026-05-20 01:20:45.074382 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-05-20 01:20:45.078232 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://github.com/openstack/oslo.db.git
2026-05-20 01:20:45.081639 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-05-20 01:20:45.084915 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://github.com/openstack/oslo.i18n.git
2026-05-20 01:20:45.088587 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-05-20 01:20:45.092568 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://github.com/openstack/oslo.limit.git
2026-05-20 01:20:45.096358 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-05-20 01:20:45.100237 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://github.com/openstack/oslo.log.git
2026-05-20 01:20:45.104347 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-05-20 01:20:45.108424 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://github.com/openstack/oslo.messaging.git
2026-05-20 01:20:45.112297 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-05-20 01:20:45.115936 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://github.com/openstack/oslo.middleware.git
2026-05-20 01:20:45.120100 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-05-20 01:20:45.123643 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://github.com/openstack/oslo.policy.git
2026-05-20 01:20:45.126911 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-05-20 01:20:45.130863 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://github.com/openstack/oslo.privsep.git
2026-05-20 01:20:45.134044 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-05-20 01:20:45.137800 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://github.com/openstack/oslo.reports.git
2026-05-20 01:20:45.141696 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-05-20 01:20:45.144935 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://github.com/openstack/oslo.rootwrap.git
2026-05-20 01:20:45.148407 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-05-20 01:20:45.152121 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://github.com/openstack/oslo.serialization.git
2026-05-20 01:20:45.156195 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-05-20 01:20:45.159987 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://github.com/openstack/oslo.service.git
2026-05-20 01:20:45.163829 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-05-20 01:20:45.167301 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://github.com/openstack/oslo.utils.git
2026-05-20 01:20:45.171082 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-05-20 01:20:45.175068 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://github.com/openstack/oslo.versionedobjects.git
2026-05-20 01:20:45.178153 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-05-20 01:20:45.181252 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://github.com/openstack/oslo.vmware.git
2026-05-20 01:20:45.184408 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-05-20 01:20:45.188081 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://github.com/openstack/osprofiler.git
2026-05-20 01:20:45.191965 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-05-20 01:20:45.195797 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://github.com/openstack/pycadf.git
2026-05-20 01:20:45.199554 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-05-20 01:20:45.203080 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://github.com/openstack/stevedore.git
2026-05-20 01:20:45.206510 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-05-20 01:20:45.209353 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://github.com/openstack/taskflow.git
2026-05-20 01:20:45.211986 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-05-20 01:20:45.215662 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://github.com/openstack/tooz.git
2026-05-20 01:20:45.219524 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-05-20 01:20:45.223379 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://github.com/openstack/pbr.git
2026-05-20 01:20:45.226826 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-05-20 01:20:45.230540 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://github.com/openstack/cursive.git
2026-05-20 01:20:45.233840 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-05-20 01:20:45.236945 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://github.com/openstack/glance_store.git
2026-05-20 01:20:45.240703 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-05-20 01:20:45.243412 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://github.com/openstack/keystonemiddleware.git
2026-05-20 01:20:45.246642 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-05-20 01:20:45.250235 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://github.com/openstack/ceilometermiddleware.git
2026-05-20 01:20:45.254101 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-05-20 01:20:45.257736 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-05-20 01:20:45.260940 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://github.com/openstack/openstacksdk.git
2026-05-20 01:20:45.264332 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-05-20 01:20:45.267669 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://github.com/openstack/os-brick.git
2026-05-20 01:20:45.271610 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-05-20 01:20:45.275349 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://github.com/openstack/os-client-config.git
2026-05-20 01:20:45.279250 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-05-20 01:20:45.283090 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-05-20 01:20:45.287395 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://github.com/openstack/os-vif.git
2026-05-20 01:20:45.291272 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-05-20 01:20:45.295115 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://github.com/openstack/osc-lib.git
2026-05-20 01:20:45.297812 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-05-20 01:20:45.300248 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://github.com/openstack/ironic-lib.git
2026-05-20 01:20:45.304004 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-05-20 01:20:45.307079 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-05-20 01:20:45.310802 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://github.com/openstack/diskimage-builder.git
2026-05-20 01:20:45.313070 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-05-20 01:20:45.316641 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-05-20 01:20:45.321558 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://github.com/openstack/neutron-lib.git
2026-05-20 01:20:45.325452 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-05-20 01:20:45.329442 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-05-20 01:20:45.333510 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://github.com/openstack/os-resource-classes.git
2026-05-20 01:20:45.336927 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-05-20 01:20:45.340619 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://github.com/openstack/os-traits.git
2026-05-20 01:20:45.344569 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-05-20 01:20:45.348000 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://github.com/openstack/ovsdbapp.git
2026-05-20 01:20:45.351998 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-05-20 01:20:45.355157 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-05-20 01:20:45.358995 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://github.com/openstack/os-ken.git
2026-05-20 01:20:45.362821 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-05-20 01:20:45.367282 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-05-20 01:20:45.371228 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://github.com/openstack/ironic-python-agent.git
2026-05-20 01:20:45.375097 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-05-20 01:20:45.378162 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-05-20 01:20:45.381635 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-05-20 01:20:45.385868 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-05-20 01:20:45.389615 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-05-20 01:20:45.393465 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-05-20 01:20:45.396683 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:45.402587 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:45.403346 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:45.410757 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:45.414585 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:45.419296 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-05-20 01:20:45.422696 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-05-20 01:20:45.425617 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-05-20 01:20:45.429485 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-05-20 01:20:45.433265 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-05-20 01:20:45.436902 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-05-20 01:20:45.440558 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-05-20 01:20:45.444722 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-05-20 01:20:45.448614 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:20:45.452435 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-05-20 01:20:45.455649 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-05-20 01:20:45.460584 | controller | +++ stackrc:source:668                       :   uname -m
2026-05-20 01:20:45.466909 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-05-20 01:20:45.471496 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-05-20 01:20:45.475226 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:45.480360 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:45.481306 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:45.488672 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:45.492579 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:45.497636 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-05-20 01:20:45.501404 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-05-20 01:20:45.505556 | controller | ++ stackrc:source:675                       :   [[ -n '' ]]
2026-05-20 01:20:45.509686 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-05-20 01:20:45.513059 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-05-20 01:20:45.517089 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-05-20 01:20:45.520858 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:45.524746 | controller | ++ stackrc:source:688                       :   IMAGE_URLS+=https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:45.527922 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-05-20 01:20:45.531611 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-05-20 01:20:45.535632 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-05-20 01:20:45.539108 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:20:45.543064 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-05-20 01:20:45.547188 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-05-20 01:20:45.550897 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-05-20 01:20:45.555297 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-05-20 01:20:45.560407 | controller | +++ functions-common:is_arch:519             :   uname -m
2026-05-20 01:20:45.565970 | controller | ++ functions-common:is_arch:519             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-05-20 01:20:45.569319 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-05-20 01:20:45.573289 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:20:45.577425 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-05-20 01:20:45.581329 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-05-20 01:20:45.585047 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-05-20 01:20:45.588608 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-05-20 01:20:45.592819 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:45.596053 | controller | ++ stackrc:source:739                       :   ETCD_DOWNLOAD_LOCATION=https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:45.599921 | controller | ++ stackrc:source:741                       :   EXTRA_CACHE_URLS+=,https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:45.603501 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-05-20 01:20:45.606932 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-05-20 01:20:45.610107 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-05-20 01:20:45.616180 | controller | +++ stackrc:source:749                       :   echo https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:45.616366 | controller | +++ stackrc:source:749                       :   grep -o -F https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:45.617105 | controller | +++ stackrc:source:749                       :   wc -l
2026-05-20 01:20:45.623973 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-05-20 01:20:45.627876 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=30G
2026-05-20 01:20:45.632149 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-05-20 01:20:45.635581 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-05-20 01:20:45.639595 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-05-20 01:20:45.643654 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-05-20 01:20:45.647740 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-05-20 01:20:45.652721 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-05-20 01:20:45.656913 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-05-20 01:20:45.662046 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:20:45.669572 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:20:45.676667 | controller | ++ stackrc:source:777                       :   API_WORKERS=4
2026-05-20 01:20:45.680473 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-05-20 01:20:45.683624 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-05-20 01:20:45.688088 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-05-20 01:20:45.691586 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-05-20 01:20:45.696846 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-05-20 01:20:45.701088 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:45.707346 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:45.707669 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:45.714707 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:45.718321 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:45.723404 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-05-20 01:20:45.728143 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-05-20 01:20:45.732032 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:45.736939 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:45.738459 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:45.745222 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:45.748184 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:45.752637 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-05-20 01:20:45.757057 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-05-20 01:20:45.760591 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:45.766287 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:45.766938 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:45.774334 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:45.777828 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:45.782293 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-05-20 01:20:45.785970 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:20:45.789604 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:20:45.793561 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-05-20 01:20:45.797082 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-05-20 01:20:45.801193 | controller | ++ stackrc:source:815                       :   HOST_IP=199.204.45.250
2026-05-20 01:20:45.804610 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-05-20 01:20:45.808624 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 199.204.45.250 inet
2026-05-20 01:20:45.812536 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=10.1.0.0/20
2026-05-20 01:20:45.815696 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=172.24.5.0/24
2026-05-20 01:20:45.818712 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:20:45.822165 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=199.204.45.250
2026-05-20 01:20:45.825706 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet
2026-05-20 01:20:45.829426 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z 199.204.45.250 -o 199.204.45.250 == dhcp ']'
2026-05-20 01:20:45.832901 | controller | +++ functions-common:get_default_host_ip:785 :   echo 199.204.45.250
2026-05-20 01:20:45.837858 | controller | ++ stackrc:source:818                       :   HOST_IP=199.204.45.250
2026-05-20 01:20:45.841842 | controller | ++ stackrc:source:819                       :   '[' 199.204.45.250 == '' ']'
2026-05-20 01:20:45.846588 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-05-20 01:20:45.850632 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=
2026-05-20 01:20:45.853737 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=
2026-05-20 01:20:45.857315 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:20:45.860748 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=
2026-05-20 01:20:45.864510 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet6
2026-05-20 01:20:45.868709 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z '' -o '' == dhcp ']'
2026-05-20 01:20:45.872622 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip=
2026-05-20 01:20:45.879237 | controller | ++++ functions-common:get_default_host_ip:764 :   grep via
2026-05-20 01:20:45.879284 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 route list match default table all
2026-05-20 01:20:45.879387 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/default/ {print $5}'
2026-05-20 01:20:45.879885 | controller | ++++ functions-common:get_default_host_ip:764 :   head -1
2026-05-20 01:20:45.886872 | controller | +++ functions-common:get_default_host_ip:764 :   host_ip_iface=
2026-05-20 01:20:45.890241 | controller | +++ functions-common:get_default_host_ip:765 :   local host_ips
2026-05-20 01:20:45.895365 | controller | ++++ functions-common:get_default_host_ip:766 :   LC_ALL=C
2026-05-20 01:20:45.896082 | controller | ++++ functions-common:get_default_host_ip:766 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-05-20 01:20:45.896103 | controller | ++++ functions-common:get_default_host_ip:766 :   sed /temporary/d
2026-05-20 01:20:45.898807 | controller | ++++ functions-common:get_default_host_ip:766 :   ip -f inet6 addr show
2026-05-20 01:20:45.907254 | controller | +++ functions-common:get_default_host_ip:766 :   host_ips='::1
2026-05-20 01:20:45.907284 | controller | 2604:e100:1:0:f816:3eff:fed8:5681
2026-05-20 01:20:45.907293 | controller | fe80::f816:3eff:fed8:5681'
2026-05-20 01:20:45.910170 | controller | +++ functions-common:get_default_host_ip:767 :   local ip
2026-05-20 01:20:45.913877 | controller | +++ functions-common:get_default_host_ip:768 :   for ip in $host_ips
2026-05-20 01:20:45.917646 | controller | +++ functions-common:get_default_host_ip:775 :   [[ inet6 == \i\n\e\t\6 ]]
2026-05-20 01:20:45.921403 | controller | +++ functions-common:get_default_host_ip:776 :   host_ip=::1
2026-05-20 01:20:45.924946 | controller | +++ functions-common:get_default_host_ip:777 :   break
2026-05-20 01:20:45.928636 | controller | +++ functions-common:get_default_host_ip:785 :   echo ::1
2026-05-20 01:20:45.933727 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-05-20 01:20:45.938286 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-05-20 01:20:45.942401 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:45.948219 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:45.948733 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:45.956061 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:45.959716 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:45.964413 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-05-20 01:20:45.968667 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-05-20 01:20:45.973485 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-05-20 01:20:45.977907 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-05-20 01:20:45.981837 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=199.204.45.250
2026-05-20 01:20:45.986117 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:20:45.990334 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:20:45.993557 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-05-20 01:20:45.997400 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:20:46.001756 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=199.204.45.250
2026-05-20 01:20:46.004959 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:20:46.008208 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-05-20 01:20:46.012673 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-05-20 01:20:46.016603 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-05-20 01:20:46.020394 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=199.204.45.250
2026-05-20 01:20:46.024081 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-05-20 01:20:46.027991 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=199.204.45.250
2026-05-20 01:20:46.033669 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-05-20 01:20:46.037675 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:46.043201 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:46.043609 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:46.051009 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:46.054814 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:46.060023 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-05-20 01:20:46.063721 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=199.204.45.250
2026-05-20 01:20:46.067894 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-05-20 01:20:46.071396 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-05-20 01:20:46.076162 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-05-20 01:20:46.080255 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:46.085962 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:46.086216 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:46.092904 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:46.095572 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:46.098647 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-05-20 01:20:46.102293 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-05-20 01:20:46.106658 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-05-20 01:20:46.110220 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:20:46.113702 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-05-20 01:20:46.117052 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-05-20 01:20:46.121018 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-05-20 01:20:46.124406 | controller | ++ stackrc:source:924                       :   [[ /opt/stack/logs == \d\e\v\s\t\a\c\k\l\o\g\.\t\x\t ]]
2026-05-20 01:20:46.128216 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-05-20 01:20:46.132025 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-05-20 01:20:46.135619 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-05-20 01:20:46.140609 | controller | + functions:write_devstack_version:876     :   cat -
2026-05-20 01:20:46.141605 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-05-20 01:20:46.145407 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-05-20 01:20:46.186223 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-05-20 01:20:46.190821 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-05-20 01:20:46.194801 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-05-20 01:20:46.199058 | controller | + functions-common:export_proxy_variables:2232 :   isset http_proxy
2026-05-20 01:20:46.202713 | controller | + functions-common:isset:266               :   [[ -v http_proxy ]]
2026-05-20 01:20:46.206388 | controller | + functions-common:export_proxy_variables:2235 :   isset https_proxy
2026-05-20 01:20:46.210512 | controller | + functions-common:isset:266               :   [[ -v https_proxy ]]
2026-05-20 01:20:46.214018 | controller | + functions-common:export_proxy_variables:2238 :   isset no_proxy
2026-05-20 01:20:46.217409 | controller | + functions-common:isset:266               :   [[ -v no_proxy ]]
2026-05-20 01:20:46.221752 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-05-20 01:20:46.225688 | controller | + functions-common:disable_negated_services:1937 :   local xtrace
2026-05-20 01:20:46.231659 | controller | ++ functions-common:disable_negated_services:1938 :   grep xtrace
2026-05-20 01:20:46.232136 | controller | ++ functions-common:disable_negated_services:1938 :   set +o
2026-05-20 01:20:46.240176 | controller | + functions-common:disable_negated_services:1938 :   xtrace='set -o xtrace'
2026-05-20 01:20:46.243941 | controller | + functions-common:disable_negated_services:1939 :   set +o xtrace
2026-05-20 01:20:46.263947 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-05-20 01:20:46.267925 | controller | + functions-common:is_package_installed:1441 :   [[ -z sudo ]]
2026-05-20 01:20:46.272550 | controller | + functions-common:is_package_installed:1445 :   [[ -z deb ]]
2026-05-20 01:20:46.276238 | controller | + functions-common:is_package_installed:1449 :   [[ deb = \d\e\b ]]
2026-05-20 01:20:46.280289 | controller | + functions-common:is_package_installed:1450 :   dpkg -s sudo
2026-05-20 01:20:46.297385 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-05-20 01:20:46.319044 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-05-20 01:20:46.320204 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-05-20 01:20:46.332807 | controller | #includedir /etc/sudoers.d
2026-05-20 01:20:46.337393 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-05-20 01:20:46.342740 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-05-20 01:20:46.348804 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.dWKLW6SG9D
2026-05-20 01:20:46.352831 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-05-20 01:20:46.356606 | controller | + ./stack.sh:main:277                      :   echo 'Defaults:stack secure_path=/sbin:/usr/sbin:/usr/bin:/bin:/usr/local/sbin:/usr/local/bin'
2026-05-20 01:20:46.360986 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-05-20 01:20:46.364533 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.dWKLW6SG9D
2026-05-20 01:20:46.370622 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.dWKLW6SG9D
2026-05-20 01:20:46.388791 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.dWKLW6SG9D /etc/sudoers.d/50_stack_sh
2026-05-20 01:20:46.409650 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-05-20 01:20:46.412495 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:20:46.415157 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:20:46.419046 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-05-20 01:20:46.419592 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-05-20 01:20:46.436191 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-05-20 01:20:46.439934 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-05-20 01:20:46.443998 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-05-20 01:20:46.447109 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-05-20 01:20:46.465706 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-05-20 01:20:46.469722 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-05-20 01:20:46.473237 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:46.478151 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:46.479217 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:46.486328 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:46.488871 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:46.496745 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/logs
2026-05-20 01:20:46.512887 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-05-20 01:20:46.517128 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-05-20 01:20:46.521126 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:46.527380 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:46.527578 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:46.535018 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:46.538799 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:46.545364 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/logs
2026-05-20 01:20:46.563689 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-05-20 01:20:46.567316 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-05-20 01:20:46.572593 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-05-20 01:20:46.590088 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-05-20 01:20:46.594044 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-05-20 01:20:46.596911 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:46.602542 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:46.603121 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:46.609743 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:46.612969 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:46.621119 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/data
2026-05-20 01:20:46.638714 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-05-20 01:20:46.642603 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-05-20 01:20:46.646616 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:46.651875 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:46.652227 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:46.659721 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:46.663533 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:46.671891 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/data
2026-05-20 01:20:46.688679 | controller | + ./stack.sh:main:353                      :   async_init
2026-05-20 01:20:46.692380 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-05-20 01:20:46.696121 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-05-20 01:20:46.701634 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-05-20 01:20:46.709443 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-05-20 01:20:46.715424 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000175147
2026-05-20 01:20:46.718922 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000175147 /etc/hosts
2026-05-20 01:20:46.727180 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-05-20 01:20:46.731188 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:46.736939 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:46.737551 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:46.744285 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:46.748284 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:46.752123 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-05-20 01:20:46.755688 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-05-20 01:20:46.759189 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-05-20 01:20:46.762985 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-05-20 01:20:46.766498 | controller | + ./stack.sh:main:404                      :   install_python
2026-05-20 01:20:46.770625 | controller | + inc/python:install_python:482            :   install_python3
2026-05-20 01:20:46.774602 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-05-20 01:20:46.778646 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:20:46.781963 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:20:46.785722 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-05-20 01:20:46.789165 | controller | + functions-common:apt_get:1188            :   local xtrace result
2026-05-20 01:20:46.795739 | controller | ++ functions-common:apt_get:1189            :   set +o
2026-05-20 01:20:46.796302 | controller | ++ functions-common:apt_get:1189            :   grep xtrace
2026-05-20 01:20:46.803340 | controller | + functions-common:apt_get:1189            :   xtrace='set -o xtrace'
2026-05-20 01:20:46.806930 | controller | + functions-common:apt_get:1190            :   set +o xtrace
2026-05-20 01:20:46.818031 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install python3.12 python3.12-dev
2026-05-20 01:20:46.863131 | controller | Reading package lists...
2026-05-20 01:20:47.127799 | controller | Building dependency tree...
2026-05-20 01:20:47.128006 | controller | Reading state information...
2026-05-20 01:20:47.471412 | controller | python3.12 is already the newest version (3.12.3-1ubuntu0.13).
2026-05-20 01:20:47.471482 | controller | python3.12 set to manually installed.
2026-05-20 01:20:47.471495 | controller | The following additional packages will be installed:
2026-05-20 01:20:47.471514 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:20:47.472531 | controller |   libpython3.12t64 linux-libc-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:20:47.473963 | controller | Suggested packages:
2026-05-20 01:20:47.473996 | controller |   glibc-doc manpages-dev
2026-05-20 01:20:47.474005 | controller | Recommended packages:
2026-05-20 01:20:47.474013 | controller |   manpages manpages-dev libc-devtools
2026-05-20 01:20:47.516694 | controller | The following NEW packages will be installed:
2026-05-20 01:20:47.516978 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:20:47.518371 | controller |   libpython3.12t64 linux-libc-dev python3.12-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:20:50.719202 | controller | 0 upgraded, 10 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:20:50.719277 | controller | Need to get 13.4 MB of archives.
2026-05-20 01:20:50.719291 | controller | After this operation, 63.4 MB of additional disk space will be used.
2026-05-20 01:20:50.719303 | controller | Get:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libc-dev-bin amd64 2.39-0ubuntu8.7 [20.4 kB]
2026-05-20 01:20:50.945041 | controller | Get:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 linux-libc-dev amd64 6.8.0-117.117 [1,495 kB]
2026-05-20 01:20:53.286974 | controller | Get:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 libcrypt-dev amd64 1:4.4.36-4build1 [112 kB]
2026-05-20 01:20:57.624489 | controller | Get:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 rpcsvc-proto amd64 1.4.2-0ubuntu7 [67.4 kB]
2026-05-20 01:21:02.096580 | controller | Get:5 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libc6-dev amd64 2.39-0ubuntu8.7 [2,124 kB]
2026-05-20 01:21:03.011149 | controller | Get:6 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libexpat1-dev amd64 2.6.1-2ubuntu0.4 [140 kB]
2026-05-20 01:21:03.013275 | controller | Get:7 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12t64 amd64 3.12.3-1ubuntu0.13 [2,338 kB]
2026-05-20 01:21:04.635035 | controller | Get:8 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 zlib1g-dev amd64 1:1.3.dfsg-3.1ubuntu2.1 [894 kB]
2026-05-20 01:21:04.979452 | controller | Get:9 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12-dev amd64 3.12.3-1ubuntu0.13 [5,682 kB]
2026-05-20 01:21:16.934507 | controller | Get:10 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 python3.12-dev amd64 3.12.3-1ubuntu0.13 [498 kB]
2026-05-20 01:21:18.800512 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-05-20 01:21:18.848029 | controller | Fetched 13.4 MB in 31s (431 kB/s)
2026-05-20 01:21:18.901800 | controller | Selecting previously unselected package libc-dev-bin.
2026-05-20 01:21:19.090154 | controller | (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 31308 files and directories currently installed.)
2026-05-20 01:21:19.092145 | controller | Preparing to unpack .../0-libc-dev-bin_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:19.098999 | controller | Unpacking libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:19.178072 | controller | Selecting previously unselected package linux-libc-dev:amd64.
2026-05-20 01:21:19.184207 | controller | Preparing to unpack .../1-linux-libc-dev_6.8.0-117.117_amd64.deb ...
2026-05-20 01:21:19.198102 | controller | Unpacking linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:19.721084 | controller | Selecting previously unselected package libcrypt-dev:amd64.
2026-05-20 01:21:19.726994 | controller | Preparing to unpack .../2-libcrypt-dev_1%3a4.4.36-4build1_amd64.deb ...
2026-05-20 01:21:19.746060 | controller | Unpacking libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:19.808070 | controller | Selecting previously unselected package rpcsvc-proto.
2026-05-20 01:21:19.813931 | controller | Preparing to unpack .../3-rpcsvc-proto_1.4.2-0ubuntu7_amd64.deb ...
2026-05-20 01:21:19.826459 | controller | Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:19.919122 | controller | Selecting previously unselected package libc6-dev:amd64.
2026-05-20 01:21:19.924693 | controller | Preparing to unpack .../4-libc6-dev_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:19.930796 | controller | Unpacking libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:20.230122 | controller | Selecting previously unselected package libexpat1-dev:amd64.
2026-05-20 01:21:20.235680 | controller | Preparing to unpack .../5-libexpat1-dev_2.6.1-2ubuntu0.4_amd64.deb ...
2026-05-20 01:21:20.241090 | controller | Unpacking libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:20.317034 | controller | Selecting previously unselected package libpython3.12t64:amd64.
2026-05-20 01:21:20.322493 | controller | Preparing to unpack .../6-libpython3.12t64_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:20.331742 | controller | Unpacking libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:20.501057 | controller | Selecting previously unselected package zlib1g-dev:amd64.
2026-05-20 01:21:20.506635 | controller | Preparing to unpack .../7-zlib1g-dev_1%3a1.3.dfsg-3.1ubuntu2.1_amd64.deb ...
2026-05-20 01:21:20.512964 | controller | Unpacking zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:20.585072 | controller | Selecting previously unselected package libpython3.12-dev:amd64.
2026-05-20 01:21:20.589577 | controller | Preparing to unpack .../8-libpython3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:20.595836 | controller | Unpacking libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:20.893072 | controller | Selecting previously unselected package python3.12-dev.
2026-05-20 01:21:20.899350 | controller | Preparing to unpack .../9-python3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:20.906055 | controller | Unpacking python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:21.034865 | controller | Setting up linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:21.051120 | controller | Setting up libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:21.062489 | controller | Setting up rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:21.082831 | controller | Setting up libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:21.107802 | controller | Setting up libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:21.120045 | controller | Setting up libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:21.136537 | controller | Setting up libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:21.152191 | controller | Setting up zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:21.167996 | controller | Setting up libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:21.181016 | controller | Setting up python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:21.205037 | controller | Processing triggers for libc-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:21.488024 | controller | + functions-common:apt_get:1205            :   result=0
2026-05-20 01:21:21.491893 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:21:21.495965 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:21:21.499880 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:21:21.503520 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:21:21.506621 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:21:21.510179 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:21:21.513058 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:21:21.516913 | controller | + functions-common:time_stop:2421          :   start_time=1779240046813
2026-05-20 01:21:21.520923 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240046813 ]]
2026-05-20 01:21:21.524878 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:21:21.530938 | controller | + functions-common:time_stop:2426          :   end_time=1779240081526
2026-05-20 01:21:21.534480 | controller | + functions-common:time_stop:2427          :   elapsed_time=34713
2026-05-20 01:21:21.538052 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:21:21.541432 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:21:21.545331 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=34713
2026-05-20 01:21:21.549327 | controller | + functions-common:apt_get:1209            :   return 0
2026-05-20 01:21:21.554726 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-05-20 01:21:21.560906 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-05-20 01:21:21.565014 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-05-20 01:21:21.570457 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-05-20 01:21:21.574412 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:21.580264 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:21.580300 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:21.586069 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:21.589229 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:21.593405 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-05-20 01:21:21.598952 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-05-20 01:21:21.603198 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:21.609823 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:21.610030 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:21.618090 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:21.621953 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:21.626393 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:21.630382 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-05-20 01:21:21.633737 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-05-20 01:21:21.638817 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-05-20 01:21:21.645411 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-05-20-012121
2026-05-20 01:21:21.649241 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:21.653072 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-05-20 01:21:21.656734 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-05-20 01:21:21.660026 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-05-20 01:21:21.666405 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-05-20 01:21:21.673190 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012121
2026-05-20 01:21:21.677810 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012121.summary.2026-05-20-012121
2026-05-20 01:21:21.681830 | controller | + ./stack.sh:main:476                      :   exec
2026-05-20 01:21:21.685823 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:21.689399 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-05-20 01:21:21.692553 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:21.696415 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-05-20 01:21:21.699862 | controller | + ./stack.sh:main:483                      :   exec
2026-05-20 01:21:21.704146 | controller | ++ ./stack.sh:main:483                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -v --no-timestamp -o /opt/stack/logs/devstacklog.txt.2026-05-20-012121
2026-05-20 01:21:21.735693 | controller | + ./stack.sh:main:485                      :   exec
2026-05-20 01:21:21.735744 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012121'
2026-05-20 01:21:21.735753 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-05-20-012121.summary.2026-05-20-012121
2026-05-20 01:21:21.735795 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-05-20 01:21:21.735815 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012121
2026-05-20 01:21:21.735825 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012121 /opt/stack/logs/devstacklog.txt
2026-05-20 01:21:21.735833 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012121.summary.2026-05-20-012121 /opt/stack/logs/devstacklog.txt.summary
2026-05-20 01:21:21.735842 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-05-20 01:21:21.735847 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-05-20 01:21:21.738986 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-05-20 01:21:21.745347 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-05-20 01:21:21.749691 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-05-20 01:21:21.756156 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-05-20 01:21:21.757295 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-05-20 01:21:21.763446 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:21.767139 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-05-20 01:21:21.771547 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-05-20 01:21:21.778337 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:21.782707 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:21.786175 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-05-20 01:21:21.791241 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-05-20 01:21:21.797206 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:21.801856 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-05-20 01:21:21.806244 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-05-20 01:21:21.810282 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-05-20 01:21:21.815031 | controller | + ./stack.sh:main:574                      :   uname -a
2026-05-20 01:21:21.817610 | controller | Linux np0000175147 6.8.0-111-generic #111-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 11 23:16:02 UTC 2026 x86_64 x86_64 x86_64 GNU/Linux
2026-05-20 01:21:21.822418 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-05-20 01:21:21.825940 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-05-20 01:21:21.831562 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-05-20 01:21:21.896943 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-05-20 01:21:21.926748 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-05-20 01:21:21.931024 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-05-20 01:21:21.956811 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-05-20 01:21:21.961067 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-05-20 01:21:21.986111 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-05-20 01:21:21.990876 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-05-20 01:21:22.017811 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-05-20 01:21:22.022204 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-05-20 01:21:22.026422 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-05-20 01:21:22.031658 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-05-20 01:21:22.057817 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:22.064077 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-05-20 01:21:22.090389 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:22.094590 | controller | + ./stack.sh:main:588                      :   tune_host
2026-05-20 01:21:22.098559 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-05-20 01:21:22.102202 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-05-20 01:21:22.106363 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:22.110433 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-05-20 01:21:22.114080 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:22.117697 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:22.123242 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-05-20 01:21:22.123289 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-05-20 01:21:22.140122 | controller | lz4
2026-05-20 01:21:22.146597 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-05-20 01:21:22.147283 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-05-20 01:21:22.160758 | controller | zsmalloc
2026-05-20 01:21:22.168404 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-05-20 01:21:22.168893 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-05-20 01:21:22.182090 | controller | 1
2026-05-20 01:21:22.207605 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-05-20 01:21:22.220884 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-05-20 01:21:22.220950 | controller | /sys/module/zswap/parameters/enabled:Y
2026-05-20 01:21:22.220961 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-05-20 01:21:22.220971 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-05-20 01:21:22.220988 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-05-20 01:21:22.220998 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-05-20 01:21:22.221008 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-05-20 01:21:22.221017 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-05-20 01:21:22.221026 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-05-20 01:21:22.226853 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-05-20 01:21:22.231168 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:22.235121 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-05-20 01:21:22.238213 | controller | + functions-common:install_package:1432    :   update_package_repo
2026-05-20 01:21:22.242071 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:21:22.245259 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=False
2026-05-20 01:21:22.248551 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=False
2026-05-20 01:21:22.251367 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:22.255427 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:21:22.259014 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:22.262020 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:22.265579 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:21:22.269733 | controller | + functions-common:apt_get_update:1160     :   [[ False == \T\r\u\e ]]
2026-05-20 01:21:22.273342 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:22.276331 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:21:22.281689 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:21:22.287074 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:21:22.291071 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:21:22.295285 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:21:22.299682 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:21:22.303745 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:21:22.308988 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:21:22.314932 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240082310
2026-05-20 01:21:22.318992 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:21:22.322649 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:21:22.326115 | controller | + functions-common:apt_get_update:1175     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-05-20 01:21:24.201259 | controller | Hit:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:21:24.201337 | controller | Hit:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:21:24.218326 | controller | Hit:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:21:32.403346 | controller | Ign:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:21:43.418917 | controller | Ign:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:21:48.505987 | controller | Ign:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:02.521632 | controller | Err:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:02.521696 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:03.565952 | controller | Reading package lists...
2026-05-20 01:22:03.593011 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:03.593077 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:22:03.603298 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:22:03.608700 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:22:03.611993 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:22:03.615394 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:22:03.618474 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:22:03.622812 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:22:03.627133 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:22:03.631170 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:22:03.635114 | controller | + functions-common:time_stop:2421          :   start_time=1779240082310
2026-05-20 01:22:03.639083 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240082310 ]]
2026-05-20 01:22:03.643200 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:22:03.649476 | controller | + functions-common:time_stop:2426          :   end_time=1779240123645
2026-05-20 01:22:03.654241 | controller | + functions-common:time_stop:2427          :   elapsed_time=41335
2026-05-20 01:22:03.657417 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:22:03.663205 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:22:03.666828 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=41335
2026-05-20 01:22:03.670914 | controller | + functions-common:install_package:1433    :   real_install_package ksmtuned
2026-05-20 01:22:03.673136 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:22:03.677905 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:22:03.680348 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:22:03.683587 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:22:03.713652 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-05-20 01:22:03.758016 | controller | Reading package lists...
2026-05-20 01:22:04.036069 | controller | Building dependency tree...
2026-05-20 01:22:04.036134 | controller | Reading state information...
2026-05-20 01:22:04.384607 | controller | Recommended packages:
2026-05-20 01:22:04.384715 | controller |   qemu-kvm
2026-05-20 01:22:04.413435 | controller | The following NEW packages will be installed:
2026-05-20 01:22:04.415396 | controller |   ksmtuned
2026-05-20 01:22:14.464427 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:22:14.464470 | controller | Need to get 7,444 B of archives.
2026-05-20 01:22:14.464477 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:22:14.464482 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:22:22.711148 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:22:39.732605 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:22:58.753122 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:22:58.753167 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:22:58.768169 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:22:58.768239 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:22:58.776114 | controller | + functions-common:apt_get:1205            :   result=100
2026-05-20 01:22:58.780206 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:22:58.784657 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:22:58.789175 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:22:58.793365 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:22:58.796341 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:22:58.799558 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:22:58.802716 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:22:58.806557 | controller | + functions-common:time_stop:2421          :   start_time=1779240123709
2026-05-20 01:22:58.811176 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240123709 ]]
2026-05-20 01:22:58.815334 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:22:58.822098 | controller | + functions-common:time_stop:2426          :   end_time=1779240178816
2026-05-20 01:22:58.826492 | controller | + functions-common:time_stop:2427          :   elapsed_time=55107
2026-05-20 01:22:58.830091 | controller | + functions-common:time_stop:2428          :   total=34713
2026-05-20 01:22:58.834866 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:22:58.839197 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=89820
2026-05-20 01:22:58.843078 | controller | + functions-common:apt_get:1209            :   return 100
2026-05-20 01:22:58.847588 | controller | + functions-common:install_package:1434    :   RETRY_UPDATE=True
2026-05-20 01:22:58.853197 | controller | + functions-common:install_package:1434    :   update_package_repo
2026-05-20 01:22:58.857138 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:22:58.861059 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=True
2026-05-20 01:22:58.864990 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=True
2026-05-20 01:22:58.869194 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:22:58.874172 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:22:58.878740 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:22:58.882979 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:22:58.887327 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:22:58.891576 | controller | + functions-common:apt_get_update:1160     :   [[ True == \T\r\u\e ]]
2026-05-20 01:22:58.896099 | controller | + functions-common:apt_get_update:1160     :   [[ True != \T\r\u\e ]]
2026-05-20 01:22:58.900538 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:22:58.904603 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:22:58.909051 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:22:58.915086 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:22:58.919359 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:22:58.923058 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:22:58.927054 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:22:58.930094 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:22:58.935222 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:22:58.941951 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240178936
2026-05-20 01:22:58.945738 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:22:58.949090 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:22:58.952811 | controller | + functions-common:apt_get_update:1175     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-05-20 01:23:09.020934 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:09.021994 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:23:19.035206 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:20.037141 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:23:29.049341 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:36.197086 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:23:36.992466 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:47.007701 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:55.219110 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:23:55.219231 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:23:57.748008 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:24:12.768943 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:24:22.778812 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:24:32.787259 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:24:42.798066 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:24:42.798144 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:52.806074 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:24:52.806112 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:02.818045 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:02.818090 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:03.803104 | controller | Reading package lists...
2026-05-20 01:25:03.825446 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:03.825486 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-updates/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:03.825502 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-backports/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:03.825613 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:25:03.825669 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:25:03.834735 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:25:03.838786 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:25:03.842794 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:25:03.846958 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:25:03.852068 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:25:03.855660 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:25:03.859364 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:25:03.862744 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:25:03.866416 | controller | + functions-common:time_stop:2421          :   start_time=1779240178936
2026-05-20 01:25:03.870061 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240178936 ]]
2026-05-20 01:25:03.875359 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:25:03.881911 | controller | + functions-common:time_stop:2426          :   end_time=1779240303876
2026-05-20 01:25:03.885578 | controller | + functions-common:time_stop:2427          :   elapsed_time=124940
2026-05-20 01:25:03.888457 | controller | + functions-common:time_stop:2428          :   total=41335
2026-05-20 01:25:03.891395 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:25:03.895039 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=166275
2026-05-20 01:25:03.898984 | controller | + functions-common:install_package:1434    :   real_install_package ksmtuned
2026-05-20 01:25:03.902836 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:25:03.906645 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:25:03.910501 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:25:03.914089 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:25:03.946530 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-05-20 01:25:03.986982 | controller | Reading package lists...
2026-05-20 01:25:04.247914 | controller | Building dependency tree...
2026-05-20 01:25:04.248211 | controller | Reading state information...
2026-05-20 01:25:04.590635 | controller | Recommended packages:
2026-05-20 01:25:04.590697 | controller |   qemu-kvm
2026-05-20 01:25:04.621239 | controller | The following NEW packages will be installed:
2026-05-20 01:25:04.624104 | controller |   ksmtuned
2026-05-20 01:25:19.674961 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:25:19.675002 | controller | Need to get 7,444 B of archives.
2026-05-20 01:25:19.675015 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:25:19.675026 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:25:30.691173 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:25:42.707166 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:25:55.998223 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:25:55.998247 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:56.005946 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:56.005967 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:25:56.011162 | controller | + functions-common:apt_get:1               :   exit_trap
2026-05-20 01:25:56.014411 | controller | + ./stack.sh:exit_trap:519                 :   local r=100
2026-05-20 01:25:56.019384 | controller | ++ ./stack.sh:exit_trap:520                 :   jobs -p
2026-05-20 01:25:56.024124 | controller | + ./stack.sh:exit_trap:520                 :   jobs=
2026-05-20 01:25:56.028033 | controller | + ./stack.sh:exit_trap:523                 :   [[ -n '' ]]
2026-05-20 01:25:56.031510 | controller | + ./stack.sh:exit_trap:529                 :   '[' -f '' ']'
2026-05-20 01:25:56.034925 | controller | + ./stack.sh:exit_trap:534                 :   kill_spinner
2026-05-20 01:25:56.037650 | controller | + ./stack.sh:kill_spinner:429              :   '[' '!' -z '' ']'
2026-05-20 01:25:56.040864 | controller | + ./stack.sh:exit_trap:536                 :   [[ 100 -ne 0 ]]
2026-05-20 01:25:56.044585 | controller | + ./stack.sh:exit_trap:537                 :   echo 'Error on exit'
2026-05-20 01:25:56.044607 | controller | Error on exit
2026-05-20 01:25:56.048537 | controller | + ./stack.sh:exit_trap:539                 :   type -p generate-subunit
2026-05-20 01:25:56.052710 | controller | + ./stack.sh:exit_trap:542                 :   [[ -z /opt/stack/logs ]]
2026-05-20 01:25:56.056044 | controller | + ./stack.sh:exit_trap:545                 :   /usr/bin/python3.12 /opt/stack/devstack/tools/worlddump.py -d /opt/stack/logs
2026-05-20 01:25:56.565108 | controller | + ./stack.sh:exit_trap:554                 :   exit 100
2026-05-20 01:25:56.566398 | controller | *** FINISHED ***
2026-05-20 01:25:56.783675 | controller | ERROR
2026-05-20 01:25:56.783981 | controller | {
2026-05-20 01:25:56.784046 | controller |   "delta": "0:05:13.829160",
2026-05-20 01:25:56.784110 | controller |   "end": "2026-05-20 01:25:56.581795",
2026-05-20 01:25:56.784301 | controller |   "msg": "non-zero return code",
2026-05-20 01:25:56.784365 | controller |   "rc": 100,
2026-05-20 01:25:56.784422 | controller |   "start": "2026-05-20 01:20:42.752635"
2026-05-20 01:25:56.784466 | controller | }
failure
2026-05-20 01:25:56.798438 | 
2026-05-20 01:25:56.798502 | PLAY RECAP
2026-05-20 01:25:56.798562 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:25:56.798593 | 
2026-05-20 01:25:56.910821 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:25:56.917709 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:25:57.574352 | 
2026-05-20 01:25:57.574462 | PLAY [all]
2026-05-20 01:25:57.586799 | 
2026-05-20 01:25:57.586880 | LOOP [Copy individual Hydrophone result files]
2026-05-20 01:25:58.214152 | controller | ERROR: Item: e2e.log
2026-05-20 01:25:58.214455 | controller | {
2026-05-20 01:25:58.214512 | controller |   "ansible_loop_var": "item",
2026-05-20 01:25:58.214553 | controller |   "item": "e2e.log",
2026-05-20 01:25:58.214627 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-20 01:25:58.214664 | controller | }
2026-05-20 01:25:58.454820 | controller | ERROR: Item: junit_01.xml
2026-05-20 01:25:58.455024 | controller | {
2026-05-20 01:25:58.455095 | controller |   "ansible_loop_var": "item",
2026-05-20 01:25:58.455143 | controller |   "item": "junit_01.xml",
2026-05-20 01:25:58.455185 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-20 01:25:58.455226 | controller | }
2026-05-20 01:25:58.466317 | controller | ERROR: Ignoring Errors
2026-05-20 01:25:58.473301 | 
2026-05-20 01:25:58.473389 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-20 01:25:58.781165 | controller | ERROR
2026-05-20 01:25:58.781395 | controller | {
2026-05-20 01:25:58.781437 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-20 01:25:58.781467 | controller | }
2026-05-20 01:25:58.781501 | controller | ERROR: Ignoring Errors
2026-05-20 01:25:58.795202 | 
2026-05-20 01:25:58.795283 | TASK [Return built artifacts to Zuul]
2026-05-20 01:25:58.829944 | controller | ok
2026-05-20 01:25:58.837090 | 
2026-05-20 01:25:58.837183 | TASK [Get the Docker volume ID]
2026-05-20 01:25:59.373490 | controller | ERROR
2026-05-20 01:25:59.373778 | controller | {
2026-05-20 01:25:59.373825 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-20 01:25:59.373855 | controller |   "rc": 2
2026-05-20 01:25:59.373882 | controller | }
2026-05-20 01:25:59.373935 | controller | ERROR: Ignoring Errors
2026-05-20 01:25:59.382121 | 
2026-05-20 01:25:59.382246 | TASK [Copy all of the pod logs]
2026-05-20 01:25:59.417441 | controller | ERROR
2026-05-20 01:25:59.417699 | controller | {
2026-05-20 01:25:59.417754 | 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/ce5fb8f0fe76471cb4327f72873e850a/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml': line 42, 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-05-20 01:25:59.417791 | controller | }
2026-05-20 01:25:59.417826 | controller | ERROR: Ignoring Errors
2026-05-20 01:25:59.418965 | 
2026-05-20 01:25:59.419026 | PLAY RECAP
2026-05-20 01:25:59.419083 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-20 01:25:59.419137 | 
2026-05-20 01:25:59.540942 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:25:59.550454 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:26:00.189776 | 
2026-05-20 01:26:00.190200 | PLAY [all]
2026-05-20 01:26:00.208801 | 
2026-05-20 01:26:00.208903 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-20 01:26:00.803389 | controller | changed
2026-05-20 01:26:00.809179 | 
2026-05-20 01:26:00.809266 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-20 01:26:01.847420 | controller | ok: Runtime: 0:00:00.485433
2026-05-20 01:26:01.854890 | 
2026-05-20 01:26:01.854970 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-20 01:26:02.392268 | controller | ok: Runtime: 0:00:00.017890
2026-05-20 01:26:02.399555 | 
2026-05-20 01:26:02.399653 | TASK [export-devstack-journal : Export journal]
2026-05-20 01:26:03.029338 | controller | ok: Runtime: 0:00:00.020975
2026-05-20 01:26:03.147002 | 
2026-05-20 01:26:03.147126 | TASK [export-devstack-journal : Save journal README]
2026-05-20 01:26:03.983868 | controller | changed
2026-05-20 01:26:03.998140 | 
2026-05-20 01:26:03.998212 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-20 01:26:04.244574 | controller | changed
2026-05-20 01:26:04.251455 | 
2026-05-20 01:26:04.251524 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:26:04.571521 | controller | Output suppressed because no_log was given
2026-05-20 01:26:04.578906 | 
2026-05-20 01:26:04.579002 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:26:04.613837 | 
2026-05-20 01:26:04.614001 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:26:04.650395 | 
2026-05-20 01:26:04.650522 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:26:04.686311 | 
2026-05-20 01:26:04.686445 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:26:04.722422 | 
2026-05-20 01:26:04.722553 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:26:04.764409 | 
2026-05-20 01:26:04.764616 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-20 01:26:05.000624 | controller | changed
2026-05-20 01:26:05.008847 | 
2026-05-20 01:26:05.008935 | TASK [apache-logs-conf : Define config paths]
2026-05-20 01:26:05.042949 | controller | ok
2026-05-20 01:26:05.050073 | 
2026-05-20 01:26:05.050159 | TASK [apache-logs-conf : Discover configurations]
2026-05-20 01:26:05.288547 | controller | Output suppressed because no_log was given
2026-05-20 01:26:05.295617 | 
2026-05-20 01:26:05.295708 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-20 01:26:05.328308 | 
2026-05-20 01:26:05.328470 | LOOP [apache-logs-conf : Link configurations]
2026-05-20 01:26:05.369861 | 
2026-05-20 01:26:05.369979 | TASK [capture-performance-data : Generate statistics]
2026-05-20 01:26:07.712404 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-20 01:26:07.730553 | controller | Using python 3.12 to install setuptools
2026-05-20 01:26:07.765494 | controller | /usr/bin/python3.12: No module named pip
2026-05-20 01:26:07.776957 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-20 01:26:07.910852 | controller | ERROR
2026-05-20 01:26:07.911146 | controller | {
2026-05-20 01:26:07.911220 | controller |   "delta": "0:00:02.159440",
2026-05-20 01:26:07.911264 | controller |   "end": "2026-05-20 01:26:07.778991",
2026-05-20 01:26:07.911305 | controller |   "msg": "non-zero return code",
2026-05-20 01:26:07.911351 | controller |   "rc": 127,
2026-05-20 01:26:07.911392 | controller |   "start": "2026-05-20 01:26:05.619551"
2026-05-20 01:26:07.911431 | controller | }
2026-05-20 01:26:07.911483 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:07.925107 | 
2026-05-20 01:26:07.925178 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-20 01:26:08.172942 | controller | changed
2026-05-20 01:26:08.180220 | 
2026-05-20 01:26:08.180286 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-20 01:26:11.810892 | controller | Output suppressed because no_log was given
2026-05-20 01:26:11.826576 | 
2026-05-20 01:26:11.826642 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-20 01:26:11.919483 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.920477 | 
2026-05-20 01:26:11.922504 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.924197 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.926123 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.929048 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.931726 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.934635 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.938208 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.941019 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.943392 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.946003 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.948769 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.951413 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.954207 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.956942 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.959839 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.962459 | controller | skipping: Conditional result was False
2026-05-20 01:26:11.965177 | controller | skipping: Conditional result was False
2026-05-20 01:26:12.046809 | 
2026-05-20 01:26:12.046965 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-20 01:26:12.274523 | controller | ok
2026-05-20 01:26:12.280131 | 
2026-05-20 01:26:12.280194 | TASK [devstack-project-conf : Copy configuration files]
2026-05-20 01:26:12.810251 | controller | skipping: Conditional result was False
2026-05-20 01:26:12.827319 | 
2026-05-20 01:26:12.827428 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-20 01:26:13.146828 | controller | /usr/bin/python3: No module named pip
2026-05-20 01:26:14.270552 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-20 01:26:14.367265 | controller | ok: Runtime: 0:00:01.187537
2026-05-20 01:26:14.377847 | 
2026-05-20 01:26:14.377909 | LOOP [stage-output : Register sources]
2026-05-20 01:26:23.687135 | controller | Output suppressed because no_log was given
2026-05-20 01:26:23.695587 | 
2026-05-20 01:26:23.695667 | TASK [stage-output : Check sudo]
2026-05-20 01:26:24.237445 | controller | ok: Runtime: 0:00:00.019788
2026-05-20 01:26:24.245783 | 
2026-05-20 01:26:24.245849 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-20 01:26:24.342063 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.342443 | 
2026-05-20 01:26:24.343988 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.346094 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.348593 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.386955 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.387452 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.388216 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.390705 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.393189 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.395878 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.398739 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.401474 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.420506 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.429809 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.430356 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.431067 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.455088 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.455447 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.457471 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.493511 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.494023 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.577681 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.588323 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.598290 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.619749 | controller | Output suppressed because no_log was given
2026-05-20 01:26:24.634750 | 
2026-05-20 01:26:24.634848 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-20 01:26:24.693866 | controller | ok
2026-05-20 01:26:24.709876 | 
2026-05-20 01:26:24.710055 | LOOP [stage-output : Ensure target folders exist]
2026-05-20 01:26:24.942275 | controller | changed: "docs"
2026-05-20 01:26:25.162954 | controller | changed: "artifacts"
2026-05-20 01:26:25.386101 | controller | ok: "logs"
2026-05-20 01:26:25.403279 | 
2026-05-20 01:26:25.403415 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-20 01:26:25.681793 | controller | ok: Item: Runtime: 0:00:00.007592
2026-05-20 01:26:25.682018 | controller | changed: All items complete
2026-05-20 01:26:25.682054 | 
2026-05-20 01:26:25.901285 | controller | ok: Item: Runtime: 0:00:00.005953
2026-05-20 01:26:26.117135 | controller | ok: Item: Runtime: 0:00:00.004531
2026-05-20 01:26:26.341009 | controller | ok: Item: Runtime: 0:00:00.007135
2026-05-20 01:26:26.597459 | controller | ok: Item: Runtime: 0:00:00.007478
2026-05-20 01:26:26.826229 | controller | ok: Item: Runtime: 0:00:00.006215
2026-05-20 01:26:27.084287 | controller | ok: Item: Runtime: 0:00:00.007834
2026-05-20 01:26:27.265416 | controller | ok: Item: Runtime: 0:00:00.004485
2026-05-20 01:26:27.483389 | controller | ok: Item: Runtime: 0:00:00.005473
2026-05-20 01:26:27.704462 | controller | ok: Item: Runtime: 0:00:00.007342
2026-05-20 01:26:27.924888 | controller | ok: Item: Runtime: 0:00:00.008796
2026-05-20 01:26:28.151561 | controller | ok: Item: Runtime: 0:00:00.007039
2026-05-20 01:26:28.374903 | controller | ok: Item: Runtime: 0:00:00.007271
2026-05-20 01:26:28.599538 | controller | ok: Item: Runtime: 0:00:00.007103
2026-05-20 01:26:28.823602 | controller | ok: Item: Runtime: 0:00:00.007245
2026-05-20 01:26:29.044877 | controller | ok: Item: Runtime: 0:00:00.008276
2026-05-20 01:26:29.270237 | controller | ok: Item: Runtime: 0:00:00.006953
2026-05-20 01:26:29.490067 | controller | ok: Item: Runtime: 0:00:00.007608
2026-05-20 01:26:29.720645 | controller | ok: Item: Runtime: 0:00:00.007723
2026-05-20 01:26:29.953396 | controller | ok: Item: Runtime: 0:00:00.009125
2026-05-20 01:26:29.976517 | 
2026-05-20 01:26:29.976634 | TASK [stage-output : Make all log files readable]
2026-05-20 01:26:30.209879 | controller | changed
2026-05-20 01:26:30.218605 | 
2026-05-20 01:26:30.218706 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-20 01:26:30.617803 | controller | changed: Renamed files for staging.
2026-05-20 01:26:30.625413 | 
2026-05-20 01:26:30.625505 | TASK [stage-output : Discover log files for compression]
2026-05-20 01:26:30.649982 | controller | skipping: Conditional result was False
2026-05-20 01:26:30.661736 | 
2026-05-20 01:26:30.661839 | LOOP [stage-output : Archive everything from logs]
2026-05-20 01:26:30.702050 | 
2026-05-20 01:26:30.702222 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-20 01:26:31.375868 | controller | changed:
2026-05-20 01:26:31.376267 | controller | created directory /var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/work/logs/controller
2026-05-20 01:26:31.376328 | controller | cd+++++++++ logs/
2026-05-20 01:26:31.376372 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-05-20 01:26:31.376403 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-20 01:26:31.376435 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-20 01:26:31.376476 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-20 01:26:31.376509 | controller | >f+++++++++ logs/devstacklog.txt
2026-05-20 01:26:31.376545 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-05-20 01:26:31.376588 | controller | >f+++++++++ logs/df.txt
2026-05-20 01:26:31.376627 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-20 01:26:31.376662 | controller | >f+++++++++ logs/iptables.txt
2026-05-20 01:26:31.376691 | controller | >f+++++++++ logs/listen53.txt
2026-05-20 01:26:31.376718 | controller | >f+++++++++ logs/local_conf.txt
2026-05-20 01:26:31.376745 | controller | >f+++++++++ logs/mount.txt
2026-05-20 01:26:31.376771 | controller | >f+++++++++ logs/performance.json
2026-05-20 01:26:31.376803 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-20 01:26:31.376864 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-20 01:26:31.376893 | controller | >f+++++++++ logs/services.txt
2026-05-20 01:26:31.376920 | controller | >f+++++++++ logs/sudoers
2026-05-20 01:26:31.376945 | controller | >f+++++++++ logs/syslog.txt
2026-05-20 01:26:31.377128 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-05-20 01:26:31.377158 | controller | cd+++++++++ logs/apache/
2026-05-20 01:26:31.377191 | controller | cd+++++++++ logs/apache_config/
2026-05-20 01:26:31.377233 | controller | cd+++++++++ logs/etc/
2026-05-20 01:26:31.377269 | controller | cd+++++++++ logs/sudoers.d/
2026-05-20 01:26:31.377297 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-20 01:26:31.377324 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-20 01:26:31.377351 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-20 01:26:31.377378 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-20 01:26:31.377404 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-20 01:26:31.391534 | 
2026-05-20 01:26:31.391619 | TASK [Check if a tempest log exits]
2026-05-20 01:26:31.680865 | controller | ok
2026-05-20 01:26:31.694937 | 
2026-05-20 01:26:31.695249 | TASK [Link post-devstack tempest.log]
2026-05-20 01:26:31.719390 | controller | skipping: Conditional result was False
2026-05-20 01:26:31.731238 | 
2026-05-20 01:26:31.731328 | TASK [Capture most recent qemu crash dump, if any]
2026-05-20 01:26:31.977782 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-20 01:26:32.276615 | controller | ERROR
2026-05-20 01:26:32.276904 | controller | {
2026-05-20 01:26:32.276948 | controller |   "delta": "0:00:00.009241",
2026-05-20 01:26:32.277007 | controller |   "end": "2026-05-20 01:26:31.978468",
2026-05-20 01:26:32.277029 | controller |   "msg": "non-zero return code",
2026-05-20 01:26:32.277048 | controller |   "rc": 127,
2026-05-20 01:26:32.277067 | controller |   "start": "2026-05-20 01:26:31.969227"
2026-05-20 01:26:32.277085 | controller | }
2026-05-20 01:26:32.277109 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:32.278971 | 
2026-05-20 01:26:32.279044 | PLAY RECAP
2026-05-20 01:26:32.279136 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-20 01:26:32.279179 | 
2026-05-20 01:26:32.498725 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:26:32.513709 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:26:33.154905 | 
2026-05-20 01:26:33.155039 | PLAY [all]
2026-05-20 01:26:33.167995 | 
2026-05-20 01:26:33.168087 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 01:26:33.213016 | controller | skipping: Conditional result was False
2026-05-20 01:26:33.224875 | 
2026-05-20 01:26:33.225030 | TASK [fetch-output : Set log path for single node]
2026-05-20 01:26:33.271589 | controller | ok
2026-05-20 01:26:33.276819 | 
2026-05-20 01:26:33.276896 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 01:26:33.916817 | controller -> localhost | ok: "/var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/work/logs"
2026-05-20 01:26:34.128897 | controller -> localhost | changed: "/var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/work/artifacts"
2026-05-20 01:26:34.332829 | controller -> localhost | changed: "/var/lib/zuul/builds/ce5fb8f0fe76471cb4327f72873e850a/work/docs"
2026-05-20 01:26:34.357115 | 
2026-05-20 01:26:34.357254 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 01:26:34.964704 | controller | changed: .d..t...... ./
2026-05-20 01:26:34.964890 | controller | changed: All items complete
2026-05-20 01:26:34.964919 | 
2026-05-20 01:26:35.434774 | controller | changed: .d..t...... ./
2026-05-20 01:26:35.908519 | controller | changed: .d..t...... ./
2026-05-20 01:26:35.933335 | 
2026-05-20 01:26:35.933456 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 01:26:36.345263 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007292
2026-05-20 01:26:36.576683 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006624
2026-05-20 01:26:36.593752 | 
2026-05-20 01:26:36.594065 | PLAY [all]
2026-05-20 01:26:36.602311 | 
2026-05-20 01:26:36.602392 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 01:26:37.277673 | controller | changed
2026-05-20 01:26:37.285820 | 
2026-05-20 01:26:37.285888 | PLAY RECAP
2026-05-20 01:26:37.285946 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:26:37.285977 | 
2026-05-20 01:26:37.401213 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:26:37.406906 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 01:26:38.062146 | 
2026-05-20 01:26:38.062272 | PLAY [localhost]
2026-05-20 01:26:38.072788 | 
2026-05-20 01:26:38.072893 | TASK [Generate Zuul manifest]
2026-05-20 01:26:38.093326 | localhost | ok
2026-05-20 01:26:38.114181 | 
2026-05-20 01:26:38.114323 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 01:26:38.733678 | localhost | changed
2026-05-20 01:26:38.746214 | 
2026-05-20 01:26:38.746294 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 01:26:38.786471 | localhost | ok
2026-05-20 01:26:38.797441 | 
2026-05-20 01:26:38.797560 | TASK [Upload logs]
2026-05-20 01:26:38.817053 | localhost | ok
2026-05-20 01:26:38.935419 | 
2026-05-20 01:26:38.935546 | TASK [Set zuul-log-path fact]
2026-05-20 01:26:38.954866 | localhost | ok
2026-05-20 01:26:39.017832 | 
2026-05-20 01:26:39.017981 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:26:39.053459 | localhost | ok
2026-05-20 01:26:39.062751 | 
2026-05-20 01:26:39.062829 | TASK [upload-logs : Create log directories]
2026-05-20 01:26:39.428531 | localhost | changed
2026-05-20 01:26:39.434977 | 
2026-05-20 01:26:39.435074 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 01:26:39.832249 | localhost -> localhost | ok: Runtime: 0:00:00.006354
2026-05-20 01:26:39.840121 | 
2026-05-20 01:26:39.840192 | TASK [upload-logs : Upload logs to log server]
2026-05-20 01:26:40.287258 | localhost | Output suppressed because no_log was given
2026-05-20 01:26:40.292804 | 
2026-05-20 01:26:40.292875 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 01:26:40.338056 | localhost | skipping: Conditional result was False
2026-05-20 01:26:40.345000 | localhost | skipping: Conditional result was False
2026-05-20 01:26:40.358736 | 
2026-05-20 01:26:40.358889 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 01:26:40.402424 | localhost | skipping: Conditional result was False
2026-05-20 01:26:40.402915 | 
2026-05-20 01:26:40.406250 | localhost | skipping: Conditional result was False
2026-05-20 01:26:40.423253 | 
2026-05-20 01:26:40.423407 | LOOP [upload-logs : Upload console log and json output]