2026-02-19 01:12:12.776262 | Job console starting
2026-02-19 01:12:12.798224 | Updating git repos
2026-02-19 01:12:16.231534 | Cloning repos into workspace
2026-02-19 01:12:28.174674 | Restoring repo states
2026-02-19 01:12:30.589221 | Merging changes
2026-02-19 01:12:31.786792 | Checking out repos
2026-02-19 01:12:41.866297 | Preparing playbooks
2026-02-19 01:12:47.801075 | Running Ansible setup
2026-02-19 01:12:52.253069 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-19 01:12:52.929646 | 
2026-02-19 01:12:52.997151 | PLAY [localhost]
2026-02-19 01:12:53.011884 | 
2026-02-19 01:12:53.011957 | TASK [Gathering Facts]
2026-02-19 01:12:54.066800 | localhost | ok
2026-02-19 01:12:54.076120 | 
2026-02-19 01:12:54.076199 | TASK [Setup log path fact]
2026-02-19 01:12:54.096129 | localhost | ok
2026-02-19 01:12:54.112738 | 
2026-02-19 01:12:54.112920 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-19 01:12:54.161036 | localhost | ok
2026-02-19 01:12:54.206183 | 
2026-02-19 01:12:54.206296 | TASK [emit-job-header : Print job information]
2026-02-19 01:12:54.245033 | # Job Information
2026-02-19 01:12:54.245185 | Ansible Version: 2.16.16
2026-02-19 01:12:54.245217 | Job: manila-tempest-plugin-cephfs-native-cephadm
2026-02-19 01:12:54.245241 | Pipeline: check
2026-02-19 01:12:54.245262 | Executor: 0a8996d2b663
2026-02-19 01:12:54.245283 | Triggered by: https://github.com/vexxhost/manila/pull/1
2026-02-19 01:12:54.245306 | Event ID: e3858b40-0d2f-11f1-9b95-7208a94fdf7d
2026-02-19 01:12:54.250903 | 
2026-02-19 01:12:54.250976 | LOOP [emit-job-header : Print node information]
2026-02-19 01:12:54.370435 | localhost | ok:
2026-02-19 01:12:54.425326 | localhost | # Node Information
2026-02-19 01:12:54.425482 | localhost | Inventory Hostname: controller
2026-02-19 01:12:54.425540 | localhost | Hostname: np0000156437
2026-02-19 01:12:54.425586 | localhost | Username: zuul
2026-02-19 01:12:54.425639 | localhost | Distro: Ubuntu 24.04
2026-02-19 01:12:54.425685 | localhost | Provider: yul1
2026-02-19 01:12:54.425728 | localhost | Region: ca-ymq-1
2026-02-19 01:12:54.425770 | localhost | Label: ubuntu-noble
2026-02-19 01:12:54.425811 | localhost | Product Name: OpenStack Nova
2026-02-19 01:12:54.425853 | localhost | Interface IP: 199.204.45.69
2026-02-19 01:12:54.439495 | 
2026-02-19 01:12:54.439675 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-02-19 01:12:54.914160 | localhost -> localhost | changed
2026-02-19 01:12:54.919958 | 
2026-02-19 01:12:54.920026 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-02-19 01:12:55.986382 | localhost -> localhost | changed
2026-02-19 01:12:55.996268 | 
2026-02-19 01:12:55.996362 | PLAY [all]
2026-02-19 01:12:56.009622 | 
2026-02-19 01:12:56.009722 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-02-19 01:12:56.309042 | controller -> localhost | ok
2026-02-19 01:12:56.317937 | 
2026-02-19 01:12:56.318136 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-02-19 01:12:56.355239 | controller | ok
2026-02-19 01:12:56.369364 | controller | included: /var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-02-19 01:12:56.376303 | 
2026-02-19 01:12:56.376363 | TASK [add-build-sshkey : Create Temp SSH key]
2026-02-19 01:12:57.446886 | controller -> localhost | Generating public/private rsa key pair.
2026-02-19 01:12:57.447176 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/work/7f9d23d19acd4c11a6b81a1e710136d3_id_rsa
2026-02-19 01:12:57.447234 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/work/7f9d23d19acd4c11a6b81a1e710136d3_id_rsa.pub
2026-02-19 01:12:57.447278 | controller -> localhost | The key fingerprint is:
2026-02-19 01:12:57.447322 | controller -> localhost | SHA256:RpHtTftbyI6tbibITIvako0YgE43fgW953kiHMg/MXw zuul-build-sshkey
2026-02-19 01:12:57.447382 | controller -> localhost | The key's randomart image is:
2026-02-19 01:12:57.447424 | controller -> localhost | +---[RSA 3072]----+
2026-02-19 01:12:57.447474 | controller -> localhost | |      . .o       |
2026-02-19 01:12:57.447524 | controller -> localhost | |     . .... .    |
2026-02-19 01:12:57.447565 | controller -> localhost | |.   . + o. o .   |
2026-02-19 01:12:57.447604 | controller -> localhost | |o. o o O E. o    |
2026-02-19 01:12:57.447643 | controller -> localhost | |o.o . + S .  o . |
2026-02-19 01:12:57.447681 | controller -> localhost | | ... . *.+ .  + .|
2026-02-19 01:12:57.447719 | controller -> localhost | |   o.+ =ooo  + o |
2026-02-19 01:12:57.447757 | controller -> localhost | |  . +.o = . + +  |
2026-02-19 01:12:57.447798 | controller -> localhost | |    .o.    =o.   |
2026-02-19 01:12:57.447836 | controller -> localhost | +----[SHA256]-----+
2026-02-19 01:12:57.447909 | controller -> localhost | ok: Runtime: 0:00:00.462494
2026-02-19 01:12:57.456526 | 
2026-02-19 01:12:57.456592 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-02-19 01:12:57.483959 | controller | ok
2026-02-19 01:12:57.493564 | controller | included: /var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-02-19 01:12:57.503233 | 
2026-02-19 01:12:57.503573 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-02-19 01:12:57.528859 | controller | skipping: Conditional result was False
2026-02-19 01:12:57.542516 | 
2026-02-19 01:12:57.542768 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-02-19 01:12:58.105529 | controller | changed
2026-02-19 01:12:58.118780 | 
2026-02-19 01:12:58.118909 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-02-19 01:12:58.358173 | controller | ok
2026-02-19 01:12:58.364051 | 
2026-02-19 01:12:58.464127 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-02-19 01:12:59.012867 | controller | changed
2026-02-19 01:12:59.021521 | 
2026-02-19 01:12:59.128294 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-02-19 01:12:59.737511 | controller | changed
2026-02-19 01:12:59.747739 | 
2026-02-19 01:12:59.748045 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-02-19 01:12:59.773525 | controller | skipping: Conditional result was False
2026-02-19 01:12:59.781973 | 
2026-02-19 01:12:59.782061 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-02-19 01:13:00.225618 | controller -> localhost | changed
2026-02-19 01:13:00.288143 | 
2026-02-19 01:13:00.288296 | TASK [add-build-sshkey : Add back temp key]
2026-02-19 01:13:00.778911 | controller -> localhost | Identity added: /var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/work/7f9d23d19acd4c11a6b81a1e710136d3_id_rsa (zuul-build-sshkey)
2026-02-19 01:13:00.779656 | controller -> localhost | ok: Runtime: 0:00:00.012759
2026-02-19 01:13:00.786494 | 
2026-02-19 01:13:00.786568 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-02-19 01:13:01.130111 | controller | ok
2026-02-19 01:13:01.151190 | 
2026-02-19 01:13:01.151445 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-02-19 01:13:01.201155 | controller | skipping: Conditional result was False
2026-02-19 01:13:01.216164 | 
2026-02-19 01:13:01.216236 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-02-19 01:13:01.556913 | controller | ok
2026-02-19 01:13:01.564947 | 
2026-02-19 01:13:01.565529 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-02-19 01:14:03.321421 | controller | Output suppressed because no_log was given
2026-02-19 01:14:03.331657 | 
2026-02-19 01:14:03.331782 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-02-19 01:14:03.751421 | controller | ok: "logs"
2026-02-19 01:14:03.751710 | controller | ok: All items complete
2026-02-19 01:14:03.751738 | 
2026-02-19 01:14:03.944309 | controller | ok: "artifacts"
2026-02-19 01:14:04.252884 | controller | ok: "docs"
2026-02-19 01:14:04.271417 | 
2026-02-19 01:14:04.271571 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-02-19 01:14:06.161452 | controller | changed: "logs"
2026-02-19 01:14:06.669107 | controller | changed: "artifacts"
2026-02-19 01:14:07.269583 | controller | changed: "docs"
2026-02-19 01:14:07.287722 | 
2026-02-19 01:14:07.287854 | PLAY RECAP
2026-02-19 01:14:07.287905 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-02-19 01:14:07.287935 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-02-19 01:14:07.287957 | 
2026-02-19 01:14:07.422097 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-02-19 01:14:07.428015 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-19 01:14:08.130037 | 
2026-02-19 01:14:08.130195 | PLAY [all]
2026-02-19 01:14:08.150265 | 
2026-02-19 01:14:08.150423 | TASK [Fix the permissions of the zuul home directory]
2026-02-19 01:14:08.735813 | controller | changed
2026-02-19 01:14:08.746738 | 
2026-02-19 01:14:08.746826 | TASK [Gather minimum local MTU]
2026-02-19 01:14:08.827800 | controller | ok
2026-02-19 01:14:08.834638 | 
2026-02-19 01:14:08.834715 | TASK [Calculate external_bridge_mtu]
2026-02-19 01:14:08.889356 | controller | ok
2026-02-19 01:14:08.898217 | 
2026-02-19 01:14:08.898317 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-02-19 01:14:08.923996 | controller | skipping: Conditional result was False
2026-02-19 01:14:08.935637 | 
2026-02-19 01:14:08.935759 | TASK [configure-swap : Get ephemeral0 device node]
2026-02-19 01:14:10.960544 | controller | ok: Runtime: 0:00:00.005024
2026-02-19 01:14:10.966493 | 
2026-02-19 01:14:10.966565 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-02-19 01:14:10.993025 | controller | skipping: Conditional result was False
2026-02-19 01:14:11.002986 | 
2026-02-19 01:14:11.003079 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-02-19 01:14:11.028083 | controller | skipping: Conditional result was False
2026-02-19 01:14:11.039578 | 
2026-02-19 01:14:11.039665 | TASK [configure-swap : Setup swap file on root device]
2026-02-19 01:14:11.105753 | controller | ok
2026-02-19 01:14:11.118522 | controller | included: /var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-02-19 01:14:11.124703 | 
2026-02-19 01:14:11.124768 | TASK [configure-swap : Calculate required swap]
2026-02-19 01:14:11.190930 | controller | ok
2026-02-19 01:14:11.196493 | 
2026-02-19 01:14:11.196556 | TASK [configure-swap : Get root filesystem]
2026-02-19 01:14:11.533413 | controller | ext4
2026-02-19 01:14:12.097344 | controller | ok: Runtime: 0:00:00.045671
2026-02-19 01:14:12.103025 | 
2026-02-19 01:14:12.103090 | TASK [configure-swap : Save root filesystem]
2026-02-19 01:14:12.138072 | controller | ok
2026-02-19 01:14:12.143709 | 
2026-02-19 01:14:12.143778 | TASK [configure-swap : Debug the root_filesystem variable]
2026-02-19 01:14:12.176640 | controller | ok:
2026-02-19 01:14:12.176799 | controller | {
2026-02-19 01:14:12.176836 | controller |   "root_filesystem": "ext4"
2026-02-19 01:14:12.176864 | controller | }
2026-02-19 01:14:12.182885 | 
2026-02-19 01:14:12.182952 | TASK [configure-swap : Create swap backing file]
2026-02-19 01:18:03.879979 | controller | 8192+0 records in
2026-02-19 01:18:03.897376 | controller | 8192+0 records out
2026-02-19 01:18:03.897398 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 231.294 s, 37.1 MB/s
2026-02-19 01:18:08.799043 | controller | ok: Runtime: 0:03:51.318539
2026-02-19 01:18:08.806296 | 
2026-02-19 01:18:08.806359 | TASK [configure-swap : Ensure swapfile perms]
2026-02-19 01:18:09.115401 | controller | changed
2026-02-19 01:18:09.121245 | 
2026-02-19 01:18:09.121318 | TASK [configure-swap : Make swapfile]
2026-02-19 01:18:32.639522 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-02-19 01:18:32.639630 | controller | no label, UUID=621aa750-8dde-4091-a394-ea02ba04c3b1
2026-02-19 01:18:32.690435 | controller | ok: Runtime: 0:00:23.288610
2026-02-19 01:18:32.695910 | 
2026-02-19 01:18:32.695975 | TASK [configure-swap : Write swap to fstab]
2026-02-19 01:18:33.091992 | controller | changed
2026-02-19 01:18:33.098529 | 
2026-02-19 01:18:33.098596 | TASK [configure-swap : Add all swap]
2026-02-19 01:18:33.637451 | controller | ok: Runtime: 0:00:00.039915
2026-02-19 01:18:33.643115 | 
2026-02-19 01:18:33.643181 | TASK [configure-swap : Debug the swap_required variable]
2026-02-19 01:18:33.678093 | controller | ok:
2026-02-19 01:18:33.678235 | controller | {
2026-02-19 01:18:33.678260 | controller |   "swap_required": "8192"
2026-02-19 01:18:33.678280 | controller | }
2026-02-19 01:18:33.684233 | 
2026-02-19 01:18:33.684305 | TASK [configure-swap : Set swappiness]
2026-02-19 01:18:34.077403 | controller | changed
2026-02-19 01:18:34.082889 | 
2026-02-19 01:18:34.082959 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-02-19 01:18:34.116083 | controller | ok:
2026-02-19 01:18:34.116262 | controller | {
2026-02-19 01:18:34.116298 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-02-19 01:18:34.116329 | controller | }
2026-02-19 01:18:34.127400 | 
2026-02-19 01:18:34.127492 | TASK [setup-stack-user : Create stack group]
2026-02-19 01:18:34.577654 | controller | changed
2026-02-19 01:18:34.583488 | 
2026-02-19 01:18:34.583555 | TASK [setup-stack-user : Create the stack user home folder]
2026-02-19 01:18:34.831493 | controller | changed
2026-02-19 01:18:34.838463 | 
2026-02-19 01:18:34.838530 | TASK [setup-stack-user : Create stack user]
2026-02-19 01:18:35.349600 | controller | changed
2026-02-19 01:18:35.357540 | 
2026-02-19 01:18:35.357608 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-02-19 01:18:35.606043 | controller | changed
2026-02-19 01:18:35.612603 | 
2026-02-19 01:18:35.612674 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-02-19 01:18:36.663837 | controller | changed
2026-02-19 01:18:36.669525 | 
2026-02-19 01:18:36.669589 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-02-19 01:18:36.901846 | controller | changed
2026-02-19 01:18:36.911624 | 
2026-02-19 01:18:36.911696 | TASK [setup-tempest-user : Create tempest group]
2026-02-19 01:18:37.259370 | controller | changed
2026-02-19 01:18:37.264620 | 
2026-02-19 01:18:37.264684 | TASK [setup-tempest-user : Create tempest user]
2026-02-19 01:18:38.608359 | controller | changed
2026-02-19 01:18:38.662451 | 
2026-02-19 01:18:38.662616 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-02-19 01:18:39.409735 | controller | changed
2026-02-19 01:18:39.421209 | 
2026-02-19 01:18:39.421277 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-02-19 01:18:39.737800 | controller | ok: Not all paths examined, check warnings for details
2026-02-19 01:18:39.748370 | 
2026-02-19 01:18:39.748438 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-02-19 01:18:44.289711 | controller | ok: Item: Runtime: 0:00:04.275040
2026-02-19 01:18:44.978787 | controller | ok: Item: Runtime: 0:00:00.481184
2026-02-19 01:18:45.269956 | controller | ok: Item: Runtime: 0:00:00.072932
2026-02-19 01:18:47.866536 | controller | ok: Item: Runtime: 0:00:02.366053
2026-02-19 01:18:48.340860 | controller | ok: Item: Runtime: 0:00:00.265570
2026-02-19 01:18:50.247047 | controller | ok: Item: Runtime: 0:00:01.696249
2026-02-19 01:18:53.559371 | controller | ok: Item: Runtime: 0:00:03.100503
2026-02-19 01:18:54.663894 | controller | ok: Item: Runtime: 0:00:00.776940
2026-02-19 01:19:21.350865 | controller | ok: Item: Runtime: 0:00:26.462273
2026-02-19 01:19:21.674960 | controller | ok: Item: Runtime: 0:00:00.118144
2026-02-19 01:20:11.361914 | controller | ok: Item: Runtime: 0:00:49.464983
2026-02-19 01:20:12.187818 | controller | ok: Item: Runtime: 0:00:00.609531
2026-02-19 01:20:25.306582 | controller | ok: Item: Runtime: 0:00:12.908174
2026-02-19 01:20:37.512803 | controller | ok: Item: Runtime: 0:00:11.982199
2026-02-19 01:20:38.299943 | controller | ok: Item: Runtime: 0:00:00.578597
2026-02-19 01:20:38.322529 | 
2026-02-19 01:20:38.322713 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-02-19 01:20:38.540588 | controller | ok: All paths examined
2026-02-19 01:20:38.549771 | 
2026-02-19 01:20:38.549845 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-02-19 01:20:38.787038 | controller | ok: All paths examined
2026-02-19 01:20:38.793584 | 
2026-02-19 01:20:38.793651 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-02-19 01:20:40.562527 | controller | ok: Item: Runtime: 0:00:01.483637
2026-02-19 01:20:40.562806 | controller | changed: All items complete
2026-02-19 01:20:40.562834 | 
2026-02-19 01:20:41.082596 | controller | ok: Item: Runtime: 0:00:00.291730
2026-02-19 01:20:41.101641 | 
2026-02-19 01:20:41.101834 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-02-19 01:20:41.141800 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.144218 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.147463 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.150981 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.154516 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.157970 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.161619 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.165492 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.168790 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.171917 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.175312 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.178703 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.182487 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.186653 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.189851 | controller | skipping: Conditional result was False
2026-02-19 01:20:41.221015 | 
2026-02-19 01:20:41.221199 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-02-19 01:20:43.445678 | controller | changed
2026-02-19 01:20:43.460273 | 
2026-02-19 01:20:43.460340 | TASK [setup-devstack-log-dir : Create logs directory]
2026-02-19 01:20:43.699926 | controller | changed
2026-02-19 01:20:43.710166 | 
2026-02-19 01:20:43.710258 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-02-19 01:20:44.007421 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-02-19 01:20:44.302824 | controller | ERROR
2026-02-19 01:20:44.303104 | controller | {
2026-02-19 01:20:44.303146 | controller |   "delta": "0:00:00.008912",
2026-02-19 01:20:44.303175 | controller |   "end": "2026-02-19 01:20:44.007889",
2026-02-19 01:20:44.303201 | controller |   "msg": "non-zero return code",
2026-02-19 01:20:44.303226 | controller |   "rc": 1,
2026-02-19 01:20:44.303254 | controller |   "start": "2026-02-19 01:20:43.998977"
2026-02-19 01:20:44.303284 | controller | }
2026-02-19 01:20:44.303323 | controller | ERROR: Ignoring Errors
2026-02-19 01:20:44.310982 | 
2026-02-19 01:20:44.311082 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-02-19 01:20:44.561165 | controller | ok
2026-02-19 01:20:44.571455 | 
2026-02-19 01:20:44.571559 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-02-19 01:20:44.798940 | controller | /usr/bin/journalctl
2026-02-19 01:20:45.105948 | controller | ok: Runtime: 0:00:00.005033
2026-02-19 01:20:45.111979 | 
2026-02-19 01:20:45.112045 | TASK [start-fresh-logging : Get current date]
2026-02-19 01:20:45.368535 | controller | 2026-02-19 01:20:45
2026-02-19 01:20:45.669083 | controller | ok: Runtime: 0:00:00.010319
2026-02-19 01:20:45.674672 | 
2026-02-19 01:20:45.674750 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-02-19 01:20:46.237834 | controller | changed
2026-02-19 01:20:46.246246 | 
2026-02-19 01:20:46.246346 | TASK [start-fresh-logging : Stop rsyslog]
2026-02-19 01:20:46.279455 | controller | skipping: Conditional result was False
2026-02-19 01:20:46.306826 | 
2026-02-19 01:20:46.306930 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-02-19 01:20:46.846249 | controller | skipping: Conditional result was False
2026-02-19 01:20:46.856783 | 
2026-02-19 01:20:46.856878 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-02-19 01:20:47.389004 | controller | skipping: Conditional result was False
2026-02-19 01:20:47.400273 | 
2026-02-19 01:20:47.400369 | TASK [start-fresh-logging : Recreate syslog file]
2026-02-19 01:20:47.426404 | controller | skipping: Conditional result was False
2026-02-19 01:20:47.437005 | 
2026-02-19 01:20:47.437074 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-02-19 01:20:47.968491 | controller | skipping: Conditional result was False
2026-02-19 01:20:47.981156 | 
2026-02-19 01:20:47.981258 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-02-19 01:20:48.522671 | controller | skipping: Conditional result was False
2026-02-19 01:20:48.532411 | 
2026-02-19 01:20:48.532518 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-02-19 01:20:48.557834 | controller | skipping: Conditional result was False
2026-02-19 01:20:48.568099 | 
2026-02-19 01:20:48.568188 | TASK [start-fresh-logging : Recreate kern.log file]
2026-02-19 01:20:48.593471 | controller | skipping: Conditional result was False
2026-02-19 01:20:48.605057 | 
2026-02-19 01:20:48.605121 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-02-19 01:20:49.134307 | controller | skipping: Conditional result was False
2026-02-19 01:20:49.145565 | 
2026-02-19 01:20:49.145641 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-02-19 01:20:49.722372 | controller | skipping: Conditional result was False
2026-02-19 01:20:49.732660 | 
2026-02-19 01:20:49.732756 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-02-19 01:20:49.758767 | controller | skipping: Conditional result was False
2026-02-19 01:20:49.770135 | 
2026-02-19 01:20:49.770227 | TASK [start-fresh-logging : Start rsyslog]
2026-02-19 01:20:49.796174 | controller | skipping: Conditional result was False
2026-02-19 01:20:49.813261 | 
2026-02-19 01:20:49.813359 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-02-19 01:20:50.205614 | controller | ok
2026-02-19 01:20:50.218504 | 
2026-02-19 01:20:50.218559 | PLAY RECAP
2026-02-19 01:20:50.218606 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-02-19 01:20:50.218627 | 
2026-02-19 01:20:50.363337 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-02-19 01:20:50.373873 | RUN START: [untrusted : opendev.org/openstack/tempest/playbooks/devstack-tempest.yaml@master]
2026-02-19 01:20:51.015920 | 
2026-02-19 01:20:51.016055 | PLAY [all]
2026-02-19 01:20:51.028827 | 
2026-02-19 01:20:51.028904 | TASK [Run devstack on the controller]
2026-02-19 01:20:51.062593 | controller | ok
2026-02-19 01:20:51.079647 | 
2026-02-19 01:20:51.079721 | TASK [run-devstack : Run devstack]
2026-02-19 01:20:51.660579 | controller | + unset GREP_OPTIONS
2026-02-19 01:20:51.660675 | controller | + unset LANG
2026-02-19 01:20:51.660714 | controller | + unset LANGUAGE
2026-02-19 01:20:51.660723 | controller | + LC_ALL=en_US.utf8
2026-02-19 01:20:51.669340 | controller | + export LC_ALL
2026-02-19 01:20:51.671734 | controller | ++ env
2026-02-19 01:20:51.671752 | controller | ++ grep -E '^OS_'
2026-02-19 01:20:51.671980 | controller | ++ cut -d = -f 1
2026-02-19 01:20:51.677134 | controller | + unset
2026-02-19 01:20:51.677157 | controller | + umask 022
2026-02-19 01:20:51.677169 | 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-02-19 01:20:51.678394 | controller | +++ dirname ./stack.sh
2026-02-19 01:20:51.680731 | controller | ++ cd .
2026-02-19 01:20:51.680775 | controller | ++ pwd
2026-02-19 01:20:51.681334 | controller | + TOP_DIR=/opt/stack/devstack
2026-02-19 01:20:51.681349 | controller | + NOUNSET=
2026-02-19 01:20:51.681356 | controller | + [[ -n '' ]]
2026-02-19 01:20:51.682046 | controller | ++ date +%s
2026-02-19 01:20:51.683289 | controller | + DEVSTACK_START_TIME=1771464051
2026-02-19 01:20:51.683304 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-02-19 01:20:51.683351 | controller | + FILES=/opt/stack/devstack/files
2026-02-19 01:20:51.683377 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-02-19 01:20:51.683424 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-02-19 01:20:51.683433 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-02-19 01:20:51.683474 | controller | + [[ '' == \y ]]
2026-02-19 01:20:51.683508 | controller | + [[ 1002 -eq 0 ]]
2026-02-19 01:20:51.683566 | controller | + [[ -n '' ]]
2026-02-19 01:20:51.683575 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-02-19 01:20:51.683607 | controller | + LAST_SPINNER_PID=
2026-02-19 01:20:51.683615 | controller | + source /opt/stack/devstack/functions
2026-02-19 01:20:51.683729 | controller | ++ [[ -z '' ]]
2026-02-19 01:20:51.683743 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-02-19 01:20:51.684999 | controller | ++++ dirname /opt/stack/devstack/functions
2026-02-19 01:20:51.686375 | controller | +++ cd /opt/stack/devstack
2026-02-19 01:20:51.686425 | controller | +++ pwd
2026-02-19 01:20:51.687268 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-02-19 01:20:51.687288 | controller | ++ source /opt/stack/devstack/functions-common
2026-02-19 01:20:51.688756 | controller | ++++ set +o
2026-02-19 01:20:51.688779 | controller | ++++ grep xtrace
2026-02-19 01:20:51.691185 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-02-19 01:20:51.691209 | controller | +++ set +o xtrace
2026-02-19 01:20:51.701450 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-02-19 01:20:51.702859 | controller | ++++ set +o
2026-02-19 01:20:51.702877 | controller | ++++ grep xtrace
2026-02-19 01:20:51.706412 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-02-19 01:20:51.706431 | controller | +++ set +o xtrace
2026-02-19 01:20:51.706437 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-02-19 01:20:51.707399 | controller | ++++ set +o
2026-02-19 01:20:51.707636 | controller | ++++ grep xtrace
2026-02-19 01:20:51.709880 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-02-19 01:20:51.709966 | controller | +++ set +o xtrace
2026-02-19 01:20:51.710531 | controller | ++ source /opt/stack/devstack/inc/python
2026-02-19 01:20:51.711935 | controller | ++++ set +o
2026-02-19 01:20:51.712172 | controller | ++++ grep xtrace
2026-02-19 01:20:51.714304 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-02-19 01:20:51.714390 | controller | +++ set +o xtrace
2026-02-19 01:20:51.715594 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-02-19 01:20:51.716954 | controller | ++++ set +o
2026-02-19 01:20:51.717164 | controller | ++++ grep xtrace
2026-02-19 01:20:51.719337 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-02-19 01:20:51.719420 | controller | +++ set +o xtrace
2026-02-19 01:20:51.719849 | controller | ++ source /opt/stack/devstack/inc/async
2026-02-19 01:20:51.720919 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-02-19 01:20:51.721039 | controller | ++++ local xtrace
2026-02-19 01:20:51.723801 | controller | +++++ set +o
2026-02-19 01:20:51.723866 | controller | +++++ grep xtrace
2026-02-19 01:20:51.725466 | controller | ++++ xtrace='set -o xtrace'
2026-02-19 01:20:51.725525 | controller | ++++ set +o xtrace
2026-02-19 01:20:51.726179 | controller | +++ DEVSTACK_PARALLEL=True
2026-02-19 01:20:51.726197 | controller | +++ _ASYNC_BG_TIME=0
2026-02-19 01:20:51.730410 | controller | +++ set +o
2026-02-19 01:20:51.730436 | controller | +++ grep xtrace
2026-02-19 01:20:51.731776 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-02-19 01:20:51.731802 | controller | ++ set +o xtrace
2026-02-19 01:20:51.733671 | controller | + source /opt/stack/devstack/lib/stack
2026-02-19 01:20:51.733827 | controller | + GetDistro
2026-02-19 01:20:51.733854 | controller | + GetOSVersion
2026-02-19 01:20:51.733863 | controller | + source /etc/os-release
2026-02-19 01:20:51.733917 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-02-19 01:20:51.733923 | controller | ++ NAME=Ubuntu
2026-02-19 01:20:51.733928 | controller | ++ VERSION_ID=24.04
2026-02-19 01:20:51.733936 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-02-19 01:20:51.733941 | controller | ++ VERSION_CODENAME=noble
2026-02-19 01:20:51.733946 | controller | ++ ID=ubuntu
2026-02-19 01:20:51.733951 | controller | ++ ID_LIKE=debian
2026-02-19 01:20:51.733959 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-02-19 01:20:51.733963 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-02-19 01:20:51.733968 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-02-19 01:20:51.733977 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-02-19 01:20:51.733982 | controller | ++ UBUNTU_CODENAME=noble
2026-02-19 01:20:51.733989 | controller | ++ LOGO=ubuntu-logo
2026-02-19 01:20:51.733996 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-02-19 01:20:51.734057 | controller | + _ensure_lsb_release
2026-02-19 01:20:51.735475 | controller | ++ command -v lsb_release
2026-02-19 01:20:51.735557 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-02-19 01:20:51.735568 | controller | + return
2026-02-19 01:20:51.736574 | controller | ++ lsb_release -r -s
2026-02-19 01:20:51.749645 | controller | + os_RELEASE=24.04
2026-02-19 01:20:51.750590 | controller | ++ lsb_release -c -s
2026-02-19 01:20:51.763651 | controller | + os_CODENAME=noble
2026-02-19 01:20:51.764771 | controller | ++ lsb_release -i -s
2026-02-19 01:20:51.779249 | controller | + os_VENDOR=Ubuntu
2026-02-19 01:20:51.779264 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-02-19 01:20:51.779269 | controller | + os_PACKAGE=deb
2026-02-19 01:20:51.779272 | controller | + typeset -xr os_VENDOR
2026-02-19 01:20:51.779276 | controller | + typeset -xr os_RELEASE
2026-02-19 01:20:51.779280 | controller | + typeset -xr os_PACKAGE
2026-02-19 01:20:51.779284 | controller | + typeset -xr os_CODENAME
2026-02-19 01:20:51.779288 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-02-19 01:20:51.779292 | controller | + DISTRO=noble
2026-02-19 01:20:51.779296 | controller | + typeset -xr DISTRO
2026-02-19 01:20:51.779300 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-02-19 01:20:51.780962 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-02-19 01:20:51.780975 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-02-19 01:20:51.780979 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-02-19 01:20:51.780983 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-02-19 01:20:51.780987 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-02-19 01:20:51.781415 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-02-19 01:20:51.781539 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-02-19 01:20:51.781546 | controller | ++ local matchgroup=local
2026-02-19 01:20:51.781562 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-02-19 01:20:51.781661 | controller | ++ awk -v matchgroup=local '
2026-02-19 01:20:51.781666 | controller |         /^\[\[.+\|.*\]\]/ {
2026-02-19 01:20:51.781669 | controller |             gsub("[][]", "", $1);
2026-02-19 01:20:51.781673 | controller |             split($1, a, "|");
2026-02-19 01:20:51.781676 | controller |             if (a[1] == matchgroup)
2026-02-19 01:20:51.781716 | controller |                 print a[2]
2026-02-19 01:20:51.781723 | controller |         }
2026-02-19 01:20:51.781728 | controller |     ' /opt/stack/devstack/local.conf
2026-02-19 01:20:51.783814 | controller | + LRC=localrc
2026-02-19 01:20:51.783866 | controller | + for lfile in $LRC
2026-02-19 01:20:51.783873 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-02-19 01:20:51.783880 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-02-19 01:20:51.783886 | controller | + echo '# Generated file, do not edit'
2026-02-19 01:20:51.783891 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-02-19 01:20:51.783896 | controller | + local file=/opt/stack/devstack/local.conf
2026-02-19 01:20:51.783901 | controller | + local matchgroup=local
2026-02-19 01:20:51.783906 | controller | + local configfile=localrc
2026-02-19 01:20:51.783911 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-02-19 01:20:51.783915 | controller | + [[ -z localrc ]]
2026-02-19 01:20:51.783920 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-02-19 01:20:51.783925 | controller |         BEGIN { group = "" }
2026-02-19 01:20:51.783930 | controller |         /^\[\[.+\|.*\]\]/ {
2026-02-19 01:20:51.783948 | controller |             gsub("[][]", "", $1);
2026-02-19 01:20:51.783953 | controller |             split($1, a, "|");
2026-02-19 01:20:51.783959 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-02-19 01:20:51.783964 | controller |                 group=a[1]
2026-02-19 01:20:51.783968 | controller |             } else {
2026-02-19 01:20:51.783973 | controller |                 group=""
2026-02-19 01:20:51.783978 | controller |             }
2026-02-19 01:20:51.783983 | controller |             next
2026-02-19 01:20:51.783987 | controller |         }
2026-02-19 01:20:51.783992 | controller |         {
2026-02-19 01:20:51.783997 | controller |             if (group != "")
2026-02-19 01:20:51.784001 | controller |                 print $0
2026-02-19 01:20:51.784006 | controller |         }
2026-02-19 01:20:51.784011 | controller |     ' /opt/stack/devstack/local.conf
2026-02-19 01:20:51.785641 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-02-19 01:20:51.785657 | controller | + source /opt/stack/devstack/stackrc
2026-02-19 01:20:51.785743 | controller | ++ [[ -z '' ]]
2026-02-19 01:20:51.785758 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-02-19 01:20:51.787373 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-02-19 01:20:51.788906 | controller | +++ cd /opt/stack/devstack
2026-02-19 01:20:51.788930 | controller | +++ pwd
2026-02-19 01:20:51.789754 | controller | ++ RC_DIR=/opt/stack/devstack
2026-02-19 01:20:51.789778 | controller | ++ source /opt/stack/devstack/functions
2026-02-19 01:20:51.789784 | controller | +++ [[ -z 1 ]]
2026-02-19 01:20:51.789789 | controller | +++ return 0
2026-02-19 01:20:51.789802 | controller | ++ TARGET_BRANCH=master
2026-02-19 01:20:51.789807 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-02-19 01:20:51.789812 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-02-19 01:20:51.789817 | controller | ++ DEST=/opt/stack
2026-02-19 01:20:51.789822 | controller | ++ DATA_DIR=/opt/stack/data
2026-02-19 01:20:51.789826 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-02-19 01:20:51.789831 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-02-19 01:20:51.789836 | controller | ++ [[ 1002 -eq 0 ]]
2026-02-19 01:20:51.790711 | controller | +++ whoami
2026-02-19 01:20:51.793218 | controller | ++ STACK_USER=stack
2026-02-19 01:20:51.793276 | controller | ++ REGION_NAME=RegionOne
2026-02-19 01:20:51.793285 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-02-19 01:20:51.793364 | controller | ++ isset ENABLED_SERVICES
2026-02-19 01:20:51.793373 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-02-19 01:20:51.793399 | controller | ++ ENABLED_SERVICES=key
2026-02-19 01:20:51.793405 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-02-19 01:20:51.793413 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-02-19 01:20:51.793418 | controller | ++ ENABLED_SERVICES+=,g-api
2026-02-19 01:20:51.793422 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-02-19 01:20:51.793427 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-02-19 01:20:51.793436 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-02-19 01:20:51.793440 | controller | ++ ENABLED_SERVICES+=,horizon
2026-02-19 01:20:51.793445 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-02-19 01:20:51.793453 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-02-19 01:20:51.793457 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-02-19 01:20:51.793476 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-02-19 01:20:51.793484 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-02-19 01:20:51.793491 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-02-19 01:20:51.793551 | controller | +++ disable_all_services
2026-02-19 01:20:51.793560 | controller | +++ ENABLED_SERVICES=
2026-02-19 01:20:51.793567 | controller | +++ enable_service c-api
2026-02-19 01:20:51.793613 | controller | +++ local xtrace
2026-02-19 01:20:51.795490 | controller | ++++ set +o
2026-02-19 01:20:51.795513 | controller | ++++ grep xtrace
2026-02-19 01:20:51.797752 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:51.797777 | controller | +++ set +o xtrace
2026-02-19 01:20:51.826062 | controller | +++ enable_service c-bak
2026-02-19 01:20:51.826124 | controller | +++ local xtrace
2026-02-19 01:20:51.827942 | controller | ++++ set +o
2026-02-19 01:20:51.827966 | controller | ++++ grep xtrace
2026-02-19 01:20:51.830431 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:51.830450 | controller | +++ set +o xtrace
2026-02-19 01:20:51.858815 | controller | +++ enable_service c-sch
2026-02-19 01:20:51.858880 | controller | +++ local xtrace
2026-02-19 01:20:51.860377 | controller | ++++ set +o
2026-02-19 01:20:51.860393 | controller | ++++ grep xtrace
2026-02-19 01:20:51.862438 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:51.862458 | controller | +++ set +o xtrace
2026-02-19 01:20:51.891865 | controller | +++ enable_service c-vol
2026-02-19 01:20:51.891929 | controller | +++ local xtrace
2026-02-19 01:20:51.893716 | controller | ++++ set +o
2026-02-19 01:20:51.893741 | controller | ++++ grep xtrace
2026-02-19 01:20:51.895749 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:51.895773 | controller | +++ set +o xtrace
2026-02-19 01:20:51.925987 | controller | +++ disable_service cinder
2026-02-19 01:20:51.926056 | controller | +++ local xtrace
2026-02-19 01:20:51.927726 | controller | ++++ set +o
2026-02-19 01:20:51.927750 | controller | ++++ grep xtrace
2026-02-19 01:20:51.930218 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:51.930239 | controller | +++ set +o xtrace
2026-02-19 01:20:51.953195 | controller | +++ disable_service dstat
2026-02-19 01:20:51.953253 | controller | +++ local xtrace
2026-02-19 01:20:51.954791 | controller | ++++ set +o
2026-02-19 01:20:51.954811 | controller | ++++ grep xtrace
2026-02-19 01:20:51.957285 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:51.957304 | controller | +++ set +o xtrace
2026-02-19 01:20:51.979157 | controller | +++ enable_service etcd3
2026-02-19 01:20:51.979216 | controller | +++ local xtrace
2026-02-19 01:20:51.980755 | controller | ++++ set +o
2026-02-19 01:20:51.980773 | controller | ++++ grep xtrace
2026-02-19 01:20:51.982896 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:51.982921 | controller | +++ set +o xtrace
2026-02-19 01:20:52.015193 | controller | +++ enable_service file_tracker
2026-02-19 01:20:52.015244 | controller | +++ local xtrace
2026-02-19 01:20:52.016866 | controller | ++++ set +o
2026-02-19 01:20:52.016886 | controller | ++++ grep xtrace
2026-02-19 01:20:52.019443 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.019466 | controller | +++ set +o xtrace
2026-02-19 01:20:52.051489 | controller | +++ enable_service g-api
2026-02-19 01:20:52.051539 | controller | +++ local xtrace
2026-02-19 01:20:52.053359 | controller | ++++ set +o
2026-02-19 01:20:52.053380 | controller | ++++ grep xtrace
2026-02-19 01:20:52.057307 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.057328 | controller | +++ set +o xtrace
2026-02-19 01:20:52.089023 | controller | +++ disable_service horizon
2026-02-19 01:20:52.089080 | controller | +++ local xtrace
2026-02-19 01:20:52.090743 | controller | ++++ set +o
2026-02-19 01:20:52.090847 | controller | ++++ grep xtrace
2026-02-19 01:20:52.093381 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.093426 | controller | +++ set +o xtrace
2026-02-19 01:20:52.117213 | controller | +++ enable_service key
2026-02-19 01:20:52.117279 | controller | +++ local xtrace
2026-02-19 01:20:52.119217 | controller | ++++ set +o
2026-02-19 01:20:52.119270 | controller | ++++ grep xtrace
2026-02-19 01:20:52.121786 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.121865 | controller | +++ set +o xtrace
2026-02-19 01:20:52.152125 | controller | +++ enable_service memory_tracker
2026-02-19 01:20:52.152184 | controller | +++ local xtrace
2026-02-19 01:20:52.154069 | controller | ++++ set +o
2026-02-19 01:20:52.154103 | controller | ++++ grep xtrace
2026-02-19 01:20:52.156779 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.156820 | controller | +++ set +o xtrace
2026-02-19 01:20:52.184867 | controller | +++ enable_service mysql
2026-02-19 01:20:52.184931 | controller | +++ local xtrace
2026-02-19 01:20:52.186727 | controller | ++++ set +o
2026-02-19 01:20:52.186782 | controller | ++++ grep xtrace
2026-02-19 01:20:52.189296 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.189322 | controller | +++ set +o xtrace
2026-02-19 01:20:52.223048 | controller | +++ enable_service n-api
2026-02-19 01:20:52.223104 | controller | +++ local xtrace
2026-02-19 01:20:52.224538 | controller | ++++ set +o
2026-02-19 01:20:52.225203 | controller | ++++ grep xtrace
2026-02-19 01:20:52.227562 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.227593 | controller | +++ set +o xtrace
2026-02-19 01:20:52.260310 | controller | +++ enable_service n-api-meta
2026-02-19 01:20:52.260362 | controller | +++ local xtrace
2026-02-19 01:20:52.261732 | controller | ++++ set +o
2026-02-19 01:20:52.262014 | controller | ++++ grep xtrace
2026-02-19 01:20:52.264969 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.265701 | controller | +++ set +o xtrace
2026-02-19 01:20:52.298796 | controller | +++ enable_service n-cond
2026-02-19 01:20:52.298866 | controller | +++ local xtrace
2026-02-19 01:20:52.299949 | controller | ++++ set +o
2026-02-19 01:20:52.300410 | controller | ++++ grep xtrace
2026-02-19 01:20:52.303022 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.303044 | controller | +++ set +o xtrace
2026-02-19 01:20:52.335469 | controller | +++ enable_service n-cpu
2026-02-19 01:20:52.335503 | controller | +++ local xtrace
2026-02-19 01:20:52.337504 | controller | ++++ set +o
2026-02-19 01:20:52.337566 | controller | ++++ grep xtrace
2026-02-19 01:20:52.339787 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.339819 | controller | +++ set +o xtrace
2026-02-19 01:20:52.371608 | controller | +++ enable_service n-novnc
2026-02-19 01:20:52.371641 | controller | +++ local xtrace
2026-02-19 01:20:52.373670 | controller | ++++ set +o
2026-02-19 01:20:52.373777 | controller | ++++ grep xtrace
2026-02-19 01:20:52.375764 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.375791 | controller | +++ set +o xtrace
2026-02-19 01:20:52.408825 | controller | +++ enable_service n-sch
2026-02-19 01:20:52.408910 | controller | +++ local xtrace
2026-02-19 01:20:52.411274 | controller | ++++ set +o
2026-02-19 01:20:52.411339 | controller | ++++ grep xtrace
2026-02-19 01:20:52.413804 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.413834 | controller | +++ set +o xtrace
2026-02-19 01:20:52.444930 | controller | +++ enable_service openstack-cli-server
2026-02-19 01:20:52.444970 | controller | +++ local xtrace
2026-02-19 01:20:52.446737 | controller | ++++ set +o
2026-02-19 01:20:52.446951 | controller | ++++ grep xtrace
2026-02-19 01:20:52.449596 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.449622 | controller | +++ set +o xtrace
2026-02-19 01:20:52.482891 | controller | +++ enable_service ovn-controller
2026-02-19 01:20:52.482922 | controller | +++ local xtrace
2026-02-19 01:20:52.484388 | controller | ++++ set +o
2026-02-19 01:20:52.484403 | controller | ++++ grep xtrace
2026-02-19 01:20:52.486794 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.486851 | controller | +++ set +o xtrace
2026-02-19 01:20:52.513097 | controller | +++ enable_service ovn-northd
2026-02-19 01:20:52.513138 | controller | +++ local xtrace
2026-02-19 01:20:52.514540 | controller | ++++ set +o
2026-02-19 01:20:52.515061 | controller | ++++ grep xtrace
2026-02-19 01:20:52.516908 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.516960 | controller | +++ set +o xtrace
2026-02-19 01:20:52.544249 | controller | +++ enable_service ovs-vswitchd
2026-02-19 01:20:52.544275 | controller | +++ local xtrace
2026-02-19 01:20:52.546279 | controller | ++++ set +o
2026-02-19 01:20:52.546332 | controller | ++++ grep xtrace
2026-02-19 01:20:52.548605 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.548626 | controller | +++ set +o xtrace
2026-02-19 01:20:52.584218 | controller | +++ enable_service ovsdb-server
2026-02-19 01:20:52.584272 | controller | +++ local xtrace
2026-02-19 01:20:52.586401 | controller | ++++ set +o
2026-02-19 01:20:52.586457 | controller | ++++ grep xtrace
2026-02-19 01:20:52.588756 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.588782 | controller | +++ set +o xtrace
2026-02-19 01:20:52.618607 | controller | +++ enable_service placement-api
2026-02-19 01:20:52.618650 | controller | +++ local xtrace
2026-02-19 01:20:52.620510 | controller | ++++ set +o
2026-02-19 01:20:52.620546 | controller | ++++ grep xtrace
2026-02-19 01:20:52.622750 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.622776 | controller | +++ set +o xtrace
2026-02-19 01:20:52.653795 | controller | +++ enable_service q-ovn-agent
2026-02-19 01:20:52.653835 | controller | +++ local xtrace
2026-02-19 01:20:52.655594 | controller | ++++ set +o
2026-02-19 01:20:52.655638 | controller | ++++ grep xtrace
2026-02-19 01:20:52.657760 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.657796 | controller | +++ set +o xtrace
2026-02-19 01:20:52.694648 | controller | +++ enable_service q-svc
2026-02-19 01:20:52.694745 | controller | +++ local xtrace
2026-02-19 01:20:52.697779 | controller | ++++ set +o
2026-02-19 01:20:52.697827 | controller | ++++ grep xtrace
2026-02-19 01:20:52.699267 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.699331 | controller | +++ set +o xtrace
2026-02-19 01:20:52.733257 | controller | +++ enable_service rabbit
2026-02-19 01:20:52.733351 | controller | +++ local xtrace
2026-02-19 01:20:52.734596 | controller | ++++ set +o
2026-02-19 01:20:52.734995 | controller | ++++ grep xtrace
2026-02-19 01:20:52.737590 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.737660 | controller | +++ set +o xtrace
2026-02-19 01:20:52.771933 | controller | +++ disable_service s-account
2026-02-19 01:20:52.772050 | controller | +++ local xtrace
2026-02-19 01:20:52.773535 | controller | ++++ set +o
2026-02-19 01:20:52.773834 | controller | ++++ grep xtrace
2026-02-19 01:20:52.776759 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.776874 | controller | +++ set +o xtrace
2026-02-19 01:20:52.801115 | controller | +++ disable_service s-container
2026-02-19 01:20:52.801183 | controller | +++ local xtrace
2026-02-19 01:20:52.802620 | controller | ++++ set +o
2026-02-19 01:20:52.803014 | controller | ++++ grep xtrace
2026-02-19 01:20:52.805528 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.805598 | controller | +++ set +o xtrace
2026-02-19 01:20:52.827505 | controller | +++ disable_service s-object
2026-02-19 01:20:52.827565 | controller | +++ local xtrace
2026-02-19 01:20:52.829027 | controller | ++++ set +o
2026-02-19 01:20:52.829373 | controller | ++++ grep xtrace
2026-02-19 01:20:52.831992 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.832061 | controller | +++ set +o xtrace
2026-02-19 01:20:52.853889 | controller | +++ disable_service s-proxy
2026-02-19 01:20:52.853950 | controller | +++ local xtrace
2026-02-19 01:20:52.855340 | controller | ++++ set +o
2026-02-19 01:20:52.855761 | controller | ++++ grep xtrace
2026-02-19 01:20:52.858310 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.858381 | controller | +++ set +o xtrace
2026-02-19 01:20:52.881013 | controller | +++ enable_service tempest
2026-02-19 01:20:52.881449 | controller | +++ local xtrace
2026-02-19 01:20:52.883062 | controller | ++++ set +o
2026-02-19 01:20:52.883079 | controller | ++++ grep xtrace
2026-02-19 01:20:52.885789 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.885850 | controller | +++ set +o xtrace
2026-02-19 01:20:52.917562 | controller | +++ enable_service tls-proxy
2026-02-19 01:20:52.917617 | controller | +++ local xtrace
2026-02-19 01:20:52.919572 | controller | ++++ set +o
2026-02-19 01:20:52.919641 | controller | ++++ grep xtrace
2026-02-19 01:20:52.922342 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.922364 | controller | +++ set +o xtrace
2026-02-19 01:20:52.954780 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-02-19 01:20:52.954833 | controller | +++ CEPHADM_DEPLOY=True
2026-02-19 01:20:52.954839 | controller | +++ CEPHADM_DEV_OSD=True
2026-02-19 01:20:52.954845 | controller | +++ CEPH_LOOPBACK_DISK_SIZE=40G
2026-02-19 01:20:52.954851 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-02-19 01:20:52.954857 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-02-19 01:20:52.954862 | controller | +++ DISABLE_CEPHADM_POST_DEPLOY=True
2026-02-19 01:20:52.954868 | controller | +++ ENABLED_SHARE_PROTOCOLS=CEPHFS
2026-02-19 01:20:52.954874 | controller | +++ ENABLE_CEPH_NOVA=False
2026-02-19 01:20:52.954879 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-02-19 01:20:52.954885 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-02-19 01:20:52.954890 | controller | +++ ENABLE_ZSWAP=True
2026-02-19 01:20:52.954895 | controller | +++ ERROR_ON_CLONE=True
2026-02-19 01:20:52.954901 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-02-19 01:20:52.954906 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-02-19 01:20:52.954912 | controller | +++ HOST_IP=199.204.45.69
2026-02-19 01:20:52.954918 | controller | +++ IMAGE_URLS='http://download.cirros-cloud.net/0.6.2/cirros-0.6.2-x86_64-disk.img, http://download.cirros-cloud.net/0.6.1/cirros-0.6.1-x86_64-disk.img'
2026-02-19 01:20:52.954924 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-02-19 01:20:52.954929 | controller | +++ LIBVIRT_TYPE=qemu
2026-02-19 01:20:52.954935 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-02-19 01:20:52.954940 | controller | +++ LOG_COLOR=False
2026-02-19 01:20:52.954946 | controller | +++ MANILA_ALLOW_NAS_SERVER_PORTS_ON_HOST=True
2026-02-19 01:20:52.954951 | controller | +++ MANILA_CONFIGURE_DEFAULT_TYPES=True
2026-02-19 01:20:52.954957 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS=snapshot_support=True
2026-02-19 01:20:52.954963 | controller | +++ MANILA_ENABLED_BACKENDS=cephfsnative
2026-02-19 01:20:52.954968 | controller | +++ MANILA_INSTALL_TEMPEST_PLUGIN_SYSTEMWIDE=False
2026-02-19 01:20:52.954974 | controller | +++ MANILA_OPTGROUP_cephfsnative_cephfs_auth_id=manila
2026-02-19 01:20:52.954979 | controller | +++ MANILA_OPTGROUP_cephfsnative_cephfs_conf_path=/etc/ceph/ceph.conf
2026-02-19 01:20:52.954986 | controller | +++ MANILA_OPTGROUP_cephfsnative_driver_handles_share_servers=False
2026-02-19 01:20:52.954992 | controller | +++ MANILA_OPTGROUP_cephfsnative_share_driver=manila.share.drivers.cephfs.driver.CephFSDriver
2026-02-19 01:20:52.954998 | controller | +++ MANILA_REPLICA_STATE_UPDATE_INTERVAL=10
2026-02-19 01:20:52.955004 | controller | +++ MANILA_SERVER_MIGRATION_PERIOD_TASK_INTERVAL=10
2026-02-19 01:20:52.955009 | controller | +++ MANILA_SERVICE_IMAGE_NAME=manila-service-image-cephfs-master
2026-02-19 01:20:52.955015 | controller | +++ MANILA_SERVICE_IMAGE_URL=https://tarballs.opendev.org/openstack/manila-image-elements/images/manila-service-image-cephfs-master.qcow2
2026-02-19 01:20:52.955020 | controller | +++ MANILA_SHARE_MIGRATION_PERIOD_TASK_INTERVAL=1
2026-02-19 01:20:52.955026 | controller | +++ MANILA_USE_DOWNGRADE_MIGRATIONS=True
2026-02-19 01:20:52.955031 | controller | +++ MYSQL_REDUCE_MEMORY=True
2026-02-19 01:20:52.955036 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-02-19 01:20:52.955042 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-02-19 01:20:52.955047 | controller | +++ NOVA_VNC_ENABLED=True
2026-02-19 01:20:52.955052 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-02-19 01:20:52.955058 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-02-19 01:20:52.955077 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-02-19 01:20:52.955082 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-02-19 01:20:52.955088 | controller | +++ SERVICE_HOST=199.204.45.69
2026-02-19 01:20:52.955093 | controller | +++ SERVICE_PASSWORD=secretservice
2026-02-19 01:20:52.955098 | controller | +++ SHARE_DRIVER=manila.share.drivers.cephfs.driver.CephFSDriver
2026-02-19 01:20:52.955104 | controller | +++ SWIFT_HASH=1234123412341234
2026-02-19 01:20:52.955109 | controller | +++ SWIFT_REPLICAS=1
2026-02-19 01:20:52.955114 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-02-19 01:20:52.955120 | controller | +++ TARGET_DEV_OSD_DIR=/opt/stack
2026-02-19 01:20:52.955125 | controller | +++ VERBOSE=True
2026-02-19 01:20:52.955131 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-02-19 01:20:52.955136 | controller | +++ VOLUME_BACKING_FILE_SIZE=60GB
2026-02-19 01:20:52.955142 | controller | +++ LIBS_FROM_GIT=novnc,cinder,devstack,devstack-plugin-ceph,glance,keystone,manila,manila-tempest-plugin,neutron,nova,os-test-images,placement,python-manilaclient,requirements,swift,tempest
2026-02-19 01:20:52.955148 | controller | +++ TEMPEST_PLUGINS=/opt/stack/manila-tempest-plugin
2026-02-19 01:20:52.955153 | controller | +++ enable_plugin devstack-plugin-ceph https://opendev.org/openstack/devstack-plugin-ceph
2026-02-19 01:20:52.955165 | controller | +++ local name=devstack-plugin-ceph
2026-02-19 01:20:52.955178 | controller | +++ local url=https://opendev.org/openstack/devstack-plugin-ceph
2026-02-19 01:20:52.955183 | controller | +++ local branch=master
2026-02-19 01:20:52.955189 | controller | +++ is_plugin_enabled devstack-plugin-ceph
2026-02-19 01:20:52.955194 | controller | +++ local name=devstack-plugin-ceph
2026-02-19 01:20:52.955199 | controller | +++ [[ ,, =~ ,devstack-plugin-ceph, ]]
2026-02-19 01:20:52.955205 | controller | +++ return 1
2026-02-19 01:20:52.955211 | controller | +++ DEVSTACK_PLUGINS+=,devstack-plugin-ceph
2026-02-19 01:20:52.955217 | controller | +++ GITREPO[$name]=https://opendev.org/openstack/devstack-plugin-ceph
2026-02-19 01:20:52.955222 | controller | +++ GITDIR[$name]=/opt/stack/devstack-plugin-ceph
2026-02-19 01:20:52.955228 | controller | +++ GITBRANCH[$name]=master
2026-02-19 01:20:52.955233 | controller | +++ enable_plugin manila https://opendev.org/openstack/manila
2026-02-19 01:20:52.955239 | controller | +++ local name=manila
2026-02-19 01:20:52.955244 | controller | +++ local url=https://opendev.org/openstack/manila
2026-02-19 01:20:52.955250 | controller | +++ local branch=master
2026-02-19 01:20:52.955255 | controller | +++ is_plugin_enabled manila
2026-02-19 01:20:52.955260 | controller | +++ local name=manila
2026-02-19 01:20:52.955266 | controller | +++ [[ ,,devstack-plugin-ceph, =~ ,manila, ]]
2026-02-19 01:20:52.955271 | controller | +++ return 1
2026-02-19 01:20:52.955277 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-02-19 01:20:52.955282 | controller | +++ GITREPO[$name]=https://opendev.org/openstack/manila
2026-02-19 01:20:52.955288 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-02-19 01:20:52.955293 | controller | +++ GITBRANCH[$name]=master
2026-02-19 01:20:52.955298 | controller | ++ CELLSV2_SETUP=superconductor
2026-02-19 01:20:52.955304 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-02-19 01:20:52.956505 | controller | +++ trueorfalse False USER_UNITS
2026-02-19 01:20:52.956524 | controller | +++ local xtrace
2026-02-19 01:20:52.957740 | controller | ++++ set +o
2026-02-19 01:20:52.957981 | controller | ++++ grep xtrace
2026-02-19 01:20:52.960750 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.960826 | controller | +++ set +o xtrace
2026-02-19 01:20:52.961569 | controller | ++ USER_UNITS=False
2026-02-19 01:20:52.961630 | controller | ++ [[ False == \T\r\u\e ]]
2026-02-19 01:20:52.961638 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-02-19 01:20:52.961647 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-02-19 01:20:52.961672 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-02-19 01:20:52.963031 | controller | +++ _get_python_version python3
2026-02-19 01:20:52.963051 | controller | +++ local interp=python3
2026-02-19 01:20:52.963057 | controller | +++ local version
2026-02-19 01:20:52.963458 | controller | ++++ which python3
2026-02-19 01:20:52.966593 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-02-19 01:20:52.968751 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-02-19 01:20:52.985367 | controller | +++ version=3.12
2026-02-19 01:20:52.985424 | controller | +++ echo 3.12
2026-02-19 01:20:52.986768 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-02-19 01:20:52.986825 | controller | ++ export PYTHON3_VERSION=3.12
2026-02-19 01:20:52.986830 | controller | ++ PYTHON3_VERSION=3.12
2026-02-19 01:20:52.986834 | controller | ++ export 'VIRTUALENV_CMD=python3 -m venv'
2026-02-19 01:20:52.986854 | controller | ++ VIRTUALENV_CMD='python3 -m venv'
2026-02-19 01:20:52.986857 | controller | ++ '[' -t 1 ']'
2026-02-19 01:20:52.986861 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-02-19 01:20:52.987765 | controller | +++ trueorfalse False LOG_COLOR
2026-02-19 01:20:52.987803 | controller | +++ local xtrace
2026-02-19 01:20:52.989666 | controller | ++++ set +o
2026-02-19 01:20:52.989725 | controller | ++++ grep xtrace
2026-02-19 01:20:52.990984 | controller | +++ xtrace='set -o xtrace'
2026-02-19 01:20:52.991007 | controller | +++ set +o xtrace
2026-02-19 01:20:52.991750 | controller | ++ LOG_COLOR=False
2026-02-19 01:20:52.991781 | controller | ++ [[ False == \T\r\u\e ]]
2026-02-19 01:20:52.991787 | controller | ++ export 'PS4=+ $(short_source):   '
2026-02-19 01:20:52.991792 | controller | ++ PS4='+ $(short_source):   '
2026-02-19 01:20:52.996442 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-02-19 01:20:52.999450 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.004435 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.004456 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.009417 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.011731 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.015524 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-02-19 01:20:53.018592 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-02-19 01:20:53.021823 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-02-19 01:20:53.026308 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-02-19 01:20:53.029331 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.034651 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.034671 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.040261 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.043051 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.047048 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-02-19 01:20:53.050883 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-02-19 01:20:53.054119 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.059353 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.059387 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.065263 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.068175 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.072433 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-02-19 01:20:53.075635 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-02-19 01:20:53.079499 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-02-19 01:20:53.082529 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.087667 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.087715 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.093132 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.095990 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.099762 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-02-19 01:20:53.103659 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-02-19 01:20:53.106416 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.111746 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.111772 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.116714 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.119368 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.123384 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-02-19 01:20:53.126949 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-02-19 01:20:53.129943 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.134716 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.134741 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.139788 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.142165 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.146077 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-02-19 01:20:53.149672 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-02-19 01:20:53.153743 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-02-19 01:20:53.157309 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-02-19 01:20:53.160375 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://opendev.org
2026-02-19 01:20:53.163540 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-02-19 01:20:53.166650 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.1
2026-02-19 01:20:53.169730 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://opendev.org/openstack/cinder.git
2026-02-19 01:20:53.172395 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-02-19 01:20:53.175616 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://opendev.org/openstack/glance.git
2026-02-19 01:20:53.179006 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-02-19 01:20:53.182274 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://opendev.org/openstack/horizon.git
2026-02-19 01:20:53.185549 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-02-19 01:20:53.188864 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://opendev.org/openstack/keystone.git
2026-02-19 01:20:53.192240 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-02-19 01:20:53.195450 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://opendev.org/openstack/neutron.git
2026-02-19 01:20:53.199509 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-02-19 01:20:53.203517 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://opendev.org/openstack/nova.git
2026-02-19 01:20:53.207394 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-02-19 01:20:53.210336 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://opendev.org/openstack/swift.git
2026-02-19 01:20:53.213343 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-02-19 01:20:53.216401 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://opendev.org/openstack/placement.git
2026-02-19 01:20:53.219346 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-02-19 01:20:53.222299 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://opendev.org/openstack/requirements.git
2026-02-19 01:20:53.225357 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-02-19 01:20:53.228271 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://opendev.org/openstack/tempest.git
2026-02-19 01:20:53.231286 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-02-19 01:20:53.234386 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-02-19 01:20:53.237443 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://opendev.org/openstack/os-test-images.git
2026-02-19 01:20:53.240565 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-02-19 01:20:53.243607 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-02-19 01:20:53.246587 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://opendev.org/openstack/python-cinderclient.git
2026-02-19 01:20:53.249740 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-02-19 01:20:53.252845 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://opendev.org/openstack/python-brick-cinderclient-ext.git
2026-02-19 01:20:53.256301 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-02-19 01:20:53.259525 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://opendev.org/openstack/python-barbicanclient.git
2026-02-19 01:20:53.262759 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-02-19 01:20:53.265678 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-02-19 01:20:53.267748 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://opendev.org/openstack/python-glanceclient.git
2026-02-19 01:20:53.269983 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-02-19 01:20:53.272189 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://opendev.org/openstack/python-ironicclient.git
2026-02-19 01:20:53.274391 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-02-19 01:20:53.276618 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-02-19 01:20:53.278826 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://opendev.org/openstack/keystoneauth.git
2026-02-19 01:20:53.280975 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-02-19 01:20:53.283162 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://opendev.org/openstack/python-keystoneclient.git
2026-02-19 01:20:53.285323 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-02-19 01:20:53.287513 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://opendev.org/openstack/python-neutronclient.git
2026-02-19 01:20:53.289739 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-02-19 01:20:53.291846 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://opendev.org/openstack/python-novaclient.git
2026-02-19 01:20:53.293988 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-02-19 01:20:53.296052 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://opendev.org/openstack/python-swiftclient.git
2026-02-19 01:20:53.298199 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-02-19 01:20:53.300245 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://opendev.org/openstack/python-openstackclient.git
2026-02-19 01:20:53.302299 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-02-19 01:20:53.304321 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-02-19 01:20:53.306251 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://opendev.org/openstack/osc-placement.git
2026-02-19 01:20:53.308192 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-02-19 01:20:53.310151 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://opendev.org/openstack/castellan.git
2026-02-19 01:20:53.312085 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-02-19 01:20:53.314016 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://opendev.org/openstack/cliff.git
2026-02-19 01:20:53.316075 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-02-19 01:20:53.318135 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://opendev.org/openstack/futurist.git
2026-02-19 01:20:53.320184 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-02-19 01:20:53.322235 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://opendev.org/openstack/debtcollector.git
2026-02-19 01:20:53.324395 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-02-19 01:20:53.326747 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://opendev.org/openstack/etcd3gw.git
2026-02-19 01:20:53.329048 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-02-19 01:20:53.331394 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://opendev.org/openstack/automaton.git
2026-02-19 01:20:53.333661 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-02-19 01:20:53.335902 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://opendev.org/openstack/oslo.cache.git
2026-02-19 01:20:53.338126 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-02-19 01:20:53.340348 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://opendev.org/openstack/oslo.concurrency.git
2026-02-19 01:20:53.342586 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-02-19 01:20:53.344916 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://opendev.org/openstack/oslo.config.git
2026-02-19 01:20:53.347332 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-02-19 01:20:53.349707 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://opendev.org/openstack/oslo.context.git
2026-02-19 01:20:53.351984 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-02-19 01:20:53.354663 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://opendev.org/openstack/oslo.db.git
2026-02-19 01:20:53.357032 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-02-19 01:20:53.359272 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://opendev.org/openstack/oslo.i18n.git
2026-02-19 01:20:53.361625 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-02-19 01:20:53.364023 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://opendev.org/openstack/oslo.limit.git
2026-02-19 01:20:53.366366 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-02-19 01:20:53.368573 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://opendev.org/openstack/oslo.log.git
2026-02-19 01:20:53.371011 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-02-19 01:20:53.373330 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://opendev.org/openstack/oslo.messaging.git
2026-02-19 01:20:53.375590 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-02-19 01:20:53.377992 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://opendev.org/openstack/oslo.middleware.git
2026-02-19 01:20:53.380258 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-02-19 01:20:53.382592 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://opendev.org/openstack/oslo.policy.git
2026-02-19 01:20:53.384934 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-02-19 01:20:53.387285 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://opendev.org/openstack/oslo.privsep.git
2026-02-19 01:20:53.389519 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-02-19 01:20:53.391514 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://opendev.org/openstack/oslo.reports.git
2026-02-19 01:20:53.393549 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-02-19 01:20:53.395595 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://opendev.org/openstack/oslo.rootwrap.git
2026-02-19 01:20:53.397572 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-02-19 01:20:53.399639 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://opendev.org/openstack/oslo.serialization.git
2026-02-19 01:20:53.401729 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-02-19 01:20:53.403763 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://opendev.org/openstack/oslo.service.git
2026-02-19 01:20:53.405832 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-02-19 01:20:53.407874 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://opendev.org/openstack/oslo.utils.git
2026-02-19 01:20:53.409982 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-02-19 01:20:53.412066 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://opendev.org/openstack/oslo.versionedobjects.git
2026-02-19 01:20:53.414143 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-02-19 01:20:53.416188 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://opendev.org/openstack/oslo.vmware.git
2026-02-19 01:20:53.418307 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-02-19 01:20:53.420326 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://opendev.org/openstack/osprofiler.git
2026-02-19 01:20:53.422501 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-02-19 01:20:53.424509 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://opendev.org/openstack/pycadf.git
2026-02-19 01:20:53.426523 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-02-19 01:20:53.428610 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://opendev.org/openstack/stevedore.git
2026-02-19 01:20:53.430657 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-02-19 01:20:53.432680 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://opendev.org/openstack/taskflow.git
2026-02-19 01:20:53.434733 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-02-19 01:20:53.436726 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://opendev.org/openstack/tooz.git
2026-02-19 01:20:53.438746 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-02-19 01:20:53.440737 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://opendev.org/openstack/pbr.git
2026-02-19 01:20:53.442799 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-02-19 01:20:53.444821 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://opendev.org/openstack/cursive.git
2026-02-19 01:20:53.446814 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-02-19 01:20:53.448827 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://opendev.org/openstack/glance_store.git
2026-02-19 01:20:53.450889 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-02-19 01:20:53.452918 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://opendev.org/openstack/keystonemiddleware.git
2026-02-19 01:20:53.454948 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-02-19 01:20:53.456961 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://opendev.org/openstack/ceilometermiddleware.git
2026-02-19 01:20:53.459024 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-02-19 01:20:53.461056 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-02-19 01:20:53.463057 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://opendev.org/openstack/openstacksdk.git
2026-02-19 01:20:53.465113 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-02-19 01:20:53.467121 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://opendev.org/openstack/os-brick.git
2026-02-19 01:20:53.469157 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-02-19 01:20:53.471169 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://opendev.org/openstack/os-client-config.git
2026-02-19 01:20:53.473228 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-02-19 01:20:53.475242 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-02-19 01:20:53.477263 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://opendev.org/openstack/os-vif.git
2026-02-19 01:20:53.479309 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-02-19 01:20:53.481422 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://opendev.org/openstack/osc-lib.git
2026-02-19 01:20:53.483430 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-02-19 01:20:53.485462 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://opendev.org/openstack/ironic-lib.git
2026-02-19 01:20:53.487466 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-02-19 01:20:53.489477 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-02-19 01:20:53.491489 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://opendev.org/openstack/diskimage-builder.git
2026-02-19 01:20:53.493491 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-02-19 01:20:53.495470 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-02-19 01:20:53.497496 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://opendev.org/openstack/neutron-lib.git
2026-02-19 01:20:53.499476 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-02-19 01:20:53.501492 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-02-19 01:20:53.503499 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://opendev.org/openstack/os-resource-classes.git
2026-02-19 01:20:53.505511 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-02-19 01:20:53.507525 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://opendev.org/openstack/os-traits.git
2026-02-19 01:20:53.509547 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-02-19 01:20:53.511643 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://opendev.org/openstack/ovsdbapp.git
2026-02-19 01:20:53.513665 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-02-19 01:20:53.515662 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-02-19 01:20:53.517675 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://opendev.org/openstack/os-ken.git
2026-02-19 01:20:53.519715 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-02-19 01:20:53.521732 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-02-19 01:20:53.523994 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://opendev.org/openstack/ironic-python-agent.git
2026-02-19 01:20:53.526083 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-02-19 01:20:53.528080 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-02-19 01:20:53.530046 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-02-19 01:20:53.532012 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-02-19 01:20:53.533996 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-02-19 01:20:53.536905 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-02-19 01:20:53.538893 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.542371 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.542393 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.546749 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.548543 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.551376 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-02-19 01:20:53.553375 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-02-19 01:20:53.555395 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-02-19 01:20:53.557364 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-02-19 01:20:53.559350 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-02-19 01:20:53.561304 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-02-19 01:20:53.563258 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-02-19 01:20:53.565364 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-02-19 01:20:53.567602 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-02-19 01:20:53.569944 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-02-19 01:20:53.572229 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-02-19 01:20:53.574863 | controller | +++ stackrc:source:668                       :   uname -m
2026-02-19 01:20:53.578778 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-02-19 01:20:53.581984 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-02-19 01:20:53.584195 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.588188 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.588217 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.592640 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.594340 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.595939 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-02-19 01:20:53.597537 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-02-19 01:20:53.598998 | controller | ++ stackrc:source:675                       :   [[ -n http://download.cirros-cloud.net/0.6.2/cirros-0.6.2-x86_64-disk.img, http://download.cirros-cloud.net/0.6.1/cirros-0.6.1-x86_64-disk.img ]]
2026-02-19 01:20:53.600474 | controller | ++ stackrc:source:676                       :   IMAGE_URLS+=,
2026-02-19 01:20:53.602364 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-02-19 01:20:53.604528 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-02-19 01:20:53.607177 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-02-19 01:20:53.609958 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-02-19 01:20:53.612226 | 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-02-19 01:20:53.614607 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-02-19 01:20:53.617220 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-02-19 01:20:53.619933 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-02-19 01:20:53.622716 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-02-19 01:20:53.626240 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-02-19 01:20:53.629956 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-02-19 01:20:53.633588 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-02-19 01:20:53.636542 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-02-19 01:20:53.640733 | controller | +++ functions-common:is_arch:517             :   uname -m
2026-02-19 01:20:53.645168 | controller | ++ functions-common:is_arch:517             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-02-19 01:20:53.648108 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-02-19 01:20:53.651007 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-02-19 01:20:53.653945 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-02-19 01:20:53.656819 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-02-19 01:20:53.659637 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-02-19 01:20:53.662466 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-02-19 01:20:53.665337 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-02-19 01:20:53.668205 | 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-02-19 01:20:53.671203 | 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-02-19 01:20:53.674154 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-02-19 01:20:53.677009 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-02-19 01:20:53.679947 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-02-19 01:20:53.687157 | controller | +++ stackrc:source:749                       :   echo 'http://download.cirros-cloud.net/0.6.2/cirros-0.6.2-x86_64-disk.img, http://download.cirros-cloud.net/0.6.1/cirros-0.6.1-x86_64-disk.img,https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img'
2026-02-19 01:20:53.687218 | controller | +++ stackrc:source:749                       :   grep -o -F http://download.cirros-cloud.net/0.6.2/cirros-0.6.2-x86_64-disk.img
2026-02-19 01:20:53.687226 | controller | +++ stackrc:source:749                       :   wc -l
2026-02-19 01:20:53.693568 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-02-19 01:20:53.696929 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-02-19 01:20:53.702741 | controller | +++ stackrc:source:749                       :   echo 'http://download.cirros-cloud.net/0.6.2/cirros-0.6.2-x86_64-disk.img, http://download.cirros-cloud.net/0.6.1/cirros-0.6.1-x86_64-disk.img,https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img'
2026-02-19 01:20:53.703880 | controller | +++ stackrc:source:749                       :   grep -o -F http://download.cirros-cloud.net/0.6.1/cirros-0.6.1-x86_64-disk.img
2026-02-19 01:20:53.704662 | controller | +++ stackrc:source:749                       :   wc -l
2026-02-19 01:20:53.709115 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-02-19 01:20:53.711604 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-02-19 01:20:53.716945 | controller | +++ stackrc:source:749                       :   wc -l
2026-02-19 01:20:53.717801 | controller | +++ stackrc:source:749                       :   echo 'http://download.cirros-cloud.net/0.6.2/cirros-0.6.2-x86_64-disk.img, http://download.cirros-cloud.net/0.6.1/cirros-0.6.1-x86_64-disk.img,https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img'
2026-02-19 01:20:53.718315 | 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-02-19 01:20:53.723914 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-02-19 01:20:53.726784 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=60GB
2026-02-19 01:20:53.729546 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-02-19 01:20:53.732449 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-02-19 01:20:53.735222 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-02-19 01:20:53.738244 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-02-19 01:20:53.741097 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-02-19 01:20:53.743983 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-02-19 01:20:53.746844 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-02-19 01:20:53.750822 | controller | +++ stackrc:source:777                       :   nproc
2026-02-19 01:20:53.757139 | controller | +++ stackrc:source:777                       :   nproc
2026-02-19 01:20:53.761206 | controller | ++ stackrc:source:777                       :   API_WORKERS=2
2026-02-19 01:20:53.764029 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-02-19 01:20:53.766879 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-02-19 01:20:53.769738 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-02-19 01:20:53.772585 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-02-19 01:20:53.776776 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-02-19 01:20:53.779614 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.784661 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.784716 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.789539 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.792665 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.795583 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-02-19 01:20:53.799788 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-02-19 01:20:53.802251 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.807057 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.807080 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.812097 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.814658 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.818064 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-02-19 01:20:53.821808 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-02-19 01:20:53.824473 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:53.830723 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:53.830743 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:53.834596 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:53.837405 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:53.842182 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-02-19 01:20:53.844997 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-02-19 01:20:53.847786 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-02-19 01:20:53.850529 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-02-19 01:20:53.862753 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-02-19 01:20:53.879536 | controller | ++ stackrc:source:815                       :   HOST_IP=199.204.45.69
2026-02-19 01:20:53.882264 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-02-19 01:20:53.885307 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 199.204.45.69 inet
2026-02-19 01:20:53.888106 | controller | +++ functions-common:get_default_host_ip:752 :   local fixed_range=10.1.0.0/20
2026-02-19 01:20:53.890883 | controller | +++ functions-common:get_default_host_ip:753 :   local floating_range=172.24.5.0/24
2026-02-19 01:20:53.893758 | controller | +++ functions-common:get_default_host_ip:754 :   local host_ip_iface=
2026-02-19 01:20:53.910179 | controller | +++ functions-common:get_default_host_ip:755 :   local host_ip=199.204.45.69
2026-02-19 01:20:53.912839 | controller | +++ functions-common:get_default_host_ip:756 :   local af=inet
2026-02-19 01:20:53.915342 | controller | +++ functions-common:get_default_host_ip:759 :   '[' -z 199.204.45.69 -o 199.204.45.69 == dhcp ']'
2026-02-19 01:20:53.917873 | controller | +++ functions-common:get_default_host_ip:783 :   echo 199.204.45.69
2026-02-19 01:20:53.921421 | controller | ++ stackrc:source:818                       :   HOST_IP=199.204.45.69
2026-02-19 01:20:53.924313 | controller | ++ stackrc:source:819                       :   '[' 199.204.45.69 == '' ']'
2026-02-19 01:20:53.928192 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-02-19 01:20:53.931658 | controller | +++ functions-common:get_default_host_ip:752 :   local fixed_range=
2026-02-19 01:20:53.934464 | controller | +++ functions-common:get_default_host_ip:753 :   local floating_range=
2026-02-19 01:20:53.937281 | controller | +++ functions-common:get_default_host_ip:754 :   local host_ip_iface=
2026-02-19 01:20:53.940058 | controller | +++ functions-common:get_default_host_ip:755 :   local host_ip=
2026-02-19 01:20:53.942852 | controller | +++ functions-common:get_default_host_ip:756 :   local af=inet6
2026-02-19 01:20:53.945665 | controller | +++ functions-common:get_default_host_ip:759 :   '[' -z '' -o '' == dhcp ']'
2026-02-19 01:20:53.948507 | controller | +++ functions-common:get_default_host_ip:760 :   host_ip=
2026-02-19 01:20:53.957718 | controller | ++++ functions-common:get_default_host_ip:762 :   awk '/default/ {print $5}'
2026-02-19 01:20:53.957738 | controller | ++++ functions-common:get_default_host_ip:762 :   ip -f inet6 route list match default table all
2026-02-19 01:20:53.959505 | controller | ++++ functions-common:get_default_host_ip:762 :   grep via
2026-02-19 01:20:53.960326 | controller | ++++ functions-common:get_default_host_ip:762 :   head -1
2026-02-19 01:20:53.965938 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip_iface=
2026-02-19 01:20:53.968140 | controller | +++ functions-common:get_default_host_ip:763 :   local host_ips
2026-02-19 01:20:53.974615 | controller | ++++ functions-common:get_default_host_ip:764 :   sed /temporary/d
2026-02-19 01:20:53.974635 | controller | ++++ functions-common:get_default_host_ip:764 :   LC_ALL=C
2026-02-19 01:20:53.974806 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-02-19 01:20:53.976487 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 addr show
2026-02-19 01:20:53.982769 | controller | +++ functions-common:get_default_host_ip:764 :   host_ips='::1
2026-02-19 01:20:53.982785 | controller | 2604:e100:1:0:f816:3eff:fe59:78c5
2026-02-19 01:20:53.982789 | controller | fe80::f816:3eff:fe59:78c5'
2026-02-19 01:20:53.985222 | controller | +++ functions-common:get_default_host_ip:765 :   local ip
2026-02-19 01:20:53.987668 | controller | +++ functions-common:get_default_host_ip:766 :   for ip in $host_ips
2026-02-19 01:20:53.990725 | controller | +++ functions-common:get_default_host_ip:773 :   [[ inet6 == \i\n\e\t\6 ]]
2026-02-19 01:20:53.993801 | controller | +++ functions-common:get_default_host_ip:774 :   host_ip=::1
2026-02-19 01:20:53.996512 | controller | +++ functions-common:get_default_host_ip:775 :   break
2026-02-19 01:20:53.999343 | controller | +++ functions-common:get_default_host_ip:783 :   echo ::1
2026-02-19 01:20:54.003144 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-02-19 01:20:54.006132 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-02-19 01:20:54.008797 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:54.013788 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:54.013850 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:54.018713 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:54.021489 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:54.024187 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-02-19 01:20:54.026749 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-02-19 01:20:54.029505 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-02-19 01:20:54.032493 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-02-19 01:20:54.035342 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=199.204.45.69
2026-02-19 01:20:54.038216 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-02-19 01:20:54.041093 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-02-19 01:20:54.043950 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-02-19 01:20:54.046833 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-02-19 01:20:54.049643 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=199.204.45.69
2026-02-19 01:20:54.052441 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-02-19 01:20:54.055305 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-02-19 01:20:54.058224 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-02-19 01:20:54.061070 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-02-19 01:20:54.063862 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=199.204.45.69
2026-02-19 01:20:54.066705 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-02-19 01:20:54.069549 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=199.204.45.69
2026-02-19 01:20:54.073741 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-02-19 01:20:54.076235 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:54.081098 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:54.081236 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:54.086280 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:54.088952 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:54.092449 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-02-19 01:20:54.095305 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=199.204.45.69
2026-02-19 01:20:54.098377 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-02-19 01:20:54.101170 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-02-19 01:20:54.105289 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-02-19 01:20:54.108554 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:54.113237 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:54.113259 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:54.118319 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:54.121508 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:54.124500 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-02-19 01:20:54.127450 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-02-19 01:20:54.130315 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-02-19 01:20:54.133158 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-02-19 01:20:54.135916 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-02-19 01:20:54.138725 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-02-19 01:20:54.141447 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-02-19 01:20:54.144072 | controller | ++ stackrc:source:924                       :   [[ /opt/stack/logs == \d\e\v\s\t\a\c\k\l\o\g\.\t\x\t ]]
2026-02-19 01:20:54.146803 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-02-19 01:20:54.149613 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-02-19 01:20:54.152539 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-02-19 01:20:54.156872 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-02-19 01:20:54.156894 | controller | + functions:write_devstack_version:876     :   cat -
2026-02-19 01:20:54.160116 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-02-19 01:20:54.199098 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-02-19 01:20:54.202470 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-02-19 01:20:54.206013 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-02-19 01:20:54.209160 | controller | + functions-common:export_proxy_variables:2230 :   isset http_proxy
2026-02-19 01:20:54.212088 | controller | + functions-common:isset:264               :   [[ -v http_proxy ]]
2026-02-19 01:20:54.215037 | controller | + functions-common:export_proxy_variables:2233 :   isset https_proxy
2026-02-19 01:20:54.217937 | controller | + functions-common:isset:264               :   [[ -v https_proxy ]]
2026-02-19 01:20:54.220896 | controller | + functions-common:export_proxy_variables:2236 :   isset no_proxy
2026-02-19 01:20:54.223875 | controller | + functions-common:isset:264               :   [[ -v no_proxy ]]
2026-02-19 01:20:54.226968 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-02-19 01:20:54.229957 | controller | + functions-common:disable_negated_services:1935 :   local xtrace
2026-02-19 01:20:54.234896 | controller | ++ functions-common:disable_negated_services:1936 :   set +o
2026-02-19 01:20:54.234972 | controller | ++ functions-common:disable_negated_services:1936 :   grep xtrace
2026-02-19 01:20:54.240225 | controller | + functions-common:disable_negated_services:1936 :   xtrace='set -o xtrace'
2026-02-19 01:20:54.242772 | controller | + functions-common:disable_negated_services:1937 :   set +o xtrace
2026-02-19 01:20:54.259164 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-02-19 01:20:54.262120 | controller | + functions-common:is_package_installed:1439 :   [[ -z sudo ]]
2026-02-19 01:20:54.265081 | controller | + functions-common:is_package_installed:1443 :   [[ -z deb ]]
2026-02-19 01:20:54.268084 | controller | + functions-common:is_package_installed:1447 :   [[ deb = \d\e\b ]]
2026-02-19 01:20:54.271059 | controller | + functions-common:is_package_installed:1448 :   dpkg -s sudo
2026-02-19 01:20:54.292499 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-02-19 01:20:54.310248 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-02-19 01:20:54.311207 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-02-19 01:20:54.319781 | controller | #includedir /etc/sudoers.d
2026-02-19 01:20:54.325397 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-02-19 01:20:54.329441 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-02-19 01:20:54.334666 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.E13PT2zGHO
2026-02-19 01:20:54.338103 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-02-19 01:20:54.341169 | controller | + ./stack.sh:main:277                      :   echo 'Defaults:stack secure_path=/sbin:/usr/sbin:/usr/bin:/bin:/usr/local/sbin:/usr/local/bin'
2026-02-19 01:20:54.344282 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-02-19 01:20:54.347301 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.E13PT2zGHO
2026-02-19 01:20:54.352239 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.E13PT2zGHO
2026-02-19 01:20:54.368437 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.E13PT2zGHO /etc/sudoers.d/50_stack_sh
2026-02-19 01:20:54.386515 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-02-19 01:20:54.389437 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-02-19 01:20:54.392803 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-02-19 01:20:54.395755 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-02-19 01:20:54.396334 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-02-19 01:20:54.411406 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-02-19 01:20:54.414319 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-02-19 01:20:54.417225 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-02-19 01:20:54.420025 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-02-19 01:20:54.438624 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-02-19 01:20:54.441592 | controller | + functions-common:safe_chown:2313         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-02-19 01:20:54.444510 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-02-19 01:20:54.449129 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-02-19 01:20:54.449192 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-02-19 01:20:54.453781 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-02-19 01:20:54.456015 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-02-19 01:20:54.461583 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chown -R stack /opt/stack/logs
2026-02-19 01:20:54.478398 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-02-19 01:20:54.480965 | controller | + functions-common:safe_chmod:2307         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-02-19 01:20:54.484121 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-02-19 01:20:54.488377 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-02-19 01:20:54.488395 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-02-19 01:20:54.493576 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-02-19 01:20:54.496302 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-02-19 01:20:54.502178 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chmod 0755 /opt/stack/logs
2026-02-19 01:20:54.518947 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-02-19 01:20:54.522169 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-02-19 01:20:54.525090 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-02-19 01:20:54.540325 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-02-19 01:20:54.543023 | controller | + functions-common:safe_chown:2313         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-02-19 01:20:54.545891 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-02-19 01:20:54.550805 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-02-19 01:20:54.550861 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-02-19 01:20:54.555858 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-02-19 01:20:54.558851 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-02-19 01:20:54.564241 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chown -R stack /opt/stack/data
2026-02-19 01:20:54.580642 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-02-19 01:20:54.583600 | controller | + functions-common:safe_chmod:2307         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-02-19 01:20:54.586395 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-02-19 01:20:54.590918 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-02-19 01:20:54.590945 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-02-19 01:20:54.595805 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-02-19 01:20:54.598535 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-02-19 01:20:54.603965 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chmod 0755 /opt/stack/data
2026-02-19 01:20:54.621200 | controller | + ./stack.sh:main:353                      :   async_init
2026-02-19 01:20:54.624336 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-02-19 01:20:54.627189 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-02-19 01:20:54.632088 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-02-19 01:20:54.639203 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-02-19 01:20:54.644213 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000156437
2026-02-19 01:20:54.647187 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000156437 /etc/hosts
2026-02-19 01:20:54.653809 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-02-19 01:20:54.656968 | controller | ++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:54.662762 | controller | +++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:54.662808 | controller | +++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:54.668009 | controller | ++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:54.670916 | controller | ++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:54.681777 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-02-19 01:20:54.684798 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-02-19 01:20:54.687736 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-02-19 01:20:54.690310 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-02-19 01:20:54.693162 | controller | + ./stack.sh:main:404                      :   install_python
2026-02-19 01:20:54.696046 | controller | + inc/python:install_python:482            :   install_python3
2026-02-19 01:20:54.699073 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-02-19 01:20:54.701912 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-02-19 01:20:54.704880 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-02-19 01:20:54.707917 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-02-19 01:20:54.710857 | controller | + functions-common:apt_get:1186            :   local xtrace result
2026-02-19 01:20:54.716392 | controller | ++ functions-common:apt_get:1187            :   grep xtrace
2026-02-19 01:20:54.716450 | controller | ++ functions-common:apt_get:1187            :   set +o
2026-02-19 01:20:54.721210 | controller | + functions-common:apt_get:1187            :   xtrace='set -o xtrace'
2026-02-19 01:20:54.724059 | controller | + functions-common:apt_get:1188            :   set +o xtrace
2026-02-19 01:20:54.744537 | controller | + functions-common:apt_get:1199            :   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-02-19 01:20:54.805565 | controller | Reading package lists...
2026-02-19 01:20:54.818520 | controller | Building dependency tree...
2026-02-19 01:20:54.819420 | controller | Reading state information...
2026-02-19 01:20:54.825855 | controller | E: Unable to locate package python3.12-dev
2026-02-19 01:20:54.825873 | controller | E: Couldn't find any package by glob 'python3.12-dev'
2026-02-19 01:20:54.825883 | controller | E: Couldn't find any package by regex 'python3.12-dev'
2026-02-19 01:20:54.833211 | controller | + functions-common:apt_get:1203            :   result=100
2026-02-19 01:20:54.836556 | controller | + functions-common:apt_get:1206            :   time_stop apt-get
2026-02-19 01:20:54.839279 | controller | + functions-common:time_stop:2412          :   local name
2026-02-19 01:20:54.841954 | controller | + functions-common:time_stop:2413          :   local end_time
2026-02-19 01:20:54.844583 | controller | + functions-common:time_stop:2414          :   local elapsed_time
2026-02-19 01:20:54.847311 | controller | + functions-common:time_stop:2415          :   local total
2026-02-19 01:20:54.850072 | controller | + functions-common:time_stop:2416          :   local start_time
2026-02-19 01:20:54.852754 | controller | + functions-common:time_stop:2418          :   name=apt-get
2026-02-19 01:20:54.855421 | controller | + functions-common:time_stop:2419          :   start_time=1771464054740
2026-02-19 01:20:54.858121 | controller | + functions-common:time_stop:2421          :   [[ -z 1771464054740 ]]
2026-02-19 01:20:54.862331 | controller | ++ functions-common:time_stop:2424          :   date +%s%3N
2026-02-19 01:20:54.866526 | controller | + functions-common:time_stop:2424          :   end_time=1771464054863
2026-02-19 01:20:54.869282 | controller | + functions-common:time_stop:2425          :   elapsed_time=123
2026-02-19 01:20:54.871973 | controller | + functions-common:time_stop:2426          :   total=0
2026-02-19 01:20:54.874763 | controller | + functions-common:time_stop:2428          :   _TIME_START[$name]=
2026-02-19 01:20:54.877501 | controller | + functions-common:time_stop:2429          :   _TIME_TOTAL[$name]=123
2026-02-19 01:20:54.880350 | controller | + functions-common:apt_get:1207            :   return 100
2026-02-19 01:20:54.884615 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-02-19 01:20:54.889926 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-02-19 01:20:54.893279 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-02-19 01:20:54.897801 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-02-19 01:20:54.900745 | controller | ++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:54.904757 | controller | +++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:54.905929 | controller | +++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:54.911255 | controller | ++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:54.914222 | controller | ++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:54.917902 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-02-19 01:20:54.922449 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-02-19 01:20:54.925435 | controller | ++ functions-common:trueorfalse:222         :   local xtrace
2026-02-19 01:20:54.930480 | controller | +++ functions-common:trueorfalse:223         :   set +o
2026-02-19 01:20:54.930656 | controller | +++ functions-common:trueorfalse:223         :   grep xtrace
2026-02-19 01:20:54.936850 | controller | ++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-02-19 01:20:54.939477 | controller | ++ functions-common:trueorfalse:224         :   set +o xtrace
2026-02-19 01:20:54.943972 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-02-19 01:20:54.946821 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-02-19 01:20:54.949770 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-02-19 01:20:54.953604 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-02-19 01:20:54.958026 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-02-19-012054
2026-02-19 01:20:54.961108 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-02-19 01:20:54.963889 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-02-19 01:20:54.966603 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-02-19 01:20:54.969333 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-02-19 01:20:54.974335 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-02-19 01:20:54.979536 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-02-19-012054
2026-02-19 01:20:54.982293 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-02-19-012054.summary.2026-02-19-012054
2026-02-19 01:20:54.984944 | controller | + ./stack.sh:main:476                      :   exec
2026-02-19 01:20:54.987605 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-02-19 01:20:54.990292 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-02-19 01:20:54.992976 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-02-19 01:20:54.995641 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-02-19 01:20:54.998363 | controller | + ./stack.sh:main:483                      :   exec
2026-02-19 01:20:55.003745 | 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-02-19-012054
2026-02-19 01:20:55.034495 | controller | + ./stack.sh:main:485                      :   exec
2026-02-19 01:20:55.034592 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-02-19-012054.summary.2026-02-19-012054
2026-02-19 01:20:55.035871 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-02-19-012054'
2026-02-19 01:20:55.035914 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-02-19 01:20:55.035944 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-02-19-012054
2026-02-19 01:20:55.035973 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-02-19-012054 /opt/stack/logs/devstacklog.txt
2026-02-19 01:20:55.036001 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-02-19-012054.summary.2026-02-19-012054 /opt/stack/logs/devstacklog.txt.summary
2026-02-19 01:20:55.037918 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-02-19 01:20:55.040111 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-02-19 01:20:55.044166 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-02-19 01:20:55.048612 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-02-19 01:20:55.050526 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-02-19 01:20:55.055450 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-02-19 01:20:55.055562 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-02-19 01:20:55.060217 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-02-19 01:20:55.062892 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-02-19 01:20:55.065921 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-02-19 01:20:55.071451 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-02-19 01:20:55.074577 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-02-19 01:20:55.077590 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-02-19 01:20:55.081292 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-02-19 01:20:55.087010 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-02-19 01:20:55.089403 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-02-19 01:20:55.092570 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-02-19 01:20:55.095582 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-02-19 01:20:55.098507 | controller | + ./stack.sh:main:574                      :   uname -a
2026-02-19 01:20:55.100417 | controller | Linux np0000156437 6.8.0-100-generic #100-Ubuntu SMP PREEMPT_DYNAMIC Tue Jan 13 16:40:06 UTC 2026 x86_64 x86_64 x86_64 GNU/Linux
2026-02-19 01:20:55.103418 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-02-19 01:20:55.106472 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-02-19 01:20:55.111155 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-02-19 01:20:55.162613 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-02-19 01:20:55.184811 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-02-19 01:20:55.189313 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-02-19 01:20:55.210011 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-02-19 01:20:55.213219 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-02-19 01:20:55.233265 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-02-19 01:20:55.236729 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-02-19 01:20:55.256941 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-02-19 01:20:55.259494 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-02-19 01:20:55.262811 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-02-19 01:20:55.267542 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-02-19 01:20:55.287912 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-02-19 01:20:55.292807 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-02-19 01:20:55.313822 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-02-19 01:20:55.317540 | controller | + ./stack.sh:main:588                      :   tune_host
2026-02-19 01:20:55.320484 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-02-19 01:20:55.323410 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-02-19 01:20:55.326258 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-02-19 01:20:55.329084 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-02-19 01:20:55.331815 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-02-19 01:20:55.335443 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-02-19 01:20:55.338227 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-02-19 01:20:55.338434 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-02-19 01:20:55.347038 | controller | lz4
2026-02-19 01:20:55.351981 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-02-19 01:20:55.352913 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-02-19 01:20:55.356803 | controller | zsmalloc
2026-02-19 01:20:55.361575 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-02-19 01:20:55.361786 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-02-19 01:20:55.369783 | controller | 1
2026-02-19 01:20:55.385561 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-02-19 01:20:55.397766 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-02-19 01:20:55.397794 | controller | /sys/module/zswap/parameters/enabled:Y
2026-02-19 01:20:55.397799 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-02-19 01:20:55.397803 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-02-19 01:20:55.397807 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-02-19 01:20:55.397820 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-02-19 01:20:55.397824 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-02-19 01:20:55.397828 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-02-19 01:20:55.397832 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-02-19 01:20:55.404063 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-02-19 01:20:55.407477 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-02-19 01:20:55.410455 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-02-19 01:20:55.413483 | controller | + functions-common:install_package:1430    :   update_package_repo
2026-02-19 01:20:55.416517 | controller | + functions-common:update_package_repo:1404 :   NO_UPDATE_REPOS=False
2026-02-19 01:20:55.419471 | controller | + functions-common:update_package_repo:1405 :   REPOS_UPDATED=False
2026-02-19 01:20:55.422469 | controller | + functions-common:update_package_repo:1406 :   RETRY_UPDATE=False
2026-02-19 01:20:55.425459 | controller | + functions-common:update_package_repo:1408 :   [[ False = \T\r\u\e ]]
2026-02-19 01:20:55.428587 | controller | + functions-common:update_package_repo:1412 :   is_ubuntu
2026-02-19 01:20:55.431488 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-02-19 01:20:55.434337 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-02-19 01:20:55.437186 | controller | + functions-common:update_package_repo:1413 :   apt_get_update
2026-02-19 01:20:55.440292 | controller | + functions-common:apt_get_update:1158     :   [[ False == \T\r\u\e ]]
2026-02-19 01:20:55.443197 | controller | + functions-common:apt_get_update:1163     :   [[ False = \T\r\u\e ]]
2026-02-19 01:20:55.446096 | controller | + functions-common:apt_get_update:1165     :   local sudo=sudo
2026-02-19 01:20:55.450132 | controller | ++ functions-common:apt_get_update:1166     :   id -u
2026-02-19 01:20:55.454723 | controller | + functions-common:apt_get_update:1166     :   [[ 1002 = \0 ]]
2026-02-19 01:20:55.457570 | controller | + functions-common:apt_get_update:1169     :   time_start apt-get-update
2026-02-19 01:20:55.460449 | controller | + functions-common:time_start:2398         :   local name=apt-get-update
2026-02-19 01:20:55.463287 | controller | + functions-common:time_start:2399         :   local start_time=
2026-02-19 01:20:55.466356 | controller | + functions-common:time_start:2400         :   [[ -n '' ]]
2026-02-19 01:20:55.470430 | controller | ++ functions-common:time_start:2403         :   date +%s%3N
2026-02-19 01:20:55.474673 | controller | + functions-common:time_start:2403         :   _TIME_START[$name]=1771464055471
2026-02-19 01:20:55.477714 | controller | + functions-common:apt_get_update:1171     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-02-19 01:20:55.480613 | controller | + functions-common:apt_get_update:1172     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-02-19 01:20:55.483605 | controller | + functions-common:apt_get_update:1173     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-02-19 01:20:55.714351 | controller | Get:1 http://security.ubuntu.com/ubuntu noble-security InRelease [126 kB]
2026-02-19 01:20:55.714390 | controller | Get:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease [256 kB]
2026-02-19 01:20:56.149360 | controller | Get:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB]
2026-02-19 01:20:56.203220 | controller | Get:4 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [2,510 kB]
2026-02-19 01:20:56.255011 | controller | Get:5 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB]
2026-02-19 01:20:56.364291 | controller | Get:6 http://nova.clouds.archive.ubuntu.com/ubuntu noble/multiverse amd64 Packages [269 kB]
2026-02-19 01:20:56.485403 | controller | Get:7 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 Packages [15.0 MB]
2026-02-19 01:20:56.648045 | controller | Get:8 http://security.ubuntu.com/ubuntu noble-security/main amd64 Packages [1,472 kB]
2026-02-19 01:20:56.680493 | controller | Get:9 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Packages [935 kB]
2026-02-19 01:20:56.700452 | controller | Get:10 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Packages [28.8 kB]
2026-02-19 01:20:57.064939 | controller | Get:11 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 Packages [1,401 kB]
2026-02-19 01:20:57.115369 | controller | Get:12 http://nova.clouds.archive.ubuntu.com/ubuntu noble/restricted amd64 Packages [93.9 kB]
2026-02-19 01:20:57.117071 | controller | Get:13 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [2,663 kB]
2026-02-19 01:20:57.167275 | controller | Get:14 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1,556 kB]
2026-02-19 01:20:57.222390 | controller | Get:15 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Packages [32.1 kB]
2026-02-19 01:20:57.222458 | controller | Get:16 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [1,771 kB]
2026-02-19 01:20:57.280994 | controller | Get:17 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Packages [29.5 kB]
2026-02-19 01:20:57.281306 | controller | Get:18 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Packages [40.4 kB]
2026-02-19 01:20:59.828377 | controller | Fetched 28.5 MB in 4s (6,792 kB/s)
2026-02-19 01:21:00.953191 | controller | Reading package lists...
2026-02-19 01:21:01.004511 | controller | + functions-common:apt_get_update:1177     :   REPOS_UPDATED=True
2026-02-19 01:21:01.008359 | controller | + functions-common:apt_get_update:1179     :   time_stop apt-get-update
2026-02-19 01:21:01.012448 | controller | + functions-common:time_stop:2412          :   local name
2026-02-19 01:21:01.015436 | controller | + functions-common:time_stop:2413          :   local end_time
2026-02-19 01:21:01.018608 | controller | + functions-common:time_stop:2414          :   local elapsed_time
2026-02-19 01:21:01.021483 | controller | + functions-common:time_stop:2415          :   local total
2026-02-19 01:21:01.024233 | controller | + functions-common:time_stop:2416          :   local start_time
2026-02-19 01:21:01.026995 | controller | + functions-common:time_stop:2418          :   name=apt-get-update
2026-02-19 01:21:01.029747 | controller | + functions-common:time_stop:2419          :   start_time=1771464055471
2026-02-19 01:21:01.032323 | controller | + functions-common:time_stop:2421          :   [[ -z 1771464055471 ]]
2026-02-19 01:21:01.036501 | controller | ++ functions-common:time_stop:2424          :   date +%s%3N
2026-02-19 01:21:01.040826 | controller | + functions-common:time_stop:2424          :   end_time=1771464061037
2026-02-19 01:21:01.043793 | controller | + functions-common:time_stop:2425          :   elapsed_time=5566
2026-02-19 01:21:01.047064 | controller | + functions-common:time_stop:2426          :   total=0
2026-02-19 01:21:01.050069 | controller | + functions-common:time_stop:2428          :   _TIME_START[$name]=
2026-02-19 01:21:01.052784 | controller | + functions-common:time_stop:2429          :   _TIME_TOTAL[$name]=5566
2026-02-19 01:21:01.055805 | controller | + functions-common:install_package:1431    :   real_install_package ksmtuned
2026-02-19 01:21:01.058777 | controller | + functions-common:real_install_package:1418 :   is_ubuntu
2026-02-19 01:21:01.061807 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-02-19 01:21:01.064896 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-02-19 01:21:01.067944 | controller | + functions-common:real_install_package:1419 :   apt_get install ksmtuned
2026-02-19 01:21:01.092372 | controller | + functions-common:apt_get:1199            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-02-19 01:21:01.127766 | controller | Reading package lists...
2026-02-19 01:21:01.575778 | controller | Building dependency tree...
2026-02-19 01:21:01.575912 | controller | Reading state information...
2026-02-19 01:21:02.068946 | controller | Recommended packages:
2026-02-19 01:21:02.068997 | controller |   qemu-kvm
2026-02-19 01:21:02.108307 | controller | The following NEW packages will be installed:
2026-02-19 01:21:02.110213 | controller |   ksmtuned
2026-02-19 01:21:02.433246 | controller | 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
2026-02-19 01:21:02.433296 | controller | Need to get 7,444 B of archives.
2026-02-19 01:21:02.433309 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-02-19 01:21:02.433321 | controller | Get:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326 [7,444 B]
2026-02-19 01:21:02.634936 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-02-19 01:21:02.692958 | controller | Fetched 7,444 B in 0s (25.0 kB/s)
2026-02-19 01:21:02.741328 | controller | Selecting previously unselected package ksmtuned.
2026-02-19 01:21:03.011162 | 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 ... 30022 files and directories currently installed.)
2026-02-19 01:21:03.014099 | controller | Preparing to unpack .../ksmtuned_4.20150326_amd64.deb ...
2026-02-19 01:21:03.048018 | controller | Unpacking ksmtuned (4.20150326) ...
2026-02-19 01:21:03.103892 | controller | Setting up ksmtuned (4.20150326) ...
2026-02-19 01:21:03.278179 | controller | Created symlink /etc/systemd/system/multi-user.target.wants/ksm.service → /usr/lib/systemd/system/ksm.service.
2026-02-19 01:21:03.415397 | controller | Created symlink /etc/systemd/system/multi-user.target.wants/ksmtuned.service → /usr/lib/systemd/system/ksmtuned.service.
2026-02-19 01:21:04.015041 | controller | + functions-common:apt_get:1203            :   result=0
2026-02-19 01:21:04.016387 | controller | + functions-common:apt_get:1206            :   time_stop apt-get
2026-02-19 01:21:04.018093 | controller | + functions-common:time_stop:2412          :   local name
2026-02-19 01:21:04.019619 | controller | + functions-common:time_stop:2413          :   local end_time
2026-02-19 01:21:04.021170 | controller | + functions-common:time_stop:2414          :   local elapsed_time
2026-02-19 01:21:04.022707 | controller | + functions-common:time_stop:2415          :   local total
2026-02-19 01:21:04.024264 | controller | + functions-common:time_stop:2416          :   local start_time
2026-02-19 01:21:04.026254 | controller | + functions-common:time_stop:2418          :   name=apt-get
2026-02-19 01:21:04.028409 | controller | + functions-common:time_stop:2419          :   start_time=1771464061088
2026-02-19 01:21:04.030621 | controller | + functions-common:time_stop:2421          :   [[ -z 1771464061088 ]]
2026-02-19 01:21:04.033258 | controller | ++ functions-common:time_stop:2424          :   date +%s%3N
2026-02-19 01:21:04.037579 | controller | + functions-common:time_stop:2424          :   end_time=1771464064034
2026-02-19 01:21:04.039808 | controller | + functions-common:time_stop:2425          :   elapsed_time=2946
2026-02-19 01:21:04.042181 | controller | + functions-common:time_stop:2426          :   total=123
2026-02-19 01:21:04.044844 | controller | + functions-common:time_stop:2428          :   _TIME_START[$name]=
2026-02-19 01:21:04.046987 | controller | + functions-common:time_stop:2429          :   _TIME_TOTAL[$name]=3069
2026-02-19 01:21:04.049162 | controller | + functions-common:apt_get:1207            :   return 0
2026-02-19 01:21:04.051723 | controller | + lib/host:configure_ksm:18                :   [[ -f /sys/kernel/mm/ksm/run ]]
2026-02-19 01:21:04.055279 | controller | ++ lib/host:configure_ksm:19                :   bool_to_int ENABLE_KSM
2026-02-19 01:21:04.057729 | controller | + lib/host:configure_ksm:19                :   sudo tee /sys/kernel/mm/ksm/run
2026-02-19 01:21:04.068486 | controller | + lib/host:configure_ksm:19                :   echo 0
2026-02-19 01:21:04.068563 | controller | 0
2026-02-19 01:21:04.071443 | controller | + lib/host:configure_host_mem:72           :   configure_sysctl_mem_parmaters
2026-02-19 01:21:04.073240 | controller | + lib/host:configure_sysctl_mem_parmaters:55 :   [[ True == \T\r\u\e ]]
2026-02-19 01:21:04.075392 | controller | + lib/host:configure_sysctl_mem_parmaters:57 :   sudo sysctl -w vm.dirty_ratio=60
2026-02-19 01:21:04.085769 | controller | vm.dirty_ratio = 60
2026-02-19 01:21:04.089509 | controller | + lib/host:configure_sysctl_mem_parmaters:58 :   sudo sysctl -w vm.dirty_background_ratio=10
2026-02-19 01:21:04.098231 | controller | vm.dirty_background_ratio = 10
2026-02-19 01:21:04.102931 | controller | + lib/host:configure_sysctl_mem_parmaters:59 :   sudo sysctl -w vm.vfs_cache_pressure=50
2026-02-19 01:21:04.112402 | controller | vm.vfs_cache_pressure = 50
2026-02-19 01:21:04.117157 | controller | + lib/host:configure_sysctl_mem_parmaters:64 :   sudo sysctl -w vm.swappiness=100
2026-02-19 01:21:04.126057 | controller | vm.swappiness = 100
2026-02-19 01:21:04.129622 | controller | + lib/host:configure_sysctl_mem_parmaters:65 :   sudo grep -R . /proc/sys/vm/
2026-02-19 01:21:04.138742 | controller | /proc/sys/vm/admin_reserve_kbytes:8192
2026-02-19 01:21:04.139902 | controller | grep: /proc/sys/vm/compact_memory: Permission denied
2026-02-19 01:21:04.139917 | controller | /proc/sys/vm/compact_unevictable_allowed:1
2026-02-19 01:21:04.139923 | controller | /proc/sys/vm/compaction_proactiveness:20
2026-02-19 01:21:04.139928 | controller | /proc/sys/vm/dirty_background_bytes:0
2026-02-19 01:21:04.139950 | controller | /proc/sys/vm/dirty_background_ratio:10
2026-02-19 01:21:04.139955 | controller | /proc/sys/vm/dirty_bytes:0
2026-02-19 01:21:04.139960 | controller | /proc/sys/vm/dirty_expire_centisecs:3000
2026-02-19 01:21:04.139966 | controller | /proc/sys/vm/dirty_ratio:60
2026-02-19 01:21:04.139971 | controller | /proc/sys/vm/dirty_writeback_centisecs:500
2026-02-19 01:21:04.139976 | controller | /proc/sys/vm/dirtytime_expire_seconds:43200
2026-02-19 01:21:04.139981 | controller | grep: /proc/sys/vm/drop_caches: Permission denied
2026-02-19 01:21:04.140678 | controller | /proc/sys/vm/extfrag_threshold:500
2026-02-19 01:21:04.140759 | controller | /proc/sys/vm/hugetlb_optimize_vmemmap:0
2026-02-19 01:21:04.140763 | controller | /proc/sys/vm/hugetlb_shm_group:0
2026-02-19 01:21:04.140767 | controller | /proc/sys/vm/laptop_mode:0
2026-02-19 01:21:04.140771 | controller | /proc/sys/vm/legacy_va_layout:0
2026-02-19 01:21:04.140774 | controller | /proc/sys/vm/lowmem_reserve_ratio:256	256	32	0	0
2026-02-19 01:21:04.140778 | controller | /proc/sys/vm/max_map_count:1048576
2026-02-19 01:21:04.140782 | controller | /proc/sys/vm/memfd_noexec:0
2026-02-19 01:21:04.140785 | controller | /proc/sys/vm/memory_failure_early_kill:0
2026-02-19 01:21:04.140789 | controller | /proc/sys/vm/memory_failure_recovery:1
2026-02-19 01:21:04.140793 | controller | /proc/sys/vm/min_free_kbytes:67584
2026-02-19 01:21:04.140796 | controller | /proc/sys/vm/min_slab_ratio:5
2026-02-19 01:21:04.140800 | controller | /proc/sys/vm/min_unmapped_ratio:1
2026-02-19 01:21:04.140803 | controller | /proc/sys/vm/mmap_min_addr:65536
2026-02-19 01:21:04.140807 | controller | /proc/sys/vm/mmap_rnd_bits:32
2026-02-19 01:21:04.140811 | controller | /proc/sys/vm/mmap_rnd_compat_bits:16
2026-02-19 01:21:04.140814 | controller | /proc/sys/vm/nr_hugepages:0
2026-02-19 01:21:04.140818 | controller | /proc/sys/vm/nr_hugepages_mempolicy:0
2026-02-19 01:21:04.140822 | controller | /proc/sys/vm/nr_overcommit_hugepages:0
2026-02-19 01:21:04.140825 | controller | /proc/sys/vm/numa_stat:1
2026-02-19 01:21:04.140829 | controller | /proc/sys/vm/numa_zonelist_order:Node
2026-02-19 01:21:04.140832 | controller | /proc/sys/vm/oom_dump_tasks:1
2026-02-19 01:21:04.140836 | controller | /proc/sys/vm/oom_kill_allocating_task:0
2026-02-19 01:21:04.140840 | controller | /proc/sys/vm/overcommit_kbytes:0
2026-02-19 01:21:04.140843 | controller | /proc/sys/vm/overcommit_memory:0
2026-02-19 01:21:04.140851 | controller | /proc/sys/vm/overcommit_ratio:50
2026-02-19 01:21:04.140859 | controller | /proc/sys/vm/page-cluster:3
2026-02-19 01:21:04.140863 | controller | /proc/sys/vm/page_lock_unfairness:5
2026-02-19 01:21:04.140867 | controller | /proc/sys/vm/panic_on_oom:0
2026-02-19 01:21:04.140870 | controller | /proc/sys/vm/percpu_pagelist_high_fraction:0
2026-02-19 01:21:04.140874 | controller | /proc/sys/vm/stat_interval:1
2026-02-19 01:21:04.140877 | controller | /proc/sys/vm/swappiness:100
2026-02-19 01:21:04.140881 | controller | /proc/sys/vm/unprivileged_userfaultfd:0
2026-02-19 01:21:04.140885 | controller | /proc/sys/vm/user_reserve_kbytes:131072
2026-02-19 01:21:04.140888 | controller | /proc/sys/vm/vfs_cache_pressure:50
2026-02-19 01:21:04.140892 | controller | /proc/sys/vm/watermark_boost_factor:15000
2026-02-19 01:21:04.140896 | controller | /proc/sys/vm/watermark_scale_factor:10
2026-02-19 01:21:04.140899 | controller | /proc/sys/vm/zone_reclaim_mode:0
2026-02-19 01:21:04.144169 | controller | + lib/host:configure_sysctl_mem_parmaters:65 :   /bin/true
2026-02-19 01:21:04.148190 | controller | + lib/host:tune_host:97                    :   configure_host_net
2026-02-19 01:21:04.151241 | controller | + lib/host:configure_host_net:92           :   configure_sysctl_net_parmaters
2026-02-19 01:21:04.154050 | controller | + lib/host:configure_sysctl_net_parmaters:77 :   [[ True == \T\r\u\e ]]
2026-02-19 01:21:04.156322 | controller | + lib/host:configure_sysctl_net_parmaters:79 :   sudo sysctl -w net.ipv4.tcp_keepalive_time=60
2026-02-19 01:21:04.165424 | controller | net.ipv4.tcp_keepalive_time = 60
2026-02-19 01:21:04.168893 | controller | + lib/host:configure_sysctl_net_parmaters:80 :   sudo sysctl -w net.ipv4.tcp_keepalive_intvl=10
2026-02-19 01:21:04.176082 | controller | net.ipv4.tcp_keepalive_intvl = 10
2026-02-19 01:21:04.179504 | controller | + lib/host:configure_sysctl_net_parmaters:81 :   sudo sysctl -w net.ipv4.tcp_keepalive_probes=6
2026-02-19 01:21:04.187761 | controller | net.ipv4.tcp_keepalive_probes = 6
2026-02-19 01:21:04.191834 | controller | + lib/host:configure_sysctl_net_parmaters:83 :   sudo sysctl -w net.ipv4.tcp_fastopen=3
2026-02-19 01:21:04.197797 | controller | net.ipv4.tcp_fastopen = 3
2026-02-19 01:21:04.202772 | controller | + lib/host:configure_sysctl_net_parmaters:85 :   sudo grep -R . /proc/sys/net/ipv4/tcp_abort_on_overflow /proc/sys/net/ipv4/tcp_adv_win_scale /proc/sys/net/ipv4/tcp_allowed_congestion_control /proc/sys/net/ipv4/tcp_app_win /proc/sys/net/ipv4/tcp_autocorking /proc/sys/net/ipv4/tcp_available_congestion_control /proc/sys/net/ipv4/tcp_available_ulp /proc/sys/net/ipv4/tcp_backlog_ack_defer /proc/sys/net/ipv4/tcp_base_mss /proc/sys/net/ipv4/tcp_challenge_ack_limit /proc/sys/net/ipv4/tcp_child_ehash_entries /proc/sys/net/ipv4/tcp_comp_sack_delay_ns /proc/sys/net/ipv4/tcp_comp_sack_nr /proc/sys/net/ipv4/tcp_comp_sack_slack_ns /proc/sys/net/ipv4/tcp_congestion_control /proc/sys/net/ipv4/tcp_dsack /proc/sys/net/ipv4/tcp_early_demux /proc/sys/net/ipv4/tcp_early_retrans /proc/sys/net/ipv4/tcp_ecn /proc/sys/net/ipv4/tcp_ecn_fallback /proc/sys/net/ipv4/tcp_ehash_entries /proc/sys/net/ipv4/tcp_fack /proc/sys/net/ipv4/tcp_fastopen /proc/sys/net/ipv4/tcp_fastopen_blackhole_timeout_sec /proc/sys/net/ipv4/tcp_fastopen_key /proc/sys/net/ipv4/tcp_fin_timeout /proc/sys/net/ipv4/tcp_frto /proc/sys/net/ipv4/tcp_fwmark_accept /proc/sys/net/ipv4/tcp_invalid_ratelimit /proc/sys/net/ipv4/tcp_keepalive_intvl /proc/sys/net/ipv4/tcp_keepalive_probes /proc/sys/net/ipv4/tcp_keepalive_time /proc/sys/net/ipv4/tcp_l3mdev_accept /proc/sys/net/ipv4/tcp_limit_output_bytes /proc/sys/net/ipv4/tcp_low_latency /proc/sys/net/ipv4/tcp_max_orphans /proc/sys/net/ipv4/tcp_max_reordering /proc/sys/net/ipv4/tcp_max_syn_backlog /proc/sys/net/ipv4/tcp_max_tw_buckets /proc/sys/net/ipv4/tcp_mem /proc/sys/net/ipv4/tcp_migrate_req /proc/sys/net/ipv4/tcp_min_rtt_wlen /proc/sys/net/ipv4/tcp_min_snd_mss /proc/sys/net/ipv4/tcp_min_tso_segs /proc/sys/net/ipv4/tcp_moderate_rcvbuf /proc/sys/net/ipv4/tcp_mtu_probe_floor /proc/sys/net/ipv4/tcp_mtu_probing /proc/sys/net/ipv4/tcp_no_metrics_save /proc/sys/net/ipv4/tcp_no_ssthresh_metrics_save /proc/sys/net/ipv4/tcp_notsent_lowat /proc/sys/net/ipv4/tcp_orphan_retries /proc/sys/net/ipv4/tcp_pacing_ca_ratio /proc/sys/net/ipv4/tcp_pacing_ss_ratio /proc/sys/net/ipv4/tcp_pingpong_thresh /proc/sys/net/ipv4/tcp_plb_cong_thresh /proc/sys/net/ipv4/tcp_plb_enabled /proc/sys/net/ipv4/tcp_plb_idle_rehash_rounds /proc/sys/net/ipv4/tcp_plb_rehash_rounds /proc/sys/net/ipv4/tcp_plb_suspend_rto_sec /proc/sys/net/ipv4/tcp_probe_interval /proc/sys/net/ipv4/tcp_probe_threshold /proc/sys/net/ipv4/tcp_recovery /proc/sys/net/ipv4/tcp_reflect_tos /proc/sys/net/ipv4/tcp_reordering /proc/sys/net/ipv4/tcp_retrans_collapse /proc/sys/net/ipv4/tcp_retries1 /proc/sys/net/ipv4/tcp_retries2 /proc/sys/net/ipv4/tcp_rfc1337 /proc/sys/net/ipv4/tcp_rmem /proc/sys/net/ipv4/tcp_sack /proc/sys/net/ipv4/tcp_shrink_window /proc/sys/net/ipv4/tcp_slow_start_after_idle /proc/sys/net/ipv4/tcp_stdurg /proc/sys/net/ipv4/tcp_synack_retries /proc/sys/net/ipv4/tcp_syncookies /proc/sys/net/ipv4/tcp_syn_linear_timeouts /proc/sys/net/ipv4/tcp_syn_retries /proc/sys/net/ipv4/tcp_thin_linear_timeouts /proc/sys/net/ipv4/tcp_timestamps /proc/sys/net/ipv4/tcp_tso_rtt_log /proc/sys/net/ipv4/tcp_tso_win_divisor /proc/sys/net/ipv4/tcp_tw_reuse /proc/sys/net/ipv4/tcp_window_scaling /proc/sys/net/ipv4/tcp_wmem /proc/sys/net/ipv4/tcp_workaround_signed_windows
2026-02-19 01:21:04.213521 | controller | /proc/sys/net/ipv4/tcp_abort_on_overflow:0
2026-02-19 01:21:04.213548 | controller | /proc/sys/net/ipv4/tcp_adv_win_scale:1
2026-02-19 01:21:04.213556 | controller | /proc/sys/net/ipv4/tcp_allowed_congestion_control:reno cubic
2026-02-19 01:21:04.213563 | controller | /proc/sys/net/ipv4/tcp_app_win:31
2026-02-19 01:21:04.213569 | controller | /proc/sys/net/ipv4/tcp_autocorking:1
2026-02-19 01:21:04.213580 | controller | /proc/sys/net/ipv4/tcp_available_congestion_control:reno cubic
2026-02-19 01:21:04.213587 | controller | /proc/sys/net/ipv4/tcp_available_ulp:espintcp mptcp
2026-02-19 01:21:04.213593 | controller | /proc/sys/net/ipv4/tcp_backlog_ack_defer:1
2026-02-19 01:21:04.213602 | controller | /proc/sys/net/ipv4/tcp_base_mss:1024
2026-02-19 01:21:04.213611 | controller | /proc/sys/net/ipv4/tcp_challenge_ack_limit:2147483647
2026-02-19 01:21:04.214437 | controller | /proc/sys/net/ipv4/tcp_child_ehash_entries:0
2026-02-19 01:21:04.214451 | controller | /proc/sys/net/ipv4/tcp_comp_sack_delay_ns:1000000
2026-02-19 01:21:04.214455 | controller | /proc/sys/net/ipv4/tcp_comp_sack_nr:44
2026-02-19 01:21:04.214459 | controller | /proc/sys/net/ipv4/tcp_comp_sack_slack_ns:100000
2026-02-19 01:21:04.214462 | controller | /proc/sys/net/ipv4/tcp_congestion_control:cubic
2026-02-19 01:21:04.214466 | controller | /proc/sys/net/ipv4/tcp_dsack:1
2026-02-19 01:21:04.214470 | controller | /proc/sys/net/ipv4/tcp_early_demux:1
2026-02-19 01:21:04.214473 | controller | /proc/sys/net/ipv4/tcp_early_retrans:3
2026-02-19 01:21:04.214477 | controller | /proc/sys/net/ipv4/tcp_ecn:2
2026-02-19 01:21:04.214481 | controller | /proc/sys/net/ipv4/tcp_ecn_fallback:1
2026-02-19 01:21:04.214484 | controller | /proc/sys/net/ipv4/tcp_ehash_entries:65536
2026-02-19 01:21:04.214488 | controller | /proc/sys/net/ipv4/tcp_fack:0
2026-02-19 01:21:04.214491 | controller | /proc/sys/net/ipv4/tcp_fastopen:3
2026-02-19 01:21:04.214495 | controller | /proc/sys/net/ipv4/tcp_fastopen_blackhole_timeout_sec:0
2026-02-19 01:21:04.214499 | controller | /proc/sys/net/ipv4/tcp_fastopen_key:8545b2e8-7a97557a-e3ddb9f6-e03b0b58
2026-02-19 01:21:04.214503 | controller | /proc/sys/net/ipv4/tcp_fin_timeout:60
2026-02-19 01:21:04.214506 | controller | /proc/sys/net/ipv4/tcp_frto:2
2026-02-19 01:21:04.214510 | controller | /proc/sys/net/ipv4/tcp_fwmark_accept:0
2026-02-19 01:21:04.214513 | controller | /proc/sys/net/ipv4/tcp_invalid_ratelimit:500
2026-02-19 01:21:04.214517 | controller | /proc/sys/net/ipv4/tcp_keepalive_intvl:10
2026-02-19 01:21:04.214520 | controller | /proc/sys/net/ipv4/tcp_keepalive_probes:6
2026-02-19 01:21:04.214524 | controller | /proc/sys/net/ipv4/tcp_keepalive_time:60
2026-02-19 01:21:04.214527 | controller | /proc/sys/net/ipv4/tcp_l3mdev_accept:0
2026-02-19 01:21:04.214531 | controller | /proc/sys/net/ipv4/tcp_limit_output_bytes:1048576
2026-02-19 01:21:04.214535 | controller | /proc/sys/net/ipv4/tcp_low_latency:0
2026-02-19 01:21:04.214538 | controller | /proc/sys/net/ipv4/tcp_max_orphans:32768
2026-02-19 01:21:04.214542 | controller | /proc/sys/net/ipv4/tcp_max_reordering:300
2026-02-19 01:21:04.214545 | controller | /proc/sys/net/ipv4/tcp_max_syn_backlog:512
2026-02-19 01:21:04.214549 | controller | /proc/sys/net/ipv4/tcp_max_tw_buckets:32768
2026-02-19 01:21:04.214552 | controller | /proc/sys/net/ipv4/tcp_mem:93273	124365	186546
2026-02-19 01:21:04.214556 | controller | /proc/sys/net/ipv4/tcp_migrate_req:0
2026-02-19 01:21:04.214560 | controller | /proc/sys/net/ipv4/tcp_min_rtt_wlen:300
2026-02-19 01:21:04.214569 | controller | /proc/sys/net/ipv4/tcp_min_snd_mss:48
2026-02-19 01:21:04.214572 | controller | /proc/sys/net/ipv4/tcp_min_tso_segs:2
2026-02-19 01:21:04.214576 | controller | /proc/sys/net/ipv4/tcp_moderate_rcvbuf:1
2026-02-19 01:21:04.214580 | controller | /proc/sys/net/ipv4/tcp_mtu_probe_floor:48
2026-02-19 01:21:04.214583 | controller | /proc/sys/net/ipv4/tcp_mtu_probing:0
2026-02-19 01:21:04.214587 | controller | /proc/sys/net/ipv4/tcp_no_metrics_save:0
2026-02-19 01:21:04.214590 | controller | /proc/sys/net/ipv4/tcp_no_ssthresh_metrics_save:1
2026-02-19 01:21:04.214596 | controller | /proc/sys/net/ipv4/tcp_notsent_lowat:4294967295
2026-02-19 01:21:04.214600 | controller | /proc/sys/net/ipv4/tcp_orphan_retries:0
2026-02-19 01:21:04.214604 | controller | /proc/sys/net/ipv4/tcp_pacing_ca_ratio:120
2026-02-19 01:21:04.214607 | controller | /proc/sys/net/ipv4/tcp_pacing_ss_ratio:200
2026-02-19 01:21:04.214611 | controller | /proc/sys/net/ipv4/tcp_pingpong_thresh:1
2026-02-19 01:21:04.214614 | controller | /proc/sys/net/ipv4/tcp_plb_cong_thresh:128
2026-02-19 01:21:04.214618 | controller | /proc/sys/net/ipv4/tcp_plb_enabled:0
2026-02-19 01:21:04.214622 | controller | /proc/sys/net/ipv4/tcp_plb_idle_rehash_rounds:3
2026-02-19 01:21:04.214625 | controller | /proc/sys/net/ipv4/tcp_plb_rehash_rounds:12
2026-02-19 01:21:04.214630 | controller | /proc/sys/net/ipv4/tcp_plb_suspend_rto_sec:60
2026-02-19 01:21:04.214634 | controller | /proc/sys/net/ipv4/tcp_probe_interval:600
2026-02-19 01:21:04.214659 | controller | /proc/sys/net/ipv4/tcp_probe_threshold:8
2026-02-19 01:21:04.214782 | controller | /proc/sys/net/ipv4/tcp_recovery:1
2026-02-19 01:21:04.214791 | controller | /proc/sys/net/ipv4/tcp_reflect_tos:0
2026-02-19 01:21:04.214795 | controller | /proc/sys/net/ipv4/tcp_reordering:3
2026-02-19 01:21:04.214799 | controller | /proc/sys/net/ipv4/tcp_retrans_collapse:1
2026-02-19 01:21:04.214804 | controller | /proc/sys/net/ipv4/tcp_retries1:3
2026-02-19 01:21:04.214809 | controller | /proc/sys/net/ipv4/tcp_retries2:15
2026-02-19 01:21:04.215064 | controller | /proc/sys/net/ipv4/tcp_rfc1337:0
2026-02-19 01:21:04.215073 | controller | /proc/sys/net/ipv4/tcp_rmem:4096	131072	6291456
2026-02-19 01:21:04.215077 | controller | /proc/sys/net/ipv4/tcp_sack:1
2026-02-19 01:21:04.215081 | controller | /proc/sys/net/ipv4/tcp_shrink_window:0
2026-02-19 01:21:04.215084 | controller | /proc/sys/net/ipv4/tcp_slow_start_after_idle:1
2026-02-19 01:21:04.215088 | controller | /proc/sys/net/ipv4/tcp_stdurg:0
2026-02-19 01:21:04.215091 | controller | /proc/sys/net/ipv4/tcp_synack_retries:5
2026-02-19 01:21:04.215095 | controller | /proc/sys/net/ipv4/tcp_syncookies:1
2026-02-19 01:21:04.215099 | controller | /proc/sys/net/ipv4/tcp_syn_linear_timeouts:4
2026-02-19 01:21:04.215102 | controller | /proc/sys/net/ipv4/tcp_syn_retries:6
2026-02-19 01:21:04.215106 | controller | /proc/sys/net/ipv4/tcp_thin_linear_timeouts:0
2026-02-19 01:21:04.215109 | controller | /proc/sys/net/ipv4/tcp_timestamps:1
2026-02-19 01:21:04.215113 | controller | /proc/sys/net/ipv4/tcp_tso_rtt_log:9
2026-02-19 01:21:04.215118 | controller | /proc/sys/net/ipv4/tcp_tso_win_divisor:3
2026-02-19 01:21:04.215122 | controller | /proc/sys/net/ipv4/tcp_tw_reuse:2
2026-02-19 01:21:04.215126 | controller | /proc/sys/net/ipv4/tcp_window_scaling:1
2026-02-19 01:21:04.215131 | controller | /proc/sys/net/ipv4/tcp_wmem:4096	16384	4194304
2026-02-19 01:21:04.215161 | controller | /proc/sys/net/ipv4/tcp_workaround_signed_windows:0
2026-02-19 01:21:04.217203 | controller | + lib/host:configure_sysctl_net_parmaters:87 :   sudo sysctl -w net.core.default_qdisc=pfifo_fast
2026-02-19 01:21:04.226052 | controller | net.core.default_qdisc = pfifo_fast
2026-02-19 01:21:04.228983 | controller | + ./stack.sh:main:594                      :   fetch_plugins
2026-02-19 01:21:04.230924 | controller | + functions-common:fetch_plugins:1758      :   local plugins=,devstack-plugin-ceph,manila
2026-02-19 01:21:04.232601 | controller | + functions-common:fetch_plugins:1759      :   local plugin
2026-02-19 01:21:04.234650 | controller | + functions-common:fetch_plugins:1762      :   [[ -z ,devstack-plugin-ceph,manila ]]
2026-02-19 01:21:04.236707 | controller | + functions-common:fetch_plugins:1766      :   echo 'Fetching DevStack plugins'
2026-02-19 01:21:04.236722 | controller | Fetching DevStack plugins
2026-02-19 01:21:04.238685 | controller | + functions-common:fetch_plugins:1767      :   for plugin in ${plugins//,/ }
2026-02-19 01:21:04.240741 | controller | + functions-common:fetch_plugins:1768      :   git_clone_by_name devstack-plugin-ceph
2026-02-19 01:21:04.242721 | controller | + functions-common:git_clone_by_name:673   :   local name=devstack-plugin-ceph
2026-02-19 01:21:04.244713 | controller | + functions-common:git_clone_by_name:674   :   local repo=https://opendev.org/openstack/devstack-plugin-ceph
2026-02-19 01:21:04.246752 | controller | + functions-common:git_clone_by_name:675   :   local dir=/opt/stack/devstack-plugin-ceph
2026-02-19 01:21:04.248766 | controller | + functions-common:git_clone_by_name:676   :   local branch=master
2026-02-19 01:21:04.250776 | controller | + functions-common:git_clone_by_name:677   :   git_clone https://opendev.org/openstack/devstack-plugin-ceph /opt/stack/devstack-plugin-ceph master
2026-02-19 01:21:04.252768 | controller | + functions-common:git_clone:581           :   local git_remote=https://opendev.org/openstack/devstack-plugin-ceph
2026-02-19 01:21:04.254726 | controller | + functions-common:git_clone:582           :   local git_dest=/opt/stack/devstack-plugin-ceph
2026-02-19 01:21:04.256596 | controller | + functions-common:git_clone:583           :   local git_ref=master
2026-02-19 01:21:04.258518 | controller | + functions-common:git_clone:584           :   local orig_dir
2026-02-19 01:21:04.261231 | controller | ++ functions-common:git_clone:585           :   pwd
2026-02-19 01:21:04.263175 | controller | + functions-common:git_clone:585           :   orig_dir=/opt/stack/devstack
2026-02-19 01:21:04.265107 | controller | + functions-common:git_clone:586           :   local git_clone_flags=
2026-02-19 01:21:04.267845 | controller | ++ functions-common:git_clone:588           :   trueorfalse False RECLONE
2026-02-19 01:21:04.281103 | controller | + functions-common:git_clone:588           :   RECLONE=False
2026-02-19 01:21:04.283041 | controller | + functions-common:git_clone:589           :   [[ 0 -gt 0 ]]
2026-02-19 01:21:04.284959 | controller | + functions-common:git_clone:593           :   [[ False = \T\r\u\e ]]
2026-02-19 01:21:04.287665 | controller | + functions-common:git_clone:602           :   echo master
2026-02-19 01:21:04.287678 | controller | + functions-common:git_clone:602           :   egrep -q '^refs'
2026-02-19 01:21:04.292628 | controller | + functions-common:git_clone:616           :   [[ ! -d /opt/stack/devstack-plugin-ceph ]]
2026-02-19 01:21:04.294567 | controller | + functions-common:git_clone:626           :   [[ False = \T\r\u\e ]]
2026-02-19 01:21:04.296567 | controller | + functions-common:git_clone:661           :   sudo git config --system --add safe.directory /opt/stack/devstack-plugin-ceph
2026-02-19 01:21:04.312799 | controller | + functions-common:git_clone:664           :   cd /opt/stack/devstack-plugin-ceph
2026-02-19 01:21:04.314909 | controller | + functions-common:git_clone:665           :   head -1
2026-02-19 01:21:04.316068 | controller | + functions-common:git_clone:665           :   git show --oneline
2026-02-19 01:21:04.319429 | controller | ac34630 Enable manage_existing volume/snapshot tempest tests
2026-02-19 01:21:04.321557 | controller | + functions-common:git_clone:666           :   cd /opt/stack/devstack
2026-02-19 01:21:04.323715 | controller | + functions-common:fetch_plugins:1767      :   for plugin in ${plugins//,/ }
2026-02-19 01:21:04.325570 | controller | + functions-common:fetch_plugins:1768      :   git_clone_by_name manila
2026-02-19 01:21:04.327447 | controller | + functions-common:git_clone_by_name:673   :   local name=manila
2026-02-19 01:21:04.329288 | controller | + functions-common:git_clone_by_name:674   :   local repo=https://opendev.org/openstack/manila
2026-02-19 01:21:04.331099 | controller | + functions-common:git_clone_by_name:675   :   local dir=/opt/stack/manila
2026-02-19 01:21:04.332896 | controller | + functions-common:git_clone_by_name:676   :   local branch=master
2026-02-19 01:21:04.334739 | controller | + functions-common:git_clone_by_name:677   :   git_clone https://opendev.org/openstack/manila /opt/stack/manila master
2026-02-19 01:21:04.336505 | controller | + functions-common:git_clone:581           :   local git_remote=https://opendev.org/openstack/manila
2026-02-19 01:21:04.338336 | controller | + functions-common:git_clone:582           :   local git_dest=/opt/stack/manila
2026-02-19 01:21:04.340075 | controller | + functions-common:git_clone:583           :   local git_ref=master
2026-02-19 01:21:04.341845 | controller | + functions-common:git_clone:584           :   local orig_dir
2026-02-19 01:21:04.343384 | controller | ++ functions-common:git_clone:585           :   pwd
2026-02-19 01:21:04.345073 | controller | + functions-common:git_clone:585           :   orig_dir=/opt/stack/devstack
2026-02-19 01:21:04.346533 | controller | + functions-common:git_clone:586           :   local git_clone_flags=
2026-02-19 01:21:04.348357 | controller | ++ functions-common:git_clone:588           :   trueorfalse False RECLONE
2026-02-19 01:21:04.360030 | controller | + functions-common:git_clone:588           :   RECLONE=False
2026-02-19 01:21:04.361907 | controller | + functions-common:git_clone:589           :   [[ 0 -gt 0 ]]
2026-02-19 01:21:04.363779 | controller | + functions-common:git_clone:593           :   [[ False = \T\r\u\e ]]
2026-02-19 01:21:04.365937 | controller | + functions-common:git_clone:602           :   egrep -q '^refs'
2026-02-19 01:21:04.366078 | controller | + functions-common:git_clone:602           :   echo master
2026-02-19 01:21:04.369177 | controller | + functions-common:git_clone:616           :   [[ ! -d /opt/stack/manila ]]
2026-02-19 01:21:04.370894 | controller | + functions-common:git_clone:626           :   [[ False = \T\r\u\e ]]
2026-02-19 01:21:04.372727 | controller | + functions-common:git_clone:661           :   sudo git config --system --add safe.directory /opt/stack/manila
2026-02-19 01:21:04.383620 | controller | + functions-common:git_clone:664           :   cd /opt/stack/manila
2026-02-19 01:21:04.384911 | controller | + functions-common:git_clone:665           :   git show --oneline
2026-02-19 01:21:04.386282 | controller | + functions-common:git_clone:665           :   head -1
2026-02-19 01:21:04.397934 | controller | 0ac4e0973 feat: Add Dockerfile for container image build
2026-02-19 01:21:04.399841 | controller | + functions-common:git_clone:666           :   cd /opt/stack/devstack
2026-02-19 01:21:04.401860 | controller | + ./stack.sh:main:598                      :   run_phase override_defaults
2026-02-19 01:21:04.403908 | controller | + functions-common:run_phase:1840          :   local mode=override_defaults
2026-02-19 01:21:04.406226 | controller | + functions-common:run_phase:1841          :   local phase=
2026-02-19 01:21:04.407992 | controller | + functions-common:run_phase:1842          :   [[ -d /opt/stack/devstack/extras.d ]]
2026-02-19 01:21:04.409834 | controller | + functions-common:run_phase:1843          :   local extra_plugin_file_name
2026-02-19 01:21:04.411629 | controller | + functions-common:run_phase:1844          :   for extra_plugin_file_name in $TOP_DIR/extras.d/*.sh
2026-02-19 01:21:04.413407 | controller | + functions-common:run_phase:1849          :   local exceptions=80-tempest.sh
2026-02-19 01:21:04.415149 | controller | + functions-common:run_phase:1850          :   local extra
2026-02-19 01:21:04.417208 | controller | ++ functions-common:run_phase:1851          :   basename /opt/stack/devstack/extras.d/80-tempest.sh
2026-02-19 01:21:04.421078 | controller | + functions-common:run_phase:1851          :   extra=80-tempest.sh
2026-02-19 01:21:04.422984 | controller | + functions-common:run_phase:1852          :   [[ 80-tempest.sh =~ 80-tempest\.sh ]]
2026-02-19 01:21:04.424902 | controller | + functions-common:run_phase:1856          :   [[ -r /opt/stack/devstack/extras.d/80-tempest.sh ]]
2026-02-19 01:21:04.426878 | controller | + functions-common:run_phase:1856          :   source /opt/stack/devstack/extras.d/80-tempest.sh override_defaults
2026-02-19 01:21:04.428595 | controller | ++ extras.d/80-tempest.sh:source:3          :   is_service_enabled tempest
2026-02-19 01:21:04.439175 | controller | ++ functions-common:is_service_enabled:2068 :   return 0
2026-02-19 01:21:04.440615 | controller | ++ extras.d/80-tempest.sh:source:4          :   [[ override_defaults == \s\o\u\r\c\e ]]
2026-02-19 01:21:04.442051 | controller | ++ extras.d/80-tempest.sh:source:7          :   [[ override_defaults == \s\t\a\c\k ]]
2026-02-19 01:21:04.443644 | controller | ++ extras.d/80-tempest.sh:source:10         :   [[ override_defaults == \s\t\a\c\k ]]
2026-02-19 01:21:04.445014 | controller | ++ extras.d/80-tempest.sh:source:13         :   [[ override_defaults == \s\t\a\c\k ]]
2026-02-19 01:21:04.446626 | controller | ++ extras.d/80-tempest.sh:source:16         :   [[ override_defaults == \s\t\a\c\k ]]
2026-02-19 01:21:04.448372 | controller | ++ extras.d/80-tempest.sh:source:19         :   [[ override_defaults == \s\t\a\c\k ]]
2026-02-19 01:21:04.450079 | controller | ++ extras.d/80-tempest.sh:source:27         :   [[ override_defaults == \u\n\s\t\a\c\k ]]
2026-02-19 01:21:04.451833 | controller | ++ extras.d/80-tempest.sh:source:32         :   [[ override_defaults == \c\l\e\a\n ]]
2026-02-19 01:21:04.453605 | controller | + functions-common:run_phase:1861          :   [[ override_defaults == \s\o\u\r\c\e ]]
2026-02-19 01:21:04.455775 | controller | + functions-common:run_phase:1864          :   [[ override_defaults == \o\v\e\r\r\i\d\e\_\d\e\f\a\u\l\t\s ]]
2026-02-19 01:21:04.457572 | controller | + functions-common:run_phase:1865          :   plugin_override_defaults
2026-02-19 01:21:04.459370 | controller | + functions-common:plugin_override_defaults:1799 :   local plugins=,devstack-plugin-ceph,manila
2026-02-19 01:21:04.461111 | controller | + functions-common:plugin_override_defaults:1800 :   local plugin
2026-02-19 01:21:04.462885 | controller | + functions-common:plugin_override_defaults:1803 :   [[ -z ,devstack-plugin-ceph,manila ]]
2026-02-19 01:21:04.464633 | controller | + functions-common:plugin_override_defaults:1807 :   echo 'Overriding Configuration Defaults'
2026-02-19 01:21:04.464647 | controller | Overriding Configuration Defaults
2026-02-19 01:21:04.466389 | controller | + functions-common:plugin_override_defaults:1808 :   for plugin in ${plugins//,/ }
2026-02-19 01:21:04.468152 | controller | + functions-common:plugin_override_defaults:1809 :   local dir=/opt/stack/devstack-plugin-ceph
2026-02-19 01:21:04.469930 | controller | + functions-common:plugin_override_defaults:1811 :   [[ -f /opt/stack/devstack-plugin-ceph/devstack/override-defaults ]]
2026-02-19 01:21:04.471660 | controller | + functions-common:plugin_override_defaults:1814 :   echo 'devstack-plugin-ceph has overridden the following defaults'
2026-02-19 01:21:04.471673 | controller | devstack-plugin-ceph has overridden the following defaults
2026-02-19 01:21:04.473429 | controller | + functions-common:plugin_override_defaults:1815 :   cat /opt/stack/devstack-plugin-ceph/devstack/override-defaults
2026-02-19 01:21:04.474246 | controller | # Plug-in overrides
2026-02-19 01:21:04.474260 | controller |
2026-02-19 01:21:04.474266 | controller | ENABLE_CEPH_CINDER=$(trueorfalse True ENABLE_CEPH_CINDER)
2026-02-19 01:21:04.474273 | controller | ENABLE_CEPH_C_BAK=$(trueorfalse True ENABLE_CEPH_C_BAK)
2026-02-19 01:21:04.474278 | controller | ENABLE_CEPH_GLANCE=$(trueorfalse True ENABLE_CEPH_GLANCE)
2026-02-19 01:21:04.474284 | controller | ENABLE_CEPH_MANILA=$(trueorfalse True ENABLE_CEPH_MANILA)
2026-02-19 01:21:04.474289 | controller | ENABLE_CEPH_NOVA=$(trueorfalse True ENABLE_CEPH_NOVA)
2026-02-19 01:21:04.474295 | controller | # Do not enable RGW by default as RGW is not tested in upstream CI.
2026-02-19 01:21:04.474300 | controller | ENABLE_CEPH_RGW=$(trueorfalse False ENABLE_CEPH_RGW)
2026-02-19 01:21:04.474306 | controller | ENABLE_CEPH_RBD_MIRROR=$(trueorfalse False ENABLE_CEPH_RBD_MIRROR)
2026-02-19 01:21:04.474311 | controller |
2026-02-19 01:21:04.474317 | controller | CINDER_DRIVER=${CINDER_DRIVER:-ceph}
2026-02-19 01:21:04.474322 | controller | CINDER_ENABLED_BACKENDS=${CINDER_ENABLED_BACKENDS:-ceph}
2026-02-19 01:21:04.474328 | controller | REMOTE_CEPH=$(trueorfalse False REMOTE_CEPH)
2026-02-19 01:21:04.474334 | controller | if [[ $ENABLE_CEPH_MANILA == "True" ]]; then
2026-02-19 01:21:04.474339 | controller |     MANILA_CEPH_DRIVER=${MANILA_CEPH_DRIVER:-cephfsnative}
2026-02-19 01:21:04.474344 | controller | fi
2026-02-19 01:21:04.476512 | controller | + functions-common:plugin_override_defaults:1816 :   source /opt/stack/devstack-plugin-ceph/devstack/override-defaults
2026-02-19 01:21:04.478587 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:3 :   trueorfalse True ENABLE_CEPH_CINDER
2026-02-19 01:21:04.490914 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:3 :   ENABLE_CEPH_CINDER=True
2026-02-19 01:21:04.492978 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:4 :   trueorfalse True ENABLE_CEPH_C_BAK
2026-02-19 01:21:04.506619 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:4 :   ENABLE_CEPH_C_BAK=True
2026-02-19 01:21:04.510054 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:5 :   trueorfalse True ENABLE_CEPH_GLANCE
2026-02-19 01:21:04.522702 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:5 :   ENABLE_CEPH_GLANCE=True
2026-02-19 01:21:04.525476 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:6 :   trueorfalse True ENABLE_CEPH_MANILA
2026-02-19 01:21:04.538735 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:6 :   ENABLE_CEPH_MANILA=True
2026-02-19 01:21:04.541732 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:7 :   trueorfalse True ENABLE_CEPH_NOVA
2026-02-19 01:21:04.555775 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:7 :   ENABLE_CEPH_NOVA=False
2026-02-19 01:21:04.557874 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:9 :   trueorfalse False ENABLE_CEPH_RGW
2026-02-19 01:21:04.570916 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:9 :   ENABLE_CEPH_RGW=False
2026-02-19 01:21:04.573454 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:10 :   trueorfalse False ENABLE_CEPH_RBD_MIRROR
2026-02-19 01:21:04.590741 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:10 :   ENABLE_CEPH_RBD_MIRROR=False
2026-02-19 01:21:04.591951 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:12 :   CINDER_DRIVER=ceph
2026-02-19 01:21:04.593738 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:13 :   CINDER_ENABLED_BACKENDS=ceph
2026-02-19 01:21:04.596496 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:14 :   trueorfalse False REMOTE_CEPH
2026-02-19 01:21:04.610499 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:14 :   REMOTE_CEPH=False
2026-02-19 01:21:04.612573 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:15 :   [[ True == \T\r\u\e ]]
2026-02-19 01:21:04.614625 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/override-defaults:source:16 :   MANILA_CEPH_DRIVER=cephfsnative
2026-02-19 01:21:04.616666 | controller | + functions-common:plugin_override_defaults:1808 :   for plugin in ${plugins//,/ }
2026-02-19 01:21:04.618711 | controller | + functions-common:plugin_override_defaults:1809 :   local dir=/opt/stack/manila
2026-02-19 01:21:04.620937 | controller | + functions-common:plugin_override_defaults:1811 :   [[ -f /opt/stack/manila/devstack/override-defaults ]]
2026-02-19 01:21:04.623146 | controller | + ./stack.sh:main:601                      :   source /opt/stack/devstack/lib/apache
2026-02-19 01:21:04.640549 | controller | + ./stack.sh:main:604                      :   source /opt/stack/devstack/lib/tls
2026-02-19 01:21:04.642554 | controller | ++ lib/tls:source:38                        :   is_service_enabled tls-proxy
2026-02-19 01:21:04.655470 | controller | ++ functions-common:is_service_enabled:2068 :   return 0
2026-02-19 01:21:04.657713 | controller | +++ lib/tls:source:40                        :   ipv6_unquote 199.204.45.69
2026-02-19 01:21:04.660373 | controller | +++ functions-common:ipv6_unquote:2210       :   echo 199.204.45.69
2026-02-19 01:21:04.660392 | controller | +++ functions-common:ipv6_unquote:2210       :   tr -d '[]'
2026-02-19 01:21:04.664340 | controller | ++ lib/tls:source:40                        :   TLS_IP=199.204.45.69
2026-02-19 01:21:04.666727 | controller | +++ lib/tls:source:43                        :   hostname -f
2026-02-19 01:21:04.670980 | controller | ++ lib/tls:source:43                        :   DEVSTACK_HOSTNAME=np0000156437.novalocal
2026-02-19 01:21:04.673085 | controller | ++ lib/tls:source:44                        :   DEVSTACK_CERT_NAME=devstack-cert
2026-02-19 01:21:04.675264 | controller | ++ lib/tls:source:45                        :   DEVSTACK_CERT=/opt/stack/data/devstack-cert.pem
2026-02-19 01:21:04.677435 | controller | ++ lib/tls:source:48                        :   ROOT_CA_DIR=/opt/stack/data/CA/root-ca
2026-02-19 01:21:04.679596 | controller | ++ lib/tls:source:49                        :   INT_CA_DIR=/opt/stack/data/CA/int-ca
2026-02-19 01:21:04.681750 | controller | ++ lib/tls:source:51                        :   ORG_NAME=OpenStack
2026-02-19 01:21:04.684337 | controller | ++ lib/tls:source:52                        :   ORG_UNIT_NAME=DevStack
2026-02-19 01:21:04.686333 | controller | ++ lib/tls:source:55                        :   STUD_PROTO=--tls
2026-02-19 01:21:04.688368 | controller | ++ lib/tls:source:56                        :   STUD_CIPHERS='TLSv1+HIGH:!DES:!aNULL:!eNULL:@STRENGTH'
2026-02-19 01:21:04.691780 | controller | ++ lib/tls:source:63                        :   OPENSSL=/usr/bin/openssl
2026-02-19 01:21:04.694081 | controller | + ./stack.sh:main:607                      :   source /opt/stack/devstack/lib/infra
2026-02-19 01:21:04.706964 | controller | + ./stack.sh:main:608                      :   source /opt/stack/devstack/lib/libraries
2026-02-19 01:21:04.725736 | controller | + ./stack.sh:main:609                      :   source /opt/stack/devstack/lib/lvm
2026-02-19 01:21:04.738336 | controller | + ./stack.sh:main:610                      :   source /opt/stack/devstack/lib/horizon
2026-02-19 01:21:04.751802 | controller | + ./stack.sh:main:611                      :   source /opt/stack/devstack/lib/keystone
2026-02-19 01:21:04.786861 | controller | + ./stack.sh:main:612                      :   source /opt/stack/devstack/lib/glance
2026-02-19 01:21:04.861396 | controller | + ./stack.sh:main:613                      :   source /opt/stack/devstack/lib/nova
2026-02-19 01:21:04.957651 | controller | + ./stack.sh:main:614                      :   source /opt/stack/devstack/lib/placement
2026-02-19 01:21:04.985744 | controller | + ./stack.sh:main:615                      :   source /opt/stack/devstack/lib/cinder
2026-02-19 01:21:05.041783 | controller | + ./stack.sh:main:616                      :   source /opt/stack/devstack/lib/swift
2026-02-19 01:21:05.092301 | controller | + ./stack.sh:main:617                      :   source /opt/stack/devstack/lib/neutron
2026-02-19 01:21:05.094648 | controller | ++ lib/neutron:source:61                    :   is_service_enabled tls-proxy
2026-02-19 01:21:05.106679 | controller | ++ functions-common:is_service_enabled:2068 :   return 0
2026-02-19 01:21:05.108524 | controller | ++ lib/neutron:source:62                    :   Q_PROTOCOL=https
2026-02-19 01:21:05.110641 | controller | ++ lib/neutron:source:66                    :   GITDIR["python-neutronclient"]=/opt/stack/python-neutronclient
2026-02-19 01:21:05.112820 | controller | ++ lib/neutron:source:68                    :   NEUTRON_DIR=/opt/stack/neutron
2026-02-19 01:21:05.114981 | controller | ++ lib/neutron:source:69                    :   NEUTRON_FWAAS_DIR=/opt/stack/neutron-fwaas
2026-02-19 01:21:05.117067 | controller | ++ lib/neutron:source:72                    :   [[ -d /opt/stack/neutron/bin/neutron-server ]]
2026-02-19 01:21:05.119306 | controller | +++ lib/neutron:source:75                    :   get_python_exec_prefix
2026-02-19 01:21:05.131856 | controller | +++ inc/python:get_python_exec_prefix:80     :   [[ True == \T\r\u\e ]]
2026-02-19 01:21:05.133741 | controller | +++ inc/python:get_python_exec_prefix:81     :   echo /opt/stack/data/venv/bin
2026-02-19 01:21:05.136227 | controller | ++ lib/neutron:source:75                    :   NEUTRON_BIN_DIR=/opt/stack/data/venv/bin
2026-02-19 01:21:05.138354 | controller | ++ lib/neutron:source:78                    :   NEUTRON_CONF_DIR=/etc/neutron
2026-02-19 01:21:05.140592 | controller | ++ lib/neutron:source:79                    :   NEUTRON_CONF=/etc/neutron/neutron.conf
2026-02-19 01:21:05.143222 | controller | ++ lib/neutron:source:80                    :   export NEUTRON_TEST_CONFIG_FILE=/etc/neutron/debug.ini
2026-02-19 01:21:05.145409 | controller | ++ lib/neutron:source:80                    :   NEUTRON_TEST_CONFIG_FILE=/etc/neutron/debug.ini
2026-02-19 01:21:05.147609 | controller | ++ lib/neutron:source:82                    :   NEUTRON_UWSGI=neutron.wsgi.api:application
2026-02-19 01:21:05.149806 | controller | ++ lib/neutron:source:83                    :   NEUTRON_UWSGI_CONF=/etc/neutron/neutron-api-uwsgi.ini
2026-02-19 01:21:05.152229 | controller | +++ lib/neutron:source:89                    :   trueorfalse True NEUTRON_ENFORCE_SCOPE
2026-02-19 01:21:05.166202 | controller | ++ lib/neutron:source:89                    :   NEUTRON_ENFORCE_SCOPE=True
2026-02-19 01:21:05.168403 | controller | ++ lib/neutron:source:93                    :   AGENT_DHCP_BINARY=/opt/stack/data/venv/bin/neutron-dhcp-agent
2026-02-19 01:21:05.170544 | controller | ++ lib/neutron:source:94                    :   AGENT_L3_BINARY=/opt/stack/data/venv/bin/neutron-l3-agent
2026-02-19 01:21:05.172715 | controller | ++ lib/neutron:source:95                    :   AGENT_META_BINARY=/opt/stack/data/venv/bin/neutron-metadata-agent
2026-02-19 01:21:05.174859 | controller | ++ lib/neutron:source:99                    :   Q_DHCP_CONF_FILE=/etc/neutron/dhcp_agent.ini
2026-02-19 01:21:05.176996 | controller | ++ lib/neutron:source:102                   :   NEUTRON_DHCP_CONF=/etc/neutron/dhcp_agent.ini
2026-02-19 01:21:05.179154 | controller | ++ lib/neutron:source:103                   :   Q_L3_CONF_FILE=/etc/neutron/l3_agent.ini
2026-02-19 01:21:05.181270 | controller | ++ lib/neutron:source:106                   :   NEUTRON_L3_CONF=/etc/neutron/l3_agent.ini
2026-02-19 01:21:05.183374 | controller | ++ lib/neutron:source:107                   :   Q_META_CONF_FILE=/etc/neutron/metadata_agent.ini
2026-02-19 01:21:05.185573 | controller | ++ lib/neutron:source:110                   :   Q_DB_NAME=neutron
2026-02-19 01:21:05.187741 | controller | ++ lib/neutron:source:112                   :   Q_PLUGIN=ml2
2026-02-19 01:21:05.189754 | controller | ++ lib/neutron:source:114                   :   Q_PORT=9696
2026-02-19 01:21:05.191749 | controller | ++ lib/neutron:source:116                   :   Q_PORT_INT=19696
2026-02-19 01:21:05.194061 | controller | ++ lib/neutron:source:118                   :   Q_HOST=199.204.45.69
2026-02-19 01:21:05.196228 | controller | ++ lib/neutron:source:120                   :   Q_PROTOCOL=https
2026-02-19 01:21:05.198838 | controller | +++ lib/neutron:source:122                   :   ipv6_unquote 0.0.0.0
2026-02-19 01:21:05.201194 | controller | +++ functions-common:ipv6_unquote:2210       :   echo 0.0.0.0
2026-02-19 01:21:05.202321 | controller | +++ functions-common:ipv6_unquote:2210       :   tr -d '[]'
2026-02-19 01:21:05.206358 | controller | ++ lib/neutron:source:122                   :   Q_LISTEN_ADDRESS=0.0.0.0
2026-02-19 01:21:05.208464 | controller | ++ lib/neutron:source:124                   :   Q_ADMIN_USERNAME=neutron
2026-02-19 01:21:05.210568 | controller | ++ lib/neutron:source:126                   :   Q_AUTH_STRATEGY=keystone
2026-02-19 01:21:05.212619 | controller | ++ lib/neutron:source:128                   :   Q_OVS_USE_VETH=False
2026-02-19 01:21:05.214764 | controller | ++ lib/neutron:source:129                   :   Q_USE_ROOTWRAP=True
2026-02-19 01:21:05.217095 | controller | +++ lib/neutron:source:130                   :   trueorfalse True Q_USE_ROOTWRAP_DAEMON
2026-02-19 01:21:05.230401 | controller | ++ lib/neutron:source:130                   :   Q_USE_ROOTWRAP_DAEMON=True
2026-02-19 01:21:05.232794 | controller | +++ lib/neutron:source:132                   :   ipv6_unquote 199.204.45.69
2026-02-19 01:21:05.235126 | controller | +++ functions-common:ipv6_unquote:2210       :   echo 199.204.45.69
2026-02-19 01:21:05.236295 | controller | +++ functions-common:ipv6_unquote:2210       :   tr -d '[]'
2026-02-19 01:21:05.240640 | controller | ++ lib/neutron:source:132                   :   Q_META_DATA_IP=199.204.45.69
2026-02-19 01:21:05.243125 | controller | ++ lib/neutron:source:134                   :   Q_ALLOW_OVERLAPPING_IP=True
2026-02-19 01:21:05.245544 | controller | ++ lib/neutron:source:135                   :   Q_NOTIFY_NOVA_PORT_STATUS_CHANGES=True
2026-02-19 01:21:05.247789 | controller | ++ lib/neutron:source:136                   :   Q_NOTIFY_NOVA_PORT_DATA_CHANGES=True
2026-02-19 01:21:05.250102 | controller | ++ lib/neutron:source:137                   :   VIF_PLUGGING_IS_FATAL=True
2026-02-19 01:21:05.252584 | controller | ++ lib/neutron:source:138                   :   VIF_PLUGGING_TIMEOUT=300
2026-02-19 01:21:05.255470 | controller | ++ lib/neutron:source:141                   :   SKIP_STOP_OVN=False
2026-02-19 01:21:05.258023 | controller | ++ lib/neutron:source:145                   :   _Q_PLUGIN_EXTRA_CONF_PATH=/etc/neutron
2026-02-19 01:21:05.260415 | controller | ++ lib/neutron:source:148                   :   NEUTRON_ENDPOINT_SERVICE_NAME=networking
2026-02-19 01:21:05.262830 | controller | ++ lib/neutron:source:149                   :   [[ -z networking ]]
2026-02-19 01:21:05.265226 | controller | ++ lib/neutron:source:154                   :   ALEMBIC_REPO=https://github.com/sqlalchemy/alembic.git
2026-02-19 01:21:05.267561 | controller | ++ lib/neutron:source:155                   :   ALEMBIC_DIR=/opt/stack/alembic
2026-02-19 01:21:05.269787 | controller | ++ lib/neutron:source:156                   :   ALEMBIC_BRANCH=main
2026-02-19 01:21:05.272062 | controller | ++ lib/neutron:source:157                   :   SQLALCHEMY_REPO=https://github.com/sqlalchemy/sqlalchemy.git
2026-02-19 01:21:05.274154 | controller | ++ lib/neutron:source:158                   :   SQLALCHEMY_DIR=/opt/stack/sqlalchemy
2026-02-19 01:21:05.276271 | controller | ++ lib/neutron:source:159                   :   SQLALCHEMY_BRANCH=main
2026-02-19 01:21:05.278612 | controller | ++ lib/neutron:source:170                   :   declare -a -g Q_PLUGIN_EXTRA_CONF_FILES
2026-02-19 01:21:05.280622 | controller | ++ lib/neutron:source:173                   :   declare -a -g _Q_PLUGIN_EXTRA_CONF_FILES_ABS
2026-02-19 01:21:05.282639 | controller | ++ lib/neutron:source:176                   :   Q_RR_CONF_FILE=/etc/neutron/rootwrap.conf
2026-02-19 01:21:05.284650 | controller | ++ lib/neutron:source:177                   :   [[ True == \F\a\l\s\e ]]
2026-02-19 01:21:05.286811 | controller | +++ lib/neutron:source:180                   :   get_rootwrap_location neutron
2026-02-19 01:21:05.288769 | controller | +++ functions:get_rootwrap_location:601      :   local module=neutron
2026-02-19 01:21:05.291414 | controller | ++++ functions:get_rootwrap_location:603      :   get_python_exec_prefix
2026-02-19 01:21:05.305923 | controller | ++++ inc/python:get_python_exec_prefix:80     :   [[ True == \T\r\u\e ]]
2026-02-19 01:21:05.307971 | controller | ++++ inc/python:get_python_exec_prefix:81     :   echo /opt/stack/data/venv/bin
2026-02-19 01:21:05.310582 | controller | +++ functions:get_rootwrap_location:603      :   echo /opt/stack/data/venv/bin/neutron-rootwrap
2026-02-19 01:21:05.312605 | controller | ++ lib/neutron:source:180                   :   NEUTRON_ROOTWRAP=/opt/stack/data/venv/bin/neutron-rootwrap
2026-02-19 01:21:05.314722 | controller | ++ lib/neutron:source:181                   :   Q_RR_COMMAND='sudo /opt/stack/data/venv/bin/neutron-rootwrap /etc/neutron/rootwrap.conf'
2026-02-19 01:21:05.316541 | controller | ++ lib/neutron:source:182                   :   [[ True == \T\r\u\e ]]
2026-02-19 01:21:05.318559 | controller | ++ lib/neutron:source:183                   :   Q_RR_DAEMON_COMMAND='sudo /opt/stack/data/venv/bin/neutron-rootwrap-daemon /etc/neutron/rootwrap.conf'
2026-02-19 01:21:05.320794 | controller | ++ lib/neutron:source:195                   :   Q_DVR_MODE=legacy
2026-02-19 01:21:05.322784 | controller | ++ lib/neutron:source:196                   :   [[ legacy != \l\e\g\a\c\y ]]
2026-02-19 01:21:05.324575 | controller | ++ lib/neutron:source:212                   :   ENABLE_TENANT_TUNNELS=True
2026-02-19 01:21:05.326400 | controller | ++ lib/neutron:source:217                   :   TENANT_TUNNEL_RANGES=1:1000
2026-02-19 01:21:05.328009 | controller | ++ lib/neutron:source:222                   :   ENABLE_TENANT_VLANS=False
2026-02-19 01:21:05.329565 | controller | ++ lib/neutron:source:230                   :   TENANT_VLAN_RANGE=
2026-02-19 01:21:05.331288 | controller | ++ lib/neutron:source:238                   :   PHYSICAL_NETWORK=public
2026-02-19 01:21:05.333288 | controller | ++ lib/neutron:source:248                   :   OVS_PHYSICAL_BRIDGE=br-ex
2026-02-19 01:21:05.335251 | controller | ++ lib/neutron:source:254                   :   OVS_ENABLE_TUNNELING=True
2026-02-19 01:21:05.337238 | controller | ++ lib/neutron:source:258                   :   ENABLE_ISOLATED_METADATA=False
2026-02-19 01:21:05.339189 | controller | ++ lib/neutron:source:263                   :   ENABLE_METADATA_NETWORK=False
2026-02-19 01:21:05.341137 | controller | ++ lib/neutron:source:268                   :   '[' -f /opt/stack/devstack/lib/neutron_plugins/ml2 ']'
2026-02-19 01:21:05.343112 | controller | ++ lib/neutron:source:269                   :   source /opt/stack/devstack/lib/neutron_plugins/ml2
2026-02-19 01:21:05.434031 | controller | ++ lib/neutron:source:276                   :   source /opt/stack/devstack/lib/neutron_plugins/services/metering
2026-02-19 01:21:05.446708 | controller | ++ lib/neutron:source:279                   :   source /opt/stack/devstack/lib/neutron_plugins/services/l3
2026-02-19 01:21:05.448974 | controller | +++ lib/neutron_plugins/services/l3:source:3 :   IP_VERSION=4+6
2026-02-19 01:21:05.451016 | controller | +++ lib/neutron_plugins/services/l3:source:5 :   [[ 4+6 != \4 ]]
2026-02-19 01:21:05.453109 | controller | +++ lib/neutron_plugins/services/l3:source:5 :   [[ 4+6 != \6 ]]
2026-02-19 01:21:05.455209 | controller | +++ lib/neutron_plugins/services/l3:source:5 :   [[ 4+6 != \4\+\6 ]]
2026-02-19 01:21:05.457339 | controller | +++ lib/neutron_plugins/services/l3:source:9 :   NEUTRON_CREATE_INITIAL_NETWORKS=True
2026-02-19 01:21:05.459456 | controller | +++ lib/neutron_plugins/services/l3:source:12 :   PROVIDER_SUBNET_NAME=provider_net
2026-02-19 01:21:05.461559 | controller | +++ lib/neutron_plugins/services/l3:source:13 :   IPV6_PROVIDER_SUBNET_NAME=provider_net_v6
2026-02-19 01:21:05.463652 | controller | +++ lib/neutron_plugins/services/l3:source:14 :   IPV6_PROVIDER_FIXED_RANGE=
2026-02-19 01:21:05.465777 | controller | +++ lib/neutron_plugins/services/l3:source:15 :   IPV6_PROVIDER_NETWORK_GATEWAY=
2026-02-19 01:21:05.467875 | controller | +++ lib/neutron_plugins/services/l3:source:17 :   PUBLIC_BRIDGE=br-ex
2026-02-19 01:21:05.470052 | controller | +++ lib/neutron_plugins/services/l3:source:18 :   PUBLIC_BRIDGE_MTU=1430
2026-02-19 01:21:05.472156 | controller | +++ lib/neutron_plugins/services/l3:source:23 :   Q_ASSIGN_GATEWAY_TO_PUBLIC_BRIDGE=True
2026-02-19 01:21:05.474334 | controller | +++ lib/neutron_plugins/services/l3:source:26 :   Q_ROUTER_NAME=router1
2026-02-19 01:21:05.476478 | controller | +++ lib/neutron_plugins/services/l3:source:31 :   Q_USE_PUBLIC_VETH=False
2026-02-19 01:21:05.478270 | controller | +++ lib/neutron_plugins/services/l3:source:32 :   Q_PUBLIC_VETH_EX=veth-pub-ex
2026-02-19 01:21:05.479955 | controller | +++ lib/neutron_plugins/services/l3:source:33 :   Q_PUBLIC_VETH_INT=veth-pub-int
2026-02-19 01:21:05.481700 | controller | +++ lib/neutron_plugins/services/l3:source:39 :   Q_L3_ROUTER_PER_TENANT=True
2026-02-19 01:21:05.483338 | controller | +++ lib/neutron_plugins/services/l3:source:72 :   Q_USE_PROVIDERNET_FOR_PUBLIC=True
2026-02-19 01:21:05.485026 | controller | +++ lib/neutron_plugins/services/l3:source:73 :   PUBLIC_PHYSICAL_NETWORK=public
2026-02-19 01:21:05.490473 | controller | ++++ lib/neutron_plugins/services/l3:source:76 :   uuidgen
2026-02-19 01:21:05.490493 | controller | /opt/stack/devstack/lib/neutron_plugins/services/l3: line 76: uuidgen: command not found
2026-02-19 01:21:05.490947 | controller | ++++ lib/neutron_plugins/services/l3:source:76 :   sed s/-//g
2026-02-19 01:21:05.491070 | controller | ++++ lib/neutron_plugins/services/l3:source:76 :   sed -e 's/\(..\)\(....\)\(....\)/\1:\2:\3/'
2026-02-19 01:21:05.492884 | controller | ++++ lib/neutron_plugins/services/l3:source:76 :   cut -c 23-
2026-02-19 01:21:05.495918 | controller | +++ lib/neutron_plugins/services/l3:source:76 :   IPV6_GLOBAL_ID=
2026-02-19 01:21:05.497588 | controller | +++ lib/neutron_plugins/services/l3:source:79 :   IPV6_RA_MODE=slaac
2026-02-19 01:21:05.499275 | controller | +++ lib/neutron_plugins/services/l3:source:80 :   IPV6_ADDRESS_MODE=slaac
2026-02-19 01:21:05.501133 | controller | +++ lib/neutron_plugins/services/l3:source:81 :   IPV6_PUBLIC_SUBNET_NAME=ipv6-public-subnet
2026-02-19 01:21:05.503253 | controller | +++ lib/neutron_plugins/services/l3:source:82 :   IPV6_PRIVATE_SUBNET_NAME=ipv6-private-subnet
2026-02-19 01:21:05.505383 | controller | +++ lib/neutron_plugins/services/l3:source:83 :   IPV6_ADDRS_SAFE_TO_USE=fd::/56
2026-02-19 01:21:05.508059 | controller | ++++ lib/neutron_plugins/services/l3:source:86 :   echo fd::/56
2026-02-19 01:21:05.509537 | controller | ++++ lib/neutron_plugins/services/l3:source:86 :   awk -F / '{ print $1"/"($2>63 ? $2 : 64) }'
2026-02-19 01:21:05.513308 | controller | +++ lib/neutron_plugins/services/l3:source:86 :   FIXED_RANGE_V6=fd::/64
2026-02-19 01:21:05.515495 | controller | +++ lib/neutron_plugins/services/l3:source:87 :   IPV6_PRIVATE_NETWORK_GATEWAY=
2026-02-19 01:21:05.517605 | controller | +++ lib/neutron_plugins/services/l3:source:88 :   IPV6_PUBLIC_RANGE=2001:db8::/64
2026-02-19 01:21:05.519703 | controller | +++ lib/neutron_plugins/services/l3:source:89 :   IPV6_PUBLIC_NETWORK_GATEWAY=2001:db8::2
2026-02-19 01:21:05.522140 | controller | +++ lib/neutron_plugins/services/l3:source:90 :   IPV6_ROUTER_GW_IP=2001:db8::1
2026-02-19 01:21:05.523946 | controller | +++ lib/neutron_plugins/services/l3:source:93 :   NETWORK_GATEWAY=10.1.0.1
2026-02-19 01:21:05.525578 | controller | +++ lib/neutron_plugins/services/l3:source:94 :   PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-02-19 01:21:05.527219 | controller | +++ lib/neutron_plugins/services/l3:source:95 :   PRIVATE_SUBNET_NAME=private-subnet
2026-02-19 01:21:05.528865 | controller | +++ lib/neutron_plugins/services/l3:source:96 :   PUBLIC_SUBNET_NAME=public-subnet
2026-02-19 01:21:05.530501 | controller | +++ lib/neutron_plugins/services/l3:source:99 :   USE_SUBNETPOOL=True
2026-02-19 01:21:05.532750 | controller | +++ lib/neutron_plugins/services/l3:source:100 :   SUBNETPOOL_NAME_V4=shared-default-subnetpool-v4
2026-02-19 01:21:05.534801 | controller | +++ lib/neutron_plugins/services/l3:source:101 :   SUBNETPOOL_NAME_V6=shared-default-subnetpool-v6
2026-02-19 01:21:05.536862 | controller | +++ lib/neutron_plugins/services/l3:source:103 :   SUBNETPOOL_PREFIX_V4=10.1.0.0/20
2026-02-19 01:21:05.538984 | controller | +++ lib/neutron_plugins/services/l3:source:104 :   SUBNETPOOL_PREFIX_V6=fd::/56
2026-02-19 01:21:05.540711 | controller | +++ lib/neutron_plugins/services/l3:source:106 :   SUBNETPOOL_SIZE_V4=26
2026-02-19 01:21:05.542435 | controller | +++ lib/neutron_plugins/services/l3:source:107 :   SUBNETPOOL_SIZE_V6=64
2026-02-19 01:21:05.547225 | controller | ++++ lib/neutron_plugins/services/l3:source:109 :   ip -4 route
2026-02-19 01:21:05.547249 | controller | ++++ lib/neutron_plugins/services/l3:source:109 :   awk '{print $5}'
2026-02-19 01:21:05.548419 | controller | ++++ lib/neutron_plugins/services/l3:source:109 :   grep '^default'
2026-02-19 01:21:05.550561 | controller | +++ lib/neutron_plugins/services/l3:source:109 :   default_v4_route_devs=ens3
2026-02-19 01:21:05.555297 | controller | ++++ lib/neutron_plugins/services/l3:source:111 :   awk '{print $5}'
2026-02-19 01:21:05.555388 | controller | ++++ lib/neutron_plugins/services/l3:source:111 :   grep via
2026-02-19 01:21:05.555454 | controller | ++++ lib/neutron_plugins/services/l3:source:111 :   ip -6 route list match default table all
2026-02-19 01:21:05.560849 | controller | +++ lib/neutron_plugins/services/l3:source:111 :   default_v6_route_devs='ens3
2026-02-19 01:21:05.560871 | controller | ens3'
2026-02-19 01:21:05.565208 | controller | ++ lib/neutron:source:282                   :   source /opt/stack/devstack/lib/neutron_plugins/services/placement
2026-02-19 01:21:05.567787 | controller | ++ lib/neutron:source:283                   :   source /opt/stack/devstack/lib/neutron_plugins/services/trunk
2026-02-19 01:21:05.570263 | controller | ++ lib/neutron:source:284                   :   source /opt/stack/devstack/lib/neutron_plugins/services/qos
2026-02-19 01:21:05.572911 | controller | ++ lib/neutron:source:285                   :   source /opt/stack/devstack/lib/neutron_plugins/services/segments
2026-02-19 01:21:05.575380 | controller | ++ lib/neutron:source:288                   :   has_neutron_plugin_security_group
2026-02-19 01:21:05.577818 | controller | ++ lib/neutron_plugins/ml2:has_neutron_plugin_security_group:146 :   return 0
2026-02-19 01:21:05.580210 | controller | ++ lib/neutron:source:289                   :   Q_USE_SECGROUP=True
2026-02-19 01:21:05.582665 | controller | ++ lib/neutron:source:297                   :   OVN_BRIDGE_MAPPINGS=public:br-ex
2026-02-19 01:21:05.599944 | controller | + ./stack.sh:main:618                      :   source /opt/stack/devstack/lib/ldap
2026-02-19 01:21:05.614644 | controller | + ./stack.sh:main:619                      :   source /opt/stack/devstack/lib/dstat
2026-02-19 01:21:05.629334 | controller | + ./stack.sh:main:620                      :   source /opt/stack/devstack/lib/atop
2026-02-19 01:21:05.642816 | controller | + ./stack.sh:main:621                      :   source /opt/stack/devstack/lib/tcpdump
2026-02-19 01:21:05.656918 | controller | + ./stack.sh:main:622                      :   source /opt/stack/devstack/lib/etcd3
2026-02-19 01:21:05.687774 | controller | + ./stack.sh:main:623                      :   source /opt/stack/devstack/lib/os-vif
2026-02-19 01:21:05.690235 | controller | +++ lib/os-vif:source:12                     :   is_ml2_ovs
2026-02-19 01:21:05.692256 | controller | +++ lib/os-vif:is_ml2_ovs:4                  :   [[ ovn == \o\p\e\n\v\s\w\i\t\c\h ]]
2026-02-19 01:21:05.694495 | controller | +++ lib/os-vif:is_ml2_ovs:7                  :   echo False
2026-02-19 01:21:05.697728 | controller | ++ lib/os-vif:source:12                     :   OS_VIF_OVS_ISOLATE_VIF=False
2026-02-19 01:21:05.700333 | controller | +++ lib/os-vif:source:13                     :   trueorfalse False OS_VIF_OVS_ISOLATE_VIF
2026-02-19 01:21:05.716968 | controller | ++ lib/os-vif:source:13                     :   OS_VIF_OVS_ISOLATE_VIF=False
2026-02-19 01:21:05.719447 | controller | + ./stack.sh:main:629                      :   run_phase source
2026-02-19 01:21:05.721728 | controller | + functions-common:run_phase:1840          :   local mode=source
2026-02-19 01:21:05.724745 | controller | + functions-common:run_phase:1841          :   local phase=
2026-02-19 01:21:05.727940 | controller | + functions-common:run_phase:1842          :   [[ -d /opt/stack/devstack/extras.d ]]
2026-02-19 01:21:05.731115 | controller | + functions-common:run_phase:1843          :   local extra_plugin_file_name
2026-02-19 01:21:05.734201 | controller | + functions-common:run_phase:1844          :   for extra_plugin_file_name in $TOP_DIR/extras.d/*.sh
2026-02-19 01:21:05.736494 | controller | + functions-common:run_phase:1849          :   local exceptions=80-tempest.sh
2026-02-19 01:21:05.738902 | controller | + functions-common:run_phase:1850          :   local extra
2026-02-19 01:21:05.742208 | controller | ++ functions-common:run_phase:1851          :   basename /opt/stack/devstack/extras.d/80-tempest.sh
2026-02-19 01:21:05.745844 | controller | + functions-common:run_phase:1851          :   extra=80-tempest.sh
2026-02-19 01:21:05.748205 | controller | + functions-common:run_phase:1852          :   [[ 80-tempest.sh =~ 80-tempest\.sh ]]
2026-02-19 01:21:05.750618 | controller | + functions-common:run_phase:1856          :   [[ -r /opt/stack/devstack/extras.d/80-tempest.sh ]]
2026-02-19 01:21:05.753005 | controller | + functions-common:run_phase:1856          :   source /opt/stack/devstack/extras.d/80-tempest.sh source
2026-02-19 01:21:05.755427 | controller | ++ extras.d/80-tempest.sh:source:3          :   is_service_enabled tempest
2026-02-19 01:21:05.770953 | controller | ++ functions-common:is_service_enabled:2068 :   return 0
2026-02-19 01:21:05.773268 | controller | ++ extras.d/80-tempest.sh:source:4          :   [[ source == \s\o\u\r\c\e ]]
2026-02-19 01:21:05.775533 | controller | ++ extras.d/80-tempest.sh:source:6          :   source /opt/stack/devstack/lib/tempest
2026-02-19 01:21:05.819362 | controller | ++ extras.d/80-tempest.sh:source:27         :   [[ source == \u\n\s\t\a\c\k ]]
2026-02-19 01:21:05.823263 | controller | ++ extras.d/80-tempest.sh:source:32         :   [[ source == \c\l\e\a\n ]]
2026-02-19 01:21:05.827557 | controller | + functions-common:run_phase:1861          :   [[ source == \s\o\u\r\c\e ]]
2026-02-19 01:21:05.831177 | controller | + functions-common:run_phase:1862          :   load_plugin_settings
2026-02-19 01:21:05.833807 | controller | + functions-common:load_plugin_settings:1776 :   local plugins=,devstack-plugin-ceph,manila
2026-02-19 01:21:05.836111 | controller | + functions-common:load_plugin_settings:1777 :   local plugin
2026-02-19 01:21:05.838509 | controller | + functions-common:load_plugin_settings:1780 :   [[ -z ,devstack-plugin-ceph,manila ]]
2026-02-19 01:21:05.840956 | controller | + functions-common:load_plugin_settings:1784 :   echo 'Loading plugin settings'
2026-02-19 01:21:05.840970 | controller | Loading plugin settings
2026-02-19 01:21:05.843374 | controller | + functions-common:load_plugin_settings:1785 :   for plugin in ${plugins//,/ }
2026-02-19 01:21:05.846123 | controller | + functions-common:load_plugin_settings:1786 :   local dir=/opt/stack/devstack-plugin-ceph
2026-02-19 01:21:05.848613 | controller | + functions-common:load_plugin_settings:1788 :   [[ -f /opt/stack/devstack-plugin-ceph/devstack/settings ]]
2026-02-19 01:21:05.851051 | controller | + functions-common:load_plugin_settings:1789 :   source /opt/stack/devstack-plugin-ceph/devstack/settings
2026-02-19 01:21:05.855328 | controller | ++++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:5 :   dirname /opt/stack/devstack-plugin-ceph/devstack/settings
2026-02-19 01:21:05.860273 | controller | +++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:5 :   readlink -f /opt/stack/devstack-plugin-ceph/devstack
2026-02-19 01:21:05.864303 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:5 :   CEPH_PLUGIN_DIR=/opt/stack/devstack-plugin-ceph/devstack
2026-02-19 01:21:05.867754 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:8 :   TEMPEST_STORAGE_PROTOCOL=ceph
2026-02-19 01:21:05.871105 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:11 :   VOLUME_BACKING_FILE_SIZE=60GB
2026-02-19 01:21:05.874459 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:12 :   CEPH_LOOPBACK_DISK_SIZE=40G
2026-02-19 01:21:05.876969 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:14 :   TEMPEST_VOLUME_MANAGE_SNAPSHOT=False
2026-02-19 01:21:05.879476 | controller | ++ /opt/stack/devstack-plugin-ceph/devstack/settings:source:16 :   source /opt/stack/devstack-plugin-ceph/devstack/lib/cephadm
2026-02-19 01:21:05.892243 | controller | /opt/stack/devstack-plugin-ceph/devstack/lib/cephadm: line 35: uuidgen: command not found
2026-02-19 01:21:05.893575 | controller | Error on exit
2026-02-19 01:21:05.984315 | controller | sudo: iptables: command not found
2026-02-19 01:21:05.991719 | controller | sudo: iptables: command not found
2026-02-19 01:21:05.998088 | controller | sudo: iptables: command not found
2026-02-19 01:21:06.199059 | controller | *** FINISHED ***
2026-02-19 01:21:06.638555 | controller | ERROR
2026-02-19 01:21:06.638919 | controller | {
2026-02-19 01:21:06.638978 | controller |   "delta": "0:00:14.560664",
2026-02-19 01:21:06.639001 | controller |   "end": "2026-02-19 01:21:06.211992",
2026-02-19 01:21:06.639019 | controller |   "msg": "non-zero return code",
2026-02-19 01:21:06.639037 | controller |   "rc": 127,
2026-02-19 01:21:06.639054 | controller |   "start": "2026-02-19 01:20:51.651328"
2026-02-19 01:21:06.639075 | controller | }
failure
2026-02-19 01:21:06.656910 | 
2026-02-19 01:21:06.657000 | PLAY RECAP
2026-02-19 01:21:06.657053 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-02-19 01:21:06.657076 | 
2026-02-19 01:21:06.795059 | RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/tempest/playbooks/devstack-tempest.yaml@master]
2026-02-19 01:21:06.806010 | POST-RUN START: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master]
2026-02-19 01:21:07.453336 | 
2026-02-19 01:21:07.453571 | PLAY [tempest]
2026-02-19 01:21:07.469798 | 
2026-02-19 01:21:07.469884 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-02-19 01:21:07.909788 | controller | changed: non-zero return code
2026-02-19 01:21:07.915698 | 
2026-02-19 01:21:07.915808 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-02-19 01:21:07.942168 | controller | skipping: Conditional result was False
2026-02-19 01:21:07.953928 | 
2026-02-19 01:21:07.954004 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-02-19 01:21:07.991344 | 
2026-02-19 01:21:07.991513 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-02-19 01:21:08.027089 | 
2026-02-19 01:21:08.027223 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-02-19 01:21:08.052405 | controller | skipping: Conditional result was False
2026-02-19 01:21:08.062533 | 
2026-02-19 01:21:08.062625 | LOOP [fetch-subunit-output : Generate subunit file]
2026-02-19 01:21:08.099191 | 
2026-02-19 01:21:08.099328 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-02-19 01:21:08.124643 | controller | skipping: Conditional result was False
2026-02-19 01:21:08.134783 | 
2026-02-19 01:21:08.134879 | TASK [fetch-subunit-output : Remove the temporary file]
2026-02-19 01:21:08.160429 | controller | skipping: Conditional result was False
2026-02-19 01:21:08.170547 | 
2026-02-19 01:21:08.170639 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-02-19 01:21:08.196461 | controller | skipping: Conditional result was False
2026-02-19 01:21:08.213686 | 
2026-02-19 01:21:08.213751 | TASK [process-stackviz : Devstack checks if stackviz archive exists]
2026-02-19 01:21:08.574116 | controller | ok
2026-02-19 01:21:08.579920 | 
2026-02-19 01:21:08.579992 | TASK [process-stackviz : debug]
2026-02-19 01:21:08.615113 | Stackviz archive could not be found in /opt/cache/files/stackviz-latest.tar.gz
2026-02-19 01:21:08.623252 | 
2026-02-19 01:21:08.623574 | TASK [process-stackviz : Check if subunit data exists]
2026-02-19 01:21:09.362890 | controller | ok
2026-02-19 01:21:09.368037 | 
2026-02-19 01:21:09.368104 | TASK [process-stackviz : debug]
2026-02-19 01:21:09.403628 | Subunit file could not be found at /opt/stack/tempest/testrepository.subunit
2026-02-19 01:21:09.408747 | 
2026-02-19 01:21:09.408809 | TASK [include_role : ensure-pip]
2026-02-19 01:21:09.434309 | controller | skipping: Conditional result was False
2026-02-19 01:21:09.447191 | 
2026-02-19 01:21:09.447261 | TASK [process-stackviz : pip]
2026-02-19 01:21:09.472670 | controller | skipping: Conditional result was False
2026-02-19 01:21:09.484479 | 
2026-02-19 01:21:09.484562 | TASK [process-stackviz : Deploy stackviz static html+js]
2026-02-19 01:21:10.072611 | controller | skipping: Conditional result was False
2026-02-19 01:21:10.084470 | 
2026-02-19 01:21:10.084568 | TASK [process-stackviz : Check if dstat data exists]
2026-02-19 01:21:10.110207 | controller | skipping: Conditional result was False
2026-02-19 01:21:10.120333 | 
2026-02-19 01:21:10.120402 | TASK [process-stackviz : Run stackviz with dstat]
2026-02-19 01:21:10.651537 | controller | skipping: Conditional result was False
2026-02-19 01:21:10.662956 | 
2026-02-19 01:21:10.663026 | TASK [process-stackviz : Run stackviz without dstat]
2026-02-19 01:21:11.192376 | controller | skipping: Conditional result was False
2026-02-19 01:21:11.203610 | 
2026-02-19 01:21:11.203671 | PLAY RECAP
2026-02-19 01:21:11.203714 | controller | ok: 5 changed: 1 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0
2026-02-19 01:21:11.203737 | 
2026-02-19 01:21:11.329645 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master]
2026-02-19 01:21:11.338921 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-19 01:21:11.988264 | 
2026-02-19 01:21:11.988420 | PLAY [all]
2026-02-19 01:21:12.007815 | 
2026-02-19 01:21:12.007935 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-02-19 01:21:12.590769 | controller | changed
2026-02-19 01:21:12.599583 | 
2026-02-19 01:21:12.599693 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-02-19 01:21:13.650188 | controller | ok: Runtime: 0:00:00.510451
2026-02-19 01:21:13.656068 | 
2026-02-19 01:21:13.656142 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-02-19 01:21:14.194381 | controller | ok: Runtime: 0:00:00.019084
2026-02-19 01:21:14.201343 | 
2026-02-19 01:21:14.201417 | TASK [export-devstack-journal : Export journal]
2026-02-19 01:21:14.789734 | controller | ok: Runtime: 0:00:00.020698
2026-02-19 01:21:14.796289 | 
2026-02-19 01:21:14.796367 | TASK [export-devstack-journal : Save journal README]
2026-02-19 01:21:15.611790 | controller | changed
2026-02-19 01:21:15.624705 | 
2026-02-19 01:21:15.624776 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-02-19 01:21:15.852927 | controller | changed
2026-02-19 01:21:15.859648 | 
2026-02-19 01:21:15.859716 | TASK [apache-logs-conf : Find logs]
2026-02-19 01:21:16.181346 | controller | Output suppressed because no_log was given
2026-02-19 01:21:16.187741 | 
2026-02-19 01:21:16.187810 | LOOP [apache-logs-conf : Dereference files]
2026-02-19 01:21:16.223535 | 
2026-02-19 01:21:16.223709 | LOOP [apache-logs-conf : Create hard links]
2026-02-19 01:21:16.259650 | 
2026-02-19 01:21:16.259783 | TASK [apache-logs-conf : Find logs]
2026-02-19 01:21:16.296794 | 
2026-02-19 01:21:16.296935 | LOOP [apache-logs-conf : Dereference files]
2026-02-19 01:21:16.331764 | 
2026-02-19 01:21:16.331897 | LOOP [apache-logs-conf : Create hard links]
2026-02-19 01:21:16.367917 | 
2026-02-19 01:21:16.368091 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-02-19 01:21:16.592750 | controller | changed
2026-02-19 01:21:16.600102 | 
2026-02-19 01:21:16.600165 | TASK [apache-logs-conf : Define config paths]
2026-02-19 01:21:16.633829 | controller | ok
2026-02-19 01:21:16.640512 | 
2026-02-19 01:21:16.640576 | TASK [apache-logs-conf : Discover configurations]
2026-02-19 01:21:16.864298 | controller | Output suppressed because no_log was given
2026-02-19 01:21:16.869481 | 
2026-02-19 01:21:16.869546 | LOOP [apache-logs-conf : Dereference configurations]
2026-02-19 01:21:16.905013 | 
2026-02-19 01:21:16.905144 | LOOP [apache-logs-conf : Link configurations]
2026-02-19 01:21:16.945979 | 
2026-02-19 01:21:16.946135 | TASK [capture-performance-data : Generate statistics]
2026-02-19 01:21:18.492173 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-02-19 01:21:18.507839 | controller | Using python 3.12 to install setuptools
2026-02-19 01:21:18.538195 | controller | /usr/bin/python3.12: No module named pip
2026-02-19 01:21:18.550444 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-02-19 01:21:18.988342 | controller | ERROR
2026-02-19 01:21:18.988654 | controller | {
2026-02-19 01:21:18.988688 | controller |   "delta": "0:00:01.369789",
2026-02-19 01:21:18.988709 | controller |   "end": "2026-02-19 01:21:18.551525",
2026-02-19 01:21:18.988727 | controller |   "msg": "non-zero return code",
2026-02-19 01:21:18.988747 | controller |   "rc": 127,
2026-02-19 01:21:18.988766 | controller |   "start": "2026-02-19 01:21:17.181736"
2026-02-19 01:21:18.988784 | controller | }
2026-02-19 01:21:18.988809 | controller | ERROR: Ignoring Errors
2026-02-19 01:21:19.000348 | 
2026-02-19 01:21:19.000423 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-02-19 01:21:19.258362 | controller | changed
2026-02-19 01:21:19.263688 | 
2026-02-19 01:21:19.263749 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-02-19 01:21:22.795496 | controller | Output suppressed because no_log was given
2026-02-19 01:21:22.808418 | 
2026-02-19 01:21:22.808491 | LOOP [devstack-project-conf : Copy configuration files]
2026-02-19 01:21:22.842062 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.843248 | 
2026-02-19 01:21:22.845214 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.846791 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.849534 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.851985 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.854901 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.857765 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.860788 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.863714 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.866709 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.869450 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.872430 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.876119 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.879199 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.881854 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.884690 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.887449 | controller | skipping: Conditional result was False
2026-02-19 01:21:22.914635 | 
2026-02-19 01:21:22.914803 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-02-19 01:21:23.141792 | controller | ok
2026-02-19 01:21:23.148787 | 
2026-02-19 01:21:23.148854 | TASK [devstack-project-conf : Copy configuration files]
2026-02-19 01:21:23.733477 | controller | skipping: Conditional result was False
2026-02-19 01:21:23.750100 | 
2026-02-19 01:21:23.750201 | TASK [capture-system-logs : Stage various logs and reports]
2026-02-19 01:21:24.022369 | controller | sudo: iptables-save: command not found
2026-02-19 01:21:24.053876 | controller | /usr/bin/python3: No module named pip
2026-02-19 01:21:25.167251 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-02-19 01:21:25.286881 | controller | ok: Runtime: 0:00:01.168838
2026-02-19 01:21:25.298564 | 
2026-02-19 01:21:25.298691 | LOOP [stage-output : Register sources]
2026-02-19 01:21:36.212766 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.269177 | 
2026-02-19 01:21:36.269377 | TASK [stage-output : Check sudo]
2026-02-19 01:21:36.811221 | controller | ok: Runtime: 0:00:00.019898
2026-02-19 01:21:36.817655 | 
2026-02-19 01:21:36.817743 | LOOP [stage-output : Set source and destination for files and folders]
2026-02-19 01:21:36.929249 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.929675 | 
2026-02-19 01:21:36.931360 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.933445 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.936058 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.973233 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.973716 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.975645 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.978316 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.980855 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.983563 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.986094 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.988913 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.991476 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.993979 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.996451 | controller | Output suppressed because no_log was given
2026-02-19 01:21:36.999025 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.019122 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.025586 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.025944 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.028578 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.053573 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.053908 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.055673 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.091791 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.092301 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.174100 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.184424 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.200712 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.201088 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.201497 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.201883 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.213437 | controller | Output suppressed because no_log was given
2026-02-19 01:21:37.228600 | 
2026-02-19 01:21:37.228685 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-02-19 01:21:37.303392 | controller | ok
2026-02-19 01:21:37.311217 | 
2026-02-19 01:21:37.311358 | LOOP [stage-output : Ensure target folders exist]
2026-02-19 01:21:37.572103 | controller | changed: "docs"
2026-02-19 01:21:37.817076 | controller | changed: "artifacts"
2026-02-19 01:21:38.029902 | controller | ok: "logs"
2026-02-19 01:21:38.050213 | 
2026-02-19 01:21:38.050397 | LOOP [stage-output : Copy files and folders to staging folder]
2026-02-19 01:21:38.324438 | controller | ok: Item: Runtime: 0:00:00.011289
2026-02-19 01:21:38.324692 | controller | changed: All items complete
2026-02-19 01:21:38.324721 | 
2026-02-19 01:21:38.563508 | controller | ok: Item: Runtime: 0:00:00.007235
2026-02-19 01:21:38.798660 | controller | ok: Item: Runtime: 0:00:00.012333
2026-02-19 01:21:39.072932 | controller | ok: Item: Runtime: 0:00:00.008127
2026-02-19 01:21:39.290420 | controller | ok: Item: Runtime: 0:00:00.005613
2026-02-19 01:21:39.511321 | controller | ok: Item: Runtime: 0:00:00.008380
2026-02-19 01:21:39.730674 | controller | ok: Item: Runtime: 0:00:00.005608
2026-02-19 01:21:39.944981 | controller | ok: Item: Runtime: 0:00:00.005950
2026-02-19 01:21:40.154814 | controller | ok: Item: Runtime: 0:00:00.004794
2026-02-19 01:21:40.458452 | controller | ok: Item: Runtime: 0:00:00.006954
2026-02-19 01:21:40.631091 | controller | ok: Item: Runtime: 0:00:00.010433
2026-02-19 01:21:40.867364 | controller | ok: Item: Runtime: 0:00:00.006751
2026-02-19 01:21:41.091236 | controller | ok: Item: Runtime: 0:00:00.006019
2026-02-19 01:21:41.335939 | controller | ok: Item: Runtime: 0:00:00.008610
2026-02-19 01:21:41.585853 | controller | ok: Item: Runtime: 0:00:00.005888
2026-02-19 01:21:41.791587 | controller | ok: Item: Runtime: 0:00:00.006562
2026-02-19 01:21:42.021537 | controller | ok: Item: Runtime: 0:00:00.007074
2026-02-19 01:21:42.249142 | controller | ok: Item: Runtime: 0:00:00.006730
2026-02-19 01:21:42.493858 | controller | ok: Item: Runtime: 0:00:00.008646
2026-02-19 01:21:42.733876 | controller | ok: Item: Runtime: 0:00:00.009061
2026-02-19 01:21:42.763972 | 
2026-02-19 01:21:42.764161 | TASK [stage-output : Make all log files readable]
2026-02-19 01:21:43.021633 | controller | changed
2026-02-19 01:21:43.028412 | 
2026-02-19 01:21:43.028480 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-02-19 01:21:43.429938 | controller | changed: Renamed files for staging.
2026-02-19 01:21:43.436862 | 
2026-02-19 01:21:43.436930 | TASK [stage-output : Discover log files for compression]
2026-02-19 01:21:43.462384 | controller | skipping: Conditional result was False
2026-02-19 01:21:43.473006 | 
2026-02-19 01:21:43.473104 | LOOP [stage-output : Archive everything from logs]
2026-02-19 01:21:43.516450 | 
2026-02-19 01:21:43.516607 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-02-19 01:21:44.179561 | controller | changed:
2026-02-19 01:21:44.179744 | controller | created directory /var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/work/logs/controller
2026-02-19 01:21:44.179772 | controller | cd+++++++++ logs/
2026-02-19 01:21:44.179793 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-02-19 01:21:44.179813 | controller | >f+++++++++ logs/deprecations_log.txt
2026-02-19 01:21:44.179903 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-02-19 01:21:44.179925 | controller | >f+++++++++ logs/devstack.journal.gz
2026-02-19 01:21:44.179943 | controller | >f+++++++++ logs/devstacklog.txt
2026-02-19 01:21:44.179962 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-02-19 01:21:44.179983 | controller | >f+++++++++ logs/df.txt
2026-02-19 01:21:44.180002 | controller | >f+++++++++ logs/dpkg-l.txt
2026-02-19 01:21:44.180020 | controller | >f+++++++++ logs/iptables.txt
2026-02-19 01:21:44.180038 | controller | >f+++++++++ logs/listen53.txt
2026-02-19 01:21:44.180056 | controller | >f+++++++++ logs/local_conf.txt
2026-02-19 01:21:44.180074 | controller | >f+++++++++ logs/mount.txt
2026-02-19 01:21:44.180092 | controller | >f+++++++++ logs/performance.json
2026-02-19 01:21:44.180110 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-02-19 01:21:44.180128 | controller | >f+++++++++ logs/resolv_conf.txt
2026-02-19 01:21:44.180145 | controller | >f+++++++++ logs/services.txt
2026-02-19 01:21:44.180163 | controller | >f+++++++++ logs/sudoers
2026-02-19 01:21:44.180181 | controller | >f+++++++++ logs/syslog.txt
2026-02-19 01:21:44.180199 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-02-19 01:21:44.180217 | controller | cd+++++++++ logs/apache/
2026-02-19 01:21:44.180235 | controller | cd+++++++++ logs/apache_config/
2026-02-19 01:21:44.180253 | controller | cd+++++++++ logs/etc/
2026-02-19 01:21:44.180271 | controller | cd+++++++++ logs/sudoers.d/
2026-02-19 01:21:44.180289 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-02-19 01:21:44.180307 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-02-19 01:21:44.180326 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-02-19 01:21:44.180345 | controller | >f+++++++++ logs/sudoers.d/README
2026-02-19 01:21:44.180363 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-02-19 01:21:44.195465 | 
2026-02-19 01:21:44.195579 | TASK [Check if a tempest log exits]
2026-02-19 01:21:44.474544 | controller | ok
2026-02-19 01:21:44.480759 | 
2026-02-19 01:21:44.480823 | TASK [Link post-devstack tempest.log]
2026-02-19 01:21:44.506796 | controller | skipping: Conditional result was False
2026-02-19 01:21:44.517411 | 
2026-02-19 01:21:44.517496 | TASK [Capture most recent qemu crash dump, if any]
2026-02-19 01:21:44.762239 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-02-19 01:21:45.057880 | controller | ERROR
2026-02-19 01:21:45.058089 | controller | {
2026-02-19 01:21:45.058131 | controller |   "delta": "0:00:00.007499",
2026-02-19 01:21:45.058160 | controller |   "end": "2026-02-19 01:21:44.762780",
2026-02-19 01:21:45.058186 | controller |   "msg": "non-zero return code",
2026-02-19 01:21:45.058211 | controller |   "rc": 127,
2026-02-19 01:21:45.058237 | controller |   "start": "2026-02-19 01:21:44.755281"
2026-02-19 01:21:45.058261 | controller | }
2026-02-19 01:21:45.058293 | controller | ERROR: Ignoring Errors
2026-02-19 01:21:45.060307 | 
2026-02-19 01:21:45.060373 | PLAY RECAP
2026-02-19 01:21:45.060437 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-02-19 01:21:45.060467 | 
2026-02-19 01:21:45.213028 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-02-19 01:21:45.223144 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-19 01:21:45.868360 | 
2026-02-19 01:21:45.868619 | PLAY [all]
2026-02-19 01:21:45.885129 | 
2026-02-19 01:21:45.885214 | TASK [fetch-output : Set log path for multiple nodes]
2026-02-19 01:21:45.930869 | controller | skipping: Conditional result was False
2026-02-19 01:21:45.938425 | 
2026-02-19 01:21:45.938532 | TASK [fetch-output : Set log path for single node]
2026-02-19 01:21:45.980314 | controller | ok
2026-02-19 01:21:45.986614 | 
2026-02-19 01:21:45.986682 | LOOP [fetch-output : Ensure local output dirs]
2026-02-19 01:21:46.383236 | controller -> localhost | ok: "/var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/work/logs"
2026-02-19 01:21:46.596040 | controller -> localhost | changed: "/var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/work/artifacts"
2026-02-19 01:21:46.822657 | controller -> localhost | changed: "/var/lib/zuul/builds/7f9d23d19acd4c11a6b81a1e710136d3/work/docs"
2026-02-19 01:21:46.843156 | 
2026-02-19 01:21:46.843314 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-02-19 01:21:47.488193 | controller | changed: .d..t...... ./
2026-02-19 01:21:47.488438 | controller | changed: All items complete
2026-02-19 01:21:47.488478 | 
2026-02-19 01:21:47.969845 | controller | changed: .d..t...... ./
2026-02-19 01:21:48.436619 | controller | changed: .d..t...... ./
2026-02-19 01:21:48.461775 | 
2026-02-19 01:21:48.461951 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-02-19 01:21:48.875809 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008720
2026-02-19 01:21:49.157084 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.008919
2026-02-19 01:21:49.179710 | 
2026-02-19 01:21:49.179872 | PLAY [all]
2026-02-19 01:21:49.189365 | 
2026-02-19 01:21:49.189477 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-02-19 01:21:49.884510 | controller | changed
2026-02-19 01:21:49.908209 | 
2026-02-19 01:21:49.908263 | PLAY RECAP
2026-02-19 01:21:49.908314 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-02-19 01:21:49.908336 | 
2026-02-19 01:21:50.041190 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-02-19 01:21:50.051715 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-02-19 01:21:50.636248 | 
2026-02-19 01:21:50.636378 | PLAY [localhost]
2026-02-19 01:21:50.646450 | 
2026-02-19 01:21:50.646524 | TASK [Generate Zuul manifest]
2026-02-19 01:21:50.679571 | localhost | ok
2026-02-19 01:21:50.696850 | 
2026-02-19 01:21:50.696942 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-02-19 01:21:51.112832 | localhost | changed
2026-02-19 01:21:51.124684 | 
2026-02-19 01:21:51.125386 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-02-19 01:21:51.157233 | localhost | ok
2026-02-19 01:21:51.164843 | 
2026-02-19 01:21:51.164942 | TASK [Upload logs]
2026-02-19 01:21:51.198736 | localhost | ok
2026-02-19 01:21:51.258395 | 
2026-02-19 01:21:51.258518 | TASK [Set zuul-log-path fact]
2026-02-19 01:21:51.281584 | localhost | ok
2026-02-19 01:21:51.332030 | 
2026-02-19 01:21:51.332174 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-02-19 01:21:51.364344 | localhost | ok
2026-02-19 01:21:51.372011 | 
2026-02-19 01:21:51.372075 | TASK [upload-logs : Create log directories]
2026-02-19 01:21:51.737259 | localhost | changed
2026-02-19 01:21:51.743655 | 
2026-02-19 01:21:51.743729 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-02-19 01:21:52.112307 | localhost -> localhost | ok: Runtime: 0:00:00.005664
2026-02-19 01:21:52.119121 | 
2026-02-19 01:21:52.119215 | TASK [upload-logs : Upload logs to log server]
2026-02-19 01:21:52.569548 | localhost | Output suppressed because no_log was given
2026-02-19 01:21:52.574465 | 
2026-02-19 01:21:52.574559 | LOOP [upload-logs : Compress console log and json output]
2026-02-19 01:21:52.623941 | localhost | skipping: Conditional result was False
2026-02-19 01:21:52.630911 | localhost | skipping: Conditional result was False
2026-02-19 01:21:52.640397 | 
2026-02-19 01:21:52.640505 | LOOP [upload-logs : Upload compressed console log and json output]
2026-02-19 01:21:52.684523 | localhost | skipping: Conditional result was False
2026-02-19 01:21:52.684922 | 
2026-02-19 01:21:52.688617 | localhost | skipping: Conditional result was False
2026-02-19 01:21:52.704275 | 
2026-02-19 01:21:52.704397 | LOOP [upload-logs : Upload console log and json output]