2026-05-20 01:17:23.592161 | Job console starting
2026-05-20 01:17:23.602648 | Updating git repos
2026-05-20 01:17:28.492148 | Cloning repos into workspace
2026-05-20 01:17:47.742308 | Restoring repo states
2026-05-20 01:17:50.562820 | Merging changes
2026-05-20 01:17:51.937359 | Checking out repos
2026-05-20 01:18:07.780606 | Preparing playbooks
2026-05-20 01:18:15.152655 | Running Ansible setup
2026-05-20 01:18:19.993939 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:18:20.626752 | 
2026-05-20 01:18:21.135509 | PLAY [localhost]
2026-05-20 01:18:21.147258 | 
2026-05-20 01:18:21.147375 | TASK [Gathering Facts]
2026-05-20 01:18:22.136120 | localhost | ok
2026-05-20 01:18:22.146489 | 
2026-05-20 01:18:22.146590 | TASK [Setup log path fact]
2026-05-20 01:18:22.172664 | localhost | ok
2026-05-20 01:18:22.186595 | 
2026-05-20 01:18:22.186700 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:18:22.231551 | localhost | ok
2026-05-20 01:18:22.242350 | 
2026-05-20 01:18:22.242442 | TASK [emit-job-header : Print job information]
2026-05-20 01:18:22.297498 | # Job Information
2026-05-20 01:18:22.297656 | Ansible Version: 2.16.18
2026-05-20 01:18:22.297690 | Job: magnum-cluster-api-hydrophone-v1.33.11-cilium
2026-05-20 01:18:22.297714 | Pipeline: check
2026-05-20 01:18:22.297736 | Executor: 2d72f0692154
2026-05-20 01:18:22.297757 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1012
2026-05-20 01:18:22.297781 | Event ID: 4b7fd430-53e9-11f1-89c4-9a5670eb8187
2026-05-20 01:18:22.302545 | 
2026-05-20 01:18:22.302679 | LOOP [emit-job-header : Print node information]
2026-05-20 01:18:22.408522 | localhost | ok:
2026-05-20 01:18:22.408683 | localhost | # Node Information
2026-05-20 01:18:22.408715 | localhost | Inventory Hostname: controller
2026-05-20 01:18:22.408740 | localhost | Hostname: np0000175163
2026-05-20 01:18:22.408794 | localhost | Username: zuul
2026-05-20 01:18:22.408823 | localhost | Distro: Ubuntu 24.04
2026-05-20 01:18:22.408854 | localhost | Provider: yul1
2026-05-20 01:18:22.408878 | localhost | Region: ca-ymq-1
2026-05-20 01:18:22.408898 | localhost | Label: ubuntu-noble-16
2026-05-20 01:18:22.408918 | localhost | Product Name: OpenStack Nova
2026-05-20 01:18:22.409064 | localhost | Interface IP: 199.204.45.30
2026-05-20 01:18:22.417277 | 
2026-05-20 01:18:22.417412 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 01:18:22.829008 | localhost -> localhost | changed
2026-05-20 01:18:22.836246 | 
2026-05-20 01:18:22.836362 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 01:18:23.959109 | localhost -> localhost | changed
2026-05-20 01:18:23.974401 | 
2026-05-20 01:18:23.974563 | PLAY [all]
2026-05-20 01:18:23.991106 | 
2026-05-20 01:18:23.991262 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 01:18:24.283105 | controller -> localhost | ok
2026-05-20 01:18:24.295834 | 
2026-05-20 01:18:24.296011 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 01:18:24.335838 | controller | ok
2026-05-20 01:18:24.357581 | controller | included: /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 01:18:24.371925 | 
2026-05-20 01:18:24.372044 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 01:18:25.953541 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 01:18:25.953814 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/work/dbd0293bc9e64f3987c504ed9177f0db_id_rsa
2026-05-20 01:18:25.953858 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/work/dbd0293bc9e64f3987c504ed9177f0db_id_rsa.pub
2026-05-20 01:18:25.953884 | controller -> localhost | The key fingerprint is:
2026-05-20 01:18:25.953906 | controller -> localhost | SHA256:KQI1iZR4j8f0ZZAt9EaiWfk8FsYX8Dwd7Z+tnpmLJRk zuul-build-sshkey
2026-05-20 01:18:25.953943 | controller -> localhost | The key's randomart image is:
2026-05-20 01:18:25.953965 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 01:18:25.953990 | controller -> localhost | | o.ooo=*o.....   |
2026-05-20 01:18:25.954012 | controller -> localhost | |. +.o=++Bo.. ..  |
2026-05-20 01:18:25.954032 | controller -> localhost | | ..=o. Boo+ ..   |
2026-05-20 01:18:25.954052 | controller -> localhost | |  ..+ ..=. .  .  |
2026-05-20 01:18:25.954072 | controller -> localhost | |   .. ..S.  E  .o|
2026-05-20 01:18:25.954092 | controller -> localhost | |     . .     o .o|
2026-05-20 01:18:25.954163 | controller -> localhost | |            o .. |
2026-05-20 01:18:25.954196 | controller -> localhost | |             +.+ |
2026-05-20 01:18:25.954221 | controller -> localhost | |            ..*. |
2026-05-20 01:18:25.954242 | controller -> localhost | +----[SHA256]-----+
2026-05-20 01:18:25.954294 | controller -> localhost | ok: Runtime: 0:00:01.032200
2026-05-20 01:18:25.962525 | 
2026-05-20 01:18:25.962597 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 01:18:25.994994 | controller | ok
2026-05-20 01:18:26.004294 | controller | included: /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 01:18:26.013112 | 
2026-05-20 01:18:26.013201 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 01:18:26.037308 | controller | skipping: Conditional result was False
2026-05-20 01:18:26.047187 | 
2026-05-20 01:18:26.047295 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 01:18:26.667804 | controller | changed
2026-05-20 01:18:26.676793 | 
2026-05-20 01:18:26.676891 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 01:18:26.901732 | controller | ok
2026-05-20 01:18:26.907309 | 
2026-05-20 01:18:26.907426 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 01:18:27.493022 | controller | changed
2026-05-20 01:18:27.506066 | 
2026-05-20 01:18:27.506185 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 01:18:28.225812 | controller | changed
2026-05-20 01:18:28.233557 | 
2026-05-20 01:18:28.233678 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 01:18:28.258989 | controller | skipping: Conditional result was False
2026-05-20 01:18:28.272214 | 
2026-05-20 01:18:28.272413 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 01:18:28.700746 | controller -> localhost | changed
2026-05-20 01:18:28.724225 | 
2026-05-20 01:18:28.724452 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 01:18:29.137054 | controller -> localhost | Identity added: /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/work/dbd0293bc9e64f3987c504ed9177f0db_id_rsa (zuul-build-sshkey)
2026-05-20 01:18:29.138765 | controller -> localhost | ok: Runtime: 0:00:00.016173
2026-05-20 01:18:29.146060 | 
2026-05-20 01:18:29.146141 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 01:18:29.499160 | controller | ok
2026-05-20 01:18:29.505687 | 
2026-05-20 01:18:29.505759 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 01:18:29.531134 | controller | skipping: Conditional result was False
2026-05-20 01:18:29.552530 | 
2026-05-20 01:18:29.552671 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 01:18:31.009071 | controller | ok
2026-05-20 01:18:31.016550 | 
2026-05-20 01:18:31.016622 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 01:19:43.044084 | controller | Output suppressed because no_log was given
2026-05-20 01:19:43.069766 | 
2026-05-20 01:19:43.070143 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 01:19:43.678754 | controller | ok: "logs"
2026-05-20 01:19:43.678908 | controller | ok: All items complete
2026-05-20 01:19:43.678936 | 
2026-05-20 01:19:43.803562 | controller | ok: "artifacts"
2026-05-20 01:19:43.996906 | controller | ok: "docs"
2026-05-20 01:19:44.268165 | 
2026-05-20 01:19:44.268418 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 01:19:44.523979 | controller | changed: "logs"
2026-05-20 01:19:44.718091 | controller | changed: "artifacts"
2026-05-20 01:19:44.901143 | controller | changed: "docs"
2026-05-20 01:19:44.921749 | 
2026-05-20 01:19:44.921849 | PLAY RECAP
2026-05-20 01:19:44.921896 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 01:19:44.921969 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:19:44.922003 | 
2026-05-20 01:19:45.372694 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:19:45.646776 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:19:47.459037 | 
2026-05-20 01:19:47.459229 | PLAY [all]
2026-05-20 01:19:47.501349 | 
2026-05-20 01:19:47.501550 | TASK [Fix the permissions of the zuul home directory]
2026-05-20 01:19:48.128327 | controller | changed
2026-05-20 01:19:48.141776 | 
2026-05-20 01:19:48.141971 | TASK [Gather minimum local MTU]
2026-05-20 01:19:48.246266 | controller | ok
2026-05-20 01:19:48.667018 | 
2026-05-20 01:19:48.667140 | TASK [Calculate external_bridge_mtu]
2026-05-20 01:19:48.730279 | controller | ok
2026-05-20 01:19:48.737092 | 
2026-05-20 01:19:48.737262 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-20 01:19:48.762379 | controller | skipping: Conditional result was False
2026-05-20 01:19:48.770216 | 
2026-05-20 01:19:48.770326 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-20 01:19:49.320538 | controller | ok: Runtime: 0:00:00.011989
2026-05-20 01:19:49.326811 | 
2026-05-20 01:19:49.326879 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-20 01:19:49.373154 | controller | skipping: Conditional result was False
2026-05-20 01:19:49.382096 | 
2026-05-20 01:19:49.382165 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-20 01:19:49.407323 | controller | skipping: Conditional result was False
2026-05-20 01:19:49.417359 | 
2026-05-20 01:19:49.417429 | TASK [configure-swap : Setup swap file on root device]
2026-05-20 01:19:49.470838 | controller | ok
2026-05-20 01:19:49.678286 | controller | included: /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-20 01:19:49.695528 | 
2026-05-20 01:19:49.695717 | TASK [configure-swap : Calculate required swap]
2026-05-20 01:19:49.825957 | controller | ok
2026-05-20 01:19:49.867154 | 
2026-05-20 01:19:49.867280 | TASK [configure-swap : Get root filesystem]
2026-05-20 01:19:50.103414 | controller | ext4
2026-05-20 01:19:50.404713 | controller | ok: Runtime: 0:00:00.011993
2026-05-20 01:19:50.420167 | 
2026-05-20 01:19:50.420289 | TASK [configure-swap : Save root filesystem]
2026-05-20 01:19:50.478047 | controller | ok
2026-05-20 01:19:50.486438 | 
2026-05-20 01:19:50.486562 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-20 01:19:50.525356 | controller | ok:
2026-05-20 01:19:50.525549 | controller | {
2026-05-20 01:19:50.525581 | controller |   "root_filesystem": "ext4"
2026-05-20 01:19:50.525602 | controller | }
2026-05-20 01:19:50.533701 | 
2026-05-20 01:19:50.533808 | TASK [configure-swap : Create swap backing file]
2026-05-20 01:20:00.425874 | controller | 8192+0 records in
2026-05-20 01:20:00.426046 | controller | 8192+0 records out
2026-05-20 01:20:00.426067 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.61723 s, 893 MB/s
2026-05-20 01:20:00.642947 | controller | ok: Runtime: 0:00:09.626798
2026-05-20 01:20:00.655257 | 
2026-05-20 01:20:00.656149 | TASK [configure-swap : Ensure swapfile perms]
2026-05-20 01:20:00.920596 | controller | changed
2026-05-20 01:20:01.078175 | 
2026-05-20 01:20:01.078337 | TASK [configure-swap : Make swapfile]
2026-05-20 01:20:15.058155 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-20 01:20:15.058376 | controller | no label, UUID=8f0e14a4-5e88-405b-8111-0c5ddfeaa7f4
2026-05-20 01:20:15.230130 | controller | ok: Runtime: 0:00:13.633069
2026-05-20 01:20:15.237472 | 
2026-05-20 01:20:15.237643 | TASK [configure-swap : Write swap to fstab]
2026-05-20 01:20:15.638070 | controller | changed
2026-05-20 01:20:15.643581 | 
2026-05-20 01:20:15.643656 | TASK [configure-swap : Add all swap]
2026-05-20 01:20:16.180492 | controller | ok: Runtime: 0:00:00.016675
2026-05-20 01:20:16.187762 | 
2026-05-20 01:20:16.187833 | TASK [configure-swap : Debug the swap_required variable]
2026-05-20 01:20:16.222893 | controller | ok:
2026-05-20 01:20:16.223056 | controller | {
2026-05-20 01:20:16.223083 | controller |   "swap_required": "8192"
2026-05-20 01:20:16.223104 | controller | }
2026-05-20 01:20:16.229285 | 
2026-05-20 01:20:16.229357 | TASK [configure-swap : Set swappiness]
2026-05-20 01:20:16.694287 | controller | changed
2026-05-20 01:20:16.701735 | 
2026-05-20 01:20:16.701823 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-20 01:20:16.735052 | controller | ok:
2026-05-20 01:20:16.735151 | controller | {
2026-05-20 01:20:16.735176 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-20 01:20:16.735199 | controller | }
2026-05-20 01:20:16.762118 | 
2026-05-20 01:20:16.762258 | TASK [setup-stack-user : Create stack group]
2026-05-20 01:20:17.242943 | controller | changed
2026-05-20 01:20:17.250597 | 
2026-05-20 01:20:17.250710 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-20 01:20:17.500496 | controller | changed
2026-05-20 01:20:17.506579 | 
2026-05-20 01:20:17.506645 | TASK [setup-stack-user : Create stack user]
2026-05-20 01:20:18.131442 | controller | changed
2026-05-20 01:20:18.225427 | 
2026-05-20 01:20:18.225571 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-20 01:20:18.484526 | controller | changed
2026-05-20 01:20:18.670211 | 
2026-05-20 01:20:18.670333 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-20 01:20:19.912058 | controller | changed
2026-05-20 01:20:19.979929 | 
2026-05-20 01:20:19.980149 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-20 01:20:20.281837 | controller | changed
2026-05-20 01:20:20.292853 | 
2026-05-20 01:20:20.427984 | TASK [setup-tempest-user : Create tempest group]
2026-05-20 01:20:20.716692 | controller | changed
2026-05-20 01:20:20.729953 | 
2026-05-20 01:20:20.730097 | TASK [setup-tempest-user : Create tempest user]
2026-05-20 01:20:21.098480 | controller | changed
2026-05-20 01:20:21.236545 | 
2026-05-20 01:20:21.236642 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-20 01:20:22.082806 | controller | changed
2026-05-20 01:20:22.100323 | 
2026-05-20 01:20:22.100755 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-20 01:20:22.457668 | controller | ok: Not all paths examined, check warnings for details
2026-05-20 01:20:22.475554 | 
2026-05-20 01:20:22.475669 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-20 01:20:22.984534 | controller | ok: Item: Runtime: 0:00:00.196681
2026-05-20 01:20:23.359305 | controller | ok: Item: Runtime: 0:00:00.139310
2026-05-20 01:20:23.637756 | controller | ok: Item: Runtime: 0:00:00.063996
2026-05-20 01:20:24.141176 | controller | ok: Item: Runtime: 0:00:00.263165
2026-05-20 01:20:24.499636 | controller | ok: Item: Runtime: 0:00:00.148697
2026-05-20 01:20:25.023412 | controller | ok: Item: Runtime: 0:00:00.298614
2026-05-20 01:20:25.766838 | controller | ok: Item: Runtime: 0:00:00.516576
2026-05-20 01:20:26.577929 | controller | ok: Item: Runtime: 0:00:00.573536
2026-05-20 01:20:27.445920 | controller | ok: Item: Runtime: 0:00:00.653736
2026-05-20 01:20:28.850653 | controller | ok: Item: Runtime: 0:00:01.158226
2026-05-20 01:20:29.160088 | controller | ok: Item: Runtime: 0:00:00.105570
2026-05-20 01:20:29.695527 | controller | ok: Item: Runtime: 0:00:00.221740
2026-05-20 01:20:30.629673 | controller | ok: Item: Runtime: 0:00:00.354221
2026-05-20 01:20:33.922906 | controller | ok: Item: Runtime: 0:00:03.064668
2026-05-20 01:20:36.061193 | controller | ok: Item: Runtime: 0:00:01.313041
2026-05-20 01:20:36.070607 | controller | ok: Item: Runtime: 0:00:00.288688
2026-05-20 01:20:36.108538 | 
2026-05-20 01:20:36.108641 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-20 01:20:36.322500 | controller | ok: All paths examined
2026-05-20 01:20:36.334651 | 
2026-05-20 01:20:36.334788 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-20 01:20:36.600886 | controller | ok: All paths examined
2026-05-20 01:20:36.607954 | 
2026-05-20 01:20:36.608051 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-20 01:20:38.690096 | controller | ok: Item: Runtime: 0:00:00.155700
2026-05-20 01:20:39.534316 | controller | changed: All items complete
2026-05-20 01:20:39.534399 | 
2026-05-20 01:20:39.737539 | controller | ok: Item: Runtime: 0:00:00.228171
2026-05-20 01:20:39.758676 | 
2026-05-20 01:20:39.759183 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-20 01:20:39.988825 | controller | skipping: Conditional result was False
2026-05-20 01:20:39.996538 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.001893 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.007938 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.012876 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.017709 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.022491 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.027191 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.033998 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.041176 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.045676 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.049684 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.053277 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.056934 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.060539 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.064129 | controller | skipping: Conditional result was False
2026-05-20 01:20:40.146978 | 
2026-05-20 01:20:40.147126 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-20 01:20:42.530593 | controller | changed
2026-05-20 01:20:42.659945 | 
2026-05-20 01:20:42.660225 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-20 01:20:42.931079 | controller | changed
2026-05-20 01:20:42.942562 | 
2026-05-20 01:20:42.942673 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-20 01:20:43.232056 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-20 01:20:43.573652 | controller | ERROR
2026-05-20 01:20:43.573910 | controller | {
2026-05-20 01:20:43.573963 | controller |   "delta": "0:00:00.011085",
2026-05-20 01:20:43.573994 | controller |   "end": "2026-05-20 01:20:43.232671",
2026-05-20 01:20:43.574033 | controller |   "msg": "non-zero return code",
2026-05-20 01:20:43.574060 | controller |   "rc": 1,
2026-05-20 01:20:43.574086 | controller |   "start": "2026-05-20 01:20:43.221586"
2026-05-20 01:20:43.574123 | controller | }
2026-05-20 01:20:43.574164 | controller | ERROR: Ignoring Errors
2026-05-20 01:20:43.583889 | 
2026-05-20 01:20:43.584012 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-20 01:20:43.839191 | controller | ok
2026-05-20 01:20:43.862810 | 
2026-05-20 01:20:43.863022 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-20 01:20:44.127229 | controller | /usr/bin/journalctl
2026-05-20 01:20:44.414303 | controller | ok: Runtime: 0:00:00.005979
2026-05-20 01:20:44.420636 | 
2026-05-20 01:20:44.420763 | TASK [start-fresh-logging : Get current date]
2026-05-20 01:20:44.676619 | controller | 2026-05-20 01:20:44
2026-05-20 01:20:44.963110 | controller | ok: Runtime: 0:00:00.009197
2026-05-20 01:20:44.970095 | 
2026-05-20 01:20:44.970166 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-20 01:20:45.588438 | controller | changed
2026-05-20 01:20:45.599968 | 
2026-05-20 01:20:45.600095 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-20 01:20:45.631678 | controller | skipping: Conditional result was False
2026-05-20 01:20:45.638459 | 
2026-05-20 01:20:45.638559 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-20 01:20:46.178393 | controller | skipping: Conditional result was False
2026-05-20 01:20:46.190115 | 
2026-05-20 01:20:46.190295 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-20 01:20:46.749260 | controller | skipping: Conditional result was False
2026-05-20 01:20:46.756583 | 
2026-05-20 01:20:46.756722 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-20 01:20:46.822093 | controller | skipping: Conditional result was False
2026-05-20 01:20:46.829463 | 
2026-05-20 01:20:46.829599 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-20 01:20:47.373077 | controller | skipping: Conditional result was False
2026-05-20 01:20:47.382378 | 
2026-05-20 01:20:47.382660 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-20 01:20:48.018100 | controller | skipping: Conditional result was False
2026-05-20 01:20:48.024467 | 
2026-05-20 01:20:48.024553 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-20 01:20:48.071882 | controller | skipping: Conditional result was False
2026-05-20 01:20:48.082306 | 
2026-05-20 01:20:48.082432 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-20 01:20:48.158739 | controller | skipping: Conditional result was False
2026-05-20 01:20:48.167760 | 
2026-05-20 01:20:48.167879 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-20 01:20:48.786572 | controller | skipping: Conditional result was False
2026-05-20 01:20:48.794204 | 
2026-05-20 01:20:48.794291 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-20 01:20:49.410312 | controller | skipping: Conditional result was False
2026-05-20 01:20:49.418833 | 
2026-05-20 01:20:49.419042 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-20 01:20:49.457479 | controller | skipping: Conditional result was False
2026-05-20 01:20:49.465544 | 
2026-05-20 01:20:49.465635 | TASK [start-fresh-logging : Start rsyslog]
2026-05-20 01:20:49.495819 | controller | skipping: Conditional result was False
2026-05-20 01:20:49.557978 | 
2026-05-20 01:20:49.559136 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-20 01:20:50.118510 | controller | ok
2026-05-20 01:20:50.138449 | 
2026-05-20 01:20:50.138540 | PLAY RECAP
2026-05-20 01:20:50.138587 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-20 01:20:50.138609 | 
2026-05-20 01:20:50.365354 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:20:50.373760 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:20:51.562614 | 
2026-05-20 01:20:51.562751 | PLAY [all]
2026-05-20 01:20:51.587327 | 
2026-05-20 01:20:51.589297 | TASK [Install curl]
2026-05-20 01:20:59.002078 | controller | ok
2026-05-20 01:20:59.009753 | 
2026-05-20 01:20:59.009854 | TASK [ensure-rust : Use rustup]
2026-05-20 01:20:59.046260 | controller | ok
2026-05-20 01:20:59.131353 | controller | included: /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-05-20 01:20:59.137681 | 
2026-05-20 01:20:59.137762 | TASK [ensure-rust : Install Rust]
2026-05-20 01:20:59.660634 | controller | info: downloading installer
2026-05-20 01:20:59.975101 | controller | warn: It looks like you have an existing rustup settings file at:
2026-05-20 01:20:59.975159 | controller | warn: /opt/rust/settings.toml
2026-05-20 01:20:59.975171 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-05-20 01:20:59.975187 | controller | warn: instead of the one inferred from the default host triple.
2026-05-20 01:21:00.009682 | controller | info: profile set to default
2026-05-20 01:21:00.009720 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-05-20 01:21:00.011489 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-05-20 01:21:00.249034 | controller | info: latest update on 2026-04-16 for version 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:21:00.249092 | controller | info: downloading 6 components
2026-05-20 01:21:13.374516 | controller | info: default toolchain set to stable-x86_64-unknown-linux-gnu
2026-05-20 01:21:13.374594 | controller |
2026-05-20 01:21:13.394312 | controller |   stable-x86_64-unknown-linux-gnu installed - rustc 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:21:13.394345 | controller |
2026-05-20 01:21:13.394469 | controller |
2026-05-20 01:21:13.394487 | controller | Rust is installed now. Great!
2026-05-20 01:21:13.394499 | controller |
2026-05-20 01:21:13.394512 | controller | To get started you need Cargo's bin directory (/opt/rust/bin) in your PATH
2026-05-20 01:21:13.394523 | controller | environment variable. This has not been done automatically.
2026-05-20 01:21:13.394534 | controller |
2026-05-20 01:21:13.394566 | controller | To configure your current shell, you need to source
2026-05-20 01:21:13.394578 | controller | the corresponding env file under /opt/rust.
2026-05-20 01:21:13.394589 | controller |
2026-05-20 01:21:13.394621 | controller | This is usually done by running one of the following (note the leading DOT):
2026-05-20 01:21:13.394632 | controller | . "/opt/rust/env"            # For sh/bash/zsh/ash/dash/pdksh
2026-05-20 01:21:13.394644 | controller | source "/opt/rust/env.fish"  # For fish
2026-05-20 01:21:13.394656 | controller | source "/opt/rust/env.nu"  # For nushell
2026-05-20 01:21:13.394668 | controller | source "/opt/rust/env.tcsh"  # For tcsh
2026-05-20 01:21:13.394678 | controller | . "/opt/rust/env.ps1"        # For pwsh
2026-05-20 01:21:13.394689 | controller | source "/opt/rust/env.xsh"   # For xonsh
2026-05-20 01:21:13.394706 | controller | warn: no default linker (`cc`) was found in your PATH
2026-05-20 01:21:13.394718 | controller | warn: many Rust crates require a system C toolchain to build
2026-05-20 01:21:13.695363 | controller | ok: Runtime: 0:00:13.908833
2026-05-20 01:21:13.705889 | 
2026-05-20 01:21:13.706135 | TASK [ensure-rust : Install wrapper helper script]
2026-05-20 01:21:14.875874 | controller | changed
2026-05-20 01:21:14.883067 | 
2026-05-20 01:21:14.883134 | TASK [ensure-rust : Run wrapper installation]
2026-05-20 01:21:15.170798 | controller | Install link for cargo
2026-05-20 01:21:15.177236 | controller | Install link for cargo-clippy
2026-05-20 01:21:15.183782 | controller | Install link for cargo-fmt
2026-05-20 01:21:15.189532 | controller | Install link for cargo-miri
2026-05-20 01:21:15.195368 | controller | Install link for clippy-driver
2026-05-20 01:21:15.201082 | controller | Install link for rls
2026-05-20 01:21:15.207093 | controller | Install link for rust-analyzer
2026-05-20 01:21:15.213219 | controller | Install link for rust-gdb
2026-05-20 01:21:15.218820 | controller | Install link for rust-gdbgui
2026-05-20 01:21:15.224447 | controller | Install link for rust-lldb
2026-05-20 01:21:15.230218 | controller | Install link for rustc
2026-05-20 01:21:15.236672 | controller | Install link for rustdoc
2026-05-20 01:21:15.241435 | controller | Install link for rustfmt
2026-05-20 01:21:15.245923 | controller | Install link for rustup
2026-05-20 01:21:15.467700 | controller | ok: Runtime: 0:00:00.098862
2026-05-20 01:21:15.483999 | 
2026-05-20 01:21:15.484166 | TASK [ensure-rust : Install packages]
2026-05-20 01:21:15.519015 | controller | skipping: Conditional result was False
2026-05-20 01:21:15.588681 | 
2026-05-20 01:21:15.588784 | PLAY RECAP
2026-05-20 01:21:15.588826 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:21:15.588849 | 
2026-05-20 01:21:15.755277 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:21:15.761257 | RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:21:16.557718 | 
2026-05-20 01:21:16.557837 | PLAY [all]
2026-05-20 01:21:16.572157 | 
2026-05-20 01:21:16.572254 | TASK [Install DevStack]
2026-05-20 01:21:16.607628 | controller | ok
2026-05-20 01:21:16.632550 | 
2026-05-20 01:21:16.632718 | TASK [Run devstack on the controller]
2026-05-20 01:21:16.663930 | controller | ok
2026-05-20 01:21:16.681604 | 
2026-05-20 01:21:16.681714 | TASK [run-devstack : Run devstack]
2026-05-20 01:21:17.425004 | controller | + unset GREP_OPTIONS
2026-05-20 01:21:17.425146 | controller | + unset LANG
2026-05-20 01:21:17.425166 | controller | + unset LANGUAGE
2026-05-20 01:21:17.425176 | controller | + LC_ALL=en_US.utf8
2026-05-20 01:21:17.429082 | controller | + export LC_ALL
2026-05-20 01:21:17.430172 | controller | ++ env
2026-05-20 01:21:17.430473 | controller | ++ grep -E '^OS_'
2026-05-20 01:21:17.430605 | controller | ++ cut -d = -f 1
2026-05-20 01:21:17.435741 | controller | + unset
2026-05-20 01:21:17.435760 | controller | + umask 022
2026-05-20 01:21:17.435770 | 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:21:17.437740 | controller | +++ dirname ./stack.sh
2026-05-20 01:21:17.440850 | controller | ++ cd .
2026-05-20 01:21:17.440876 | controller | ++ pwd
2026-05-20 01:21:17.441567 | controller | + TOP_DIR=/opt/stack/devstack
2026-05-20 01:21:17.441590 | controller | + NOUNSET=
2026-05-20 01:21:17.441598 | controller | + [[ -n '' ]]
2026-05-20 01:21:17.442180 | controller | ++ date +%s
2026-05-20 01:21:17.443220 | controller | + DEVSTACK_START_TIME=1779240077
2026-05-20 01:21:17.443287 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-05-20 01:21:17.443325 | controller | + FILES=/opt/stack/devstack/files
2026-05-20 01:21:17.443334 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-05-20 01:21:17.443344 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-05-20 01:21:17.443352 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-05-20 01:21:17.443360 | controller | + [[ '' == \y ]]
2026-05-20 01:21:17.443372 | controller | + [[ 1002 -eq 0 ]]
2026-05-20 01:21:17.443382 | controller | + [[ -n '' ]]
2026-05-20 01:21:17.443395 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-05-20 01:21:17.443403 | controller | + LAST_SPINNER_PID=
2026-05-20 01:21:17.443442 | controller | + source /opt/stack/devstack/functions
2026-05-20 01:21:17.443507 | controller | ++ [[ -z '' ]]
2026-05-20 01:21:17.443540 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-05-20 01:21:17.444756 | controller | ++++ dirname /opt/stack/devstack/functions
2026-05-20 01:21:17.445697 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:21:17.445721 | controller | +++ pwd
2026-05-20 01:21:17.446122 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-05-20 01:21:17.446136 | controller | ++ source /opt/stack/devstack/functions-common
2026-05-20 01:21:17.447595 | controller | ++++ set +o
2026-05-20 01:21:17.448948 | controller | ++++ grep xtrace
2026-05-20 01:21:17.452446 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-05-20 01:21:17.452471 | controller | +++ set +o xtrace
2026-05-20 01:21:17.462445 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-05-20 01:21:17.463785 | controller | ++++ set +o
2026-05-20 01:21:17.464399 | controller | ++++ grep xtrace
2026-05-20 01:21:17.467625 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-05-20 01:21:17.467656 | controller | +++ set +o xtrace
2026-05-20 01:21:17.468543 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-05-20 01:21:17.469864 | controller | ++++ set +o
2026-05-20 01:21:17.470075 | controller | ++++ grep xtrace
2026-05-20 01:21:17.473523 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-05-20 01:21:17.473551 | controller | +++ set +o xtrace
2026-05-20 01:21:17.474404 | controller | ++ source /opt/stack/devstack/inc/python
2026-05-20 01:21:17.476346 | controller | ++++ set +o
2026-05-20 01:21:17.476746 | controller | ++++ grep xtrace
2026-05-20 01:21:17.480877 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-05-20 01:21:17.480913 | controller | +++ set +o xtrace
2026-05-20 01:21:17.482773 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-05-20 01:21:17.484687 | controller | ++++ set +o
2026-05-20 01:21:17.485183 | controller | ++++ grep xtrace
2026-05-20 01:21:17.488678 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-05-20 01:21:17.488710 | controller | +++ set +o xtrace
2026-05-20 01:21:17.489019 | controller | ++ source /opt/stack/devstack/inc/async
2026-05-20 01:21:17.490021 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-05-20 01:21:17.490196 | controller | ++++ local xtrace
2026-05-20 01:21:17.492225 | controller | +++++ set +o
2026-05-20 01:21:17.492626 | controller | +++++ grep xtrace
2026-05-20 01:21:17.496284 | controller | ++++ xtrace='set -o xtrace'
2026-05-20 01:21:17.496322 | controller | ++++ set +o xtrace
2026-05-20 01:21:17.497406 | controller | +++ DEVSTACK_PARALLEL=True
2026-05-20 01:21:17.497440 | controller | +++ _ASYNC_BG_TIME=0
2026-05-20 01:21:17.500265 | controller | +++ set +o
2026-05-20 01:21:17.500995 | controller | +++ grep xtrace
2026-05-20 01:21:17.504455 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-05-20 01:21:17.504532 | controller | ++ set +o xtrace
2026-05-20 01:21:17.508758 | controller | + source /opt/stack/devstack/lib/stack
2026-05-20 01:21:17.508962 | controller | + GetDistro
2026-05-20 01:21:17.508999 | controller | + GetOSVersion
2026-05-20 01:21:17.509012 | controller | + source /etc/os-release
2026-05-20 01:21:17.509082 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-05-20 01:21:17.509092 | controller | ++ NAME=Ubuntu
2026-05-20 01:21:17.509101 | controller | ++ VERSION_ID=24.04
2026-05-20 01:21:17.509114 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-05-20 01:21:17.509122 | controller | ++ VERSION_CODENAME=noble
2026-05-20 01:21:17.509130 | controller | ++ ID=ubuntu
2026-05-20 01:21:17.509142 | controller | ++ ID_LIKE=debian
2026-05-20 01:21:17.509151 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-05-20 01:21:17.509159 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-05-20 01:21:17.509171 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-05-20 01:21:17.509181 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-05-20 01:21:17.509193 | controller | ++ UBUNTU_CODENAME=noble
2026-05-20 01:21:17.509201 | controller | ++ LOGO=ubuntu-logo
2026-05-20 01:21:17.509213 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-05-20 01:21:17.509322 | controller | + _ensure_lsb_release
2026-05-20 01:21:17.510412 | controller | ++ command -v lsb_release
2026-05-20 01:21:17.511078 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-05-20 01:21:17.511090 | controller | + return
2026-05-20 01:21:17.512033 | controller | ++ lsb_release -r -s
2026-05-20 01:21:17.530974 | controller | + os_RELEASE=24.04
2026-05-20 01:21:17.532169 | controller | ++ lsb_release -c -s
2026-05-20 01:21:17.549605 | controller | + os_CODENAME=noble
2026-05-20 01:21:17.550620 | controller | ++ lsb_release -i -s
2026-05-20 01:21:17.569847 | controller | + os_VENDOR=Ubuntu
2026-05-20 01:21:17.569886 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:21:17.570000 | controller | + os_PACKAGE=deb
2026-05-20 01:21:17.570015 | controller | + typeset -xr os_VENDOR
2026-05-20 01:21:17.570028 | controller | + typeset -xr os_RELEASE
2026-05-20 01:21:17.570040 | controller | + typeset -xr os_PACKAGE
2026-05-20 01:21:17.570053 | controller | + typeset -xr os_CODENAME
2026-05-20 01:21:17.570147 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-05-20 01:21:17.570185 | controller | + DISTRO=noble
2026-05-20 01:21:17.570199 | controller | + typeset -xr DISTRO
2026-05-20 01:21:17.570334 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:17.573127 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:17.573213 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-05-20 01:21:17.573227 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-05-20 01:21:17.573238 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-05-20 01:21:17.573283 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:17.574104 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-05-20 01:21:17.574182 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-05-20 01:21:17.574192 | controller | ++ local matchgroup=local
2026-05-20 01:21:17.574205 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:17.574258 | controller | ++ awk -v matchgroup=local '
2026-05-20 01:21:17.574273 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:21:17.574281 | controller |             gsub("[][]", "", $1);
2026-05-20 01:21:17.574289 | controller |             split($1, a, "|");
2026-05-20 01:21:17.574298 | controller |             if (a[1] == matchgroup)
2026-05-20 01:21:17.574306 | controller |                 print a[2]
2026-05-20 01:21:17.574314 | controller |         }
2026-05-20 01:21:17.574322 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:21:17.575963 | controller | + LRC=localrc
2026-05-20 01:21:17.575989 | controller | + for lfile in $LRC
2026-05-20 01:21:17.576001 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-05-20 01:21:17.576035 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-05-20 01:21:17.576083 | controller | + echo '# Generated file, do not edit'
2026-05-20 01:21:17.576294 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-05-20 01:21:17.576382 | controller | + local file=/opt/stack/devstack/local.conf
2026-05-20 01:21:17.576395 | controller | + local matchgroup=local
2026-05-20 01:21:17.576404 | controller | + local configfile=localrc
2026-05-20 01:21:17.576415 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:17.576427 | controller | + [[ -z localrc ]]
2026-05-20 01:21:17.576494 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-05-20 01:21:17.576504 | controller |         BEGIN { group = "" }
2026-05-20 01:21:17.576512 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:21:17.576536 | controller |             gsub("[][]", "", $1);
2026-05-20 01:21:17.576545 | controller |             split($1, a, "|");
2026-05-20 01:21:17.576554 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-05-20 01:21:17.576562 | controller |                 group=a[1]
2026-05-20 01:21:17.576571 | controller |             } else {
2026-05-20 01:21:17.576579 | controller |                 group=""
2026-05-20 01:21:17.576587 | controller |             }
2026-05-20 01:21:17.576595 | controller |             next
2026-05-20 01:21:17.576604 | controller |         }
2026-05-20 01:21:17.576612 | controller |         {
2026-05-20 01:21:17.576620 | controller |             if (group != "")
2026-05-20 01:21:17.576628 | controller |                 print $0
2026-05-20 01:21:17.576636 | controller |         }
2026-05-20 01:21:17.576645 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:21:17.579968 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-05-20 01:21:17.579991 | controller | + source /opt/stack/devstack/stackrc
2026-05-20 01:21:17.580136 | controller | ++ [[ -z '' ]]
2026-05-20 01:21:17.580149 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-05-20 01:21:17.582979 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-05-20 01:21:17.585464 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:21:17.585490 | controller | +++ pwd
2026-05-20 01:21:17.586311 | controller | ++ RC_DIR=/opt/stack/devstack
2026-05-20 01:21:17.586381 | controller | ++ source /opt/stack/devstack/functions
2026-05-20 01:21:17.586575 | controller | +++ [[ -z 1 ]]
2026-05-20 01:21:17.586586 | controller | +++ return 0
2026-05-20 01:21:17.586608 | controller | ++ TARGET_BRANCH=master
2026-05-20 01:21:17.586617 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-05-20 01:21:17.586629 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-05-20 01:21:17.586637 | controller | ++ DEST=/opt/stack
2026-05-20 01:21:17.586649 | controller | ++ DATA_DIR=/opt/stack/data
2026-05-20 01:21:17.586657 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-05-20 01:21:17.586668 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-05-20 01:21:17.586718 | controller | ++ [[ 1002 -eq 0 ]]
2026-05-20 01:21:17.587895 | controller | +++ whoami
2026-05-20 01:21:17.591741 | controller | ++ STACK_USER=stack
2026-05-20 01:21:17.591821 | controller | ++ REGION_NAME=RegionOne
2026-05-20 01:21:17.591836 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-05-20 01:21:17.591951 | controller | ++ isset ENABLED_SERVICES
2026-05-20 01:21:17.591965 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-05-20 01:21:17.591999 | controller | ++ ENABLED_SERVICES=key
2026-05-20 01:21:17.592009 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-05-20 01:21:17.592021 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-05-20 01:21:17.592030 | controller | ++ ENABLED_SERVICES+=,g-api
2026-05-20 01:21:17.592038 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-05-20 01:21:17.592047 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-05-20 01:21:17.592059 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-05-20 01:21:17.592068 | controller | ++ ENABLED_SERVICES+=,horizon
2026-05-20 01:21:17.592076 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-05-20 01:21:17.592088 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-05-20 01:21:17.592096 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-05-20 01:21:17.592108 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-05-20 01:21:17.592119 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-05-20 01:21:17.592166 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:17.592215 | controller | +++ disable_all_services
2026-05-20 01:21:17.592228 | controller | +++ ENABLED_SERVICES=
2026-05-20 01:21:17.592240 | controller | +++ enable_service c-api
2026-05-20 01:21:17.592362 | controller | +++ local xtrace
2026-05-20 01:21:17.594316 | controller | ++++ set +o
2026-05-20 01:21:17.594732 | controller | ++++ grep xtrace
2026-05-20 01:21:17.598295 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.598339 | controller | +++ set +o xtrace
2026-05-20 01:21:17.640434 | controller | +++ enable_service c-bak
2026-05-20 01:21:17.640466 | controller | +++ local xtrace
2026-05-20 01:21:17.642274 | controller | ++++ set +o
2026-05-20 01:21:17.642619 | controller | ++++ grep xtrace
2026-05-20 01:21:17.646058 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.646083 | controller | +++ set +o xtrace
2026-05-20 01:21:17.685926 | controller | +++ enable_service c-sch
2026-05-20 01:21:17.685962 | controller | +++ local xtrace
2026-05-20 01:21:17.687942 | controller | ++++ set +o
2026-05-20 01:21:17.688504 | controller | ++++ grep xtrace
2026-05-20 01:21:17.692591 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.692619 | controller | +++ set +o xtrace
2026-05-20 01:21:17.733999 | controller | +++ enable_service c-vol
2026-05-20 01:21:17.734035 | controller | +++ local xtrace
2026-05-20 01:21:17.735621 | controller | ++++ set +o
2026-05-20 01:21:17.735820 | controller | ++++ grep xtrace
2026-05-20 01:21:17.739192 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.739229 | controller | +++ set +o xtrace
2026-05-20 01:21:17.779631 | controller | +++ disable_service dstat
2026-05-20 01:21:17.779665 | controller | +++ local xtrace
2026-05-20 01:21:17.781612 | controller | ++++ set +o
2026-05-20 01:21:17.781779 | controller | ++++ grep xtrace
2026-05-20 01:21:17.784019 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.784046 | controller | +++ set +o xtrace
2026-05-20 01:21:17.814116 | controller | +++ enable_service etcd3
2026-05-20 01:21:17.814151 | controller | +++ local xtrace
2026-05-20 01:21:17.815818 | controller | ++++ set +o
2026-05-20 01:21:17.816143 | controller | ++++ grep xtrace
2026-05-20 01:21:17.819433 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.819460 | controller | +++ set +o xtrace
2026-05-20 01:21:17.860322 | controller | +++ enable_service file_tracker
2026-05-20 01:21:17.860357 | controller | +++ local xtrace
2026-05-20 01:21:17.861963 | controller | ++++ set +o
2026-05-20 01:21:17.862142 | controller | ++++ grep xtrace
2026-05-20 01:21:17.865689 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.865716 | controller | +++ set +o xtrace
2026-05-20 01:21:17.907154 | controller | +++ enable_service g-api
2026-05-20 01:21:17.907216 | controller | +++ local xtrace
2026-05-20 01:21:17.908946 | controller | ++++ set +o
2026-05-20 01:21:17.909747 | controller | ++++ grep xtrace
2026-05-20 01:21:17.913416 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.913442 | controller | +++ set +o xtrace
2026-05-20 01:21:17.953709 | controller | +++ disable_service horizon
2026-05-20 01:21:17.953745 | controller | +++ local xtrace
2026-05-20 01:21:17.955484 | controller | ++++ set +o
2026-05-20 01:21:17.955877 | controller | ++++ grep xtrace
2026-05-20 01:21:17.958178 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.958210 | controller | +++ set +o xtrace
2026-05-20 01:21:17.988451 | controller | +++ enable_service key
2026-05-20 01:21:17.988489 | controller | +++ local xtrace
2026-05-20 01:21:17.989765 | controller | ++++ set +o
2026-05-20 01:21:17.990417 | controller | ++++ grep xtrace
2026-05-20 01:21:17.993675 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.993721 | controller | +++ set +o xtrace
2026-05-20 01:21:18.035090 | controller | +++ enable_service memory_tracker
2026-05-20 01:21:18.035134 | controller | +++ local xtrace
2026-05-20 01:21:18.037449 | controller | ++++ set +o
2026-05-20 01:21:18.037543 | controller | ++++ grep xtrace
2026-05-20 01:21:18.040771 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.040792 | controller | +++ set +o xtrace
2026-05-20 01:21:18.083433 | controller | +++ enable_service mysql
2026-05-20 01:21:18.083470 | controller | +++ local xtrace
2026-05-20 01:21:18.085412 | controller | ++++ set +o
2026-05-20 01:21:18.085746 | controller | ++++ grep xtrace
2026-05-20 01:21:18.089492 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.089516 | controller | +++ set +o xtrace
2026-05-20 01:21:18.130739 | controller | +++ enable_service n-api
2026-05-20 01:21:18.130774 | controller | +++ local xtrace
2026-05-20 01:21:18.132632 | controller | ++++ set +o
2026-05-20 01:21:18.133026 | controller | ++++ grep xtrace
2026-05-20 01:21:18.136511 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.136539 | controller | +++ set +o xtrace
2026-05-20 01:21:18.179395 | controller | +++ enable_service n-api-meta
2026-05-20 01:21:18.179420 | controller | +++ local xtrace
2026-05-20 01:21:18.181401 | controller | ++++ set +o
2026-05-20 01:21:18.181483 | controller | ++++ grep xtrace
2026-05-20 01:21:18.183907 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.183929 | controller | +++ set +o xtrace
2026-05-20 01:21:18.226628 | controller | +++ enable_service n-cond
2026-05-20 01:21:18.226670 | controller | +++ local xtrace
2026-05-20 01:21:18.228713 | controller | ++++ set +o
2026-05-20 01:21:18.229092 | controller | ++++ grep xtrace
2026-05-20 01:21:18.232723 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.232760 | controller | +++ set +o xtrace
2026-05-20 01:21:18.274875 | controller | +++ enable_service n-cpu
2026-05-20 01:21:18.274913 | controller | +++ local xtrace
2026-05-20 01:21:18.276983 | controller | ++++ set +o
2026-05-20 01:21:18.277288 | controller | ++++ grep xtrace
2026-05-20 01:21:18.280988 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.281027 | controller | +++ set +o xtrace
2026-05-20 01:21:18.321983 | controller | +++ enable_service n-novnc
2026-05-20 01:21:18.324351 | controller | +++ local xtrace
2026-05-20 01:21:18.326376 | controller | ++++ set +o
2026-05-20 01:21:18.326856 | controller | ++++ grep xtrace
2026-05-20 01:21:18.330608 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.330644 | controller | +++ set +o xtrace
2026-05-20 01:21:18.371175 | controller | +++ enable_service n-sch
2026-05-20 01:21:18.371208 | controller | +++ local xtrace
2026-05-20 01:21:18.373422 | controller | ++++ set +o
2026-05-20 01:21:18.373484 | controller | ++++ grep xtrace
2026-05-20 01:21:18.376899 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.376923 | controller | +++ set +o xtrace
2026-05-20 01:21:18.419585 | controller | +++ enable_service o-api
2026-05-20 01:21:18.419619 | controller | +++ local xtrace
2026-05-20 01:21:18.421091 | controller | ++++ set +o
2026-05-20 01:21:18.421202 | controller | ++++ grep xtrace
2026-05-20 01:21:18.423539 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.423569 | controller | +++ set +o xtrace
2026-05-20 01:21:18.466299 | controller | +++ enable_service o-da
2026-05-20 01:21:18.466332 | controller | +++ local xtrace
2026-05-20 01:21:18.468093 | controller | ++++ set +o
2026-05-20 01:21:18.468595 | controller | ++++ grep xtrace
2026-05-20 01:21:18.472085 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.472112 | controller | +++ set +o xtrace
2026-05-20 01:21:18.516704 | controller | +++ enable_service o-hk
2026-05-20 01:21:18.516739 | controller | +++ local xtrace
2026-05-20 01:21:18.518509 | controller | ++++ set +o
2026-05-20 01:21:18.519082 | controller | ++++ grep xtrace
2026-05-20 01:21:18.522529 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.522564 | controller | +++ set +o xtrace
2026-05-20 01:21:18.562632 | controller | +++ enable_service octavia
2026-05-20 01:21:18.562667 | controller | +++ local xtrace
2026-05-20 01:21:18.564467 | controller | ++++ set +o
2026-05-20 01:21:18.564889 | controller | ++++ grep xtrace
2026-05-20 01:21:18.568301 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.568331 | controller | +++ set +o xtrace
2026-05-20 01:21:18.610102 | controller | +++ enable_service openstack-cli-server
2026-05-20 01:21:18.610135 | controller | +++ local xtrace
2026-05-20 01:21:18.612058 | controller | ++++ set +o
2026-05-20 01:21:18.612333 | controller | ++++ grep xtrace
2026-05-20 01:21:18.614895 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.614922 | controller | +++ set +o xtrace
2026-05-20 01:21:18.656546 | controller | +++ enable_service ovn-controller
2026-05-20 01:21:18.656577 | controller | +++ local xtrace
2026-05-20 01:21:18.658161 | controller | ++++ set +o
2026-05-20 01:21:18.658886 | controller | ++++ grep xtrace
2026-05-20 01:21:18.662405 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.662431 | controller | +++ set +o xtrace
2026-05-20 01:21:18.705426 | controller | +++ enable_service ovn-northd
2026-05-20 01:21:18.705461 | controller | +++ local xtrace
2026-05-20 01:21:18.707297 | controller | ++++ set +o
2026-05-20 01:21:18.707608 | controller | ++++ grep xtrace
2026-05-20 01:21:18.710615 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.710642 | controller | +++ set +o xtrace
2026-05-20 01:21:18.753453 | controller | +++ enable_service ovs-vswitchd
2026-05-20 01:21:18.753489 | controller | +++ local xtrace
2026-05-20 01:21:18.755353 | controller | ++++ set +o
2026-05-20 01:21:18.755851 | controller | ++++ grep xtrace
2026-05-20 01:21:18.759564 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.759600 | controller | +++ set +o xtrace
2026-05-20 01:21:18.803097 | controller | +++ enable_service ovsdb-server
2026-05-20 01:21:18.803129 | controller | +++ local xtrace
2026-05-20 01:21:18.804827 | controller | ++++ set +o
2026-05-20 01:21:18.805217 | controller | ++++ grep xtrace
2026-05-20 01:21:18.808701 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.808726 | controller | +++ set +o xtrace
2026-05-20 01:21:18.850337 | controller | +++ enable_service placement-api
2026-05-20 01:21:18.850372 | controller | +++ local xtrace
2026-05-20 01:21:18.852053 | controller | ++++ set +o
2026-05-20 01:21:18.852432 | controller | ++++ grep xtrace
2026-05-20 01:21:18.855753 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.855773 | controller | +++ set +o xtrace
2026-05-20 01:21:18.896513 | controller | +++ enable_service q-ovn-agent
2026-05-20 01:21:18.896553 | controller | +++ local xtrace
2026-05-20 01:21:18.899272 | controller | ++++ set +o
2026-05-20 01:21:18.899301 | controller | ++++ grep xtrace
2026-05-20 01:21:18.901605 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.901641 | controller | +++ set +o xtrace
2026-05-20 01:21:18.942769 | controller | +++ enable_service q-svc
2026-05-20 01:21:18.942803 | controller | +++ local xtrace
2026-05-20 01:21:18.944399 | controller | ++++ set +o
2026-05-20 01:21:18.944590 | controller | ++++ grep xtrace
2026-05-20 01:21:18.947993 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.948022 | controller | +++ set +o xtrace
2026-05-20 01:21:18.991043 | controller | +++ enable_service rabbit
2026-05-20 01:21:18.991078 | controller | +++ local xtrace
2026-05-20 01:21:18.993062 | controller | ++++ set +o
2026-05-20 01:21:18.993430 | controller | ++++ grep xtrace
2026-05-20 01:21:18.996954 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:18.996982 | controller | +++ set +o xtrace
2026-05-20 01:21:19.039312 | controller | +++ disable_service s-account
2026-05-20 01:21:19.039352 | controller | +++ local xtrace
2026-05-20 01:21:19.041136 | controller | ++++ set +o
2026-05-20 01:21:19.041364 | controller | ++++ grep xtrace
2026-05-20 01:21:19.043524 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.043563 | controller | +++ set +o xtrace
2026-05-20 01:21:19.072810 | controller | +++ disable_service s-container
2026-05-20 01:21:19.072852 | controller | +++ local xtrace
2026-05-20 01:21:19.074879 | controller | ++++ set +o
2026-05-20 01:21:19.075325 | controller | ++++ grep xtrace
2026-05-20 01:21:19.079129 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.079152 | controller | +++ set +o xtrace
2026-05-20 01:21:19.106987 | controller | +++ disable_service s-object
2026-05-20 01:21:19.107021 | controller | +++ local xtrace
2026-05-20 01:21:19.108979 | controller | ++++ set +o
2026-05-20 01:21:19.109320 | controller | ++++ grep xtrace
2026-05-20 01:21:19.112756 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.112786 | controller | +++ set +o xtrace
2026-05-20 01:21:19.141321 | controller | +++ disable_service s-proxy
2026-05-20 01:21:19.141359 | controller | +++ local xtrace
2026-05-20 01:21:19.143367 | controller | ++++ set +o
2026-05-20 01:21:19.143767 | controller | ++++ grep xtrace
2026-05-20 01:21:19.147410 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.147439 | controller | +++ set +o xtrace
2026-05-20 01:21:19.175984 | controller | +++ disable_service tempest
2026-05-20 01:21:19.176021 | controller | +++ local xtrace
2026-05-20 01:21:19.177964 | controller | ++++ set +o
2026-05-20 01:21:19.178342 | controller | ++++ grep xtrace
2026-05-20 01:21:19.181680 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.181709 | controller | +++ set +o xtrace
2026-05-20 01:21:19.209931 | controller | +++ enable_service tls-proxy
2026-05-20 01:21:19.209969 | controller | +++ local xtrace
2026-05-20 01:21:19.211693 | controller | ++++ set +o
2026-05-20 01:21:19.212084 | controller | ++++ grep xtrace
2026-05-20 01:21:19.215521 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.215553 | controller | +++ set +o xtrace
2026-05-20 01:21:19.257776 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-05-20 01:21:19.257811 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-05-20 01:21:19.257821 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-05-20 01:21:19.257829 | controller | +++ DISABLE_AMP_IMAGE_BUILD=True
2026-05-20 01:21:19.257838 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:19.257852 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:19.257861 | controller | +++ ENABLE_ZSWAP=True
2026-05-20 01:21:19.257870 | controller | +++ ERROR_ON_CLONE=True
2026-05-20 01:21:19.257878 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-05-20 01:21:19.257886 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:21:19.257895 | controller | +++ GIT_BASE=https://github.com
2026-05-20 01:21:19.257903 | controller | +++ HOST_IP=199.204.45.30
2026-05-20 01:21:19.257911 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:21:19.257920 | controller | +++ LIBVIRT_TYPE=qemu
2026-05-20 01:21:19.257928 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-05-20 01:21:19.257940 | controller | +++ LOG_COLOR=False
2026-05-20 01:21:19.257949 | controller | +++ MAGNUM_GUEST_IMAGE_URL=https://github.com/vexxhost/capo-image-elements/releases/latest/download/ubuntu-22.04-v1.33.11.qcow2
2026-05-20 01:21:19.257959 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS='snapshot_support=True create_share_from_snapshot_support=True'
2026-05-20 01:21:19.257967 | controller | +++ MANILA_ENABLED_BACKENDS=generic
2026-05-20 01:21:19.257975 | controller | +++ MANILA_USE_SERVICE_INSTANCE_PASSWORD=True
2026-05-20 01:21:19.257984 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-05-20 01:21:19.257992 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-05-20 01:21:19.258005 | controller | +++ NOVA_VNC_ENABLED=True
2026-05-20 01:21:19.258014 | controller | +++ OCTAVIA_NODE=api
2026-05-20 01:21:19.258022 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-05-20 01:21:19.258030 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-05-20 01:21:19.258038 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-05-20 01:21:19.258047 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-05-20 01:21:19.258055 | controller | +++ SERVICE_HOST=199.204.45.30
2026-05-20 01:21:19.258067 | controller | +++ SERVICE_PASSWORD=secretservice
2026-05-20 01:21:19.258075 | controller | +++ SWIFT_HASH=1234123412341234
2026-05-20 01:21:19.258084 | controller | +++ SWIFT_REPLICAS=1
2026-05-20 01:21:19.258092 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-05-20 01:21:19.258100 | controller | +++ VERBOSE=True
2026-05-20 01:21:19.258130 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:19.258144 | 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:21:19.258154 | controller | +++ enable_plugin barbican https://github.com/openstack/barbican
2026-05-20 01:21:19.258162 | controller | +++ local name=barbican
2026-05-20 01:21:19.258175 | controller | +++ local url=https://github.com/openstack/barbican
2026-05-20 01:21:19.258183 | controller | +++ local branch=master
2026-05-20 01:21:19.258195 | controller | +++ is_plugin_enabled barbican
2026-05-20 01:21:19.258207 | controller | +++ local name=barbican
2026-05-20 01:21:19.258236 | controller | +++ [[ ,, =~ ,barbican, ]]
2026-05-20 01:21:19.258358 | controller | +++ return 1
2026-05-20 01:21:19.258373 | controller | +++ DEVSTACK_PLUGINS+=,barbican
2026-05-20 01:21:19.258382 | controller | +++ GITREPO[$name]=https://github.com/openstack/barbican
2026-05-20 01:21:19.258394 | controller | +++ GITDIR[$name]=/opt/stack/barbican
2026-05-20 01:21:19.258427 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:19.258471 | controller | +++ enable_plugin magnum https://review.opendev.org/openstack/magnum
2026-05-20 01:21:19.258484 | controller | +++ local name=magnum
2026-05-20 01:21:19.258496 | controller | +++ local url=https://review.opendev.org/openstack/magnum
2026-05-20 01:21:19.258508 | controller | +++ local branch=master
2026-05-20 01:21:19.258519 | controller | +++ is_plugin_enabled magnum
2026-05-20 01:21:19.258531 | controller | +++ local name=magnum
2026-05-20 01:21:19.258565 | controller | +++ [[ ,,barbican, =~ ,magnum, ]]
2026-05-20 01:21:19.258609 | controller | +++ return 1
2026-05-20 01:21:19.258618 | controller | +++ DEVSTACK_PLUGINS+=,magnum
2026-05-20 01:21:19.258631 | controller | +++ GITREPO[$name]=https://review.opendev.org/openstack/magnum
2026-05-20 01:21:19.258639 | controller | +++ GITDIR[$name]=/opt/stack/magnum
2026-05-20 01:21:19.258651 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:19.258673 | controller | +++ enable_plugin octavia https://github.com/openstack/octavia
2026-05-20 01:21:19.258686 | controller | +++ local name=octavia
2026-05-20 01:21:19.258694 | controller | +++ local url=https://github.com/openstack/octavia
2026-05-20 01:21:19.258707 | controller | +++ local branch=master
2026-05-20 01:21:19.258715 | controller | +++ is_plugin_enabled octavia
2026-05-20 01:21:19.258727 | controller | +++ local name=octavia
2026-05-20 01:21:19.258739 | controller | +++ [[ ,,barbican,magnum, =~ ,octavia, ]]
2026-05-20 01:21:19.258776 | controller | +++ return 1
2026-05-20 01:21:19.258785 | controller | +++ DEVSTACK_PLUGINS+=,octavia
2026-05-20 01:21:19.258797 | controller | +++ GITREPO[$name]=https://github.com/openstack/octavia
2026-05-20 01:21:19.258806 | controller | +++ GITDIR[$name]=/opt/stack/octavia
2026-05-20 01:21:19.258818 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:19.258830 | controller | +++ enable_plugin manila https://github.com/openstack/manila
2026-05-20 01:21:19.258841 | controller | +++ local name=manila
2026-05-20 01:21:19.258853 | controller | +++ local url=https://github.com/openstack/manila
2026-05-20 01:21:19.258865 | controller | +++ local branch=master
2026-05-20 01:21:19.258876 | controller | +++ is_plugin_enabled manila
2026-05-20 01:21:19.258888 | controller | +++ local name=manila
2026-05-20 01:21:19.258900 | controller | +++ [[ ,,barbican,magnum,octavia, =~ ,manila, ]]
2026-05-20 01:21:19.258934 | controller | +++ return 1
2026-05-20 01:21:19.258946 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-05-20 01:21:19.258955 | controller | +++ GITREPO[$name]=https://github.com/openstack/manila
2026-05-20 01:21:19.258970 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-05-20 01:21:19.258979 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:19.258991 | controller | +++ enable_plugin magnum-cluster-api https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:19.259005 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:21:19.259018 | controller | +++ local url=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:19.259029 | controller | +++ local branch=master
2026-05-20 01:21:19.259041 | controller | +++ is_plugin_enabled magnum-cluster-api
2026-05-20 01:21:19.259052 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:21:19.259064 | controller | +++ [[ ,,barbican,magnum,octavia,manila, =~ ,magnum-cluster-api, ]]
2026-05-20 01:21:19.259141 | controller | +++ return 1
2026-05-20 01:21:19.259152 | controller | +++ DEVSTACK_PLUGINS+=,magnum-cluster-api
2026-05-20 01:21:19.259168 | controller | +++ GITREPO[$name]=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:19.259193 | controller | +++ GITDIR[$name]=/opt/stack/magnum-cluster-api
2026-05-20 01:21:19.259205 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:19.259222 | controller | +++ enable_plugin ovn-octavia-provider https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:19.259234 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:21:19.259280 | controller | +++ local url=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:19.259295 | controller | +++ local branch=master
2026-05-20 01:21:19.259303 | controller | +++ is_plugin_enabled ovn-octavia-provider
2026-05-20 01:21:19.259312 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:21:19.259321 | controller | +++ [[ ,,barbican,magnum,octavia,manila,magnum-cluster-api, =~ ,ovn-octavia-provider, ]]
2026-05-20 01:21:19.259332 | controller | +++ return 1
2026-05-20 01:21:19.259344 | controller | +++ DEVSTACK_PLUGINS+=,ovn-octavia-provider
2026-05-20 01:21:19.259353 | controller | +++ GITREPO[$name]=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:19.259364 | controller | +++ GITDIR[$name]=/opt/stack/ovn-octavia-provider
2026-05-20 01:21:19.259373 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:19.259496 | controller | ++ CELLSV2_SETUP=superconductor
2026-05-20 01:21:19.259507 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-05-20 01:21:19.260604 | controller | +++ trueorfalse False USER_UNITS
2026-05-20 01:21:19.260806 | controller | +++ local xtrace
2026-05-20 01:21:19.262742 | controller | ++++ set +o
2026-05-20 01:21:19.263146 | controller | ++++ grep xtrace
2026-05-20 01:21:19.266559 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.266627 | controller | +++ set +o xtrace
2026-05-20 01:21:19.267617 | controller | ++ USER_UNITS=False
2026-05-20 01:21:19.267725 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:21:19.267736 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-05-20 01:21:19.267744 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-05-20 01:21:19.267757 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-05-20 01:21:19.268918 | controller | +++ _get_python_version python3
2026-05-20 01:21:19.269060 | controller | +++ local interp=python3
2026-05-20 01:21:19.269071 | controller | +++ local version
2026-05-20 01:21:19.270229 | controller | ++++ which python3
2026-05-20 01:21:19.274184 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-05-20 01:21:19.275519 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-05-20 01:21:19.292108 | controller | +++ version=3.12
2026-05-20 01:21:19.292145 | controller | +++ echo 3.12
2026-05-20 01:21:19.293014 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-05-20 01:21:19.293111 | controller | ++ export PYTHON3_VERSION=3.12
2026-05-20 01:21:19.293121 | controller | ++ PYTHON3_VERSION=3.12
2026-05-20 01:21:19.293133 | controller | ++ export 'VIRTUALENV_CMD=python3.12 -m venv'
2026-05-20 01:21:19.293142 | controller | ++ VIRTUALENV_CMD='python3.12 -m venv'
2026-05-20 01:21:19.293197 | controller | ++ '[' -t 1 ']'
2026-05-20 01:21:19.293207 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-05-20 01:21:19.294704 | controller | +++ trueorfalse False LOG_COLOR
2026-05-20 01:21:19.294941 | controller | +++ local xtrace
2026-05-20 01:21:19.297208 | controller | ++++ set +o
2026-05-20 01:21:19.297674 | controller | ++++ grep xtrace
2026-05-20 01:21:19.301289 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:19.301363 | controller | +++ set +o xtrace
2026-05-20 01:21:19.302452 | controller | ++ LOG_COLOR=False
2026-05-20 01:21:19.302624 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:21:19.302635 | controller | ++ export 'PS4=+ $(short_source):   '
2026-05-20 01:21:19.302644 | controller | ++ PS4='+ $(short_source):   '
2026-05-20 01:21:19.308371 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-05-20 01:21:19.312657 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.319183 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.319722 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.326971 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.331320 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.336169 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-05-20 01:21:19.340476 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-05-20 01:21:19.345051 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-05-20 01:21:19.350699 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-05-20 01:21:19.354808 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.361148 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.361913 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.369451 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.373639 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.379010 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-05-20 01:21:19.385214 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-05-20 01:21:19.389408 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.395854 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.395901 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.403393 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.407816 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.413259 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-05-20 01:21:19.417619 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-05-20 01:21:19.423141 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-05-20 01:21:19.427501 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.433322 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.434481 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.442368 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.446280 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.451110 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-05-20 01:21:19.456729 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-05-20 01:21:19.460872 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.467219 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.467781 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.475040 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.479292 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.484952 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-05-20 01:21:19.490528 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-05-20 01:21:19.494224 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.500321 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.501038 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.508898 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.512950 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.517847 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-05-20 01:21:19.522022 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-05-20 01:21:19.526070 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-05-20 01:21:19.530073 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-05-20 01:21:19.534477 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://github.com
2026-05-20 01:21:19.538729 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-05-20 01:21:19.543220 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.2
2026-05-20 01:21:19.547746 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://github.com/openstack/cinder.git
2026-05-20 01:21:19.552611 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-05-20 01:21:19.557462 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://github.com/openstack/glance.git
2026-05-20 01:21:19.561376 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-05-20 01:21:19.564858 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://github.com/openstack/horizon.git
2026-05-20 01:21:19.569158 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-05-20 01:21:19.573070 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://github.com/openstack/keystone.git
2026-05-20 01:21:19.577146 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-05-20 01:21:19.580982 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://github.com/openstack/neutron.git
2026-05-20 01:21:19.584673 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-05-20 01:21:19.588566 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://github.com/openstack/nova.git
2026-05-20 01:21:19.592976 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-05-20 01:21:19.597098 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://github.com/openstack/swift.git
2026-05-20 01:21:19.601504 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-05-20 01:21:19.605704 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://github.com/openstack/placement.git
2026-05-20 01:21:19.609582 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-05-20 01:21:19.614586 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://github.com/openstack/requirements.git
2026-05-20 01:21:19.618151 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-05-20 01:21:19.621866 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://github.com/openstack/tempest.git
2026-05-20 01:21:19.625526 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-05-20 01:21:19.629063 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-05-20 01:21:19.633649 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://github.com/openstack/os-test-images.git
2026-05-20 01:21:19.637676 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-05-20 01:21:19.641697 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-05-20 01:21:19.646206 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://github.com/openstack/python-cinderclient.git
2026-05-20 01:21:19.649940 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-05-20 01:21:19.653417 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://github.com/openstack/python-brick-cinderclient-ext.git
2026-05-20 01:21:19.658525 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-05-20 01:21:19.662602 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://github.com/openstack/python-barbicanclient.git
2026-05-20 01:21:19.666219 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-05-20 01:21:19.671448 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-05-20 01:21:19.674610 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://github.com/openstack/python-glanceclient.git
2026-05-20 01:21:19.678713 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-05-20 01:21:19.682515 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://github.com/openstack/python-ironicclient.git
2026-05-20 01:21:19.686578 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-05-20 01:21:19.690780 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-05-20 01:21:19.695078 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://github.com/openstack/keystoneauth.git
2026-05-20 01:21:19.698746 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-05-20 01:21:19.702536 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://github.com/openstack/python-keystoneclient.git
2026-05-20 01:21:19.706644 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-05-20 01:21:19.709820 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://github.com/openstack/python-neutronclient.git
2026-05-20 01:21:19.714090 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-05-20 01:21:19.717486 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://github.com/openstack/python-novaclient.git
2026-05-20 01:21:19.720829 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-05-20 01:21:19.724583 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://github.com/openstack/python-swiftclient.git
2026-05-20 01:21:19.728733 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-05-20 01:21:19.733477 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://github.com/openstack/python-openstackclient.git
2026-05-20 01:21:19.737458 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-05-20 01:21:19.740809 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-05-20 01:21:19.745188 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://github.com/openstack/osc-placement.git
2026-05-20 01:21:19.749198 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-05-20 01:21:19.753645 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://github.com/openstack/castellan.git
2026-05-20 01:21:19.757916 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-05-20 01:21:19.761914 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://github.com/openstack/cliff.git
2026-05-20 01:21:19.766264 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-05-20 01:21:19.770616 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://github.com/openstack/futurist.git
2026-05-20 01:21:19.774701 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-05-20 01:21:19.778704 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://github.com/openstack/debtcollector.git
2026-05-20 01:21:19.782677 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-05-20 01:21:19.786547 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://github.com/openstack/etcd3gw.git
2026-05-20 01:21:19.790498 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-05-20 01:21:19.794521 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://github.com/openstack/automaton.git
2026-05-20 01:21:19.798614 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-05-20 01:21:19.802906 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://github.com/openstack/oslo.cache.git
2026-05-20 01:21:19.806815 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-05-20 01:21:19.810830 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://github.com/openstack/oslo.concurrency.git
2026-05-20 01:21:19.815676 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-05-20 01:21:19.820592 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://github.com/openstack/oslo.config.git
2026-05-20 01:21:19.824826 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-05-20 01:21:19.828411 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://github.com/openstack/oslo.context.git
2026-05-20 01:21:19.832429 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-05-20 01:21:19.836651 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://github.com/openstack/oslo.db.git
2026-05-20 01:21:19.840996 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-05-20 01:21:19.845103 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://github.com/openstack/oslo.i18n.git
2026-05-20 01:21:19.848983 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-05-20 01:21:19.852898 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://github.com/openstack/oslo.limit.git
2026-05-20 01:21:19.856946 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-05-20 01:21:19.861122 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://github.com/openstack/oslo.log.git
2026-05-20 01:21:19.865551 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-05-20 01:21:19.869608 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://github.com/openstack/oslo.messaging.git
2026-05-20 01:21:19.873624 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-05-20 01:21:19.877415 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://github.com/openstack/oslo.middleware.git
2026-05-20 01:21:19.881362 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-05-20 01:21:19.885280 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://github.com/openstack/oslo.policy.git
2026-05-20 01:21:19.889071 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-05-20 01:21:19.893175 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://github.com/openstack/oslo.privsep.git
2026-05-20 01:21:19.897230 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-05-20 01:21:19.901361 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://github.com/openstack/oslo.reports.git
2026-05-20 01:21:19.905303 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-05-20 01:21:19.909187 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://github.com/openstack/oslo.rootwrap.git
2026-05-20 01:21:19.913066 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-05-20 01:21:19.916887 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://github.com/openstack/oslo.serialization.git
2026-05-20 01:21:19.920858 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-05-20 01:21:19.924691 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://github.com/openstack/oslo.service.git
2026-05-20 01:21:19.928797 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-05-20 01:21:19.932097 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://github.com/openstack/oslo.utils.git
2026-05-20 01:21:19.936314 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-05-20 01:21:19.940819 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://github.com/openstack/oslo.versionedobjects.git
2026-05-20 01:21:19.944819 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-05-20 01:21:19.948912 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://github.com/openstack/oslo.vmware.git
2026-05-20 01:21:19.953194 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-05-20 01:21:19.957066 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://github.com/openstack/osprofiler.git
2026-05-20 01:21:19.961048 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-05-20 01:21:19.964955 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://github.com/openstack/pycadf.git
2026-05-20 01:21:19.968977 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-05-20 01:21:19.973485 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://github.com/openstack/stevedore.git
2026-05-20 01:21:19.978231 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-05-20 01:21:19.982475 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://github.com/openstack/taskflow.git
2026-05-20 01:21:19.986453 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-05-20 01:21:19.990576 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://github.com/openstack/tooz.git
2026-05-20 01:21:19.994198 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-05-20 01:21:19.997568 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://github.com/openstack/pbr.git
2026-05-20 01:21:20.001021 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-05-20 01:21:20.004960 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://github.com/openstack/cursive.git
2026-05-20 01:21:20.008604 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-05-20 01:21:20.012471 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://github.com/openstack/glance_store.git
2026-05-20 01:21:20.016659 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-05-20 01:21:20.020794 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://github.com/openstack/keystonemiddleware.git
2026-05-20 01:21:20.024682 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-05-20 01:21:20.028881 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://github.com/openstack/ceilometermiddleware.git
2026-05-20 01:21:20.033130 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-05-20 01:21:20.037160 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-05-20 01:21:20.041677 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://github.com/openstack/openstacksdk.git
2026-05-20 01:21:20.045935 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-05-20 01:21:20.050278 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://github.com/openstack/os-brick.git
2026-05-20 01:21:20.054575 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-05-20 01:21:20.058607 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://github.com/openstack/os-client-config.git
2026-05-20 01:21:20.063004 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-05-20 01:21:20.067265 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-05-20 01:21:20.071191 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://github.com/openstack/os-vif.git
2026-05-20 01:21:20.075283 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-05-20 01:21:20.079660 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://github.com/openstack/osc-lib.git
2026-05-20 01:21:20.083756 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-05-20 01:21:20.087991 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://github.com/openstack/ironic-lib.git
2026-05-20 01:21:20.092148 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-05-20 01:21:20.096404 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-05-20 01:21:20.100601 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://github.com/openstack/diskimage-builder.git
2026-05-20 01:21:20.104762 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-05-20 01:21:20.108616 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-05-20 01:21:20.112758 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://github.com/openstack/neutron-lib.git
2026-05-20 01:21:20.116975 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-05-20 01:21:20.120843 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-05-20 01:21:20.124185 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://github.com/openstack/os-resource-classes.git
2026-05-20 01:21:20.128265 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-05-20 01:21:20.132594 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://github.com/openstack/os-traits.git
2026-05-20 01:21:20.136850 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-05-20 01:21:20.141107 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://github.com/openstack/ovsdbapp.git
2026-05-20 01:21:20.145429 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-05-20 01:21:20.149795 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-05-20 01:21:20.154121 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://github.com/openstack/os-ken.git
2026-05-20 01:21:20.158236 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-05-20 01:21:20.162574 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-05-20 01:21:20.166873 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://github.com/openstack/ironic-python-agent.git
2026-05-20 01:21:20.170914 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-05-20 01:21:20.175262 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-05-20 01:21:20.179425 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-05-20 01:21:20.183715 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-05-20 01:21:20.187452 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-05-20 01:21:20.192707 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-05-20 01:21:20.196931 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.203622 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.204115 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.212220 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.216221 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.221212 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-05-20 01:21:20.225396 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-05-20 01:21:20.229092 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-05-20 01:21:20.232888 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-05-20 01:21:20.237342 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-05-20 01:21:20.242011 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-05-20 01:21:20.246409 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-05-20 01:21:20.250471 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-05-20 01:21:20.254390 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:21:20.258569 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-05-20 01:21:20.262969 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-05-20 01:21:20.268164 | controller | +++ stackrc:source:668                       :   uname -m
2026-05-20 01:21:20.274077 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-05-20 01:21:20.279571 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-05-20 01:21:20.283940 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.290191 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.291203 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.297636 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.301809 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.307291 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-05-20 01:21:20.310729 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:20.314107 | controller | ++ stackrc:source:675                       :   [[ -n '' ]]
2026-05-20 01:21:20.318396 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-05-20 01:21:20.322259 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-05-20 01:21:20.326284 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-05-20 01:21:20.330495 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-05-20 01:21:20.334274 | 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:21:20.338467 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-05-20 01:21:20.342309 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-05-20 01:21:20.345844 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-05-20 01:21:20.349908 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:21:20.353558 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-05-20 01:21:20.356992 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-05-20 01:21:20.360875 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-05-20 01:21:20.364443 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-05-20 01:21:20.369787 | controller | +++ functions-common:is_arch:519             :   uname -m
2026-05-20 01:21:20.376411 | controller | ++ functions-common:is_arch:519             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-05-20 01:21:20.380437 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-05-20 01:21:20.384558 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:21:20.388722 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-05-20 01:21:20.393097 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-05-20 01:21:20.397188 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-05-20 01:21:20.401458 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-05-20 01:21:20.406602 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:21:20.411825 | 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:21:20.415974 | 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:21:20.419458 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-05-20 01:21:20.423727 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-05-20 01:21:20.428206 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-05-20 01:21:20.436194 | 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:21:20.436513 | 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:21:20.437133 | controller | +++ stackrc:source:749                       :   wc -l
2026-05-20 01:21:20.445547 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-05-20 01:21:20.449107 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=30G
2026-05-20 01:21:20.453234 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-05-20 01:21:20.457502 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-05-20 01:21:20.461617 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-05-20 01:21:20.465849 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-05-20 01:21:20.470302 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-05-20 01:21:20.474595 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-05-20 01:21:20.478635 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-05-20 01:21:20.483918 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:21:20.492629 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:21:20.499302 | controller | ++ stackrc:source:777                       :   API_WORKERS=4
2026-05-20 01:21:20.503589 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-05-20 01:21:20.507879 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-05-20 01:21:20.511550 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-05-20 01:21:20.515770 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-05-20 01:21:20.521186 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-05-20 01:21:20.525676 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.532066 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.532584 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.540504 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.544844 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.550271 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-05-20 01:21:20.555879 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-05-20 01:21:20.559818 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.566322 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.566810 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.574481 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.579123 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.585338 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-05-20 01:21:20.590591 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-05-20 01:21:20.594887 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.601140 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.601679 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.609535 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.613163 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.618277 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-05-20 01:21:20.622461 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:21:20.626560 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:21:20.630582 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-05-20 01:21:20.634353 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-05-20 01:21:20.637841 | controller | ++ stackrc:source:815                       :   HOST_IP=199.204.45.30
2026-05-20 01:21:20.641863 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-05-20 01:21:20.647186 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 199.204.45.30 inet
2026-05-20 01:21:20.651459 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=10.1.0.0/20
2026-05-20 01:21:20.655782 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=172.24.5.0/24
2026-05-20 01:21:20.659946 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:21:20.663831 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=199.204.45.30
2026-05-20 01:21:20.667779 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet
2026-05-20 01:21:20.671409 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z 199.204.45.30 -o 199.204.45.30 == dhcp ']'
2026-05-20 01:21:20.675683 | controller | +++ functions-common:get_default_host_ip:785 :   echo 199.204.45.30
2026-05-20 01:21:20.680392 | controller | ++ stackrc:source:818                       :   HOST_IP=199.204.45.30
2026-05-20 01:21:20.684655 | controller | ++ stackrc:source:819                       :   '[' 199.204.45.30 == '' ']'
2026-05-20 01:21:20.690270 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-05-20 01:21:20.694634 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=
2026-05-20 01:21:20.698883 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=
2026-05-20 01:21:20.702551 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:21:20.706390 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=
2026-05-20 01:21:20.710001 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet6
2026-05-20 01:21:20.714516 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z '' -o '' == dhcp ']'
2026-05-20 01:21:20.718681 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip=
2026-05-20 01:21:20.726363 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 route list match default table all
2026-05-20 01:21:20.726419 | controller | ++++ functions-common:get_default_host_ip:764 :   grep via
2026-05-20 01:21:20.727532 | controller | ++++ functions-common:get_default_host_ip:764 :   head -1
2026-05-20 01:21:20.727627 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/default/ {print $5}'
2026-05-20 01:21:20.735775 | controller | +++ functions-common:get_default_host_ip:764 :   host_ip_iface=
2026-05-20 01:21:20.739388 | controller | +++ functions-common:get_default_host_ip:765 :   local host_ips
2026-05-20 01:21:20.745585 | controller | ++++ functions-common:get_default_host_ip:766 :   LC_ALL=C
2026-05-20 01:21:20.746095 | controller | ++++ functions-common:get_default_host_ip:766 :   sed /temporary/d
2026-05-20 01:21:20.746677 | controller | ++++ functions-common:get_default_host_ip:766 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-05-20 01:21:20.750486 | controller | ++++ functions-common:get_default_host_ip:766 :   ip -f inet6 addr show
2026-05-20 01:21:20.759914 | controller | +++ functions-common:get_default_host_ip:766 :   host_ips='::1
2026-05-20 01:21:20.759948 | controller | 2604:e100:1:0:f816:3eff:fe0c:9a92
2026-05-20 01:21:20.759958 | controller | fe80::f816:3eff:fe0c:9a92'
2026-05-20 01:21:20.763760 | controller | +++ functions-common:get_default_host_ip:767 :   local ip
2026-05-20 01:21:20.767459 | controller | +++ functions-common:get_default_host_ip:768 :   for ip in $host_ips
2026-05-20 01:21:20.771546 | controller | +++ functions-common:get_default_host_ip:775 :   [[ inet6 == \i\n\e\t\6 ]]
2026-05-20 01:21:20.775368 | controller | +++ functions-common:get_default_host_ip:776 :   host_ip=::1
2026-05-20 01:21:20.779558 | controller | +++ functions-common:get_default_host_ip:777 :   break
2026-05-20 01:21:20.783334 | controller | +++ functions-common:get_default_host_ip:785 :   echo ::1
2026-05-20 01:21:20.787561 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-05-20 01:21:20.793398 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-05-20 01:21:20.797040 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.802974 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.804212 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.812016 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.816122 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.821631 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-05-20 01:21:20.825273 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-05-20 01:21:20.829477 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-05-20 01:21:20.833442 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-05-20 01:21:20.837285 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=199.204.45.30
2026-05-20 01:21:20.840990 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:21:20.845004 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:21:20.849424 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-05-20 01:21:20.853356 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:21:20.857160 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=199.204.45.30
2026-05-20 01:21:20.860708 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:21:20.864520 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-05-20 01:21:20.868735 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-05-20 01:21:20.872740 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-05-20 01:21:20.877029 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=199.204.45.30
2026-05-20 01:21:20.881120 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-05-20 01:21:20.885664 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=199.204.45.30
2026-05-20 01:21:20.891025 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-05-20 01:21:20.895075 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.901027 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.902535 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.910861 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.914514 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.919304 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-05-20 01:21:20.923196 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=199.204.45.30
2026-05-20 01:21:20.927447 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-05-20 01:21:20.931164 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-05-20 01:21:20.936290 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-05-20 01:21:20.941352 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:20.947486 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:20.947867 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:20.955382 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:20.959696 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:20.964279 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-05-20 01:21:20.968843 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-05-20 01:21:20.973059 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-05-20 01:21:20.977182 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:20.981449 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-05-20 01:21:20.985823 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-05-20 01:21:20.989683 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-05-20 01:21:20.993392 | 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:21:20.997054 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-05-20 01:21:21.001187 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-05-20 01:21:21.005832 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-05-20 01:21:21.011503 | controller | + functions:write_devstack_version:876     :   cat -
2026-05-20 01:21:21.011973 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-05-20 01:21:21.016967 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-05-20 01:21:21.063213 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-05-20 01:21:21.067538 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-05-20 01:21:21.071880 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-05-20 01:21:21.076458 | controller | + functions-common:export_proxy_variables:2232 :   isset http_proxy
2026-05-20 01:21:21.080652 | controller | + functions-common:isset:266               :   [[ -v http_proxy ]]
2026-05-20 01:21:21.084130 | controller | + functions-common:export_proxy_variables:2235 :   isset https_proxy
2026-05-20 01:21:21.088675 | controller | + functions-common:isset:266               :   [[ -v https_proxy ]]
2026-05-20 01:21:21.092450 | controller | + functions-common:export_proxy_variables:2238 :   isset no_proxy
2026-05-20 01:21:21.096807 | controller | + functions-common:isset:266               :   [[ -v no_proxy ]]
2026-05-20 01:21:21.100868 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-05-20 01:21:21.105126 | controller | + functions-common:disable_negated_services:1937 :   local xtrace
2026-05-20 01:21:21.111370 | controller | ++ functions-common:disable_negated_services:1938 :   set +o
2026-05-20 01:21:21.111933 | controller | ++ functions-common:disable_negated_services:1938 :   grep xtrace
2026-05-20 01:21:21.118985 | controller | + functions-common:disable_negated_services:1938 :   xtrace='set -o xtrace'
2026-05-20 01:21:21.122899 | controller | + functions-common:disable_negated_services:1939 :   set +o xtrace
2026-05-20 01:21:21.147563 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-05-20 01:21:21.151601 | controller | + functions-common:is_package_installed:1441 :   [[ -z sudo ]]
2026-05-20 01:21:21.155836 | controller | + functions-common:is_package_installed:1445 :   [[ -z deb ]]
2026-05-20 01:21:21.159790 | controller | + functions-common:is_package_installed:1449 :   [[ deb = \d\e\b ]]
2026-05-20 01:21:21.164035 | controller | + functions-common:is_package_installed:1450 :   dpkg -s sudo
2026-05-20 01:21:21.182176 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-05-20 01:21:21.205846 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-05-20 01:21:21.206229 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-05-20 01:21:21.216524 | controller | #includedir /etc/sudoers.d
2026-05-20 01:21:21.222281 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-05-20 01:21:21.227880 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-05-20 01:21:21.234579 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.hQsQdHheEB
2026-05-20 01:21:21.239621 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-05-20 01:21:21.244377 | 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:21:21.248617 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-05-20 01:21:21.253747 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.hQsQdHheEB
2026-05-20 01:21:21.260929 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.hQsQdHheEB
2026-05-20 01:21:21.281936 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.hQsQdHheEB /etc/sudoers.d/50_stack_sh
2026-05-20 01:21:21.303646 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-05-20 01:21:21.307496 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:21.312516 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:21.317575 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-05-20 01:21:21.319286 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-05-20 01:21:21.339375 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-05-20 01:21:21.345152 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-05-20 01:21:21.349325 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-05-20 01:21:21.353450 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-05-20 01:21:21.373295 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-05-20 01:21:21.377497 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-05-20 01:21:21.381804 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:21.387713 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:21.387918 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:21.395286 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:21.398794 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:21.406990 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/logs
2026-05-20 01:21:21.426170 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-05-20 01:21:21.430645 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-05-20 01:21:21.435093 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:21.441215 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:21.441958 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:21.449753 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:21.455060 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:21.463632 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/logs
2026-05-20 01:21:21.481855 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-05-20 01:21:21.485968 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-05-20 01:21:21.490430 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-05-20 01:21:21.509021 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-05-20 01:21:21.513999 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-05-20 01:21:21.518546 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:21.525966 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:21.526064 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:21.533476 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:21.537135 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:21.545749 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/data
2026-05-20 01:21:21.565864 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-05-20 01:21:21.570450 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-05-20 01:21:21.574208 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:21.580740 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:21.581407 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:21.589080 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:21.593208 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:21.601526 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/data
2026-05-20 01:21:21.619738 | controller | + ./stack.sh:main:353                      :   async_init
2026-05-20 01:21:21.624341 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-05-20 01:21:21.628731 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-05-20 01:21:21.635040 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-05-20 01:21:21.643841 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-05-20 01:21:21.650543 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000175163
2026-05-20 01:21:21.655269 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000175163 /etc/hosts
2026-05-20 01:21:21.663297 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-05-20 01:21:21.667938 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:21.674456 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:21.674714 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:21.683397 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:21.687607 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:21.692907 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-05-20 01:21:21.696878 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-05-20 01:21:21.700873 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-05-20 01:21:21.704789 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-05-20 01:21:21.708547 | controller | + ./stack.sh:main:404                      :   install_python
2026-05-20 01:21:21.713070 | controller | + inc/python:install_python:482            :   install_python3
2026-05-20 01:21:21.717172 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-05-20 01:21:21.720795 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:21.724497 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:21.728639 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-05-20 01:21:21.732633 | controller | + functions-common:apt_get:1188            :   local xtrace result
2026-05-20 01:21:21.738507 | controller | ++ functions-common:apt_get:1189            :   set +o
2026-05-20 01:21:21.739783 | controller | ++ functions-common:apt_get:1189            :   grep xtrace
2026-05-20 01:21:21.747815 | controller | + functions-common:apt_get:1189            :   xtrace='set -o xtrace'
2026-05-20 01:21:21.751431 | controller | + functions-common:apt_get:1190            :   set +o xtrace
2026-05-20 01:21:21.764354 | 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:21:21.819350 | controller | Reading package lists...
2026-05-20 01:21:22.080419 | controller | Building dependency tree...
2026-05-20 01:21:22.080646 | controller | Reading state information...
2026-05-20 01:21:22.344006 | controller | python3.12 is already the newest version (3.12.3-1ubuntu0.13).
2026-05-20 01:21:22.344091 | controller | python3.12 set to manually installed.
2026-05-20 01:21:22.344102 | controller | The following additional packages will be installed:
2026-05-20 01:21:22.344120 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:21:22.345168 | controller |   libpython3.12t64 linux-libc-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:21:22.346575 | controller | Suggested packages:
2026-05-20 01:21:22.346612 | controller |   glibc-doc manpages-dev
2026-05-20 01:21:22.346622 | controller | Recommended packages:
2026-05-20 01:21:22.346631 | controller |   manpages manpages-dev libc-devtools
2026-05-20 01:21:22.395679 | controller | The following NEW packages will be installed:
2026-05-20 01:21:22.395878 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:21:22.397171 | controller |   libpython3.12t64 linux-libc-dev python3.12-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:21:22.815672 | controller | 0 upgraded, 10 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:21:22.815750 | controller | Need to get 13.4 MB of archives.
2026-05-20 01:21:22.815765 | controller | After this operation, 63.4 MB of additional disk space will be used.
2026-05-20 01:21:22.815778 | 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:21:52.915969 | 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:21:53.061212 | 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:21:53.080821 | 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:53.099836 | 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:53.177285 | 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:53.198024 | 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:53.269839 | 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:53.309714 | 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:53.415011 | 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:53.658140 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-05-20 01:21:53.706659 | controller | Fetched 13.4 MB in 31s (431 kB/s)
2026-05-20 01:21:53.876621 | controller | Selecting previously unselected package libc-dev-bin.
2026-05-20 01:21:54.240741 | 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:54.241903 | controller | Preparing to unpack .../0-libc-dev-bin_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:54.249529 | controller | Unpacking libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:54.312506 | controller | Selecting previously unselected package linux-libc-dev:amd64.
2026-05-20 01:21:54.318031 | controller | Preparing to unpack .../1-linux-libc-dev_6.8.0-117.117_amd64.deb ...
2026-05-20 01:21:54.321865 | controller | Unpacking linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:54.686532 | controller | Selecting previously unselected package libcrypt-dev:amd64.
2026-05-20 01:21:54.692875 | controller | Preparing to unpack .../2-libcrypt-dev_1%3a4.4.36-4build1_amd64.deb ...
2026-05-20 01:21:54.713358 | controller | Unpacking libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:54.776532 | controller | Selecting previously unselected package rpcsvc-proto.
2026-05-20 01:21:54.782723 | controller | Preparing to unpack .../3-rpcsvc-proto_1.4.2-0ubuntu7_amd64.deb ...
2026-05-20 01:21:54.787922 | controller | Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:54.858504 | controller | Selecting previously unselected package libc6-dev:amd64.
2026-05-20 01:21:54.864741 | controller | Preparing to unpack .../4-libc6-dev_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:54.869534 | controller | Unpacking libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:55.125533 | controller | Selecting previously unselected package libexpat1-dev:amd64.
2026-05-20 01:21:55.132506 | controller | Preparing to unpack .../5-libexpat1-dev_2.6.1-2ubuntu0.4_amd64.deb ...
2026-05-20 01:21:55.137830 | controller | Unpacking libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:55.214506 | controller | Selecting previously unselected package libpython3.12t64:amd64.
2026-05-20 01:21:55.221050 | controller | Preparing to unpack .../6-libpython3.12t64_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:55.232498 | controller | Unpacking libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.365504 | controller | Selecting previously unselected package zlib1g-dev:amd64.
2026-05-20 01:21:55.372026 | controller | Preparing to unpack .../7-zlib1g-dev_1%3a1.3.dfsg-3.1ubuntu2.1_amd64.deb ...
2026-05-20 01:21:55.377511 | controller | Unpacking zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:55.446476 | controller | Selecting previously unselected package libpython3.12-dev:amd64.
2026-05-20 01:21:55.453221 | controller | Preparing to unpack .../8-libpython3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:55.458434 | controller | Unpacking libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.738570 | controller | Selecting previously unselected package python3.12-dev.
2026-05-20 01:21:55.744452 | controller | Preparing to unpack .../9-python3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:55.750180 | controller | Unpacking python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.823741 | controller | Setting up linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:55.837358 | controller | Setting up libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.850868 | controller | Setting up rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:55.864682 | controller | Setting up libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:55.890164 | controller | Setting up libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:55.902297 | controller | Setting up libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:55.917549 | controller | Setting up libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:55.931671 | controller | Setting up zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:55.945111 | controller | Setting up libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.958274 | controller | Setting up python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.973189 | controller | Processing triggers for libc-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:56.275269 | controller | + functions-common:apt_get:1205            :   result=0
2026-05-20 01:21:56.279632 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:21:56.284062 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:21:56.288293 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:21:56.292089 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:21:56.296162 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:21:56.299751 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:21:56.302583 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:21:56.306716 | controller | + functions-common:time_stop:2421          :   start_time=1779240081759
2026-05-20 01:21:56.310864 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240081759 ]]
2026-05-20 01:21:56.315380 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:21:56.322934 | controller | + functions-common:time_stop:2426          :   end_time=1779240116317
2026-05-20 01:21:56.326443 | controller | + functions-common:time_stop:2427          :   elapsed_time=34558
2026-05-20 01:21:56.330896 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:21:56.334323 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:21:56.338812 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=34558
2026-05-20 01:21:56.342668 | controller | + functions-common:apt_get:1209            :   return 0
2026-05-20 01:21:56.347976 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-05-20 01:21:56.356194 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-05-20 01:21:56.360954 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-05-20 01:21:56.366474 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-05-20 01:21:56.371097 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:56.377511 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:56.378231 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:56.385697 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:56.390104 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:56.395230 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-05-20 01:21:56.401305 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-05-20 01:21:56.406036 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:56.412798 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:56.412829 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:56.420432 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:56.424020 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:56.428543 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:56.433352 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-05-20 01:21:56.437313 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-05-20 01:21:56.443093 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-05-20 01:21:56.449615 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-05-20-012156
2026-05-20 01:21:56.453220 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:56.456712 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-05-20 01:21:56.460868 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-05-20 01:21:56.464913 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-05-20 01:21:56.471195 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-05-20 01:21:56.478611 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012156
2026-05-20 01:21:56.482610 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012156.summary.2026-05-20-012156
2026-05-20 01:21:56.486354 | controller | + ./stack.sh:main:476                      :   exec
2026-05-20 01:21:56.490563 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:56.494057 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-05-20 01:21:56.497672 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:56.501308 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-05-20 01:21:56.504777 | controller | + ./stack.sh:main:483                      :   exec
2026-05-20 01:21:56.509294 | 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-012156
2026-05-20 01:21:56.538126 | controller | + ./stack.sh:main:485                      :   exec
2026-05-20 01:21:56.538173 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012156'
2026-05-20 01:21:56.538187 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-05-20-012156.summary.2026-05-20-012156
2026-05-20 01:21:56.538200 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-05-20 01:21:56.538211 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012156
2026-05-20 01:21:56.538218 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012156 /opt/stack/logs/devstacklog.txt
2026-05-20 01:21:56.538227 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012156.summary.2026-05-20-012156 /opt/stack/logs/devstacklog.txt.summary
2026-05-20 01:21:56.539525 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-05-20 01:21:56.542483 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-05-20 01:21:56.547145 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-05-20 01:21:56.554177 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-05-20 01:21:56.557452 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-05-20 01:21:56.564172 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-05-20 01:21:56.564577 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-05-20 01:21:56.572120 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:56.576223 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-05-20 01:21:56.581514 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-05-20 01:21:56.588471 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:56.592475 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:56.596419 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-05-20 01:21:56.602111 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-05-20 01:21:56.607768 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:56.611765 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-05-20 01:21:56.616928 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-05-20 01:21:56.621629 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-05-20 01:21:56.625200 | controller | + ./stack.sh:main:574                      :   uname -a
2026-05-20 01:21:56.627502 | controller | Linux np0000175163 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:56.632019 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-05-20 01:21:56.636277 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-05-20 01:21:56.643625 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-05-20 01:21:56.712990 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-05-20 01:21:56.744231 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-05-20 01:21:56.749890 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-05-20 01:21:56.772752 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-05-20 01:21:56.778484 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-05-20 01:21:56.804056 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-05-20 01:21:56.809863 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-05-20 01:21:56.836824 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-05-20 01:21:56.841397 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-05-20 01:21:56.845198 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-05-20 01:21:56.851183 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-05-20 01:21:56.878076 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:56.882994 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-05-20 01:21:56.911090 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:56.915737 | controller | + ./stack.sh:main:588                      :   tune_host
2026-05-20 01:21:56.919817 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-05-20 01:21:56.923995 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-05-20 01:21:56.927187 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:56.930977 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-05-20 01:21:56.934740 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:56.938853 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:56.943855 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-05-20 01:21:56.943884 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-05-20 01:21:56.957523 | controller | lz4
2026-05-20 01:21:56.964459 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-05-20 01:21:56.964803 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-05-20 01:21:56.977778 | controller | zsmalloc
2026-05-20 01:21:56.984822 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-05-20 01:21:56.985522 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-05-20 01:21:56.998357 | controller | 1
2026-05-20 01:21:57.026971 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-05-20 01:21:57.042353 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-05-20 01:21:57.042385 | controller | /sys/module/zswap/parameters/enabled:Y
2026-05-20 01:21:57.042401 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-05-20 01:21:57.042411 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-05-20 01:21:57.042420 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-05-20 01:21:57.042433 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-05-20 01:21:57.042446 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-05-20 01:21:57.042485 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-05-20 01:21:57.042499 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-05-20 01:21:57.048460 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-05-20 01:21:57.052286 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:57.055977 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-05-20 01:21:57.060270 | controller | + functions-common:install_package:1432    :   update_package_repo
2026-05-20 01:21:57.064209 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:21:57.068097 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=False
2026-05-20 01:21:57.071574 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=False
2026-05-20 01:21:57.075612 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:57.079674 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:21:57.083472 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:57.087355 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:57.091234 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:21:57.095126 | controller | + functions-common:apt_get_update:1160     :   [[ False == \T\r\u\e ]]
2026-05-20 01:21:57.098227 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:57.101285 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:21:57.105700 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:21:57.112185 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:21:57.115943 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:21:57.120077 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:21:57.124123 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:21:57.128142 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:21:57.133269 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:21:57.139105 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240117134
2026-05-20 01:21:57.143738 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:21:57.147216 | 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:57.150793 | 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:22:07.230164 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:07.230287 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:16.446545 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:22:23.246999 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:29.348829 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:22:40.266539 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:44.364843 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:56.577332 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:56.577406 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:58.231569 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:13.280785 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:28.305276 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:38.323593 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:48.333002 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:58.346535 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:58.346577 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:13.367915 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:24:13.367957 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:23.385067 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:24:23.385117 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:24.403959 | controller | Reading package lists...
2026-05-20 01:24:24.435942 | 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:24:24.437453 | 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:24:24.437477 | 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:24:24.437489 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:24:24.437503 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:24:24.449008 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:24:24.454131 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:24:24.457662 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:24:24.461977 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:24:24.465804 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:24:24.469949 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:24:24.473882 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:24:24.477779 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:24:24.482560 | controller | + functions-common:time_stop:2421          :   start_time=1779240117134
2026-05-20 01:24:24.486941 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240117134 ]]
2026-05-20 01:24:24.491751 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:24:24.498743 | controller | + functions-common:time_stop:2426          :   end_time=1779240264493
2026-05-20 01:24:24.502465 | controller | + functions-common:time_stop:2427          :   elapsed_time=147359
2026-05-20 01:24:24.506718 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:24:24.510535 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:24:24.514536 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=147359
2026-05-20 01:24:24.518552 | controller | + functions-common:install_package:1433    :   real_install_package ksmtuned
2026-05-20 01:24:24.522613 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:24:24.526331 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:24:24.530311 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:24:24.533803 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:24:24.567424 | 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:24:24.611556 | controller | Reading package lists...
2026-05-20 01:24:24.894798 | controller | Building dependency tree...
2026-05-20 01:24:24.894841 | controller | Reading state information...
2026-05-20 01:24:25.176622 | controller | Recommended packages:
2026-05-20 01:24:25.176654 | controller |   qemu-kvm
2026-05-20 01:24:25.219499 | controller | The following NEW packages will be installed:
2026-05-20 01:24:25.222533 | controller |   ksmtuned
2026-05-20 01:24:33.730713 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:24:33.730775 | controller | Need to get 7,444 B of archives.
2026-05-20 01:24:33.730786 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:24:33.730795 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:44.747808 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:56.759361 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:25:10.769798 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:25:10.769850 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:10.793225 | 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:10.793284 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:25:10.800980 | controller | + functions-common:apt_get:1205            :   result=100
2026-05-20 01:25:10.804698 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:25:10.808069 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:25:10.811367 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:25:10.816146 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:25:10.820957 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:25:10.824496 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:25:10.828846 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:25:10.833361 | controller | + functions-common:time_stop:2421          :   start_time=1779240264561
2026-05-20 01:25:10.837393 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240264561 ]]
2026-05-20 01:25:10.842846 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:25:10.850775 | controller | + functions-common:time_stop:2426          :   end_time=1779240310845
2026-05-20 01:25:10.853758 | controller | + functions-common:time_stop:2427          :   elapsed_time=46284
2026-05-20 01:25:10.857795 | controller | + functions-common:time_stop:2428          :   total=34558
2026-05-20 01:25:10.861695 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:25:10.865657 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=80842
2026-05-20 01:25:10.869721 | controller | + functions-common:apt_get:1209            :   return 100
2026-05-20 01:25:10.874121 | controller | + functions-common:install_package:1434    :   RETRY_UPDATE=True
2026-05-20 01:25:10.878593 | controller | + functions-common:install_package:1434    :   update_package_repo
2026-05-20 01:25:10.882717 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:25:10.886610 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=True
2026-05-20 01:25:10.890531 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=True
2026-05-20 01:25:10.894526 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:25:10.898402 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:25:10.902575 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:25:10.907022 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:25:10.911534 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:25:10.915776 | controller | + functions-common:apt_get_update:1160     :   [[ True == \T\r\u\e ]]
2026-05-20 01:25:10.919447 | controller | + functions-common:apt_get_update:1160     :   [[ True != \T\r\u\e ]]
2026-05-20 01:25:10.923607 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:25:10.927637 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:25:10.932602 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:25:10.939476 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:25:10.943351 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:25:10.947324 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:25:10.951124 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:25:10.954995 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:25:10.960497 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:25:10.966786 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240310962
2026-05-20 01:25:10.970941 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:25:10.975272 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:25:10.979534 | 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:25:17.731366 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:25:21.058590 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:27.741535 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:25:32.074338 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:42.759693 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:44.085049 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:52.773452 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:02.786930 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:03.102709 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:26:03.104284 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:26:07.795978 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:20.801818 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:30.815810 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:40.831335 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:50.844869 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:50.844913 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:00.858815 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:27:00.858857 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:10.872053 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:27:10.872094 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:11.887595 | controller | Reading package lists...
2026-05-20 01:27:11.907285 | 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:27:11.907339 | 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:27:11.907347 | 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:27:11.907354 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:27:11.907362 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:27:11.917203 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:27:11.920825 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:27:11.924880 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:27:11.929171 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:27:11.933523 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:27:11.938143 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:27:11.942571 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:27:11.946435 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:27:11.950578 | controller | + functions-common:time_stop:2421          :   start_time=1779240310962
2026-05-20 01:27:11.954100 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240310962 ]]
2026-05-20 01:27:11.959088 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:27:11.965563 | controller | + functions-common:time_stop:2426          :   end_time=1779240431960
2026-05-20 01:27:11.969615 | controller | + functions-common:time_stop:2427          :   elapsed_time=120998
2026-05-20 01:27:11.973463 | controller | + functions-common:time_stop:2428          :   total=147359
2026-05-20 01:27:11.977302 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:27:11.981030 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=268357
2026-05-20 01:27:11.985282 | controller | + functions-common:install_package:1434    :   real_install_package ksmtuned
2026-05-20 01:27:11.989208 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:27:11.993030 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:27:11.997015 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:27:12.000754 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:27:12.034306 | 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:27:12.080687 | controller | Reading package lists...
2026-05-20 01:27:12.327554 | controller | Building dependency tree...
2026-05-20 01:27:12.327599 | controller | Reading state information...
2026-05-20 01:27:12.564847 | controller | Recommended packages:
2026-05-20 01:27:12.564893 | controller |   qemu-kvm
2026-05-20 01:27:12.593432 | controller | The following NEW packages will be installed:
2026-05-20 01:27:12.595483 | controller |   ksmtuned
2026-05-20 01:27:22.640351 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:27:22.640418 | controller | Need to get 7,444 B of archives.
2026-05-20 01:27:22.640430 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:27:22.640443 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:33.651160 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:42.231010 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:56.250460 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:56.250500 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:56.264524 | 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:27:56.264614 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:27:56.273502 | controller | + functions-common:apt_get:1               :   exit_trap
2026-05-20 01:27:56.280051 | controller | + ./stack.sh:exit_trap:519                 :   local r=100
2026-05-20 01:27:56.286783 | controller | ++ ./stack.sh:exit_trap:520                 :   jobs -p
2026-05-20 01:27:56.291278 | controller | + ./stack.sh:exit_trap:520                 :   jobs=
2026-05-20 01:27:56.295492 | controller | + ./stack.sh:exit_trap:523                 :   [[ -n '' ]]
2026-05-20 01:27:56.300507 | controller | + ./stack.sh:exit_trap:529                 :   '[' -f '' ']'
2026-05-20 01:27:56.304522 | controller | + ./stack.sh:exit_trap:534                 :   kill_spinner
2026-05-20 01:27:56.308707 | controller | + ./stack.sh:kill_spinner:429              :   '[' '!' -z '' ']'
2026-05-20 01:27:56.312492 | controller | + ./stack.sh:exit_trap:536                 :   [[ 100 -ne 0 ]]
2026-05-20 01:27:56.316597 | controller | + ./stack.sh:exit_trap:537                 :   echo 'Error on exit'
2026-05-20 01:27:56.316635 | controller | Error on exit
2026-05-20 01:27:56.320608 | controller | + ./stack.sh:exit_trap:539                 :   type -p generate-subunit
2026-05-20 01:27:56.325044 | controller | + ./stack.sh:exit_trap:542                 :   [[ -z /opt/stack/logs ]]
2026-05-20 01:27:56.329026 | controller | + ./stack.sh:exit_trap:545                 :   /usr/bin/python3.12 /opt/stack/devstack/tools/worlddump.py -d /opt/stack/logs
2026-05-20 01:27:56.859477 | controller | + ./stack.sh:exit_trap:554                 :   exit 100
2026-05-20 01:27:56.861140 | controller | *** FINISHED ***
2026-05-20 01:27:57.126622 | controller | ERROR
2026-05-20 01:27:57.126873 | controller | {
2026-05-20 01:27:57.126905 | controller |   "delta": "0:06:39.465411",
2026-05-20 01:27:57.126927 | controller |   "end": "2026-05-20 01:27:56.878000",
2026-05-20 01:27:57.126948 | controller |   "msg": "non-zero return code",
2026-05-20 01:27:57.126968 | controller |   "rc": 100,
2026-05-20 01:27:57.126991 | controller |   "start": "2026-05-20 01:21:17.412589"
2026-05-20 01:27:57.127010 | controller | }
failure
2026-05-20 01:27:57.145123 | 
2026-05-20 01:27:57.145260 | PLAY RECAP
2026-05-20 01:27:57.145330 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:27:57.145357 | 
2026-05-20 01:27:57.286484 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:27:57.294934 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:27:58.045350 | 
2026-05-20 01:27:58.045467 | PLAY [all]
2026-05-20 01:27:58.056910 | 
2026-05-20 01:27:58.056998 | LOOP [Copy individual Hydrophone result files]
2026-05-20 01:27:58.665431 | controller | ERROR: Item: e2e.log
2026-05-20 01:27:58.665748 | controller | {
2026-05-20 01:27:58.665805 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:58.665845 | controller |   "item": "e2e.log",
2026-05-20 01:27:58.665919 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-20 01:27:58.665954 | controller | }
2026-05-20 01:27:58.932517 | controller | ERROR: Item: junit_01.xml
2026-05-20 01:27:58.932721 | controller | {
2026-05-20 01:27:58.932756 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:58.932780 | controller |   "item": "junit_01.xml",
2026-05-20 01:27:58.932799 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-20 01:27:58.932818 | controller | }
2026-05-20 01:27:58.944076 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:58.952088 | 
2026-05-20 01:27:58.952201 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-20 01:27:59.240923 | controller | ERROR
2026-05-20 01:27:59.241206 | controller | {
2026-05-20 01:27:59.241313 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-20 01:27:59.241348 | controller | }
2026-05-20 01:27:59.241383 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:59.253735 | 
2026-05-20 01:27:59.253832 | TASK [Return built artifacts to Zuul]
2026-05-20 01:27:59.300233 | controller | ok
2026-05-20 01:27:59.306439 | 
2026-05-20 01:27:59.306575 | TASK [Get the Docker volume ID]
2026-05-20 01:27:59.849788 | controller | ERROR
2026-05-20 01:27:59.850018 | controller | {
2026-05-20 01:27:59.850048 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-20 01:27:59.850069 | controller |   "rc": 2
2026-05-20 01:27:59.850089 | controller | }
2026-05-20 01:27:59.850115 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:59.855059 | 
2026-05-20 01:27:59.855123 | TASK [Copy all of the pod logs]
2026-05-20 01:27:59.889489 | controller | ERROR
2026-05-20 01:27:59.889702 | controller | {
2026-05-20 01:27:59.889746 | 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/dbd0293bc9e64f3987c504ed9177f0db/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:27:59.889781 | controller | }
2026-05-20 01:27:59.889817 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:59.890969 | 
2026-05-20 01:27:59.891030 | PLAY RECAP
2026-05-20 01:27:59.891092 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-20 01:27:59.891124 | 
2026-05-20 01:28:00.002253 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:28:00.012192 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:28:05.077277 | 
2026-05-20 01:28:05.077406 | PLAY [all]
2026-05-20 01:28:05.097425 | 
2026-05-20 01:28:05.097562 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-20 01:28:05.662716 | controller | changed
2026-05-20 01:28:05.669019 | 
2026-05-20 01:28:05.669106 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-20 01:28:07.212244 | controller | ok: Runtime: 0:00:00.456845
2026-05-20 01:28:07.218892 | 
2026-05-20 01:28:07.218957 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-20 01:28:07.758301 | controller | ok: Runtime: 0:00:00.020302
2026-05-20 01:28:07.764000 | 
2026-05-20 01:28:07.764072 | TASK [export-devstack-journal : Export journal]
2026-05-20 01:28:08.330847 | controller | ok: Runtime: 0:00:00.026774
2026-05-20 01:28:08.339278 | 
2026-05-20 01:28:08.339352 | TASK [export-devstack-journal : Save journal README]
2026-05-20 01:28:09.310738 | controller | changed
2026-05-20 01:28:09.322896 | 
2026-05-20 01:28:09.322965 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-20 01:28:09.562536 | controller | changed
2026-05-20 01:28:09.568114 | 
2026-05-20 01:28:09.568185 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:28:09.903530 | controller | Output suppressed because no_log was given
2026-05-20 01:28:09.908718 | 
2026-05-20 01:28:09.908799 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:28:09.935084 | 
2026-05-20 01:28:09.935235 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:28:09.963508 | 
2026-05-20 01:28:09.963683 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:28:09.991839 | 
2026-05-20 01:28:09.992003 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:28:10.022740 | 
2026-05-20 01:28:10.023035 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:28:10.060526 | 
2026-05-20 01:28:10.060679 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-20 01:28:10.296476 | controller | changed
2026-05-20 01:28:10.304191 | 
2026-05-20 01:28:10.304256 | TASK [apache-logs-conf : Define config paths]
2026-05-20 01:28:10.337315 | controller | ok
2026-05-20 01:28:10.344841 | 
2026-05-20 01:28:10.344937 | TASK [apache-logs-conf : Discover configurations]
2026-05-20 01:28:10.572296 | controller | Output suppressed because no_log was given
2026-05-20 01:28:10.579375 | 
2026-05-20 01:28:10.579442 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-20 01:28:10.617923 | 
2026-05-20 01:28:10.618134 | LOOP [apache-logs-conf : Link configurations]
2026-05-20 01:28:10.667699 | 
2026-05-20 01:28:10.667910 | TASK [capture-performance-data : Generate statistics]
2026-05-20 01:28:13.123463 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-20 01:28:13.141453 | controller | Using python 3.12 to install setuptools
2026-05-20 01:28:13.173301 | controller | /usr/bin/python3.12: No module named pip
2026-05-20 01:28:13.183613 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-20 01:28:13.236731 | controller | ERROR
2026-05-20 01:28:13.236910 | controller | {
2026-05-20 01:28:13.236944 | controller |   "delta": "0:00:02.260572",
2026-05-20 01:28:13.236986 | controller |   "end": "2026-05-20 01:28:13.186177",
2026-05-20 01:28:13.237008 | controller |   "msg": "non-zero return code",
2026-05-20 01:28:13.237032 | controller |   "rc": 127,
2026-05-20 01:28:13.237051 | controller |   "start": "2026-05-20 01:28:10.925605"
2026-05-20 01:28:13.237070 | controller | }
2026-05-20 01:28:13.237095 | controller | ERROR: Ignoring Errors
2026-05-20 01:28:13.248336 | 
2026-05-20 01:28:13.248405 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-20 01:28:13.482503 | controller | changed
2026-05-20 01:28:13.517152 | 
2026-05-20 01:28:13.517297 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-20 01:28:17.105352 | controller | Output suppressed because no_log was given
2026-05-20 01:28:17.123709 | 
2026-05-20 01:28:17.123857 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-20 01:28:17.207224 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.207490 | 
2026-05-20 01:28:17.209683 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.213034 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.217071 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.219324 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.222852 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.227823 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.233432 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.238352 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.242672 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.246515 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.248729 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.251767 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.255541 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.257735 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.260739 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.263546 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.266500 | controller | skipping: Conditional result was False
2026-05-20 01:28:17.341344 | 
2026-05-20 01:28:17.341489 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-20 01:28:17.571191 | controller | ok
2026-05-20 01:28:17.579025 | 
2026-05-20 01:28:17.579093 | TASK [devstack-project-conf : Copy configuration files]
2026-05-20 01:28:18.111908 | controller | skipping: Conditional result was False
2026-05-20 01:28:18.132786 | 
2026-05-20 01:28:18.132997 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-20 01:28:18.447587 | controller | /usr/bin/python3: No module named pip
2026-05-20 01:28:19.534945 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-20 01:28:19.673042 | controller | ok: Runtime: 0:00:01.153693
2026-05-20 01:28:19.688004 | 
2026-05-20 01:28:19.688131 | LOOP [stage-output : Register sources]
2026-05-20 01:28:28.764935 | controller | Output suppressed because no_log was given
2026-05-20 01:28:28.773243 | 
2026-05-20 01:28:28.773317 | TASK [stage-output : Check sudo]
2026-05-20 01:28:29.306898 | controller | ok: Runtime: 0:00:00.021949
2026-05-20 01:28:29.312502 | 
2026-05-20 01:28:29.312564 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-20 01:28:29.410503 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.410878 | 
2026-05-20 01:28:29.412445 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.414491 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.417221 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.456093 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.458872 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.463708 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.467945 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.470342 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.473122 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.479310 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.484363 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.506027 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.514306 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.515118 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.518749 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.559404 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.560066 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.561326 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.609517 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.610002 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.702395 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.708164 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.719324 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.730575 | controller | Output suppressed because no_log was given
2026-05-20 01:28:29.740755 | 
2026-05-20 01:28:29.740827 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-20 01:28:29.804790 | controller | ok
2026-05-20 01:28:29.812721 | 
2026-05-20 01:28:29.812801 | LOOP [stage-output : Ensure target folders exist]
2026-05-20 01:28:30.053037 | controller | changed: "docs"
2026-05-20 01:28:30.254072 | controller | changed: "artifacts"
2026-05-20 01:28:30.467955 | controller | ok: "logs"
2026-05-20 01:28:30.485371 | 
2026-05-20 01:28:30.485622 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-20 01:28:30.774282 | controller | ok: Item: Runtime: 0:00:00.007351
2026-05-20 01:28:30.774481 | controller | changed: All items complete
2026-05-20 01:28:30.774510 | 
2026-05-20 01:28:31.006176 | controller | ok: Item: Runtime: 0:00:00.007284
2026-05-20 01:28:31.234977 | controller | ok: Item: Runtime: 0:00:00.008380
2026-05-20 01:28:31.455951 | controller | ok: Item: Runtime: 0:00:00.007370
2026-05-20 01:28:31.671515 | controller | ok: Item: Runtime: 0:00:00.007886
2026-05-20 01:28:31.893473 | controller | ok: Item: Runtime: 0:00:00.007479
2026-05-20 01:28:32.155065 | controller | ok: Item: Runtime: 0:00:00.007131
2026-05-20 01:28:32.328087 | controller | ok: Item: Runtime: 0:00:00.006698
2026-05-20 01:28:32.722300 | controller | ok: Item: Runtime: 0:00:00.007221
2026-05-20 01:28:32.756402 | controller | ok: Item: Runtime: 0:00:00.007944
2026-05-20 01:28:32.976841 | controller | ok: Item: Runtime: 0:00:00.005298
2026-05-20 01:28:33.203845 | controller | ok: Item: Runtime: 0:00:00.008253
2026-05-20 01:28:33.414119 | controller | ok: Item: Runtime: 0:00:00.006102
2026-05-20 01:28:33.731927 | controller | ok: Item: Runtime: 0:00:00.008294
2026-05-20 01:28:33.904029 | controller | ok: Item: Runtime: 0:00:00.006164
2026-05-20 01:28:34.137828 | controller | ok: Item: Runtime: 0:00:00.007826
2026-05-20 01:28:34.496469 | controller | ok: Item: Runtime: 0:00:00.007871
2026-05-20 01:28:34.583280 | controller | ok: Item: Runtime: 0:00:00.007820
2026-05-20 01:28:34.793557 | controller | ok: Item: Runtime: 0:00:00.007517
2026-05-20 01:28:35.017008 | controller | ok: Item: Runtime: 0:00:00.007742
2026-05-20 01:28:35.038085 | 
2026-05-20 01:28:35.038208 | TASK [stage-output : Make all log files readable]
2026-05-20 01:28:35.278053 | controller | changed
2026-05-20 01:28:35.288591 | 
2026-05-20 01:28:35.288741 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-20 01:28:35.729952 | controller | changed: Renamed files for staging.
2026-05-20 01:28:35.735898 | 
2026-05-20 01:28:35.735975 | TASK [stage-output : Discover log files for compression]
2026-05-20 01:28:35.774894 | controller | skipping: Conditional result was False
2026-05-20 01:28:35.790418 | 
2026-05-20 01:28:35.790548 | LOOP [stage-output : Archive everything from logs]
2026-05-20 01:28:35.832558 | 
2026-05-20 01:28:36.055207 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-20 01:28:36.813521 | controller | changed:
2026-05-20 01:28:36.813730 | controller | created directory /var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/work/logs/controller
2026-05-20 01:28:36.813772 | controller | cd+++++++++ logs/
2026-05-20 01:28:36.813806 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-05-20 01:28:36.813837 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-20 01:28:36.813866 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-20 01:28:36.813895 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-20 01:28:36.813922 | controller | >f+++++++++ logs/devstacklog.txt
2026-05-20 01:28:36.813950 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-05-20 01:28:36.813977 | controller | >f+++++++++ logs/df.txt
2026-05-20 01:28:36.814005 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-20 01:28:36.814032 | controller | >f+++++++++ logs/iptables.txt
2026-05-20 01:28:36.814058 | controller | >f+++++++++ logs/listen53.txt
2026-05-20 01:28:36.814085 | controller | >f+++++++++ logs/local_conf.txt
2026-05-20 01:28:36.814112 | controller | >f+++++++++ logs/mount.txt
2026-05-20 01:28:36.814140 | controller | >f+++++++++ logs/performance.json
2026-05-20 01:28:36.814174 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-20 01:28:36.814205 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-20 01:28:36.814233 | controller | >f+++++++++ logs/services.txt
2026-05-20 01:28:36.814261 | controller | >f+++++++++ logs/sudoers
2026-05-20 01:28:36.814288 | controller | >f+++++++++ logs/syslog.txt
2026-05-20 01:28:36.814315 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-05-20 01:28:36.814342 | controller | cd+++++++++ logs/apache/
2026-05-20 01:28:36.814369 | controller | cd+++++++++ logs/apache_config/
2026-05-20 01:28:36.814396 | controller | cd+++++++++ logs/etc/
2026-05-20 01:28:36.814423 | controller | cd+++++++++ logs/sudoers.d/
2026-05-20 01:28:36.814450 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-20 01:28:36.814497 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-20 01:28:36.814526 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-20 01:28:36.814580 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-20 01:28:36.814610 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-20 01:28:36.829543 | 
2026-05-20 01:28:36.829663 | TASK [Check if a tempest log exits]
2026-05-20 01:28:37.085359 | controller | ok
2026-05-20 01:28:37.092175 | 
2026-05-20 01:28:37.092315 | TASK [Link post-devstack tempest.log]
2026-05-20 01:28:37.116515 | controller | skipping: Conditional result was False
2026-05-20 01:28:37.125945 | 
2026-05-20 01:28:37.126026 | TASK [Capture most recent qemu crash dump, if any]
2026-05-20 01:28:37.383473 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-20 01:28:37.870334 | controller | ERROR
2026-05-20 01:28:37.870514 | controller | {
2026-05-20 01:28:37.870555 | controller |   "delta": "0:00:00.005794",
2026-05-20 01:28:37.870583 | controller |   "end": "2026-05-20 01:28:37.383837",
2026-05-20 01:28:37.870609 | controller |   "msg": "non-zero return code",
2026-05-20 01:28:37.870634 | controller |   "rc": 127,
2026-05-20 01:28:37.870660 | controller |   "start": "2026-05-20 01:28:37.378043"
2026-05-20 01:28:37.870684 | controller | }
2026-05-20 01:28:37.870716 | controller | ERROR: Ignoring Errors
2026-05-20 01:28:37.872329 | 
2026-05-20 01:28:37.872389 | PLAY RECAP
2026-05-20 01:28:37.872447 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-20 01:28:37.872480 | 
2026-05-20 01:28:38.008982 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:28:38.016201 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:38.821786 | 
2026-05-20 01:28:38.821937 | PLAY [all]
2026-05-20 01:28:38.833863 | 
2026-05-20 01:28:38.967278 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 01:28:39.014936 | controller | skipping: Conditional result was False
2026-05-20 01:28:39.022242 | 
2026-05-20 01:28:39.022350 | TASK [fetch-output : Set log path for single node]
2026-05-20 01:28:39.065226 | controller | ok
2026-05-20 01:28:39.070975 | 
2026-05-20 01:28:39.071046 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 01:28:39.587237 | controller -> localhost | ok: "/var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/work/logs"
2026-05-20 01:28:39.822974 | controller -> localhost | changed: "/var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/work/artifacts"
2026-05-20 01:28:40.158185 | controller -> localhost | changed: "/var/lib/zuul/builds/dbd0293bc9e64f3987c504ed9177f0db/work/docs"
2026-05-20 01:28:40.170866 | 
2026-05-20 01:28:40.171018 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 01:28:40.964936 | controller | changed: .d..t...... ./
2026-05-20 01:28:40.965377 | controller | changed: All items complete
2026-05-20 01:28:40.965420 | 
2026-05-20 01:28:41.545664 | controller | changed: .d..t...... ./
2026-05-20 01:28:42.020659 | controller | changed: .d..t...... ./
2026-05-20 01:28:42.038027 | 
2026-05-20 01:28:42.038328 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 01:28:42.653671 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006791
2026-05-20 01:28:42.905367 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007786
2026-05-20 01:28:42.931468 | 
2026-05-20 01:28:42.931954 | PLAY [all]
2026-05-20 01:28:42.944344 | 
2026-05-20 01:28:42.944413 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 01:28:43.738624 | controller | changed
2026-05-20 01:28:43.746129 | 
2026-05-20 01:28:43.746195 | PLAY RECAP
2026-05-20 01:28:43.746238 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:28:43.746261 | 
2026-05-20 01:28:43.897378 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:43.908628 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 01:28:44.827030 | 
2026-05-20 01:28:44.892783 | PLAY [localhost]
2026-05-20 01:28:44.910897 | 
2026-05-20 01:28:44.911005 | TASK [Generate Zuul manifest]
2026-05-20 01:28:44.934280 | localhost | ok
2026-05-20 01:28:45.349802 | 
2026-05-20 01:28:45.349944 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 01:28:45.794207 | localhost | changed
2026-05-20 01:28:45.804234 | 
2026-05-20 01:28:45.804314 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 01:28:46.029366 | localhost | ok
2026-05-20 01:28:46.038244 | 
2026-05-20 01:28:46.038312 | TASK [Upload logs]
2026-05-20 01:28:46.061736 | localhost | ok
2026-05-20 01:28:46.290414 | 
2026-05-20 01:28:46.290527 | TASK [Set zuul-log-path fact]
2026-05-20 01:28:46.309183 | localhost | ok
2026-05-20 01:28:46.323073 | 
2026-05-20 01:28:46.323139 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:28:46.354576 | localhost | ok
2026-05-20 01:28:46.367075 | 
2026-05-20 01:28:46.367155 | TASK [upload-logs : Create log directories]
2026-05-20 01:28:46.763798 | localhost | changed
2026-05-20 01:28:46.769700 | 
2026-05-20 01:28:46.769812 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 01:28:47.157337 | localhost -> localhost | ok: Runtime: 0:00:00.004433
2026-05-20 01:28:47.163778 | 
2026-05-20 01:28:47.163860 | TASK [upload-logs : Upload logs to log server]
2026-05-20 01:28:47.916092 | localhost | Output suppressed because no_log was given
2026-05-20 01:28:47.919719 | 
2026-05-20 01:28:47.919785 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 01:28:47.967010 | localhost | skipping: Conditional result was False
2026-05-20 01:28:47.975232 | localhost | skipping: Conditional result was False
2026-05-20 01:28:47.991811 | 
2026-05-20 01:28:47.991984 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 01:28:48.031533 | localhost | skipping: Conditional result was False
2026-05-20 01:28:48.031855 | 
2026-05-20 01:28:48.036149 | localhost | skipping: Conditional result was False
2026-05-20 01:28:48.043013 | 
2026-05-20 01:28:48.043143 | LOOP [upload-logs : Upload console log and json output]