2026-05-20 01:17:40.784310 | Job console starting
2026-05-20 01:17:40.795235 | Updating git repos
2026-05-20 01:17:43.381441 | Cloning repos into workspace
2026-05-20 01:18:02.420340 | Restoring repo states
2026-05-20 01:18:04.652705 | Merging changes
2026-05-20 01:18:05.654425 | Checking out repos
2026-05-20 01:18:13.897899 | Preparing playbooks
2026-05-20 01:18:19.981691 | Running Ansible setup
2026-05-20 01:18:25.134682 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:18:25.922904 | 
2026-05-20 01:18:25.923048 | PLAY [localhost]
2026-05-20 01:18:25.930869 | 
2026-05-20 01:18:25.930948 | TASK [Gathering Facts]
2026-05-20 01:18:27.129379 | localhost | ok
2026-05-20 01:18:27.143169 | 
2026-05-20 01:18:27.143325 | TASK [Setup log path fact]
2026-05-20 01:18:27.170880 | localhost | ok
2026-05-20 01:18:27.189999 | 
2026-05-20 01:18:27.190130 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:18:27.233199 | localhost | ok
2026-05-20 01:18:27.244468 | 
2026-05-20 01:18:27.244636 | TASK [emit-job-header : Print job information]
2026-05-20 01:18:27.288544 | # Job Information
2026-05-20 01:18:27.288829 | Ansible Version: 2.16.18
2026-05-20 01:18:27.288926 | Job: magnum-cluster-api-hydrophone-v1.34.7-calico
2026-05-20 01:18:27.288993 | Pipeline: check
2026-05-20 01:18:27.289050 | Executor: 2d72f0692154
2026-05-20 01:18:27.289099 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1008
2026-05-20 01:18:27.289291 | Event ID: 4e04f870-53e9-11f1-8295-1cc955408759
2026-05-20 01:18:27.294514 | 
2026-05-20 01:18:27.294591 | LOOP [emit-job-header : Print node information]
2026-05-20 01:18:27.514029 | localhost | ok:
2026-05-20 01:18:27.514214 | localhost | # Node Information
2026-05-20 01:18:27.514256 | localhost | Inventory Hostname: controller
2026-05-20 01:18:27.514288 | localhost | Hostname: np0000175170
2026-05-20 01:18:27.514317 | localhost | Username: zuul
2026-05-20 01:18:27.514419 | localhost | Distro: Ubuntu 24.04
2026-05-20 01:18:27.514476 | localhost | Provider: yul1
2026-05-20 01:18:27.514513 | localhost | Region: ca-ymq-1
2026-05-20 01:18:27.514548 | localhost | Label: ubuntu-noble-16
2026-05-20 01:18:27.514581 | localhost | Product Name: OpenStack Nova
2026-05-20 01:18:27.514612 | localhost | Interface IP: 199.204.45.248
2026-05-20 01:18:27.527524 | 
2026-05-20 01:18:27.527703 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 01:18:27.959831 | localhost -> localhost | changed
2026-05-20 01:18:27.965762 | 
2026-05-20 01:18:27.965873 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 01:18:29.296633 | localhost -> localhost | changed
2026-05-20 01:18:29.308341 | 
2026-05-20 01:18:29.308506 | PLAY [all]
2026-05-20 01:18:29.322133 | 
2026-05-20 01:18:29.322241 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 01:18:29.649072 | controller -> localhost | ok
2026-05-20 01:18:29.663034 | 
2026-05-20 01:18:29.663197 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 01:18:29.704562 | controller | ok
2026-05-20 01:18:29.725120 | controller | included: /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 01:18:29.732745 | 
2026-05-20 01:18:29.732810 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 01:18:31.527825 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 01:18:31.528107 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/work/ba43d3794ad340d28a76ac38f08f3031_id_rsa
2026-05-20 01:18:31.528153 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/work/ba43d3794ad340d28a76ac38f08f3031_id_rsa.pub
2026-05-20 01:18:31.528188 | controller -> localhost | The key fingerprint is:
2026-05-20 01:18:31.528221 | controller -> localhost | SHA256:x90kwEP4PcfCfoQvEqDHJdmKyKXLYa/r4qofh/Valzc zuul-build-sshkey
2026-05-20 01:18:31.528259 | controller -> localhost | The key's randomart image is:
2026-05-20 01:18:31.528282 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 01:18:31.528307 | controller -> localhost | |         *o      |
2026-05-20 01:18:31.528330 | controller -> localhost | |      . = =.     |
2026-05-20 01:18:31.528351 | controller -> localhost | |   . + + * +.o.  |
2026-05-20 01:18:31.528380 | controller -> localhost | |    * o +.o.*++  |
2026-05-20 01:18:31.528408 | controller -> localhost | |   o.+ .S oo.*.  |
2026-05-20 01:18:31.528440 | controller -> localhost | |   oo..  o. o o  |
2026-05-20 01:18:31.528473 | controller -> localhost | |  o ..o o E. o   |
2026-05-20 01:18:31.528499 | controller -> localhost | |  .o.o . . .     |
2026-05-20 01:18:31.528531 | controller -> localhost | |++oo+.           |
2026-05-20 01:18:31.528554 | controller -> localhost | +----[SHA256]-----+
2026-05-20 01:18:31.528609 | controller -> localhost | ok: Runtime: 0:00:01.370013
2026-05-20 01:18:31.540336 | 
2026-05-20 01:18:31.540483 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 01:18:31.576858 | controller | ok
2026-05-20 01:18:31.590887 | controller | included: /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 01:18:31.602341 | 
2026-05-20 01:18:31.602411 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 01:18:31.627118 | controller | skipping: Conditional result was False
2026-05-20 01:18:31.639620 | 
2026-05-20 01:18:31.639713 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 01:18:32.188217 | controller | changed
2026-05-20 01:18:32.197744 | 
2026-05-20 01:18:32.197881 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 01:18:32.434709 | controller | ok
2026-05-20 01:18:32.443127 | 
2026-05-20 01:18:32.443217 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 01:18:33.032886 | controller | changed
2026-05-20 01:18:33.045612 | 
2026-05-20 01:18:33.045771 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 01:18:33.741735 | controller | changed
2026-05-20 01:18:33.748390 | 
2026-05-20 01:18:33.748479 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 01:18:33.773082 | controller | skipping: Conditional result was False
2026-05-20 01:18:33.783150 | 
2026-05-20 01:18:33.783257 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 01:18:34.234258 | controller -> localhost | changed
2026-05-20 01:18:34.254637 | 
2026-05-20 01:18:34.254807 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 01:18:34.832919 | controller -> localhost | Identity added: /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/work/ba43d3794ad340d28a76ac38f08f3031_id_rsa (zuul-build-sshkey)
2026-05-20 01:18:34.833109 | controller -> localhost | ok: Runtime: 0:00:00.028037
2026-05-20 01:18:34.839368 | 
2026-05-20 01:18:34.839452 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 01:18:35.236785 | controller | ok
2026-05-20 01:18:35.248948 | 
2026-05-20 01:18:35.249148 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 01:18:35.290350 | controller | skipping: Conditional result was False
2026-05-20 01:18:35.315187 | 
2026-05-20 01:18:35.315313 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 01:18:35.712405 | controller | ok
2026-05-20 01:18:35.722138 | 
2026-05-20 01:18:35.722246 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 01:19:42.882952 | controller | Output suppressed because no_log was given
2026-05-20 01:19:42.925746 | 
2026-05-20 01:19:42.926106 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 01:19:43.570743 | controller | ok: "logs"
2026-05-20 01:19:43.570914 | controller | ok: All items complete
2026-05-20 01:19:43.570942 | 
2026-05-20 01:19:43.715014 | controller | ok: "artifacts"
2026-05-20 01:19:43.897941 | controller | ok: "docs"
2026-05-20 01:19:44.278867 | 
2026-05-20 01:19:44.279038 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 01:19:44.518057 | controller | changed: "logs"
2026-05-20 01:19:44.711628 | controller | changed: "artifacts"
2026-05-20 01:19:44.937758 | controller | changed: "docs"
2026-05-20 01:19:44.958339 | 
2026-05-20 01:19:44.958445 | PLAY RECAP
2026-05-20 01:19:44.958500 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 01:19:44.958529 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:19:44.958550 | 
2026-05-20 01:19:45.392635 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:19:45.954471 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:19:47.011831 | 
2026-05-20 01:19:47.011974 | PLAY [all]
2026-05-20 01:19:47.059838 | 
2026-05-20 01:19:47.060187 | TASK [Fix the permissions of the zuul home directory]
2026-05-20 01:19:47.732204 | controller | changed
2026-05-20 01:19:47.738649 | 
2026-05-20 01:19:47.738765 | TASK [Gather minimum local MTU]
2026-05-20 01:19:47.833325 | controller | ok
2026-05-20 01:19:47.838680 | 
2026-05-20 01:19:47.838755 | TASK [Calculate external_bridge_mtu]
2026-05-20 01:19:47.899357 | controller | ok
2026-05-20 01:19:47.905684 | 
2026-05-20 01:19:47.905826 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-20 01:19:47.940531 | controller | skipping: Conditional result was False
2026-05-20 01:19:47.948066 | 
2026-05-20 01:19:47.948148 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-20 01:19:48.533959 | controller | ok: Runtime: 0:00:00.010597
2026-05-20 01:19:48.677510 | 
2026-05-20 01:19:48.677682 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-20 01:19:48.717194 | controller | skipping: Conditional result was False
2026-05-20 01:19:48.723855 | 
2026-05-20 01:19:48.723973 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-20 01:19:48.827139 | controller | skipping: Conditional result was False
2026-05-20 01:19:48.836114 | 
2026-05-20 01:19:48.836228 | TASK [configure-swap : Setup swap file on root device]
2026-05-20 01:19:48.929383 | controller | ok
2026-05-20 01:19:48.948146 | controller | included: /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-20 01:19:48.959362 | 
2026-05-20 01:19:48.959490 | TASK [configure-swap : Calculate required swap]
2026-05-20 01:19:49.059919 | controller | ok
2026-05-20 01:19:49.164061 | 
2026-05-20 01:19:49.164231 | TASK [configure-swap : Get root filesystem]
2026-05-20 01:19:49.433099 | controller | ext4
2026-05-20 01:19:49.717917 | controller | ok: Runtime: 0:00:00.013460
2026-05-20 01:19:49.725891 | 
2026-05-20 01:19:49.725961 | TASK [configure-swap : Save root filesystem]
2026-05-20 01:19:49.770834 | controller | ok
2026-05-20 01:19:49.778230 | 
2026-05-20 01:19:49.778354 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-20 01:19:49.816248 | controller | ok:
2026-05-20 01:19:49.816446 | controller | {
2026-05-20 01:19:49.816484 | controller |   "root_filesystem": "ext4"
2026-05-20 01:19:49.816514 | controller | }
2026-05-20 01:19:49.822994 | 
2026-05-20 01:19:49.823065 | TASK [configure-swap : Create swap backing file]
2026-05-20 01:20:00.313967 | controller | 8192+0 records in
2026-05-20 01:20:00.314275 | controller | 8192+0 records out
2026-05-20 01:20:00.314286 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.1979 s, 842 MB/s
2026-05-20 01:20:00.374093 | controller | ok: Runtime: 0:00:10.207503
2026-05-20 01:20:00.379944 | 
2026-05-20 01:20:00.380024 | TASK [configure-swap : Ensure swapfile perms]
2026-05-20 01:20:00.627404 | controller | changed
2026-05-20 01:20:00.638426 | 
2026-05-20 01:20:00.638534 | TASK [configure-swap : Make swapfile]
2026-05-20 01:20:07.277798 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-20 01:20:07.277934 | controller | no label, UUID=8f3b55cc-548f-4b7f-af19-0faf8dc6fd02
2026-05-20 01:20:07.691435 | controller | ok: Runtime: 0:00:06.332246
2026-05-20 01:20:07.710451 | 
2026-05-20 01:20:07.710666 | TASK [configure-swap : Write swap to fstab]
2026-05-20 01:20:08.213515 | controller | changed
2026-05-20 01:20:08.219757 | 
2026-05-20 01:20:08.219869 | TASK [configure-swap : Add all swap]
2026-05-20 01:20:08.763220 | controller | ok: Runtime: 0:00:00.021821
2026-05-20 01:20:08.774657 | 
2026-05-20 01:20:08.774774 | TASK [configure-swap : Debug the swap_required variable]
2026-05-20 01:20:08.815859 | controller | ok:
2026-05-20 01:20:08.816072 | controller | {
2026-05-20 01:20:08.816098 | controller |   "swap_required": "8192"
2026-05-20 01:20:08.816118 | controller | }
2026-05-20 01:20:08.822958 | 
2026-05-20 01:20:08.823052 | TASK [configure-swap : Set swappiness]
2026-05-20 01:20:09.275175 | controller | changed
2026-05-20 01:20:09.374343 | 
2026-05-20 01:20:09.374463 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-20 01:20:09.420553 | controller | ok:
2026-05-20 01:20:09.420713 | controller | {
2026-05-20 01:20:09.420740 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-20 01:20:09.420763 | controller | }
2026-05-20 01:20:09.446913 | 
2026-05-20 01:20:09.447051 | TASK [setup-stack-user : Create stack group]
2026-05-20 01:20:10.480548 | controller | changed
2026-05-20 01:20:10.489541 | 
2026-05-20 01:20:10.489638 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-20 01:20:10.745701 | controller | changed
2026-05-20 01:20:10.751887 | 
2026-05-20 01:20:10.751970 | TASK [setup-stack-user : Create stack user]
2026-05-20 01:20:11.346217 | controller | changed
2026-05-20 01:20:11.419938 | 
2026-05-20 01:20:11.420177 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-20 01:20:11.688850 | controller | changed
2026-05-20 01:20:11.833436 | 
2026-05-20 01:20:11.834203 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-20 01:20:12.972588 | controller | changed
2026-05-20 01:20:12.982986 | 
2026-05-20 01:20:12.983113 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-20 01:20:13.283808 | controller | changed
2026-05-20 01:20:13.294484 | 
2026-05-20 01:20:13.294596 | TASK [setup-tempest-user : Create tempest group]
2026-05-20 01:20:13.594947 | controller | changed
2026-05-20 01:20:13.624459 | 
2026-05-20 01:20:13.624693 | TASK [setup-tempest-user : Create tempest user]
2026-05-20 01:20:14.000253 | controller | changed
2026-05-20 01:20:14.008468 | 
2026-05-20 01:20:14.008546 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-20 01:20:14.808922 | controller | changed
2026-05-20 01:20:14.821366 | 
2026-05-20 01:20:14.821436 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-20 01:20:15.161968 | controller | ok: Not all paths examined, check warnings for details
2026-05-20 01:20:15.241038 | 
2026-05-20 01:20:15.241152 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-20 01:20:15.739880 | controller | ok: Item: Runtime: 0:00:00.203838
2026-05-20 01:20:16.104571 | controller | ok: Item: Runtime: 0:00:00.145828
2026-05-20 01:20:16.387247 | controller | ok: Item: Runtime: 0:00:00.061462
2026-05-20 01:20:16.887268 | controller | ok: Item: Runtime: 0:00:00.265696
2026-05-20 01:20:17.262008 | controller | ok: Item: Runtime: 0:00:00.158094
2026-05-20 01:20:17.805361 | controller | ok: Item: Runtime: 0:00:00.314575
2026-05-20 01:20:18.560362 | controller | ok: Item: Runtime: 0:00:00.531571
2026-05-20 01:20:19.381032 | controller | ok: Item: Runtime: 0:00:00.586032
2026-05-20 01:20:20.216029 | controller | ok: Item: Runtime: 0:00:00.619263
2026-05-20 01:20:21.651990 | controller | ok: Item: Runtime: 0:00:01.189242
2026-05-20 01:20:21.997540 | controller | ok: Item: Runtime: 0:00:00.105298
2026-05-20 01:20:22.442541 | controller | ok: Item: Runtime: 0:00:00.215555
2026-05-20 01:20:23.044238 | controller | ok: Item: Runtime: 0:00:00.369329
2026-05-20 01:20:26.572099 | controller | ok: Item: Runtime: 0:00:03.287427
2026-05-20 01:20:28.033316 | controller | ok: Item: Runtime: 0:00:01.240217
2026-05-20 01:20:28.570493 | controller | ok: Item: Runtime: 0:00:00.297193
2026-05-20 01:20:28.606997 | 
2026-05-20 01:20:28.607106 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-20 01:20:28.840783 | controller | ok: All paths examined
2026-05-20 01:20:28.856310 | 
2026-05-20 01:20:28.856435 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-20 01:20:29.109816 | controller | ok: All paths examined
2026-05-20 01:20:29.119347 | 
2026-05-20 01:20:29.119462 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-20 01:20:29.588541 | controller | ok: Item: Runtime: 0:00:00.159842
2026-05-20 01:20:29.588753 | controller | changed: All items complete
2026-05-20 01:20:29.588783 | 
2026-05-20 01:20:30.447635 | controller | ok: Item: Runtime: 0:00:00.212489
2026-05-20 01:20:30.498740 | 
2026-05-20 01:20:30.498880 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-20 01:20:30.677987 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.682933 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.689509 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.694074 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.698246 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.702362 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.706171 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.709814 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.713753 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.717552 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.723440 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.728551 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.734524 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.742095 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.747701 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.757635 | controller | skipping: Conditional result was False
2026-05-20 01:20:30.903041 | 
2026-05-20 01:20:30.903172 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-20 01:20:33.288173 | controller | changed
2026-05-20 01:20:36.037388 | 
2026-05-20 01:20:36.037510 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-20 01:20:36.292977 | controller | changed
2026-05-20 01:20:36.306005 | 
2026-05-20 01:20:36.306068 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-20 01:20:36.564027 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-20 01:20:39.533532 | controller | ERROR
2026-05-20 01:20:39.533686 | controller | {
2026-05-20 01:20:39.533717 | controller |   "delta": "0:00:00.010839",
2026-05-20 01:20:39.533738 | controller |   "end": "2026-05-20 01:20:36.564603",
2026-05-20 01:20:39.533756 | controller |   "msg": "non-zero return code",
2026-05-20 01:20:39.533775 | controller |   "rc": 1,
2026-05-20 01:20:39.533793 | controller |   "start": "2026-05-20 01:20:36.553764"
2026-05-20 01:20:39.533890 | controller | }
2026-05-20 01:20:39.533932 | controller | ERROR: Ignoring Errors
2026-05-20 01:20:39.543160 | 
2026-05-20 01:20:39.543308 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-20 01:20:39.826226 | controller | ok
2026-05-20 01:20:39.851283 | 
2026-05-20 01:20:39.851419 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-20 01:20:40.188828 | controller | /usr/bin/journalctl
2026-05-20 01:20:40.464776 | controller | ok: Runtime: 0:00:00.008518
2026-05-20 01:20:40.484675 | 
2026-05-20 01:20:40.484813 | TASK [start-fresh-logging : Get current date]
2026-05-20 01:20:40.745156 | controller | 2026-05-20 01:20:40
2026-05-20 01:20:41.025159 | controller | ok: Runtime: 0:00:00.009494
2026-05-20 01:20:41.038285 | 
2026-05-20 01:20:41.038428 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-20 01:20:41.636050 | controller | changed
2026-05-20 01:20:41.643520 | 
2026-05-20 01:20:41.643617 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-20 01:20:41.669471 | controller | skipping: Conditional result was False
2026-05-20 01:20:41.687435 | 
2026-05-20 01:20:41.687577 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-20 01:20:42.239094 | controller | skipping: Conditional result was False
2026-05-20 01:20:42.250647 | 
2026-05-20 01:20:42.250874 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-20 01:20:42.792486 | controller | skipping: Conditional result was False
2026-05-20 01:20:42.802127 | 
2026-05-20 01:20:42.802242 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-20 01:20:42.840020 | controller | skipping: Conditional result was False
2026-05-20 01:20:42.852252 | 
2026-05-20 01:20:42.852425 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-20 01:20:43.402351 | controller | skipping: Conditional result was False
2026-05-20 01:20:43.568242 | 
2026-05-20 01:20:43.568370 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-20 01:20:44.155648 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.165442 | 
2026-05-20 01:20:44.165576 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-20 01:20:44.190239 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.201676 | 
2026-05-20 01:20:44.201782 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-20 01:20:44.227858 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.240605 | 
2026-05-20 01:20:44.240711 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-20 01:20:44.784430 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.798515 | 
2026-05-20 01:20:44.798626 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-20 01:20:45.339145 | controller | skipping: Conditional result was False
2026-05-20 01:20:45.403781 | 
2026-05-20 01:20:45.403953 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-20 01:20:45.428169 | controller | skipping: Conditional result was False
2026-05-20 01:20:45.434323 | 
2026-05-20 01:20:45.434449 | TASK [start-fresh-logging : Start rsyslog]
2026-05-20 01:20:45.458351 | controller | skipping: Conditional result was False
2026-05-20 01:20:45.470235 | 
2026-05-20 01:20:45.470343 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-20 01:20:45.979146 | controller | ok
2026-05-20 01:20:46.041961 | 
2026-05-20 01:20:46.042040 | PLAY RECAP
2026-05-20 01:20:46.042101 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-20 01:20:46.042135 | 
2026-05-20 01:20:46.176501 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:20:46.182012 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:20:47.435521 | 
2026-05-20 01:20:47.435724 | PLAY [all]
2026-05-20 01:20:47.455288 | 
2026-05-20 01:20:47.455514 | TASK [Install curl]
2026-05-20 01:20:54.138019 | controller | ok
2026-05-20 01:20:54.158813 | 
2026-05-20 01:20:54.159004 | TASK [ensure-rust : Use rustup]
2026-05-20 01:20:54.236580 | controller | ok
2026-05-20 01:20:54.267590 | controller | included: /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-05-20 01:20:54.277676 | 
2026-05-20 01:20:54.277774 | TASK [ensure-rust : Install Rust]
2026-05-20 01:20:54.901849 | controller | info: downloading installer
2026-05-20 01:20:55.573033 | controller | warn: It looks like you have an existing rustup settings file at:
2026-05-20 01:20:55.573121 | controller | warn: /opt/rust/settings.toml
2026-05-20 01:20:55.573134 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-05-20 01:20:55.573150 | controller | warn: instead of the one inferred from the default host triple.
2026-05-20 01:20:55.669841 | controller | info: profile set to default
2026-05-20 01:20:55.669931 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-05-20 01:20:55.672071 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-05-20 01:20:56.577882 | controller | info: latest update on 2026-04-16 for version 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:20:56.577975 | controller | info: downloading 6 components
2026-05-20 01:21:10.382506 | controller | info: default toolchain set to stable-x86_64-unknown-linux-gnu
2026-05-20 01:21:10.382593 | controller |
2026-05-20 01:21:10.400636 | controller |   stable-x86_64-unknown-linux-gnu installed - rustc 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:21:10.400661 | controller |
2026-05-20 01:21:10.400778 | controller |
2026-05-20 01:21:10.400790 | controller | Rust is installed now. Great!
2026-05-20 01:21:10.400798 | controller |
2026-05-20 01:21:10.400820 | controller | To get started you need Cargo's bin directory (/opt/rust/bin) in your PATH
2026-05-20 01:21:10.400829 | controller | environment variable. This has not been done automatically.
2026-05-20 01:21:10.400850 | controller |
2026-05-20 01:21:10.400858 | controller | To configure your current shell, you need to source
2026-05-20 01:21:10.400866 | controller | the corresponding env file under /opt/rust.
2026-05-20 01:21:10.400874 | controller |
2026-05-20 01:21:10.400910 | controller | This is usually done by running one of the following (note the leading DOT):
2026-05-20 01:21:10.400921 | controller | . "/opt/rust/env"            # For sh/bash/zsh/ash/dash/pdksh
2026-05-20 01:21:10.400929 | controller | source "/opt/rust/env.fish"  # For fish
2026-05-20 01:21:10.400937 | controller | source "/opt/rust/env.nu"  # For nushell
2026-05-20 01:21:10.400946 | controller | source "/opt/rust/env.tcsh"  # For tcsh
2026-05-20 01:21:10.400953 | controller | . "/opt/rust/env.ps1"        # For pwsh
2026-05-20 01:21:10.400961 | controller | source "/opt/rust/env.xsh"   # For xonsh
2026-05-20 01:21:10.401039 | controller | warn: no default linker (`cc`) was found in your PATH
2026-05-20 01:21:10.401049 | controller | warn: many Rust crates require a system C toolchain to build
2026-05-20 01:21:10.859018 | controller | ok: Runtime: 0:00:15.694913
2026-05-20 01:21:10.869622 | 
2026-05-20 01:21:10.869734 | TASK [ensure-rust : Install wrapper helper script]
2026-05-20 01:21:12.184454 | controller | changed
2026-05-20 01:21:12.201212 | 
2026-05-20 01:21:12.202467 | TASK [ensure-rust : Run wrapper installation]
2026-05-20 01:21:12.555908 | controller | Install link for cargo
2026-05-20 01:21:12.561512 | controller | Install link for cargo-clippy
2026-05-20 01:21:12.567666 | controller | Install link for cargo-fmt
2026-05-20 01:21:12.573824 | controller | Install link for cargo-miri
2026-05-20 01:21:12.580222 | controller | Install link for clippy-driver
2026-05-20 01:21:12.586707 | controller | Install link for rls
2026-05-20 01:21:12.591749 | controller | Install link for rust-analyzer
2026-05-20 01:21:12.598063 | controller | Install link for rust-gdb
2026-05-20 01:21:12.604074 | controller | Install link for rust-gdbgui
2026-05-20 01:21:12.608212 | controller | Install link for rust-lldb
2026-05-20 01:21:12.614189 | controller | Install link for rustc
2026-05-20 01:21:12.620184 | controller | Install link for rustdoc
2026-05-20 01:21:12.625930 | controller | Install link for rustfmt
2026-05-20 01:21:12.632079 | controller | Install link for rustup
2026-05-20 01:21:12.764058 | controller | ok: Runtime: 0:00:00.122896
2026-05-20 01:21:12.771034 | 
2026-05-20 01:21:12.771161 | TASK [ensure-rust : Install packages]
2026-05-20 01:21:12.799892 | controller | skipping: Conditional result was False
2026-05-20 01:21:12.875709 | 
2026-05-20 01:21:12.875809 | PLAY RECAP
2026-05-20 01:21:12.875851 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:21:12.875875 | 
2026-05-20 01:21:13.159944 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:21:13.167675 | RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:21:14.144250 | 
2026-05-20 01:21:14.144508 | PLAY [all]
2026-05-20 01:21:14.160366 | 
2026-05-20 01:21:14.160477 | TASK [Install DevStack]
2026-05-20 01:21:14.195089 | controller | ok
2026-05-20 01:21:14.228227 | 
2026-05-20 01:21:14.228453 | TASK [Run devstack on the controller]
2026-05-20 01:21:14.289164 | controller | ok
2026-05-20 01:21:14.345605 | 
2026-05-20 01:21:14.345737 | TASK [run-devstack : Run devstack]
2026-05-20 01:21:15.240689 | controller | + unset GREP_OPTIONS
2026-05-20 01:21:15.240863 | controller | + unset LANG
2026-05-20 01:21:15.240884 | controller | + unset LANGUAGE
2026-05-20 01:21:15.240897 | controller | + LC_ALL=en_US.utf8
2026-05-20 01:21:15.262010 | controller | + export LC_ALL
2026-05-20 01:21:15.264017 | controller | ++ env
2026-05-20 01:21:15.264709 | controller | ++ grep -E '^OS_'
2026-05-20 01:21:15.265012 | controller | ++ cut -d = -f 1
2026-05-20 01:21:15.284532 | controller | + unset
2026-05-20 01:21:15.284550 | controller | + umask 022
2026-05-20 01:21:15.284563 | 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:15.286075 | controller | +++ dirname ./stack.sh
2026-05-20 01:21:15.293999 | controller | ++ cd .
2026-05-20 01:21:15.294052 | controller | ++ pwd
2026-05-20 01:21:15.294738 | controller | + TOP_DIR=/opt/stack/devstack
2026-05-20 01:21:15.294801 | controller | + NOUNSET=
2026-05-20 01:21:15.294834 | controller | + [[ -n '' ]]
2026-05-20 01:21:15.295457 | controller | ++ date +%s
2026-05-20 01:21:15.296485 | controller | + DEVSTACK_START_TIME=1779240075
2026-05-20 01:21:15.296542 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-05-20 01:21:15.296606 | controller | + FILES=/opt/stack/devstack/files
2026-05-20 01:21:15.296647 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-05-20 01:21:15.296719 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-05-20 01:21:15.296747 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-05-20 01:21:15.296795 | controller | + [[ '' == \y ]]
2026-05-20 01:21:15.296850 | controller | + [[ 1002 -eq 0 ]]
2026-05-20 01:21:15.296930 | controller | + [[ -n '' ]]
2026-05-20 01:21:15.296970 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-05-20 01:21:15.297004 | controller | + LAST_SPINNER_PID=
2026-05-20 01:21:15.297022 | controller | + source /opt/stack/devstack/functions
2026-05-20 01:21:15.297177 | controller | ++ [[ -z '' ]]
2026-05-20 01:21:15.297184 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-05-20 01:21:15.299060 | controller | ++++ dirname /opt/stack/devstack/functions
2026-05-20 01:21:15.301168 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:21:15.301223 | controller | +++ pwd
2026-05-20 01:21:15.301867 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-05-20 01:21:15.301918 | controller | ++ source /opt/stack/devstack/functions-common
2026-05-20 01:21:15.303673 | controller | ++++ set +o
2026-05-20 01:21:15.304232 | controller | ++++ grep xtrace
2026-05-20 01:21:15.308014 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-05-20 01:21:15.308031 | controller | +++ set +o xtrace
2026-05-20 01:21:15.322285 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-05-20 01:21:15.324131 | controller | ++++ set +o
2026-05-20 01:21:15.324561 | controller | ++++ grep xtrace
2026-05-20 01:21:15.328331 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-05-20 01:21:15.328385 | controller | +++ set +o xtrace
2026-05-20 01:21:15.330273 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-05-20 01:21:15.331966 | controller | ++++ set +o
2026-05-20 01:21:15.332476 | controller | ++++ grep xtrace
2026-05-20 01:21:15.335524 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-05-20 01:21:15.335616 | controller | +++ set +o xtrace
2026-05-20 01:21:15.336419 | controller | ++ source /opt/stack/devstack/inc/python
2026-05-20 01:21:15.338446 | controller | ++++ set +o
2026-05-20 01:21:15.338673 | controller | ++++ grep xtrace
2026-05-20 01:21:15.341355 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-05-20 01:21:15.341409 | controller | +++ set +o xtrace
2026-05-20 01:21:15.342641 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-05-20 01:21:15.344496 | controller | ++++ set +o
2026-05-20 01:21:15.344570 | controller | ++++ grep xtrace
2026-05-20 01:21:15.346518 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-05-20 01:21:15.346539 | controller | +++ set +o xtrace
2026-05-20 01:21:15.346941 | controller | ++ source /opt/stack/devstack/inc/async
2026-05-20 01:21:15.348007 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-05-20 01:21:15.348174 | controller | ++++ local xtrace
2026-05-20 01:21:15.349790 | controller | +++++ set +o
2026-05-20 01:21:15.349900 | controller | +++++ grep xtrace
2026-05-20 01:21:15.353348 | controller | ++++ xtrace='set -o xtrace'
2026-05-20 01:21:15.353379 | controller | ++++ set +o xtrace
2026-05-20 01:21:15.354413 | controller | +++ DEVSTACK_PARALLEL=True
2026-05-20 01:21:15.354456 | controller | +++ _ASYNC_BG_TIME=0
2026-05-20 01:21:15.357801 | controller | +++ set +o
2026-05-20 01:21:15.358191 | controller | +++ grep xtrace
2026-05-20 01:21:15.361790 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-05-20 01:21:15.361825 | controller | ++ set +o xtrace
2026-05-20 01:21:15.365860 | controller | + source /opt/stack/devstack/lib/stack
2026-05-20 01:21:15.366049 | controller | + GetDistro
2026-05-20 01:21:15.366091 | controller | + GetOSVersion
2026-05-20 01:21:15.366100 | controller | + source /etc/os-release
2026-05-20 01:21:15.366157 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-05-20 01:21:15.366166 | controller | ++ NAME=Ubuntu
2026-05-20 01:21:15.366178 | controller | ++ VERSION_ID=24.04
2026-05-20 01:21:15.366187 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-05-20 01:21:15.366199 | controller | ++ VERSION_CODENAME=noble
2026-05-20 01:21:15.366207 | controller | ++ ID=ubuntu
2026-05-20 01:21:15.366215 | controller | ++ ID_LIKE=debian
2026-05-20 01:21:15.366227 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-05-20 01:21:15.366236 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-05-20 01:21:15.366248 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-05-20 01:21:15.366257 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-05-20 01:21:15.366270 | controller | ++ UBUNTU_CODENAME=noble
2026-05-20 01:21:15.366278 | controller | ++ LOGO=ubuntu-logo
2026-05-20 01:21:15.366290 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-05-20 01:21:15.366387 | controller | + _ensure_lsb_release
2026-05-20 01:21:15.367770 | controller | ++ command -v lsb_release
2026-05-20 01:21:15.368619 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-05-20 01:21:15.368637 | controller | + return
2026-05-20 01:21:15.369814 | controller | ++ lsb_release -r -s
2026-05-20 01:21:15.392173 | controller | + os_RELEASE=24.04
2026-05-20 01:21:15.444698 | controller | ++ lsb_release -c -s
2026-05-20 01:21:15.444729 | controller | + os_CODENAME=noble
2026-05-20 01:21:15.444738 | controller | ++ lsb_release -i -s
2026-05-20 01:21:15.444747 | controller | + os_VENDOR=Ubuntu
2026-05-20 01:21:15.444755 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:21:15.444764 | controller | + os_PACKAGE=deb
2026-05-20 01:21:15.444772 | controller | + typeset -xr os_VENDOR
2026-05-20 01:21:15.444781 | controller | + typeset -xr os_RELEASE
2026-05-20 01:21:15.444789 | controller | + typeset -xr os_PACKAGE
2026-05-20 01:21:15.444797 | controller | + typeset -xr os_CODENAME
2026-05-20 01:21:15.444805 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-05-20 01:21:15.444814 | controller | + DISTRO=noble
2026-05-20 01:21:15.444822 | controller | + typeset -xr DISTRO
2026-05-20 01:21:15.444832 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:15.444842 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:15.444851 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-05-20 01:21:15.444860 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-05-20 01:21:15.444869 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-05-20 01:21:15.444877 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:15.444886 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-05-20 01:21:15.444895 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-05-20 01:21:15.444903 | controller | ++ local matchgroup=local
2026-05-20 01:21:15.444913 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:15.444922 | controller | ++ awk -v matchgroup=local '
2026-05-20 01:21:15.444931 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:21:15.444939 | controller |             gsub("[][]", "", $1);
2026-05-20 01:21:15.444947 | controller |             split($1, a, "|");
2026-05-20 01:21:15.444956 | controller |             if (a[1] == matchgroup)
2026-05-20 01:21:15.444964 | controller |                 print a[2]
2026-05-20 01:21:15.444972 | controller |         }
2026-05-20 01:21:15.444981 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:21:15.444989 | controller | + LRC=localrc
2026-05-20 01:21:15.444997 | controller | + for lfile in $LRC
2026-05-20 01:21:15.445005 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-05-20 01:21:15.445014 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-05-20 01:21:15.445022 | controller | + echo '# Generated file, do not edit'
2026-05-20 01:21:15.445031 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-05-20 01:21:15.445039 | controller | + local file=/opt/stack/devstack/local.conf
2026-05-20 01:21:15.445047 | controller | + local matchgroup=local
2026-05-20 01:21:15.445056 | controller | + local configfile=localrc
2026-05-20 01:21:15.445064 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:15.445072 | controller | + [[ -z localrc ]]
2026-05-20 01:21:15.445081 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-05-20 01:21:15.445089 | controller |         BEGIN { group = "" }
2026-05-20 01:21:15.445097 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:21:15.445126 | controller |             gsub("[][]", "", $1);
2026-05-20 01:21:15.445135 | controller |             split($1, a, "|");
2026-05-20 01:21:15.445143 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-05-20 01:21:15.445152 | controller |                 group=a[1]
2026-05-20 01:21:15.445160 | controller |             } else {
2026-05-20 01:21:15.445169 | controller |                 group=""
2026-05-20 01:21:15.445177 | controller |             }
2026-05-20 01:21:15.445185 | controller |             next
2026-05-20 01:21:15.445193 | controller |         }
2026-05-20 01:21:15.445201 | controller |         {
2026-05-20 01:21:15.445210 | controller |             if (group != "")
2026-05-20 01:21:15.445218 | controller |                 print $0
2026-05-20 01:21:15.445226 | controller |         }
2026-05-20 01:21:15.445235 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:21:15.447532 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-05-20 01:21:15.447553 | controller | + source /opt/stack/devstack/stackrc
2026-05-20 01:21:15.447643 | controller | ++ [[ -z '' ]]
2026-05-20 01:21:15.447654 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-05-20 01:21:15.449966 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-05-20 01:21:15.452168 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:21:15.452244 | controller | +++ pwd
2026-05-20 01:21:15.453065 | controller | ++ RC_DIR=/opt/stack/devstack
2026-05-20 01:21:15.453132 | controller | ++ source /opt/stack/devstack/functions
2026-05-20 01:21:15.453333 | controller | +++ [[ -z 1 ]]
2026-05-20 01:21:15.453347 | controller | +++ return 0
2026-05-20 01:21:15.453372 | controller | ++ TARGET_BRANCH=master
2026-05-20 01:21:15.453385 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-05-20 01:21:15.453393 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-05-20 01:21:15.453405 | controller | ++ DEST=/opt/stack
2026-05-20 01:21:15.453417 | controller | ++ DATA_DIR=/opt/stack/data
2026-05-20 01:21:15.453429 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-05-20 01:21:15.453442 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-05-20 01:21:15.453494 | controller | ++ [[ 1002 -eq 0 ]]
2026-05-20 01:21:15.454635 | controller | +++ whoami
2026-05-20 01:21:15.466688 | controller | ++ STACK_USER=stack
2026-05-20 01:21:15.466770 | controller | ++ REGION_NAME=RegionOne
2026-05-20 01:21:15.466784 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-05-20 01:21:15.466906 | controller | ++ isset ENABLED_SERVICES
2026-05-20 01:21:15.466920 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-05-20 01:21:15.466958 | controller | ++ ENABLED_SERVICES=key
2026-05-20 01:21:15.466967 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-05-20 01:21:15.466979 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-05-20 01:21:15.466988 | controller | ++ ENABLED_SERVICES+=,g-api
2026-05-20 01:21:15.466996 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-05-20 01:21:15.467005 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-05-20 01:21:15.467017 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-05-20 01:21:15.467026 | controller | ++ ENABLED_SERVICES+=,horizon
2026-05-20 01:21:15.467034 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-05-20 01:21:15.467045 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-05-20 01:21:15.467054 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-05-20 01:21:15.467065 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-05-20 01:21:15.467098 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-05-20 01:21:15.467110 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:15.467194 | controller | +++ disable_all_services
2026-05-20 01:21:15.467205 | controller | +++ ENABLED_SERVICES=
2026-05-20 01:21:15.467216 | controller | +++ enable_service c-api
2026-05-20 01:21:15.467280 | controller | +++ local xtrace
2026-05-20 01:21:15.469327 | controller | ++++ set +o
2026-05-20 01:21:15.469738 | controller | ++++ grep xtrace
2026-05-20 01:21:15.473653 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.473673 | controller | +++ set +o xtrace
2026-05-20 01:21:15.515218 | controller | +++ enable_service c-bak
2026-05-20 01:21:15.515250 | controller | +++ local xtrace
2026-05-20 01:21:15.517390 | controller | ++++ set +o
2026-05-20 01:21:15.517598 | controller | ++++ grep xtrace
2026-05-20 01:21:15.520981 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.521016 | controller | +++ set +o xtrace
2026-05-20 01:21:15.567145 | controller | +++ enable_service c-sch
2026-05-20 01:21:15.567176 | controller | +++ local xtrace
2026-05-20 01:21:15.569001 | controller | ++++ set +o
2026-05-20 01:21:15.569539 | controller | ++++ grep xtrace
2026-05-20 01:21:15.573250 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.573270 | controller | +++ set +o xtrace
2026-05-20 01:21:15.616395 | controller | +++ enable_service c-vol
2026-05-20 01:21:15.616432 | controller | +++ local xtrace
2026-05-20 01:21:15.618278 | controller | ++++ set +o
2026-05-20 01:21:15.618997 | controller | ++++ grep xtrace
2026-05-20 01:21:15.622822 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.622843 | controller | +++ set +o xtrace
2026-05-20 01:21:15.666264 | controller | +++ disable_service dstat
2026-05-20 01:21:15.666355 | controller | +++ local xtrace
2026-05-20 01:21:15.668004 | controller | ++++ set +o
2026-05-20 01:21:15.668437 | controller | ++++ grep xtrace
2026-05-20 01:21:15.671916 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.671938 | controller | +++ set +o xtrace
2026-05-20 01:21:15.702512 | controller | +++ enable_service etcd3
2026-05-20 01:21:15.702538 | controller | +++ local xtrace
2026-05-20 01:21:15.705004 | controller | ++++ set +o
2026-05-20 01:21:15.705024 | controller | ++++ grep xtrace
2026-05-20 01:21:15.707931 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.707958 | controller | +++ set +o xtrace
2026-05-20 01:21:15.749480 | controller | +++ enable_service file_tracker
2026-05-20 01:21:15.749513 | controller | +++ local xtrace
2026-05-20 01:21:15.751388 | controller | ++++ set +o
2026-05-20 01:21:15.751780 | controller | ++++ grep xtrace
2026-05-20 01:21:15.755424 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.755450 | controller | +++ set +o xtrace
2026-05-20 01:21:15.800156 | controller | +++ enable_service g-api
2026-05-20 01:21:15.800189 | controller | +++ local xtrace
2026-05-20 01:21:15.802208 | controller | ++++ set +o
2026-05-20 01:21:15.802711 | controller | ++++ grep xtrace
2026-05-20 01:21:15.805938 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.805972 | controller | +++ set +o xtrace
2026-05-20 01:21:15.851556 | controller | +++ disable_service horizon
2026-05-20 01:21:15.851596 | controller | +++ local xtrace
2026-05-20 01:21:15.854453 | controller | ++++ set +o
2026-05-20 01:21:15.854529 | controller | ++++ grep xtrace
2026-05-20 01:21:15.858511 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.858540 | controller | +++ set +o xtrace
2026-05-20 01:21:15.891255 | controller | +++ enable_service key
2026-05-20 01:21:15.891278 | controller | +++ local xtrace
2026-05-20 01:21:15.893254 | controller | ++++ set +o
2026-05-20 01:21:15.893766 | controller | ++++ grep xtrace
2026-05-20 01:21:15.897595 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.897637 | controller | +++ set +o xtrace
2026-05-20 01:21:15.942182 | controller | +++ enable_service memory_tracker
2026-05-20 01:21:15.942246 | controller | +++ local xtrace
2026-05-20 01:21:15.944248 | controller | ++++ set +o
2026-05-20 01:21:15.944667 | controller | ++++ grep xtrace
2026-05-20 01:21:15.948271 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.948331 | controller | +++ set +o xtrace
2026-05-20 01:21:15.990673 | controller | +++ enable_service mysql
2026-05-20 01:21:15.990697 | controller | +++ local xtrace
2026-05-20 01:21:15.992390 | controller | ++++ set +o
2026-05-20 01:21:15.992472 | controller | ++++ grep xtrace
2026-05-20 01:21:15.995077 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:15.995101 | controller | +++ set +o xtrace
2026-05-20 01:21:16.040017 | controller | +++ enable_service n-api
2026-05-20 01:21:16.040047 | controller | +++ local xtrace
2026-05-20 01:21:16.042166 | controller | ++++ set +o
2026-05-20 01:21:16.042538 | controller | ++++ grep xtrace
2026-05-20 01:21:16.046062 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.046095 | controller | +++ set +o xtrace
2026-05-20 01:21:16.090732 | controller | +++ enable_service n-api-meta
2026-05-20 01:21:16.090766 | controller | +++ local xtrace
2026-05-20 01:21:16.092718 | controller | ++++ set +o
2026-05-20 01:21:16.092938 | controller | ++++ grep xtrace
2026-05-20 01:21:16.095951 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.095977 | controller | +++ set +o xtrace
2026-05-20 01:21:16.139052 | controller | +++ enable_service n-cond
2026-05-20 01:21:16.139087 | controller | +++ local xtrace
2026-05-20 01:21:16.141397 | controller | ++++ set +o
2026-05-20 01:21:16.141533 | controller | ++++ grep xtrace
2026-05-20 01:21:16.146397 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.146422 | controller | +++ set +o xtrace
2026-05-20 01:21:16.192212 | controller | +++ enable_service n-cpu
2026-05-20 01:21:16.192245 | controller | +++ local xtrace
2026-05-20 01:21:16.193965 | controller | ++++ set +o
2026-05-20 01:21:16.194386 | controller | ++++ grep xtrace
2026-05-20 01:21:16.197715 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.197750 | controller | +++ set +o xtrace
2026-05-20 01:21:16.243393 | controller | +++ enable_service n-novnc
2026-05-20 01:21:16.243424 | controller | +++ local xtrace
2026-05-20 01:21:16.245296 | controller | ++++ set +o
2026-05-20 01:21:16.245809 | controller | ++++ grep xtrace
2026-05-20 01:21:16.249677 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.249714 | controller | +++ set +o xtrace
2026-05-20 01:21:16.293739 | controller | +++ enable_service n-sch
2026-05-20 01:21:16.293758 | controller | +++ local xtrace
2026-05-20 01:21:16.295660 | controller | ++++ set +o
2026-05-20 01:21:16.296097 | controller | ++++ grep xtrace
2026-05-20 01:21:16.299902 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.299929 | controller | +++ set +o xtrace
2026-05-20 01:21:16.343119 | controller | +++ enable_service o-api
2026-05-20 01:21:16.343148 | controller | +++ local xtrace
2026-05-20 01:21:16.345365 | controller | ++++ set +o
2026-05-20 01:21:16.345556 | controller | ++++ grep xtrace
2026-05-20 01:21:16.349958 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.349985 | controller | +++ set +o xtrace
2026-05-20 01:21:16.393359 | controller | +++ enable_service o-da
2026-05-20 01:21:16.393401 | controller | +++ local xtrace
2026-05-20 01:21:16.396535 | controller | ++++ set +o
2026-05-20 01:21:16.396607 | controller | ++++ grep xtrace
2026-05-20 01:21:16.400721 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.400743 | controller | +++ set +o xtrace
2026-05-20 01:21:16.444261 | controller | +++ enable_service o-hk
2026-05-20 01:21:16.444295 | controller | +++ local xtrace
2026-05-20 01:21:16.446436 | controller | ++++ set +o
2026-05-20 01:21:16.446793 | controller | ++++ grep xtrace
2026-05-20 01:21:16.451523 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.451606 | controller | +++ set +o xtrace
2026-05-20 01:21:16.494849 | controller | +++ enable_service octavia
2026-05-20 01:21:16.494871 | controller | +++ local xtrace
2026-05-20 01:21:16.496724 | controller | ++++ set +o
2026-05-20 01:21:16.497246 | controller | ++++ grep xtrace
2026-05-20 01:21:16.501543 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.501569 | controller | +++ set +o xtrace
2026-05-20 01:21:16.547119 | controller | +++ enable_service openstack-cli-server
2026-05-20 01:21:16.547154 | controller | +++ local xtrace
2026-05-20 01:21:16.549555 | controller | ++++ set +o
2026-05-20 01:21:16.549939 | controller | ++++ grep xtrace
2026-05-20 01:21:16.553817 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.553839 | controller | +++ set +o xtrace
2026-05-20 01:21:16.601334 | controller | +++ enable_service ovn-controller
2026-05-20 01:21:16.601368 | controller | +++ local xtrace
2026-05-20 01:21:16.603272 | controller | ++++ set +o
2026-05-20 01:21:16.603719 | controller | ++++ grep xtrace
2026-05-20 01:21:16.607843 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.607880 | controller | +++ set +o xtrace
2026-05-20 01:21:16.651520 | controller | +++ enable_service ovn-northd
2026-05-20 01:21:16.651554 | controller | +++ local xtrace
2026-05-20 01:21:16.653547 | controller | ++++ set +o
2026-05-20 01:21:16.653733 | controller | ++++ grep xtrace
2026-05-20 01:21:16.656352 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.656373 | controller | +++ set +o xtrace
2026-05-20 01:21:16.702123 | controller | +++ enable_service ovs-vswitchd
2026-05-20 01:21:16.702146 | controller | +++ local xtrace
2026-05-20 01:21:16.704435 | controller | ++++ set +o
2026-05-20 01:21:16.704571 | controller | ++++ grep xtrace
2026-05-20 01:21:16.708209 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.708235 | controller | +++ set +o xtrace
2026-05-20 01:21:16.753835 | controller | +++ enable_service ovsdb-server
2026-05-20 01:21:16.753869 | controller | +++ local xtrace
2026-05-20 01:21:16.755908 | controller | ++++ set +o
2026-05-20 01:21:16.756111 | controller | ++++ grep xtrace
2026-05-20 01:21:16.759083 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.759116 | controller | +++ set +o xtrace
2026-05-20 01:21:16.805915 | controller | +++ enable_service placement-api
2026-05-20 01:21:16.805949 | controller | +++ local xtrace
2026-05-20 01:21:16.807865 | controller | ++++ set +o
2026-05-20 01:21:16.808101 | controller | ++++ grep xtrace
2026-05-20 01:21:16.811328 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.811359 | controller | +++ set +o xtrace
2026-05-20 01:21:16.854426 | controller | +++ enable_service q-ovn-agent
2026-05-20 01:21:16.854448 | controller | +++ local xtrace
2026-05-20 01:21:16.856466 | controller | ++++ set +o
2026-05-20 01:21:16.856555 | controller | ++++ grep xtrace
2026-05-20 01:21:16.860009 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.860035 | controller | +++ set +o xtrace
2026-05-20 01:21:16.903752 | controller | +++ enable_service q-svc
2026-05-20 01:21:16.903785 | controller | +++ local xtrace
2026-05-20 01:21:16.905645 | controller | ++++ set +o
2026-05-20 01:21:16.906123 | controller | ++++ grep xtrace
2026-05-20 01:21:16.909585 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.909655 | controller | +++ set +o xtrace
2026-05-20 01:21:16.953806 | controller | +++ enable_service rabbit
2026-05-20 01:21:16.953847 | controller | +++ local xtrace
2026-05-20 01:21:16.955649 | controller | ++++ set +o
2026-05-20 01:21:16.955966 | controller | ++++ grep xtrace
2026-05-20 01:21:16.959776 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:16.959811 | controller | +++ set +o xtrace
2026-05-20 01:21:17.005629 | controller | +++ disable_service s-account
2026-05-20 01:21:17.005666 | controller | +++ local xtrace
2026-05-20 01:21:17.007705 | controller | ++++ set +o
2026-05-20 01:21:17.008074 | controller | ++++ grep xtrace
2026-05-20 01:21:17.011701 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.011774 | controller | +++ set +o xtrace
2026-05-20 01:21:17.045180 | controller | +++ disable_service s-container
2026-05-20 01:21:17.045229 | controller | +++ local xtrace
2026-05-20 01:21:17.047378 | controller | ++++ set +o
2026-05-20 01:21:17.047708 | controller | ++++ grep xtrace
2026-05-20 01:21:17.051349 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.051375 | controller | +++ set +o xtrace
2026-05-20 01:21:17.084664 | controller | +++ disable_service s-object
2026-05-20 01:21:17.084698 | controller | +++ local xtrace
2026-05-20 01:21:17.086655 | controller | ++++ set +o
2026-05-20 01:21:17.087043 | controller | ++++ grep xtrace
2026-05-20 01:21:17.090667 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.090700 | controller | +++ set +o xtrace
2026-05-20 01:21:17.121708 | controller | +++ disable_service s-proxy
2026-05-20 01:21:17.121746 | controller | +++ local xtrace
2026-05-20 01:21:17.124491 | controller | ++++ set +o
2026-05-20 01:21:17.124553 | controller | ++++ grep xtrace
2026-05-20 01:21:17.128104 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.128130 | controller | +++ set +o xtrace
2026-05-20 01:21:17.161160 | controller | +++ disable_service tempest
2026-05-20 01:21:17.161182 | controller | +++ local xtrace
2026-05-20 01:21:17.163167 | controller | ++++ set +o
2026-05-20 01:21:17.163491 | controller | ++++ grep xtrace
2026-05-20 01:21:17.167205 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.167238 | controller | +++ set +o xtrace
2026-05-20 01:21:17.200039 | controller | +++ enable_service tls-proxy
2026-05-20 01:21:17.200071 | controller | +++ local xtrace
2026-05-20 01:21:17.201997 | controller | ++++ set +o
2026-05-20 01:21:17.202658 | controller | ++++ grep xtrace
2026-05-20 01:21:17.206347 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.206380 | controller | +++ set +o xtrace
2026-05-20 01:21:17.251274 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-05-20 01:21:17.251296 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-05-20 01:21:17.251325 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-05-20 01:21:17.251330 | controller | +++ DISABLE_AMP_IMAGE_BUILD=True
2026-05-20 01:21:17.251336 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:17.251341 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:17.251350 | controller | +++ ENABLE_ZSWAP=True
2026-05-20 01:21:17.251356 | controller | +++ ERROR_ON_CLONE=True
2026-05-20 01:21:17.251361 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-05-20 01:21:17.251366 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:21:17.251371 | controller | +++ GIT_BASE=https://github.com
2026-05-20 01:21:17.251377 | controller | +++ HOST_IP=199.204.45.248
2026-05-20 01:21:17.251382 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:21:17.251390 | controller | +++ LIBVIRT_TYPE=qemu
2026-05-20 01:21:17.251396 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-05-20 01:21:17.251401 | controller | +++ LOG_COLOR=False
2026-05-20 01:21:17.251408 | controller | +++ MAGNUM_GUEST_IMAGE_URL=https://github.com/vexxhost/capo-image-elements/releases/latest/download/ubuntu-22.04-v1.34.7.qcow2
2026-05-20 01:21:17.251418 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS='snapshot_support=True create_share_from_snapshot_support=True'
2026-05-20 01:21:17.251425 | controller | +++ MANILA_ENABLED_BACKENDS=generic
2026-05-20 01:21:17.251430 | controller | +++ MANILA_USE_SERVICE_INSTANCE_PASSWORD=True
2026-05-20 01:21:17.251436 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-05-20 01:21:17.251444 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-05-20 01:21:17.251451 | controller | +++ NOVA_VNC_ENABLED=True
2026-05-20 01:21:17.251456 | controller | +++ OCTAVIA_NODE=api
2026-05-20 01:21:17.251462 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-05-20 01:21:17.251470 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-05-20 01:21:17.251475 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-05-20 01:21:17.251480 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-05-20 01:21:17.251486 | controller | +++ SERVICE_HOST=199.204.45.248
2026-05-20 01:21:17.251495 | controller | +++ SERVICE_PASSWORD=secretservice
2026-05-20 01:21:17.251500 | controller | +++ SWIFT_HASH=1234123412341234
2026-05-20 01:21:17.251505 | controller | +++ SWIFT_REPLICAS=1
2026-05-20 01:21:17.251511 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-05-20 01:21:17.251519 | controller | +++ VERBOSE=True
2026-05-20 01:21:17.251538 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:17.251544 | 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:17.251553 | controller | +++ enable_plugin barbican https://github.com/openstack/barbican
2026-05-20 01:21:17.251587 | controller | +++ local name=barbican
2026-05-20 01:21:17.251593 | controller | +++ local url=https://github.com/openstack/barbican
2026-05-20 01:21:17.251601 | controller | +++ local branch=master
2026-05-20 01:21:17.251606 | controller | +++ is_plugin_enabled barbican
2026-05-20 01:21:17.251632 | controller | +++ local name=barbican
2026-05-20 01:21:17.251640 | controller | +++ [[ ,, =~ ,barbican, ]]
2026-05-20 01:21:17.251724 | controller | +++ return 1
2026-05-20 01:21:17.251731 | controller | +++ DEVSTACK_PLUGINS+=,barbican
2026-05-20 01:21:17.251737 | controller | +++ GITREPO[$name]=https://github.com/openstack/barbican
2026-05-20 01:21:17.251745 | controller | +++ GITDIR[$name]=/opt/stack/barbican
2026-05-20 01:21:17.251751 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:17.251786 | controller | +++ enable_plugin magnum https://review.opendev.org/openstack/magnum
2026-05-20 01:21:17.251793 | controller | +++ local name=magnum
2026-05-20 01:21:17.251798 | controller | +++ local url=https://review.opendev.org/openstack/magnum
2026-05-20 01:21:17.251806 | controller | +++ local branch=master
2026-05-20 01:21:17.251811 | controller | +++ is_plugin_enabled magnum
2026-05-20 01:21:17.251817 | controller | +++ local name=magnum
2026-05-20 01:21:17.251825 | controller | +++ [[ ,,barbican, =~ ,magnum, ]]
2026-05-20 01:21:17.251830 | controller | +++ return 1
2026-05-20 01:21:17.251838 | controller | +++ DEVSTACK_PLUGINS+=,magnum
2026-05-20 01:21:17.251846 | controller | +++ GITREPO[$name]=https://review.opendev.org/openstack/magnum
2026-05-20 01:21:17.251854 | controller | +++ GITDIR[$name]=/opt/stack/magnum
2026-05-20 01:21:17.251860 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:17.251907 | controller | +++ enable_plugin manila https://github.com/openstack/manila
2026-05-20 01:21:17.251914 | controller | +++ local name=manila
2026-05-20 01:21:17.251922 | controller | +++ local url=https://github.com/openstack/manila
2026-05-20 01:21:17.251929 | controller | +++ local branch=master
2026-05-20 01:21:17.251943 | controller | +++ is_plugin_enabled manila
2026-05-20 01:21:17.251953 | controller | +++ local name=manila
2026-05-20 01:21:17.251963 | controller | +++ [[ ,,barbican,magnum, =~ ,manila, ]]
2026-05-20 01:21:17.251973 | controller | +++ return 1
2026-05-20 01:21:17.251982 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-05-20 01:21:17.251997 | controller | +++ GITREPO[$name]=https://github.com/openstack/manila
2026-05-20 01:21:17.252007 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-05-20 01:21:17.252017 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:17.252026 | controller | +++ enable_plugin octavia https://github.com/openstack/octavia
2026-05-20 01:21:17.252036 | controller | +++ local name=octavia
2026-05-20 01:21:17.252046 | controller | +++ local url=https://github.com/openstack/octavia
2026-05-20 01:21:17.252056 | controller | +++ local branch=master
2026-05-20 01:21:17.252070 | controller | +++ is_plugin_enabled octavia
2026-05-20 01:21:17.252080 | controller | +++ local name=octavia
2026-05-20 01:21:17.252089 | controller | +++ [[ ,,barbican,magnum,manila, =~ ,octavia, ]]
2026-05-20 01:21:17.252099 | controller | +++ return 1
2026-05-20 01:21:17.252109 | controller | +++ DEVSTACK_PLUGINS+=,octavia
2026-05-20 01:21:17.252118 | controller | +++ GITREPO[$name]=https://github.com/openstack/octavia
2026-05-20 01:21:17.252131 | controller | +++ GITDIR[$name]=/opt/stack/octavia
2026-05-20 01:21:17.252141 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:17.252151 | controller | +++ enable_plugin magnum-cluster-api https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:17.252164 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:21:17.252172 | controller | +++ local url=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:17.252180 | controller | +++ local branch=master
2026-05-20 01:21:17.252188 | controller | +++ is_plugin_enabled magnum-cluster-api
2026-05-20 01:21:17.252197 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:21:17.252205 | controller | +++ [[ ,,barbican,magnum,manila,octavia, =~ ,magnum-cluster-api, ]]
2026-05-20 01:21:17.252213 | controller | +++ return 1
2026-05-20 01:21:17.252221 | controller | +++ DEVSTACK_PLUGINS+=,magnum-cluster-api
2026-05-20 01:21:17.252230 | controller | +++ GITREPO[$name]=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:17.252253 | controller | +++ GITDIR[$name]=/opt/stack/magnum-cluster-api
2026-05-20 01:21:17.252261 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:17.252270 | controller | +++ enable_plugin ovn-octavia-provider https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:17.252278 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:21:17.252286 | controller | +++ local url=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:17.252294 | controller | +++ local branch=master
2026-05-20 01:21:17.252338 | controller | +++ is_plugin_enabled ovn-octavia-provider
2026-05-20 01:21:17.252347 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:21:17.252355 | controller | +++ [[ ,,barbican,magnum,manila,octavia,magnum-cluster-api, =~ ,ovn-octavia-provider, ]]
2026-05-20 01:21:17.252368 | controller | +++ return 1
2026-05-20 01:21:17.252377 | controller | +++ DEVSTACK_PLUGINS+=,ovn-octavia-provider
2026-05-20 01:21:17.252385 | controller | +++ GITREPO[$name]=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:17.252393 | controller | +++ GITDIR[$name]=/opt/stack/ovn-octavia-provider
2026-05-20 01:21:17.252401 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:17.252409 | controller | ++ CELLSV2_SETUP=superconductor
2026-05-20 01:21:17.252417 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-05-20 01:21:17.253495 | controller | +++ trueorfalse False USER_UNITS
2026-05-20 01:21:17.253755 | controller | +++ local xtrace
2026-05-20 01:21:17.256053 | controller | ++++ set +o
2026-05-20 01:21:17.256512 | controller | ++++ grep xtrace
2026-05-20 01:21:17.260293 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.260353 | controller | +++ set +o xtrace
2026-05-20 01:21:17.261520 | controller | ++ USER_UNITS=False
2026-05-20 01:21:17.261657 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:21:17.261670 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-05-20 01:21:17.261679 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-05-20 01:21:17.261691 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-05-20 01:21:17.262939 | controller | +++ _get_python_version python3
2026-05-20 01:21:17.263104 | controller | +++ local interp=python3
2026-05-20 01:21:17.263119 | controller | +++ local version
2026-05-20 01:21:17.264398 | controller | ++++ which python3
2026-05-20 01:21:17.268016 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-05-20 01:21:17.269347 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-05-20 01:21:17.288352 | controller | +++ version=3.12
2026-05-20 01:21:17.288390 | controller | +++ echo 3.12
2026-05-20 01:21:17.289284 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-05-20 01:21:17.289422 | controller | ++ export PYTHON3_VERSION=3.12
2026-05-20 01:21:17.289440 | controller | ++ PYTHON3_VERSION=3.12
2026-05-20 01:21:17.289453 | controller | ++ export 'VIRTUALENV_CMD=python3.12 -m venv'
2026-05-20 01:21:17.289461 | controller | ++ VIRTUALENV_CMD='python3.12 -m venv'
2026-05-20 01:21:17.289503 | controller | ++ '[' -t 1 ']'
2026-05-20 01:21:17.289512 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-05-20 01:21:17.290942 | controller | +++ trueorfalse False LOG_COLOR
2026-05-20 01:21:17.291179 | controller | +++ local xtrace
2026-05-20 01:21:17.292985 | controller | ++++ set +o
2026-05-20 01:21:17.293141 | controller | ++++ grep xtrace
2026-05-20 01:21:17.295646 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:17.295682 | controller | +++ set +o xtrace
2026-05-20 01:21:17.296797 | controller | ++ LOG_COLOR=False
2026-05-20 01:21:17.296925 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:21:17.296936 | controller | ++ export 'PS4=+ $(short_source):   '
2026-05-20 01:21:17.296944 | controller | ++ PS4='+ $(short_source):   '
2026-05-20 01:21:17.303065 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-05-20 01:21:17.307981 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:17.314697 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:17.314959 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:17.323532 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:17.327425 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:17.333662 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-05-20 01:21:17.338469 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-05-20 01:21:17.343519 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-05-20 01:21:17.349600 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-05-20 01:21:17.352966 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:17.359623 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:17.359762 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:17.367917 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:17.372356 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:17.378058 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-05-20 01:21:17.383615 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-05-20 01:21:17.387776 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:17.394524 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:17.394584 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:17.401801 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:17.406137 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:17.412231 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-05-20 01:21:17.416783 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-05-20 01:21:17.422212 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-05-20 01:21:17.426541 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:17.433570 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:17.433790 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:17.441409 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:17.445861 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:17.451924 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-05-20 01:21:17.457785 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-05-20 01:21:17.462449 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:17.469424 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:17.469484 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:17.476286 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:17.479592 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:17.485065 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-05-20 01:21:17.490981 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-05-20 01:21:17.495629 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:17.502632 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:17.502691 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:17.509721 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:17.512785 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:17.517290 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-05-20 01:21:17.521794 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-05-20 01:21:17.526461 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-05-20 01:21:17.531190 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-05-20 01:21:17.535780 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://github.com
2026-05-20 01:21:17.540333 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-05-20 01:21:17.544891 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.2
2026-05-20 01:21:17.548889 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://github.com/openstack/cinder.git
2026-05-20 01:21:17.551871 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-05-20 01:21:17.554749 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://github.com/openstack/glance.git
2026-05-20 01:21:17.557487 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-05-20 01:21:17.560399 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://github.com/openstack/horizon.git
2026-05-20 01:21:17.563661 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-05-20 01:21:17.567794 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://github.com/openstack/keystone.git
2026-05-20 01:21:17.572600 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-05-20 01:21:17.576470 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://github.com/openstack/neutron.git
2026-05-20 01:21:17.580298 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-05-20 01:21:17.583550 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://github.com/openstack/nova.git
2026-05-20 01:21:17.588421 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-05-20 01:21:17.592938 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://github.com/openstack/swift.git
2026-05-20 01:21:17.596713 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-05-20 01:21:17.600924 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://github.com/openstack/placement.git
2026-05-20 01:21:17.604958 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-05-20 01:21:17.610799 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://github.com/openstack/requirements.git
2026-05-20 01:21:17.615678 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-05-20 01:21:17.620298 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://github.com/openstack/tempest.git
2026-05-20 01:21:17.625023 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-05-20 01:21:17.629455 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-05-20 01:21:17.633716 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://github.com/openstack/os-test-images.git
2026-05-20 01:21:17.638197 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-05-20 01:21:17.642989 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-05-20 01:21:17.647759 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://github.com/openstack/python-cinderclient.git
2026-05-20 01:21:17.652296 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-05-20 01:21:17.657042 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://github.com/openstack/python-brick-cinderclient-ext.git
2026-05-20 01:21:17.661822 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-05-20 01:21:17.666446 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://github.com/openstack/python-barbicanclient.git
2026-05-20 01:21:17.670641 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-05-20 01:21:17.674968 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-05-20 01:21:17.678799 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://github.com/openstack/python-glanceclient.git
2026-05-20 01:21:17.683098 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-05-20 01:21:17.687701 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://github.com/openstack/python-ironicclient.git
2026-05-20 01:21:17.691981 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-05-20 01:21:17.696675 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-05-20 01:21:17.700504 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://github.com/openstack/keystoneauth.git
2026-05-20 01:21:17.704682 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-05-20 01:21:17.708746 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://github.com/openstack/python-keystoneclient.git
2026-05-20 01:21:17.712678 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-05-20 01:21:17.717245 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://github.com/openstack/python-neutronclient.git
2026-05-20 01:21:17.721714 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-05-20 01:21:17.726026 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://github.com/openstack/python-novaclient.git
2026-05-20 01:21:17.730230 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-05-20 01:21:17.734648 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://github.com/openstack/python-swiftclient.git
2026-05-20 01:21:17.739136 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-05-20 01:21:17.743472 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://github.com/openstack/python-openstackclient.git
2026-05-20 01:21:17.748007 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-05-20 01:21:17.752443 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-05-20 01:21:17.756946 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://github.com/openstack/osc-placement.git
2026-05-20 01:21:17.761362 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-05-20 01:21:17.765916 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://github.com/openstack/castellan.git
2026-05-20 01:21:17.770276 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-05-20 01:21:17.774819 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://github.com/openstack/cliff.git
2026-05-20 01:21:17.778647 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-05-20 01:21:17.783203 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://github.com/openstack/futurist.git
2026-05-20 01:21:17.787824 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-05-20 01:21:17.792816 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://github.com/openstack/debtcollector.git
2026-05-20 01:21:17.797281 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-05-20 01:21:17.801932 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://github.com/openstack/etcd3gw.git
2026-05-20 01:21:17.805838 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-05-20 01:21:17.810727 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://github.com/openstack/automaton.git
2026-05-20 01:21:17.814694 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-05-20 01:21:17.818366 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://github.com/openstack/oslo.cache.git
2026-05-20 01:21:17.821125 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-05-20 01:21:17.825414 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://github.com/openstack/oslo.concurrency.git
2026-05-20 01:21:17.829790 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-05-20 01:21:17.834260 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://github.com/openstack/oslo.config.git
2026-05-20 01:21:17.838230 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-05-20 01:21:17.842380 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://github.com/openstack/oslo.context.git
2026-05-20 01:21:17.846361 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-05-20 01:21:17.850530 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://github.com/openstack/oslo.db.git
2026-05-20 01:21:17.854950 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-05-20 01:21:17.858991 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://github.com/openstack/oslo.i18n.git
2026-05-20 01:21:17.863604 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-05-20 01:21:17.868284 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://github.com/openstack/oslo.limit.git
2026-05-20 01:21:17.872838 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-05-20 01:21:17.877230 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://github.com/openstack/oslo.log.git
2026-05-20 01:21:17.881957 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-05-20 01:21:17.886570 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://github.com/openstack/oslo.messaging.git
2026-05-20 01:21:17.890945 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-05-20 01:21:17.894746 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://github.com/openstack/oslo.middleware.git
2026-05-20 01:21:17.899266 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-05-20 01:21:17.903485 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://github.com/openstack/oslo.policy.git
2026-05-20 01:21:17.908236 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-05-20 01:21:17.912732 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://github.com/openstack/oslo.privsep.git
2026-05-20 01:21:17.916827 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-05-20 01:21:17.920897 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://github.com/openstack/oslo.reports.git
2026-05-20 01:21:17.925293 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-05-20 01:21:17.929731 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://github.com/openstack/oslo.rootwrap.git
2026-05-20 01:21:17.934009 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-05-20 01:21:17.938092 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://github.com/openstack/oslo.serialization.git
2026-05-20 01:21:17.943194 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-05-20 01:21:17.947462 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://github.com/openstack/oslo.service.git
2026-05-20 01:21:17.951875 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-05-20 01:21:17.955855 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://github.com/openstack/oslo.utils.git
2026-05-20 01:21:17.959664 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-05-20 01:21:17.964038 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://github.com/openstack/oslo.versionedobjects.git
2026-05-20 01:21:17.968475 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-05-20 01:21:17.972752 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://github.com/openstack/oslo.vmware.git
2026-05-20 01:21:17.976509 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-05-20 01:21:17.980881 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://github.com/openstack/osprofiler.git
2026-05-20 01:21:17.985070 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-05-20 01:21:17.989390 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://github.com/openstack/pycadf.git
2026-05-20 01:21:17.993519 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-05-20 01:21:17.997955 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://github.com/openstack/stevedore.git
2026-05-20 01:21:18.002460 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-05-20 01:21:18.006780 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://github.com/openstack/taskflow.git
2026-05-20 01:21:18.010933 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-05-20 01:21:18.014820 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://github.com/openstack/tooz.git
2026-05-20 01:21:18.019485 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-05-20 01:21:18.023839 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://github.com/openstack/pbr.git
2026-05-20 01:21:18.028077 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-05-20 01:21:18.032453 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://github.com/openstack/cursive.git
2026-05-20 01:21:18.035457 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-05-20 01:21:18.039257 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://github.com/openstack/glance_store.git
2026-05-20 01:21:18.043059 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-05-20 01:21:18.047163 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://github.com/openstack/keystonemiddleware.git
2026-05-20 01:21:18.050740 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-05-20 01:21:18.054939 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://github.com/openstack/ceilometermiddleware.git
2026-05-20 01:21:18.059484 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-05-20 01:21:18.063762 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-05-20 01:21:18.068093 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://github.com/openstack/openstacksdk.git
2026-05-20 01:21:18.072002 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-05-20 01:21:18.076102 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://github.com/openstack/os-brick.git
2026-05-20 01:21:18.081139 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-05-20 01:21:18.085880 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://github.com/openstack/os-client-config.git
2026-05-20 01:21:18.090522 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-05-20 01:21:18.094981 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-05-20 01:21:18.099823 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://github.com/openstack/os-vif.git
2026-05-20 01:21:18.104175 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-05-20 01:21:18.108644 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://github.com/openstack/osc-lib.git
2026-05-20 01:21:18.113250 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-05-20 01:21:18.117169 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://github.com/openstack/ironic-lib.git
2026-05-20 01:21:18.121721 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-05-20 01:21:18.125603 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-05-20 01:21:18.130120 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://github.com/openstack/diskimage-builder.git
2026-05-20 01:21:18.134101 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-05-20 01:21:18.138888 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-05-20 01:21:18.143299 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://github.com/openstack/neutron-lib.git
2026-05-20 01:21:18.147709 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-05-20 01:21:18.151685 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-05-20 01:21:18.155514 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://github.com/openstack/os-resource-classes.git
2026-05-20 01:21:18.159781 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-05-20 01:21:18.164378 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://github.com/openstack/os-traits.git
2026-05-20 01:21:18.168914 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-05-20 01:21:18.173371 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://github.com/openstack/ovsdbapp.git
2026-05-20 01:21:18.177384 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-05-20 01:21:18.181532 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-05-20 01:21:18.185224 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://github.com/openstack/os-ken.git
2026-05-20 01:21:18.189067 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-05-20 01:21:18.193546 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-05-20 01:21:18.197268 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://github.com/openstack/ironic-python-agent.git
2026-05-20 01:21:18.201683 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-05-20 01:21:18.206090 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-05-20 01:21:18.210483 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-05-20 01:21:18.214921 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-05-20 01:21:18.219369 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-05-20 01:21:18.224725 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-05-20 01:21:18.230466 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:18.236747 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:18.237734 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:18.244623 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:18.248704 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:18.253793 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-05-20 01:21:18.258395 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-05-20 01:21:18.262202 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-05-20 01:21:18.265941 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-05-20 01:21:18.270098 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-05-20 01:21:18.275112 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-05-20 01:21:18.279386 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-05-20 01:21:18.283579 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-05-20 01:21:18.287914 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:21:18.290859 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-05-20 01:21:18.295381 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-05-20 01:21:18.301028 | controller | +++ stackrc:source:668                       :   uname -m
2026-05-20 01:21:18.308123 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-05-20 01:21:18.313173 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-05-20 01:21:18.317680 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:18.323859 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:18.324906 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:18.333023 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:18.337148 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:18.342345 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-05-20 01:21:18.346882 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:18.351252 | controller | ++ stackrc:source:675                       :   [[ -n '' ]]
2026-05-20 01:21:18.355568 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-05-20 01:21:18.359759 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-05-20 01:21:18.363926 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-05-20 01:21:18.368226 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-05-20 01:21:18.371986 | 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:18.376031 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-05-20 01:21:18.381068 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-05-20 01:21:18.385633 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-05-20 01:21:18.390177 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:21:18.394210 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-05-20 01:21:18.398494 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-05-20 01:21:18.403194 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-05-20 01:21:18.407556 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-05-20 01:21:18.413070 | controller | +++ functions-common:is_arch:519             :   uname -m
2026-05-20 01:21:18.419445 | controller | ++ functions-common:is_arch:519             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-05-20 01:21:18.423600 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-05-20 01:21:18.427715 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:21:18.432002 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-05-20 01:21:18.436609 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-05-20 01:21:18.441351 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-05-20 01:21:18.446353 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-05-20 01:21:18.450894 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:21:18.455461 | 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:18.460131 | 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:18.464575 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-05-20 01:21:18.468706 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-05-20 01:21:18.473977 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-05-20 01:21:18.480395 | 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:18.480966 | 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:18.481876 | controller | +++ stackrc:source:749                       :   wc -l
2026-05-20 01:21:18.494225 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-05-20 01:21:18.498564 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=30G
2026-05-20 01:21:18.502976 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-05-20 01:21:18.506754 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-05-20 01:21:18.511123 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-05-20 01:21:18.515846 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-05-20 01:21:18.520342 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-05-20 01:21:18.524643 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-05-20 01:21:18.529469 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-05-20 01:21:18.535016 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:21:18.545276 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:21:18.552186 | controller | ++ stackrc:source:777                       :   API_WORKERS=4
2026-05-20 01:21:18.556650 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-05-20 01:21:18.561049 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-05-20 01:21:18.564585 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-05-20 01:21:18.569010 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-05-20 01:21:18.574585 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-05-20 01:21:18.579420 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:18.586532 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:18.586862 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:18.594794 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:18.598364 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:18.603557 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-05-20 01:21:18.608974 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-05-20 01:21:18.613509 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:18.620446 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:18.621128 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:18.629683 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:18.633151 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:18.638557 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-05-20 01:21:18.643866 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-05-20 01:21:18.648253 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:18.654997 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:18.655626 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:18.663579 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:18.667686 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:18.672514 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-05-20 01:21:18.677291 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:21:18.681724 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:21:18.686205 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-05-20 01:21:18.690818 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-05-20 01:21:18.695694 | controller | ++ stackrc:source:815                       :   HOST_IP=199.204.45.248
2026-05-20 01:21:18.700098 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-05-20 01:21:18.705381 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 199.204.45.248 inet
2026-05-20 01:21:18.709338 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=10.1.0.0/20
2026-05-20 01:21:18.713507 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=172.24.5.0/24
2026-05-20 01:21:18.717815 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:21:18.721898 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=199.204.45.248
2026-05-20 01:21:18.725471 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet
2026-05-20 01:21:18.730092 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z 199.204.45.248 -o 199.204.45.248 == dhcp ']'
2026-05-20 01:21:18.734201 | controller | +++ functions-common:get_default_host_ip:785 :   echo 199.204.45.248
2026-05-20 01:21:18.739485 | controller | ++ stackrc:source:818                       :   HOST_IP=199.204.45.248
2026-05-20 01:21:18.744185 | controller | ++ stackrc:source:819                       :   '[' 199.204.45.248 == '' ']'
2026-05-20 01:21:18.749862 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-05-20 01:21:18.753639 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=
2026-05-20 01:21:18.757218 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=
2026-05-20 01:21:18.761785 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:21:18.766025 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=
2026-05-20 01:21:18.770650 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet6
2026-05-20 01:21:18.774557 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z '' -o '' == dhcp ']'
2026-05-20 01:21:18.778745 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip=
2026-05-20 01:21:18.785473 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 route list match default table all
2026-05-20 01:21:18.785969 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/default/ {print $5}'
2026-05-20 01:21:18.786582 | controller | ++++ functions-common:get_default_host_ip:764 :   grep via
2026-05-20 01:21:18.787589 | controller | ++++ functions-common:get_default_host_ip:764 :   head -1
2026-05-20 01:21:18.794472 | controller | +++ functions-common:get_default_host_ip:764 :   host_ip_iface=
2026-05-20 01:21:18.798649 | controller | +++ functions-common:get_default_host_ip:765 :   local host_ips
2026-05-20 01:21:18.805187 | controller | ++++ functions-common:get_default_host_ip:766 :   LC_ALL=C
2026-05-20 01:21:18.806358 | controller | ++++ functions-common:get_default_host_ip:766 :   sed /temporary/d
2026-05-20 01:21:18.806691 | controller | ++++ functions-common:get_default_host_ip:766 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-05-20 01:21:18.809364 | controller | ++++ functions-common:get_default_host_ip:766 :   ip -f inet6 addr show
2026-05-20 01:21:18.818779 | controller | +++ functions-common:get_default_host_ip:766 :   host_ips='::1
2026-05-20 01:21:18.818801 | controller | 2604:e100:1:0:f816:3eff:fed4:4ebe
2026-05-20 01:21:18.818811 | controller | fe80::f816:3eff:fed4:4ebe'
2026-05-20 01:21:18.822452 | controller | +++ functions-common:get_default_host_ip:767 :   local ip
2026-05-20 01:21:18.826593 | controller | +++ functions-common:get_default_host_ip:768 :   for ip in $host_ips
2026-05-20 01:21:18.831009 | controller | +++ functions-common:get_default_host_ip:775 :   [[ inet6 == \i\n\e\t\6 ]]
2026-05-20 01:21:18.835493 | controller | +++ functions-common:get_default_host_ip:776 :   host_ip=::1
2026-05-20 01:21:18.839859 | controller | +++ functions-common:get_default_host_ip:777 :   break
2026-05-20 01:21:18.844435 | controller | +++ functions-common:get_default_host_ip:785 :   echo ::1
2026-05-20 01:21:18.849892 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-05-20 01:21:18.855794 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-05-20 01:21:18.859512 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:18.866190 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:18.866361 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:18.874016 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:18.878097 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:18.882933 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-05-20 01:21:18.887084 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-05-20 01:21:18.891543 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-05-20 01:21:18.896105 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-05-20 01:21:18.900343 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=199.204.45.248
2026-05-20 01:21:18.903838 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:21:18.907542 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:21:18.912076 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-05-20 01:21:18.915937 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:21:18.919893 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=199.204.45.248
2026-05-20 01:21:18.924404 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:21:18.927891 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-05-20 01:21:18.932054 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-05-20 01:21:18.936390 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-05-20 01:21:18.940985 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=199.204.45.248
2026-05-20 01:21:18.945640 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-05-20 01:21:18.949648 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=199.204.45.248
2026-05-20 01:21:18.955283 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-05-20 01:21:18.958946 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:18.965848 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:18.965994 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:18.973621 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:18.978098 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:18.983196 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-05-20 01:21:18.987581 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=199.204.45.248
2026-05-20 01:21:18.992008 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-05-20 01:21:18.996291 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-05-20 01:21:19.001476 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-05-20 01:21:19.005988 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.013514 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.013545 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.021965 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.025334 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.030080 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-05-20 01:21:19.034228 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-05-20 01:21:19.038646 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-05-20 01:21:19.043221 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:19.047458 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-05-20 01:21:19.051844 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-05-20 01:21:19.056984 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-05-20 01:21:19.061200 | 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:19.065488 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-05-20 01:21:19.070266 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-05-20 01:21:19.074582 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-05-20 01:21:19.080121 | controller | + functions:write_devstack_version:876     :   cat -
2026-05-20 01:21:19.081225 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-05-20 01:21:19.085524 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-05-20 01:21:19.291966 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-05-20 01:21:19.296426 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-05-20 01:21:19.300576 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-05-20 01:21:19.305185 | controller | + functions-common:export_proxy_variables:2232 :   isset http_proxy
2026-05-20 01:21:19.309160 | controller | + functions-common:isset:266               :   [[ -v http_proxy ]]
2026-05-20 01:21:19.313505 | controller | + functions-common:export_proxy_variables:2235 :   isset https_proxy
2026-05-20 01:21:19.318568 | controller | + functions-common:isset:266               :   [[ -v https_proxy ]]
2026-05-20 01:21:19.323152 | controller | + functions-common:export_proxy_variables:2238 :   isset no_proxy
2026-05-20 01:21:19.327659 | controller | + functions-common:isset:266               :   [[ -v no_proxy ]]
2026-05-20 01:21:19.331464 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-05-20 01:21:19.335275 | controller | + functions-common:disable_negated_services:1937 :   local xtrace
2026-05-20 01:21:19.340738 | controller | ++ functions-common:disable_negated_services:1938 :   set +o
2026-05-20 01:21:19.342565 | controller | ++ functions-common:disable_negated_services:1938 :   grep xtrace
2026-05-20 01:21:19.350950 | controller | + functions-common:disable_negated_services:1938 :   xtrace='set -o xtrace'
2026-05-20 01:21:19.355053 | controller | + functions-common:disable_negated_services:1939 :   set +o xtrace
2026-05-20 01:21:19.378491 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-05-20 01:21:19.383013 | controller | + functions-common:is_package_installed:1441 :   [[ -z sudo ]]
2026-05-20 01:21:19.387550 | controller | + functions-common:is_package_installed:1445 :   [[ -z deb ]]
2026-05-20 01:21:19.391708 | controller | + functions-common:is_package_installed:1449 :   [[ deb = \d\e\b ]]
2026-05-20 01:21:19.396383 | controller | + functions-common:is_package_installed:1450 :   dpkg -s sudo
2026-05-20 01:21:19.443983 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-05-20 01:21:19.466674 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-05-20 01:21:19.466735 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-05-20 01:21:19.477796 | controller | #includedir /etc/sudoers.d
2026-05-20 01:21:19.484219 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-05-20 01:21:19.490268 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-05-20 01:21:19.497375 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.7F6hIL52t5
2026-05-20 01:21:19.502072 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-05-20 01:21:19.506958 | 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:19.511679 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-05-20 01:21:19.516553 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.7F6hIL52t5
2026-05-20 01:21:19.523669 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.7F6hIL52t5
2026-05-20 01:21:19.549634 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.7F6hIL52t5 /etc/sudoers.d/50_stack_sh
2026-05-20 01:21:19.584745 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-05-20 01:21:19.589727 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:19.593424 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:19.599074 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-05-20 01:21:19.600126 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-05-20 01:21:19.619769 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-05-20 01:21:19.624469 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-05-20 01:21:19.629019 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-05-20 01:21:19.633675 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-05-20 01:21:19.655909 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-05-20 01:21:19.661196 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-05-20 01:21:19.665703 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:19.672590 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:19.672961 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:19.681619 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:19.685446 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:19.694047 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/logs
2026-05-20 01:21:19.715642 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-05-20 01:21:19.719613 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-05-20 01:21:19.724112 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:19.731053 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:19.731638 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:19.739838 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:19.744269 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:19.752528 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/logs
2026-05-20 01:21:19.773953 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-05-20 01:21:19.778660 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-05-20 01:21:19.783487 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-05-20 01:21:19.802023 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-05-20 01:21:19.807340 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-05-20 01:21:19.811329 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:19.818433 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:19.819539 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:19.827630 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:19.831021 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:19.837331 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/data
2026-05-20 01:21:19.858706 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-05-20 01:21:19.863134 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-05-20 01:21:19.868604 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:19.876083 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:19.876564 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:19.881713 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:19.884859 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:19.891959 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/data
2026-05-20 01:21:19.906171 | controller | + ./stack.sh:main:353                      :   async_init
2026-05-20 01:21:19.910237 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-05-20 01:21:19.914712 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-05-20 01:21:19.921865 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-05-20 01:21:19.930912 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-05-20 01:21:19.937203 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000175170
2026-05-20 01:21:19.940921 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000175170 /etc/hosts
2026-05-20 01:21:19.949116 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-05-20 01:21:19.953204 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:19.959602 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:19.960380 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:19.968114 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:19.971922 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:19.977835 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-05-20 01:21:19.981103 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-05-20 01:21:19.984747 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-05-20 01:21:19.989046 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-05-20 01:21:19.993946 | controller | + ./stack.sh:main:404                      :   install_python
2026-05-20 01:21:19.997988 | controller | + inc/python:install_python:482            :   install_python3
2026-05-20 01:21:20.002114 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-05-20 01:21:20.006546 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:20.010576 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:20.014101 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-05-20 01:21:20.018588 | controller | + functions-common:apt_get:1188            :   local xtrace result
2026-05-20 01:21:20.024135 | controller | ++ functions-common:apt_get:1189            :   set +o
2026-05-20 01:21:20.025762 | controller | ++ functions-common:apt_get:1189            :   grep xtrace
2026-05-20 01:21:20.033520 | controller | + functions-common:apt_get:1189            :   xtrace='set -o xtrace'
2026-05-20 01:21:20.037349 | controller | + functions-common:apt_get:1190            :   set +o xtrace
2026-05-20 01:21:20.063154 | 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:20.118821 | controller | Reading package lists...
2026-05-20 01:21:20.385586 | controller | Building dependency tree...
2026-05-20 01:21:20.385772 | controller | Reading state information...
2026-05-20 01:21:20.643223 | controller | python3.12 is already the newest version (3.12.3-1ubuntu0.13).
2026-05-20 01:21:20.643271 | controller | python3.12 set to manually installed.
2026-05-20 01:21:20.643286 | controller | The following additional packages will be installed:
2026-05-20 01:21:20.643455 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:21:20.644720 | controller |   libpython3.12t64 linux-libc-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:21:20.646361 | controller | Suggested packages:
2026-05-20 01:21:20.646398 | controller |   glibc-doc manpages-dev
2026-05-20 01:21:20.646411 | controller | Recommended packages:
2026-05-20 01:21:20.646423 | controller |   manpages manpages-dev libc-devtools
2026-05-20 01:21:20.725684 | controller | The following NEW packages will be installed:
2026-05-20 01:21:20.725976 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:21:20.727325 | controller |   libpython3.12t64 linux-libc-dev python3.12-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:21:21.222834 | controller | 0 upgraded, 10 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:21:21.222923 | controller | Need to get 13.4 MB of archives.
2026-05-20 01:21:21.222938 | controller | After this operation, 63.4 MB of additional disk space will be used.
2026-05-20 01:21:21.222951 | 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.496003 | 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:52.651735 | 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:52.672710 | 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:52.694373 | 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:52.766145 | 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:52.785861 | 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:52.852254 | 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:52.896729 | 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.000689 | 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.182391 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-05-20 01:21:53.227936 | controller | Fetched 13.4 MB in 32s (415 kB/s)
2026-05-20 01:21:53.269969 | controller | Selecting previously unselected package libc-dev-bin.
2026-05-20 01:21:53.384689 | 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:53.385367 | controller | Preparing to unpack .../0-libc-dev-bin_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:53.394467 | controller | Unpacking libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:53.451545 | controller | Selecting previously unselected package linux-libc-dev:amd64.
2026-05-20 01:21:53.455024 | controller | Preparing to unpack .../1-linux-libc-dev_6.8.0-117.117_amd64.deb ...
2026-05-20 01:21:53.460366 | controller | Unpacking linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:53.820689 | controller | Selecting previously unselected package libcrypt-dev:amd64.
2026-05-20 01:21:53.826410 | controller | Preparing to unpack .../2-libcrypt-dev_1%3a4.4.36-4build1_amd64.deb ...
2026-05-20 01:21:53.848484 | controller | Unpacking libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:53.917627 | controller | Selecting previously unselected package rpcsvc-proto.
2026-05-20 01:21:53.923246 | controller | Preparing to unpack .../3-rpcsvc-proto_1.4.2-0ubuntu7_amd64.deb ...
2026-05-20 01:21:53.928796 | controller | Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:53.996634 | controller | Selecting previously unselected package libc6-dev:amd64.
2026-05-20 01:21:54.001800 | controller | Preparing to unpack .../4-libc6-dev_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:54.007141 | controller | Unpacking libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:54.270715 | controller | Selecting previously unselected package libexpat1-dev:amd64.
2026-05-20 01:21:54.276408 | controller | Preparing to unpack .../5-libexpat1-dev_2.6.1-2ubuntu0.4_amd64.deb ...
2026-05-20 01:21:54.281551 | controller | Unpacking libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:54.361589 | controller | Selecting previously unselected package libpython3.12t64:amd64.
2026-05-20 01:21:54.366342 | controller | Preparing to unpack .../6-libpython3.12t64_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:54.376610 | controller | Unpacking libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:54.513671 | controller | Selecting previously unselected package zlib1g-dev:amd64.
2026-05-20 01:21:54.519180 | controller | Preparing to unpack .../7-zlib1g-dev_1%3a1.3.dfsg-3.1ubuntu2.1_amd64.deb ...
2026-05-20 01:21:54.524493 | controller | Unpacking zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:54.599545 | controller | Selecting previously unselected package libpython3.12-dev:amd64.
2026-05-20 01:21:54.605666 | controller | Preparing to unpack .../8-libpython3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:54.611553 | controller | Unpacking libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:54.902639 | controller | Selecting previously unselected package python3.12-dev.
2026-05-20 01:21:54.908434 | controller | Preparing to unpack .../9-python3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:54.913584 | controller | Unpacking python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:54.992115 | controller | Setting up linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:55.007932 | controller | Setting up libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.023232 | controller | Setting up rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:55.038235 | controller | Setting up libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:55.065647 | controller | Setting up libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:55.079871 | controller | Setting up libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:55.095713 | controller | Setting up libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:55.109765 | controller | Setting up zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:55.123679 | controller | Setting up libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.137433 | controller | Setting up python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:55.150395 | controller | Processing triggers for libc-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:55.451056 | controller | + functions-common:apt_get:1205            :   result=0
2026-05-20 01:21:55.455889 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:21:55.459583 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:21:55.463505 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:21:55.467534 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:21:55.471776 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:21:55.475599 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:21:55.479240 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:21:55.483588 | controller | + functions-common:time_stop:2421          :   start_time=1779240080058
2026-05-20 01:21:55.487834 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240080058 ]]
2026-05-20 01:21:55.493156 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:21:55.499050 | controller | + functions-common:time_stop:2426          :   end_time=1779240115495
2026-05-20 01:21:55.502845 | controller | + functions-common:time_stop:2427          :   elapsed_time=35437
2026-05-20 01:21:55.510925 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:21:55.511478 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:21:55.515225 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=35437
2026-05-20 01:21:55.519321 | controller | + functions-common:apt_get:1209            :   return 0
2026-05-20 01:21:55.525113 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-05-20 01:21:55.533636 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-05-20 01:21:55.538544 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-05-20 01:21:55.545022 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-05-20 01:21:55.549572 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:55.557189 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:55.557453 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:55.564823 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:55.568862 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:55.574567 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-05-20 01:21:55.580501 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-05-20 01:21:55.585176 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:55.591644 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:55.592947 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:55.601017 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:55.605542 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:55.610586 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:55.615501 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-05-20 01:21:55.620346 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-05-20 01:21:55.625802 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-05-20 01:21:55.632983 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-05-20-012155
2026-05-20 01:21:55.636809 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:55.641176 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-05-20 01:21:55.645246 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-05-20 01:21:55.648361 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-05-20 01:21:55.655721 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-05-20 01:21:55.663347 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012155
2026-05-20 01:21:55.667654 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012155.summary.2026-05-20-012155
2026-05-20 01:21:55.672158 | controller | + ./stack.sh:main:476                      :   exec
2026-05-20 01:21:55.675857 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:55.680344 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-05-20 01:21:55.684294 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:55.687974 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-05-20 01:21:55.692255 | controller | + ./stack.sh:main:483                      :   exec
2026-05-20 01:21:55.697524 | 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-012155
2026-05-20 01:21:55.726205 | controller | + ./stack.sh:main:485                      :   exec
2026-05-20 01:21:55.726245 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012155'
2026-05-20 01:21:55.726257 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-05-20-012155.summary.2026-05-20-012155
2026-05-20 01:21:55.726266 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-05-20 01:21:55.726274 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012155
2026-05-20 01:21:55.726287 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012155 /opt/stack/logs/devstacklog.txt
2026-05-20 01:21:55.726295 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012155.summary.2026-05-20-012155 /opt/stack/logs/devstacklog.txt.summary
2026-05-20 01:21:55.726344 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-05-20 01:21:55.730254 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-05-20 01:21:55.735502 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-05-20 01:21:55.742183 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-05-20 01:21:55.745978 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-05-20 01:21:55.753099 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-05-20 01:21:55.753425 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-05-20 01:21:55.760449 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:55.765269 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-05-20 01:21:55.770638 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-05-20 01:21:55.778288 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:55.782850 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:55.787075 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-05-20 01:21:55.792704 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-05-20 01:21:55.800124 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:55.804401 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-05-20 01:21:55.808851 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-05-20 01:21:55.813496 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-05-20 01:21:55.817873 | controller | + ./stack.sh:main:574                      :   uname -a
2026-05-20 01:21:55.820423 | controller | Linux np0000175170 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:55.825338 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-05-20 01:21:55.830406 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-05-20 01:21:55.837245 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-05-20 01:21:55.908766 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-05-20 01:21:55.940887 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-05-20 01:21:55.946403 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-05-20 01:21:55.975952 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-05-20 01:21:55.981704 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-05-20 01:21:56.010773 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-05-20 01:21:56.016756 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-05-20 01:21:56.046464 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-05-20 01:21:56.050796 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-05-20 01:21:56.055464 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-05-20 01:21:56.061268 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-05-20 01:21:56.090128 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:56.095944 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-05-20 01:21:56.123512 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:56.127542 | controller | + ./stack.sh:main:588                      :   tune_host
2026-05-20 01:21:56.132013 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-05-20 01:21:56.137695 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-05-20 01:21:56.142062 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:56.147079 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-05-20 01:21:56.150161 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:56.154919 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:56.160336 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-05-20 01:21:56.160530 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-05-20 01:21:56.176147 | controller | lz4
2026-05-20 01:21:56.182593 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-05-20 01:21:56.182991 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-05-20 01:21:56.194488 | controller | zsmalloc
2026-05-20 01:21:56.201734 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-05-20 01:21:56.202731 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-05-20 01:21:56.217061 | controller | 1
2026-05-20 01:21:56.243019 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-05-20 01:21:56.257943 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-05-20 01:21:56.257981 | controller | /sys/module/zswap/parameters/enabled:Y
2026-05-20 01:21:56.257992 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-05-20 01:21:56.258006 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-05-20 01:21:56.258015 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-05-20 01:21:56.258027 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-05-20 01:21:56.258067 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-05-20 01:21:56.258080 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-05-20 01:21:56.258091 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-05-20 01:21:56.263919 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-05-20 01:21:56.267823 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:56.272451 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-05-20 01:21:56.276344 | controller | + functions-common:install_package:1432    :   update_package_repo
2026-05-20 01:21:56.280215 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:21:56.284265 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=False
2026-05-20 01:21:56.288006 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=False
2026-05-20 01:21:56.291716 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:56.294931 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:21:56.298035 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:56.302460 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:56.306672 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:21:56.310698 | controller | + functions-common:apt_get_update:1160     :   [[ False == \T\r\u\e ]]
2026-05-20 01:21:56.315000 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:56.319022 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:21:56.324070 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:21:56.330572 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:21:56.335384 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:21:56.339713 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:21:56.343925 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:21:56.348404 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:21:56.354263 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:21:56.360268 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240116356
2026-05-20 01:21:56.364546 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:21:56.369571 | 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:56.374004 | 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:06.454767 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:06.455591 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:16.445181 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:22:22.474907 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:29.349895 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:22:34.489721 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:39.363761 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:48.505193 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:48.505238 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:49.372166 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:22:58.062972 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:08.075965 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:18.128543 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:28.145570 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:38.156639 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:38.156736 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:48.167850 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:48.167903 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:58.181295 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:58.181382 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:59.186225 | controller | Reading package lists...
2026-05-20 01:23:59.210543 | 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:23:59.212697 | 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:23:59.212712 | 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:23:59.212720 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:23:59.212728 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:23:59.223159 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:23:59.227425 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:23:59.233429 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:23:59.238481 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:23:59.242277 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:23:59.246554 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:23:59.250537 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:23:59.254985 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:23:59.259432 | controller | + functions-common:time_stop:2421          :   start_time=1779240116356
2026-05-20 01:23:59.263901 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240116356 ]]
2026-05-20 01:23:59.268656 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:23:59.275701 | controller | + functions-common:time_stop:2426          :   end_time=1779240239270
2026-05-20 01:23:59.280394 | controller | + functions-common:time_stop:2427          :   elapsed_time=122914
2026-05-20 01:23:59.284113 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:23:59.288440 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:23:59.293330 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=122914
2026-05-20 01:23:59.298318 | controller | + functions-common:install_package:1433    :   real_install_package ksmtuned
2026-05-20 01:23:59.302625 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:23:59.306959 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:23:59.311615 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:23:59.315514 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:23:59.353457 | 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:23:59.399718 | controller | Reading package lists...
2026-05-20 01:23:59.764528 | controller | Building dependency tree...
2026-05-20 01:23:59.764630 | controller | Reading state information...
2026-05-20 01:24:00.105856 | controller | Recommended packages:
2026-05-20 01:24:00.105912 | controller |   qemu-kvm
2026-05-20 01:24:00.146883 | controller | The following NEW packages will be installed:
2026-05-20 01:24:00.148938 | controller |   ksmtuned
2026-05-20 01:24:14.976028 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:24:14.976116 | controller | Need to get 7,444 B of archives.
2026-05-20 01:24:14.976128 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:24:14.976146 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:25.999884 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:38.019988 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:55.816546 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:55.816631 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:55.835719 | 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:24:55.835746 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:24:55.845667 | controller | + functions-common:apt_get:1205            :   result=100
2026-05-20 01:24:55.850842 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:24:55.855079 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:24:55.858621 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:24:55.864053 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:24:55.869161 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:24:55.875010 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:24:55.878942 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:24:55.883697 | controller | + functions-common:time_stop:2421          :   start_time=1779240239347
2026-05-20 01:24:55.888254 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240239347 ]]
2026-05-20 01:24:55.892763 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:24:55.900238 | controller | + functions-common:time_stop:2426          :   end_time=1779240295894
2026-05-20 01:24:55.904614 | controller | + functions-common:time_stop:2427          :   elapsed_time=56547
2026-05-20 01:24:55.908221 | controller | + functions-common:time_stop:2428          :   total=35437
2026-05-20 01:24:55.912554 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:24:55.916400 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=91984
2026-05-20 01:24:55.920027 | controller | + functions-common:apt_get:1209            :   return 100
2026-05-20 01:24:55.924561 | controller | + functions-common:install_package:1434    :   RETRY_UPDATE=True
2026-05-20 01:24:55.929600 | controller | + functions-common:install_package:1434    :   update_package_repo
2026-05-20 01:24:55.934688 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:24:55.939122 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=True
2026-05-20 01:24:55.943552 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=True
2026-05-20 01:24:55.947527 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:24:55.951658 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:24:55.956238 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:24:55.960622 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:24:55.965079 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:24:55.968998 | controller | + functions-common:apt_get_update:1160     :   [[ True == \T\r\u\e ]]
2026-05-20 01:24:55.972668 | controller | + functions-common:apt_get_update:1160     :   [[ True != \T\r\u\e ]]
2026-05-20 01:24:55.975467 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:24:55.979653 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:24:55.986824 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:24:55.994254 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:24:55.999093 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:24:56.002613 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:24:56.006516 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:24:56.012128 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:24:56.017534 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:24:56.025622 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240296019
2026-05-20 01:24:56.029471 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:24:56.033193 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:24:56.037591 | 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:06.115637 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:25:06.116437 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:16.129691 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:25:22.133846 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:26.143086 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:34.148256 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:36.156689 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:25:47.450604 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:25:48.163587 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:48.163651 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:25:58.538141 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:07.316573 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:17.330929 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:27.345031 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:37.359388 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:37.359434 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:47.374508 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:47.374590 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:57.388578 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:57.388623 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:58.487762 | controller | Reading package lists...
2026-05-20 01:26:58.514824 | 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:26:58.515476 | 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:26:58.515493 | 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:26:58.515503 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:26:58.515512 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:26:58.525881 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:26:58.531424 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:26:58.536494 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:26:58.541009 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:26:58.545629 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:26:58.550461 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:26:58.555608 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:26:58.559570 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:26:58.563646 | controller | + functions-common:time_stop:2421          :   start_time=1779240296019
2026-05-20 01:26:58.568770 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240296019 ]]
2026-05-20 01:26:58.574487 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:26:58.581565 | controller | + functions-common:time_stop:2426          :   end_time=1779240418576
2026-05-20 01:26:58.586137 | controller | + functions-common:time_stop:2427          :   elapsed_time=122557
2026-05-20 01:26:58.589943 | controller | + functions-common:time_stop:2428          :   total=122914
2026-05-20 01:26:58.594408 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:26:58.598612 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=245471
2026-05-20 01:26:58.603573 | controller | + functions-common:install_package:1434    :   real_install_package ksmtuned
2026-05-20 01:26:58.607493 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:26:58.611530 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:26:58.616623 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:26:58.621211 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:26:58.657600 | 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:26:58.704680 | controller | Reading package lists...
2026-05-20 01:26:59.059889 | controller | Building dependency tree...
2026-05-20 01:26:59.060423 | controller | Reading state information...
2026-05-20 01:26:59.390609 | controller | Recommended packages:
2026-05-20 01:26:59.390646 | controller |   qemu-kvm
2026-05-20 01:26:59.425736 | controller | The following NEW packages will be installed:
2026-05-20 01:26:59.427895 | controller |   ksmtuned
2026-05-20 01:27:09.476422 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:27:09.476499 | controller | Need to get 7,444 B of archives.
2026-05-20 01:27:09.476518 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:27:09.476528 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:19.812992 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:31.828556 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:42.315864 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:42.315911 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:42.330869 | 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:42.330943 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:27:42.342198 | controller | + functions-common:apt_get:1               :   exit_trap
2026-05-20 01:27:42.347245 | controller | + ./stack.sh:exit_trap:519                 :   local r=100
2026-05-20 01:27:42.353692 | controller | ++ ./stack.sh:exit_trap:520                 :   jobs -p
2026-05-20 01:27:42.358670 | controller | + ./stack.sh:exit_trap:520                 :   jobs=
2026-05-20 01:27:42.362660 | controller | + ./stack.sh:exit_trap:523                 :   [[ -n '' ]]
2026-05-20 01:27:42.366874 | controller | + ./stack.sh:exit_trap:529                 :   '[' -f '' ']'
2026-05-20 01:27:42.371636 | controller | + ./stack.sh:exit_trap:534                 :   kill_spinner
2026-05-20 01:27:42.375690 | controller | + ./stack.sh:kill_spinner:429              :   '[' '!' -z '' ']'
2026-05-20 01:27:42.379671 | controller | + ./stack.sh:exit_trap:536                 :   [[ 100 -ne 0 ]]
2026-05-20 01:27:42.384548 | controller | + ./stack.sh:exit_trap:537                 :   echo 'Error on exit'
2026-05-20 01:27:42.384572 | controller | Error on exit
2026-05-20 01:27:42.388767 | controller | + ./stack.sh:exit_trap:539                 :   type -p generate-subunit
2026-05-20 01:27:42.392775 | controller | + ./stack.sh:exit_trap:542                 :   [[ -z /opt/stack/logs ]]
2026-05-20 01:27:42.396799 | 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:42.939671 | controller | + ./stack.sh:exit_trap:554                 :   exit 100
2026-05-20 01:27:42.940922 | controller | *** FINISHED ***
2026-05-20 01:27:43.456007 | controller | ERROR
2026-05-20 01:27:43.456220 | controller | {
2026-05-20 01:27:43.456261 | controller |   "delta": "0:06:27.731345",
2026-05-20 01:27:43.456290 | controller |   "end": "2026-05-20 01:27:42.960688",
2026-05-20 01:27:43.456318 | controller |   "msg": "non-zero return code",
2026-05-20 01:27:43.456344 | controller |   "rc": 100,
2026-05-20 01:27:43.456375 | controller |   "start": "2026-05-20 01:21:15.229343"
2026-05-20 01:27:43.456401 | controller | }
failure
2026-05-20 01:27:43.471629 | 
2026-05-20 01:27:43.471678 | PLAY RECAP
2026-05-20 01:27:43.471722 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:27:43.471743 | 
2026-05-20 01:27:43.601516 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:27:43.609745 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:27:44.413117 | 
2026-05-20 01:27:44.413253 | PLAY [all]
2026-05-20 01:27:44.431244 | 
2026-05-20 01:27:44.431388 | LOOP [Copy individual Hydrophone result files]
2026-05-20 01:27:45.098282 | controller | ERROR: Item: e2e.log
2026-05-20 01:27:45.098580 | controller | {
2026-05-20 01:27:45.098630 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:45.098665 | controller |   "item": "e2e.log",
2026-05-20 01:27:45.098726 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-20 01:27:45.098757 | controller | }
2026-05-20 01:27:45.369618 | controller | ERROR: Item: junit_01.xml
2026-05-20 01:27:45.369757 | controller | {
2026-05-20 01:27:45.369794 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:45.369816 | controller |   "item": "junit_01.xml",
2026-05-20 01:27:45.369834 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-20 01:27:45.369852 | controller | }
2026-05-20 01:27:45.384869 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:45.402723 | 
2026-05-20 01:27:45.402960 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-20 01:27:45.692235 | controller | ERROR
2026-05-20 01:27:45.692530 | controller | {
2026-05-20 01:27:45.692577 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-20 01:27:45.692610 | controller | }
2026-05-20 01:27:45.692646 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:45.704938 | 
2026-05-20 01:27:45.705032 | TASK [Return built artifacts to Zuul]
2026-05-20 01:27:45.760145 | controller | ok
2026-05-20 01:27:45.766328 | 
2026-05-20 01:27:45.766411 | TASK [Get the Docker volume ID]
2026-05-20 01:27:46.310910 | controller | ERROR
2026-05-20 01:27:46.311235 | controller | {
2026-05-20 01:27:46.311272 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-20 01:27:46.311346 | controller |   "rc": 2
2026-05-20 01:27:46.311373 | controller | }
2026-05-20 01:27:46.311402 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:46.316904 | 
2026-05-20 01:27:46.317000 | TASK [Copy all of the pod logs]
2026-05-20 01:27:46.354131 | controller | ERROR
2026-05-20 01:27:46.354402 | controller | {
2026-05-20 01:27:46.354447 | 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/ba43d3794ad340d28a76ac38f08f3031/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:46.354480 | controller | }
2026-05-20 01:27:46.354516 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:46.355982 | 
2026-05-20 01:27:46.356049 | PLAY RECAP
2026-05-20 01:27:46.356113 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-20 01:27:46.356144 | 
2026-05-20 01:27:46.569002 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:27:46.598835 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:27:47.998916 | 
2026-05-20 01:27:47.999233 | PLAY [all]
2026-05-20 01:27:48.118910 | 
2026-05-20 01:27:48.119054 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-20 01:27:48.714812 | controller | changed
2026-05-20 01:27:48.722733 | 
2026-05-20 01:27:48.722817 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-20 01:27:49.763163 | controller | ok: Runtime: 0:00:00.460064
2026-05-20 01:27:49.768557 | 
2026-05-20 01:27:49.768623 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-20 01:27:50.306090 | controller | ok: Runtime: 0:00:00.024215
2026-05-20 01:27:50.313962 | 
2026-05-20 01:27:50.314053 | TASK [export-devstack-journal : Export journal]
2026-05-20 01:27:50.887245 | controller | ok: Runtime: 0:00:00.028098
2026-05-20 01:27:50.895220 | 
2026-05-20 01:27:50.895285 | TASK [export-devstack-journal : Save journal README]
2026-05-20 01:27:51.793755 | controller | changed
2026-05-20 01:27:51.812354 | 
2026-05-20 01:27:51.812464 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-20 01:27:52.051485 | controller | changed
2026-05-20 01:27:52.061852 | 
2026-05-20 01:27:52.061921 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:27:52.398188 | controller | Output suppressed because no_log was given
2026-05-20 01:27:52.405755 | 
2026-05-20 01:27:52.405829 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:27:52.445188 | 
2026-05-20 01:27:52.445344 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:27:52.480067 | 
2026-05-20 01:27:52.480234 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:27:52.514760 | 
2026-05-20 01:27:52.514969 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:27:52.550194 | 
2026-05-20 01:27:52.550377 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:27:52.591397 | 
2026-05-20 01:27:52.591670 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-20 01:27:52.845463 | controller | changed
2026-05-20 01:27:52.857592 | 
2026-05-20 01:27:52.857659 | TASK [apache-logs-conf : Define config paths]
2026-05-20 01:27:52.891463 | controller | ok
2026-05-20 01:27:52.897966 | 
2026-05-20 01:27:52.898026 | TASK [apache-logs-conf : Discover configurations]
2026-05-20 01:27:53.148265 | controller | Output suppressed because no_log was given
2026-05-20 01:27:53.156873 | 
2026-05-20 01:27:53.156944 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-20 01:27:53.193728 | 
2026-05-20 01:27:53.193903 | LOOP [apache-logs-conf : Link configurations]
2026-05-20 01:27:53.225375 | 
2026-05-20 01:27:53.257676 | TASK [capture-performance-data : Generate statistics]
2026-05-20 01:27:55.782590 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-20 01:27:55.800872 | controller | Using python 3.12 to install setuptools
2026-05-20 01:27:55.836698 | controller | /usr/bin/python3.12: No module named pip
2026-05-20 01:27:55.847735 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-20 01:27:56.306314 | controller | ERROR
2026-05-20 01:27:56.306486 | controller | {
2026-05-20 01:27:56.306518 | controller |   "delta": "0:00:02.337723",
2026-05-20 01:27:56.306540 | controller |   "end": "2026-05-20 01:27:55.849897",
2026-05-20 01:27:56.306559 | controller |   "msg": "non-zero return code",
2026-05-20 01:27:56.306580 | controller |   "rc": 127,
2026-05-20 01:27:56.306599 | controller |   "start": "2026-05-20 01:27:53.512174"
2026-05-20 01:27:56.306617 | controller | }
2026-05-20 01:27:56.306641 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:56.317783 | 
2026-05-20 01:27:56.317851 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-20 01:27:56.566627 | controller | changed
2026-05-20 01:27:56.573652 | 
2026-05-20 01:27:56.573736 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-20 01:28:00.281820 | controller | Output suppressed because no_log was given
2026-05-20 01:28:00.294671 | 
2026-05-20 01:28:00.294763 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-20 01:28:00.404700 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.405176 | 
2026-05-20 01:28:00.406981 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.412137 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.413222 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.414949 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.417583 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.420466 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.423465 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.427999 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.431793 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.434935 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.437360 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.440094 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.443124 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.445971 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.448620 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.451367 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.454204 | controller | skipping: Conditional result was False
2026-05-20 01:28:00.515213 | 
2026-05-20 01:28:00.515326 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-20 01:28:00.743519 | controller | ok
2026-05-20 01:28:00.750925 | 
2026-05-20 01:28:00.750993 | TASK [devstack-project-conf : Copy configuration files]
2026-05-20 01:28:01.281662 | controller | skipping: Conditional result was False
2026-05-20 01:28:01.296795 | 
2026-05-20 01:28:01.296881 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-20 01:28:01.603786 | controller | /usr/bin/python3: No module named pip
2026-05-20 01:28:02.704175 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-20 01:28:02.834488 | controller | ok: Runtime: 0:00:01.170924
2026-05-20 01:28:02.846155 | 
2026-05-20 01:28:02.846221 | LOOP [stage-output : Register sources]
2026-05-20 01:28:12.243367 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.253788 | 
2026-05-20 01:28:12.253889 | TASK [stage-output : Check sudo]
2026-05-20 01:28:12.788814 | controller | ok: Runtime: 0:00:00.022425
2026-05-20 01:28:12.796011 | 
2026-05-20 01:28:12.796238 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-20 01:28:12.908571 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.922892 | 
2026-05-20 01:28:12.924265 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.924817 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.925368 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.956841 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.957396 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.959178 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.961795 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.964485 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.967302 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.971344 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.973938 | controller | Output suppressed because no_log was given
2026-05-20 01:28:12.993375 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.004605 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.005232 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.005739 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.032314 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.032923 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.033461 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.078398 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.078809 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.156092 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.165858 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.179582 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.187008 | controller | Output suppressed because no_log was given
2026-05-20 01:28:13.203980 | 
2026-05-20 01:28:13.204164 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-20 01:28:13.270483 | controller | ok
2026-05-20 01:28:13.278682 | 
2026-05-20 01:28:13.278750 | LOOP [stage-output : Ensure target folders exist]
2026-05-20 01:28:13.535972 | controller | changed: "docs"
2026-05-20 01:28:13.772483 | controller | changed: "artifacts"
2026-05-20 01:28:13.999547 | controller | ok: "logs"
2026-05-20 01:28:14.130001 | 
2026-05-20 01:28:14.130155 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-20 01:28:14.418414 | controller | ok: Item: Runtime: 0:00:00.012269
2026-05-20 01:28:14.418674 | controller | changed: All items complete
2026-05-20 01:28:14.418705 | 
2026-05-20 01:28:14.650271 | controller | ok: Item: Runtime: 0:00:00.008513
2026-05-20 01:28:14.886341 | controller | ok: Item: Runtime: 0:00:00.008460
2026-05-20 01:28:15.111846 | controller | ok: Item: Runtime: 0:00:00.008120
2026-05-20 01:28:15.347184 | controller | ok: Item: Runtime: 0:00:00.010893
2026-05-20 01:28:15.571654 | controller | ok: Item: Runtime: 0:00:00.011355
2026-05-20 01:28:15.841731 | controller | ok: Item: Runtime: 0:00:00.008664
2026-05-20 01:28:16.025161 | controller | ok: Item: Runtime: 0:00:00.009182
2026-05-20 01:28:16.263969 | controller | ok: Item: Runtime: 0:00:00.008832
2026-05-20 01:28:16.496345 | controller | ok: Item: Runtime: 0:00:00.008889
2026-05-20 01:28:16.720812 | controller | ok: Item: Runtime: 0:00:00.010510
2026-05-20 01:28:16.951838 | controller | ok: Item: Runtime: 0:00:00.009088
2026-05-20 01:28:17.184436 | controller | ok: Item: Runtime: 0:00:00.009007
2026-05-20 01:28:17.408041 | controller | ok: Item: Runtime: 0:00:00.006298
2026-05-20 01:28:17.634352 | controller | ok: Item: Runtime: 0:00:00.009911
2026-05-20 01:28:17.854280 | controller | ok: Item: Runtime: 0:00:00.009234
2026-05-20 01:28:18.077373 | controller | ok: Item: Runtime: 0:00:00.008606
2026-05-20 01:28:18.305435 | controller | ok: Item: Runtime: 0:00:00.008608
2026-05-20 01:28:18.523971 | controller | ok: Item: Runtime: 0:00:00.006228
2026-05-20 01:28:18.760385 | controller | ok: Item: Runtime: 0:00:00.008655
2026-05-20 01:28:18.790079 | 
2026-05-20 01:28:18.790216 | TASK [stage-output : Make all log files readable]
2026-05-20 01:28:19.041391 | controller | changed
2026-05-20 01:28:19.053703 | 
2026-05-20 01:28:19.053801 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-20 01:28:19.461004 | controller | changed: Renamed files for staging.
2026-05-20 01:28:19.467358 | 
2026-05-20 01:28:19.467432 | TASK [stage-output : Discover log files for compression]
2026-05-20 01:28:19.492249 | controller | skipping: Conditional result was False
2026-05-20 01:28:19.504094 | 
2026-05-20 01:28:19.504226 | LOOP [stage-output : Archive everything from logs]
2026-05-20 01:28:19.543760 | 
2026-05-20 01:28:19.543891 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-20 01:28:20.326174 | controller | changed:
2026-05-20 01:28:20.326394 | controller | created directory /var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/work/logs/controller
2026-05-20 01:28:20.326424 | controller | cd+++++++++ logs/
2026-05-20 01:28:20.326445 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-05-20 01:28:20.326465 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-20 01:28:20.326484 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-20 01:28:20.326502 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-20 01:28:20.326520 | controller | >f+++++++++ logs/devstacklog.txt
2026-05-20 01:28:20.326539 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-05-20 01:28:20.326557 | controller | >f+++++++++ logs/df.txt
2026-05-20 01:28:20.326589 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-20 01:28:20.326608 | controller | >f+++++++++ logs/iptables.txt
2026-05-20 01:28:20.326626 | controller | >f+++++++++ logs/listen53.txt
2026-05-20 01:28:20.326644 | controller | >f+++++++++ logs/local_conf.txt
2026-05-20 01:28:20.326662 | controller | >f+++++++++ logs/mount.txt
2026-05-20 01:28:20.326679 | controller | >f+++++++++ logs/performance.json
2026-05-20 01:28:20.326704 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-20 01:28:20.326723 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-20 01:28:20.326741 | controller | >f+++++++++ logs/services.txt
2026-05-20 01:28:20.326759 | controller | >f+++++++++ logs/sudoers
2026-05-20 01:28:20.326777 | controller | >f+++++++++ logs/syslog.txt
2026-05-20 01:28:20.326794 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-05-20 01:28:20.326812 | controller | cd+++++++++ logs/apache/
2026-05-20 01:28:20.326830 | controller | cd+++++++++ logs/apache_config/
2026-05-20 01:28:20.326847 | controller | cd+++++++++ logs/etc/
2026-05-20 01:28:20.326866 | controller | cd+++++++++ logs/sudoers.d/
2026-05-20 01:28:20.326883 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-20 01:28:20.326901 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-20 01:28:20.326920 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-20 01:28:20.326953 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-20 01:28:20.326972 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-20 01:28:20.337467 | 
2026-05-20 01:28:20.337531 | TASK [Check if a tempest log exits]
2026-05-20 01:28:20.616768 | controller | ok
2026-05-20 01:28:20.624103 | 
2026-05-20 01:28:20.624182 | TASK [Link post-devstack tempest.log]
2026-05-20 01:28:20.650128 | controller | skipping: Conditional result was False
2026-05-20 01:28:20.661779 | 
2026-05-20 01:28:20.661873 | TASK [Capture most recent qemu crash dump, if any]
2026-05-20 01:28:20.918133 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-20 01:28:21.210660 | controller | ERROR
2026-05-20 01:28:21.211339 | controller | {
2026-05-20 01:28:21.211425 | controller |   "delta": "0:00:00.005890",
2026-05-20 01:28:21.211474 | controller |   "end": "2026-05-20 01:28:20.918546",
2026-05-20 01:28:21.211518 | controller |   "msg": "non-zero return code",
2026-05-20 01:28:21.211557 | controller |   "rc": 127,
2026-05-20 01:28:21.211597 | controller |   "start": "2026-05-20 01:28:20.912656"
2026-05-20 01:28:21.211636 | controller | }
2026-05-20 01:28:21.211690 | controller | ERROR: Ignoring Errors
2026-05-20 01:28:21.214479 | 
2026-05-20 01:28:21.214588 | PLAY RECAP
2026-05-20 01:28:21.214683 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-20 01:28:21.214732 | 
2026-05-20 01:28:21.378253 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:28:21.397483 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:23.034541 | 
2026-05-20 01:28:23.242205 | PLAY [all]
2026-05-20 01:28:23.301178 | 
2026-05-20 01:28:23.301354 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 01:28:23.345225 | controller | skipping: Conditional result was False
2026-05-20 01:28:23.352708 | 
2026-05-20 01:28:23.352854 | TASK [fetch-output : Set log path for single node]
2026-05-20 01:28:23.399585 | controller | ok
2026-05-20 01:28:23.405778 | 
2026-05-20 01:28:23.405848 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 01:28:24.197156 | controller -> localhost | ok: "/var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/work/logs"
2026-05-20 01:28:24.406570 | controller -> localhost | changed: "/var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/work/artifacts"
2026-05-20 01:28:24.607472 | controller -> localhost | changed: "/var/lib/zuul/builds/ba43d3794ad340d28a76ac38f08f3031/work/docs"
2026-05-20 01:28:24.623019 | 
2026-05-20 01:28:24.623156 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 01:28:25.689497 | controller | changed: .d..t...... ./
2026-05-20 01:28:25.689870 | controller | changed: All items complete
2026-05-20 01:28:25.689924 | 
2026-05-20 01:28:26.210921 | controller | changed: .d..t...... ./
2026-05-20 01:28:26.728454 | controller | changed: .d..t...... ./
2026-05-20 01:28:26.744318 | 
2026-05-20 01:28:26.744423 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 01:28:27.317230 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.114589
2026-05-20 01:28:27.581168 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008357
2026-05-20 01:28:27.596486 | 
2026-05-20 01:28:27.596607 | PLAY [all]
2026-05-20 01:28:27.603725 | 
2026-05-20 01:28:27.603804 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 01:28:28.320494 | controller | changed
2026-05-20 01:28:28.397493 | 
2026-05-20 01:28:28.397578 | PLAY RECAP
2026-05-20 01:28:28.397630 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:28:28.397653 | 
2026-05-20 01:28:28.590999 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:28.619736 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 01:28:29.305915 | 
2026-05-20 01:28:29.306045 | PLAY [localhost]
2026-05-20 01:28:29.316257 | 
2026-05-20 01:28:29.316347 | TASK [Generate Zuul manifest]
2026-05-20 01:28:29.334913 | localhost | ok
2026-05-20 01:28:29.386664 | 
2026-05-20 01:28:29.386844 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 01:28:29.766587 | localhost | changed
2026-05-20 01:28:29.778957 | 
2026-05-20 01:28:29.779047 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 01:28:29.806328 | localhost | ok
2026-05-20 01:28:29.812762 | 
2026-05-20 01:28:29.812825 | TASK [Upload logs]
2026-05-20 01:28:29.834070 | localhost | ok
2026-05-20 01:28:29.943452 | 
2026-05-20 01:28:29.943591 | TASK [Set zuul-log-path fact]
2026-05-20 01:28:29.965411 | localhost | ok
2026-05-20 01:28:29.979998 | 
2026-05-20 01:28:29.980068 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:28:30.019213 | localhost | ok
2026-05-20 01:28:30.030516 | 
2026-05-20 01:28:30.030595 | TASK [upload-logs : Create log directories]
2026-05-20 01:28:30.548191 | localhost | changed
2026-05-20 01:28:30.554056 | 
2026-05-20 01:28:30.554148 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 01:28:31.017813 | localhost -> localhost | ok: Runtime: 0:00:00.004513
2026-05-20 01:28:31.023003 | 
2026-05-20 01:28:31.023084 | TASK [upload-logs : Upload logs to log server]
2026-05-20 01:28:31.719990 | localhost | Output suppressed because no_log was given
2026-05-20 01:28:31.725804 | 
2026-05-20 01:28:31.725875 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 01:28:31.774141 | localhost | skipping: Conditional result was False
2026-05-20 01:28:31.786497 | localhost | skipping: Conditional result was False
2026-05-20 01:28:31.796164 | 
2026-05-20 01:28:31.796304 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 01:28:31.855836 | localhost | skipping: Conditional result was False
2026-05-20 01:28:31.856220 | 
2026-05-20 01:28:31.862708 | localhost | skipping: Conditional result was False
2026-05-20 01:28:31.878771 | 
2026-05-20 01:28:31.878980 | LOOP [upload-logs : Upload console log and json output]