2026-05-20 01:15:05.579565 | Job console starting
2026-05-20 01:15:05.858209 | Updating git repos
2026-05-20 01:15:12.275365 | Cloning repos into workspace
2026-05-20 01:15:25.380859 | Restoring repo states
2026-05-20 01:15:25.639716 | Merging changes
2026-05-20 01:15:27.323271 | Checking out repos
2026-05-20 01:15:41.516311 | Preparing playbooks
2026-05-20 01:15:52.370648 | Running Ansible setup
2026-05-20 01:15:56.586446 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:15:57.284218 | 
2026-05-20 01:15:57.355832 | PLAY [localhost]
2026-05-20 01:15:57.369082 | 
2026-05-20 01:15:57.369187 | TASK [Gathering Facts]
2026-05-20 01:15:58.401024 | localhost | ok
2026-05-20 01:15:58.407901 | 
2026-05-20 01:15:58.407984 | TASK [Setup log path fact]
2026-05-20 01:15:58.429237 | localhost | ok
2026-05-20 01:15:58.441022 | 
2026-05-20 01:15:58.441103 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:15:58.471992 | localhost | ok
2026-05-20 01:15:58.479622 | 
2026-05-20 01:15:58.479686 | TASK [emit-job-header : Print job information]
2026-05-20 01:15:58.518557 | # Job Information
2026-05-20 01:15:58.518735 | Ansible Version: 2.16.18
2026-05-20 01:15:58.518780 | Job: magnum-cluster-api-hydrophone-v1.35.4-cilium
2026-05-20 01:15:58.518812 | Pipeline: check
2026-05-20 01:15:58.518841 | Executor: 2d72f0692154
2026-05-20 01:15:58.518868 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/993
2026-05-20 01:15:58.518899 | Event ID: 2871d470-53e9-11f1-8907-20ed16c57d99
2026-05-20 01:15:58.521775 | 
2026-05-20 01:15:58.521838 | LOOP [emit-job-header : Print node information]
2026-05-20 01:15:58.686216 | localhost | ok:
2026-05-20 01:15:58.806353 | localhost | # Node Information
2026-05-20 01:15:58.806537 | localhost | Inventory Hostname: controller
2026-05-20 01:15:58.806598 | localhost | Hostname: np0000175143
2026-05-20 01:15:58.806655 | localhost | Username: zuul
2026-05-20 01:15:58.806717 | localhost | Distro: Ubuntu 24.04
2026-05-20 01:15:58.806773 | localhost | Provider: yul1
2026-05-20 01:15:58.806825 | localhost | Region: ca-ymq-1
2026-05-20 01:15:58.806875 | localhost | Label: ubuntu-noble-16
2026-05-20 01:15:58.806925 | localhost | Product Name: OpenStack Nova
2026-05-20 01:15:58.806975 | localhost | Interface IP: 199.204.45.72
2026-05-20 01:15:58.818004 | 
2026-05-20 01:15:58.818192 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 01:15:59.285020 | localhost -> localhost | changed
2026-05-20 01:15:59.293523 | 
2026-05-20 01:15:59.293592 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 01:16:00.242216 | localhost -> localhost | changed
2026-05-20 01:16:00.254360 | 
2026-05-20 01:16:00.254428 | PLAY [all]
2026-05-20 01:16:00.265055 | 
2026-05-20 01:16:00.265155 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 01:16:00.557850 | controller -> localhost | ok
2026-05-20 01:16:00.568262 | 
2026-05-20 01:16:00.568392 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 01:16:00.605446 | controller | ok
2026-05-20 01:16:00.630021 | controller | included: /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 01:16:00.638896 | 
2026-05-20 01:16:00.638959 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 01:16:02.274678 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 01:16:02.274897 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/work/e83b3bba95ed4c16ba96d18210cbcf05_id_rsa
2026-05-20 01:16:02.274953 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/work/e83b3bba95ed4c16ba96d18210cbcf05_id_rsa.pub
2026-05-20 01:16:02.274997 | controller -> localhost | The key fingerprint is:
2026-05-20 01:16:02.275037 | controller -> localhost | SHA256:LwGJk36pU3ZxvogODkKUOrlo7ssnxdaDneyMqFvnOZw zuul-build-sshkey
2026-05-20 01:16:02.275111 | controller -> localhost | The key's randomart image is:
2026-05-20 01:16:02.275156 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 01:16:02.275210 | controller -> localhost | |                 |
2026-05-20 01:16:02.275250 | controller -> localhost | |  .  o .         |
2026-05-20 01:16:02.275287 | controller -> localhost | | o  + o . .      |
2026-05-20 01:16:02.275322 | controller -> localhost | |o. . . o +       |
2026-05-20 01:16:02.275368 | controller -> localhost | |+.. * * S .      |
2026-05-20 01:16:02.275413 | controller -> localhost | |oo = @ o + .     |
2026-05-20 01:16:02.275449 | controller -> localhost | |+.*.O.o o o      |
2026-05-20 01:16:02.275485 | controller -> localhost | |+= BEB   .       |
2026-05-20 01:16:02.275525 | controller -> localhost | |=*+ +..          |
2026-05-20 01:16:02.275563 | controller -> localhost | +----[SHA256]-----+
2026-05-20 01:16:02.275643 | controller -> localhost | ok: Runtime: 0:00:01.103755
2026-05-20 01:16:02.288533 | 
2026-05-20 01:16:02.288657 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 01:16:02.323925 | controller | ok
2026-05-20 01:16:02.339399 | controller | included: /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 01:16:02.350232 | 
2026-05-20 01:16:02.350299 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 01:16:02.375621 | controller | skipping: Conditional result was False
2026-05-20 01:16:02.388498 | 
2026-05-20 01:16:02.388575 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 01:16:02.944272 | controller | changed
2026-05-20 01:16:03.008064 | 
2026-05-20 01:16:03.008269 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 01:16:03.230090 | controller | ok
2026-05-20 01:16:03.256640 | 
2026-05-20 01:16:03.256703 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 01:16:03.843126 | controller | changed
2026-05-20 01:16:03.849586 | 
2026-05-20 01:16:03.849657 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 01:16:04.469604 | controller | changed
2026-05-20 01:16:04.475164 | 
2026-05-20 01:16:04.475230 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 01:16:04.499475 | controller | skipping: Conditional result was False
2026-05-20 01:16:04.511016 | 
2026-05-20 01:16:04.511105 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 01:16:04.874188 | controller -> localhost | changed
2026-05-20 01:16:04.892799 | 
2026-05-20 01:16:04.892927 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 01:16:05.309039 | controller -> localhost | Identity added: /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/work/e83b3bba95ed4c16ba96d18210cbcf05_id_rsa (zuul-build-sshkey)
2026-05-20 01:16:05.309210 | controller -> localhost | ok: Runtime: 0:00:00.011990
2026-05-20 01:16:05.314670 | 
2026-05-20 01:16:05.314732 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 01:16:05.607179 | controller | ok
2026-05-20 01:16:05.612330 | 
2026-05-20 01:16:05.612396 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 01:16:05.636591 | controller | skipping: Conditional result was False
2026-05-20 01:16:05.655835 | 
2026-05-20 01:16:05.655944 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 01:16:05.984372 | controller | ok
2026-05-20 01:16:06.001161 | 
2026-05-20 01:16:06.001661 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 01:17:58.125502 | controller | Output suppressed because no_log was given
2026-05-20 01:17:58.193542 | 
2026-05-20 01:17:58.193679 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 01:17:58.837907 | controller | ok: "logs"
2026-05-20 01:17:58.838107 | controller | ok: All items complete
2026-05-20 01:17:58.838135 | 
2026-05-20 01:17:58.878595 | controller | ok: "artifacts"
2026-05-20 01:17:59.088539 | controller | ok: "docs"
2026-05-20 01:17:59.419751 | 
2026-05-20 01:17:59.419848 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 01:17:59.688459 | controller | changed: "logs"
2026-05-20 01:17:59.990015 | controller | changed: "artifacts"
2026-05-20 01:18:00.213257 | controller | changed: "docs"
2026-05-20 01:18:00.461976 | 
2026-05-20 01:18:00.462089 | PLAY RECAP
2026-05-20 01:18:00.462141 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 01:18:00.462177 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:18:00.462204 | 
2026-05-20 01:18:00.650234 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:18:00.764048 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:18:01.690371 | 
2026-05-20 01:18:01.690764 | PLAY [all]
2026-05-20 01:18:01.725708 | 
2026-05-20 01:18:01.725854 | TASK [Fix the permissions of the zuul home directory]
2026-05-20 01:18:02.437710 | controller | changed
2026-05-20 01:18:02.444596 | 
2026-05-20 01:18:02.444684 | TASK [Gather minimum local MTU]
2026-05-20 01:18:02.633267 | controller | ok
2026-05-20 01:18:02.639563 | 
2026-05-20 01:18:02.639763 | TASK [Calculate external_bridge_mtu]
2026-05-20 01:18:02.734686 | controller | ok
2026-05-20 01:18:03.000079 | 
2026-05-20 01:18:03.000238 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-20 01:18:03.027798 | controller | skipping: Conditional result was False
2026-05-20 01:18:03.038977 | 
2026-05-20 01:18:03.039081 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-20 01:18:03.652207 | controller | ok: Runtime: 0:00:00.009416
2026-05-20 01:18:03.765512 | 
2026-05-20 01:18:03.765831 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-20 01:18:03.816348 | controller | skipping: Conditional result was False
2026-05-20 01:18:03.994581 | 
2026-05-20 01:18:03.994707 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-20 01:18:04.055729 | controller | skipping: Conditional result was False
2026-05-20 01:18:04.063902 | 
2026-05-20 01:18:04.064025 | TASK [configure-swap : Setup swap file on root device]
2026-05-20 01:18:04.138445 | controller | ok
2026-05-20 01:18:04.161730 | controller | included: /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-20 01:18:04.192602 | 
2026-05-20 01:18:04.193377 | TASK [configure-swap : Calculate required swap]
2026-05-20 01:18:04.407818 | controller | ok
2026-05-20 01:18:04.579095 | 
2026-05-20 01:18:04.579224 | TASK [configure-swap : Get root filesystem]
2026-05-20 01:18:04.894850 | controller | ext4
2026-05-20 01:18:05.169320 | controller | ok: Runtime: 0:00:00.011740
2026-05-20 01:18:05.175061 | 
2026-05-20 01:18:05.175134 | TASK [configure-swap : Save root filesystem]
2026-05-20 01:18:05.227785 | controller | ok
2026-05-20 01:18:05.234352 | 
2026-05-20 01:18:05.234426 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-20 01:18:05.269853 | controller | ok:
2026-05-20 01:18:05.418851 | controller | {
2026-05-20 01:18:05.418971 | controller |   "root_filesystem": "ext4"
2026-05-20 01:18:05.418997 | controller | }
2026-05-20 01:18:05.431145 | 
2026-05-20 01:18:05.431230 | TASK [configure-swap : Create swap backing file]
2026-05-20 01:18:15.234985 | controller | 8192+0 records in
2026-05-20 01:18:15.235141 | controller | 8192+0 records out
2026-05-20 01:18:15.235154 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.45903 s, 908 MB/s
2026-05-20 01:18:15.525219 | controller | ok: Runtime: 0:00:09.468476
2026-05-20 01:18:15.531795 | 
2026-05-20 01:18:15.531900 | TASK [configure-swap : Ensure swapfile perms]
2026-05-20 01:18:16.303217 | controller | changed
2026-05-20 01:18:16.311631 | 
2026-05-20 01:18:16.311743 | TASK [configure-swap : Make swapfile]
2026-05-20 01:18:50.477304 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-20 01:18:50.477465 | controller | no label, UUID=eec166d6-cb01-4f1e-b710-cee3437069c4
2026-05-20 01:18:50.884143 | controller | ok: Runtime: 0:00:33.901768
2026-05-20 01:18:50.890344 | 
2026-05-20 01:18:50.890489 | TASK [configure-swap : Write swap to fstab]
2026-05-20 01:18:51.334234 | controller | changed
2026-05-20 01:18:51.358745 | 
2026-05-20 01:18:51.358864 | TASK [configure-swap : Add all swap]
2026-05-20 01:18:51.937837 | controller | ok: Runtime: 0:00:00.066284
2026-05-20 01:18:52.021075 | 
2026-05-20 01:18:52.021222 | TASK [configure-swap : Debug the swap_required variable]
2026-05-20 01:18:52.079338 | controller | ok:
2026-05-20 01:18:52.079632 | controller | {
2026-05-20 01:18:52.079665 | controller |   "swap_required": "8192"
2026-05-20 01:18:52.079687 | controller | }
2026-05-20 01:18:52.087287 | 
2026-05-20 01:18:52.087371 | TASK [configure-swap : Set swappiness]
2026-05-20 01:18:52.573940 | controller | changed
2026-05-20 01:18:52.588938 | 
2026-05-20 01:18:52.589217 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-20 01:18:52.664203 | controller | ok:
2026-05-20 01:18:52.664414 | controller | {
2026-05-20 01:18:52.664441 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-20 01:18:52.664466 | controller | }
2026-05-20 01:18:52.687416 | 
2026-05-20 01:18:52.687651 | TASK [setup-stack-user : Create stack group]
2026-05-20 01:18:53.452388 | controller | changed
2026-05-20 01:18:53.464408 | 
2026-05-20 01:18:53.464533 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-20 01:18:53.800853 | controller | changed
2026-05-20 01:18:53.816645 | 
2026-05-20 01:18:53.816880 | TASK [setup-stack-user : Create stack user]
2026-05-20 01:18:54.549034 | controller | changed
2026-05-20 01:18:54.557507 | 
2026-05-20 01:18:54.557634 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-20 01:18:54.801817 | controller | changed
2026-05-20 01:18:54.835493 | 
2026-05-20 01:18:54.835593 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-20 01:18:56.227330 | controller | changed
2026-05-20 01:18:56.233932 | 
2026-05-20 01:18:56.233996 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-20 01:18:56.530612 | controller | changed
2026-05-20 01:18:56.553355 | 
2026-05-20 01:18:56.553470 | TASK [setup-tempest-user : Create tempest group]
2026-05-20 01:18:56.849975 | controller | changed
2026-05-20 01:18:56.855431 | 
2026-05-20 01:18:56.855500 | TASK [setup-tempest-user : Create tempest user]
2026-05-20 01:18:57.251911 | controller | changed
2026-05-20 01:18:57.308669 | 
2026-05-20 01:18:57.308811 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-20 01:18:58.145696 | controller | changed
2026-05-20 01:18:58.165775 | 
2026-05-20 01:18:58.165906 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-20 01:18:58.487522 | controller | ok: Not all paths examined, check warnings for details
2026-05-20 01:18:58.507732 | 
2026-05-20 01:18:58.507853 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-20 01:18:59.026579 | controller | ok: Item: Runtime: 0:00:00.202555
2026-05-20 01:18:59.394663 | controller | ok: Item: Runtime: 0:00:00.138286
2026-05-20 01:18:59.674501 | controller | ok: Item: Runtime: 0:00:00.065565
2026-05-20 01:19:00.153984 | controller | ok: Item: Runtime: 0:00:00.249794
2026-05-20 01:19:00.554679 | controller | ok: Item: Runtime: 0:00:00.156792
2026-05-20 01:19:01.079849 | controller | ok: Item: Runtime: 0:00:00.303660
2026-05-20 01:19:01.795561 | controller | ok: Item: Runtime: 0:00:00.496302
2026-05-20 01:19:02.927120 | controller | ok: Item: Runtime: 0:00:00.599941
2026-05-20 01:19:04.036578 | controller | ok: Item: Runtime: 0:00:00.552687
2026-05-20 01:19:05.456383 | controller | ok: Item: Runtime: 0:00:01.204783
2026-05-20 01:19:05.770785 | controller | ok: Item: Runtime: 0:00:00.104079
2026-05-20 01:19:07.217260 | controller | ok: Item: Runtime: 0:00:00.221294
2026-05-20 01:19:07.817661 | controller | ok: Item: Runtime: 0:00:00.364030
2026-05-20 01:19:11.387250 | controller | ok: Item: Runtime: 0:00:03.382569
2026-05-20 01:19:12.920005 | controller | ok: Item: Runtime: 0:00:01.309950
2026-05-20 01:19:13.477793 | controller | ok: Item: Runtime: 0:00:00.300666
2026-05-20 01:19:13.507277 | 
2026-05-20 01:19:13.507400 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-20 01:19:14.301520 | controller | ok: All paths examined
2026-05-20 01:19:14.372302 | 
2026-05-20 01:19:14.372429 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-20 01:19:14.638230 | controller | ok: All paths examined
2026-05-20 01:19:14.647363 | 
2026-05-20 01:19:14.647441 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-20 01:19:15.112672 | controller | ok: Item: Runtime: 0:00:00.147164
2026-05-20 01:19:15.114730 | controller | changed: All items complete
2026-05-20 01:19:15.116265 | 
2026-05-20 01:19:15.528004 | controller | ok: Item: Runtime: 0:00:00.215231
2026-05-20 01:19:15.605704 | 
2026-05-20 01:19:15.605862 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-20 01:19:15.836940 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.845749 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.851495 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.858797 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.864339 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.869619 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.876099 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.881500 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.887504 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.897288 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.901470 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.907245 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.919498 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.943983 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.948023 | controller | skipping: Conditional result was False
2026-05-20 01:19:15.951674 | controller | skipping: Conditional result was False
2026-05-20 01:19:16.085774 | 
2026-05-20 01:19:16.085904 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-20 01:19:18.482733 | controller | changed
2026-05-20 01:19:18.503038 | 
2026-05-20 01:19:18.503204 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-20 01:19:18.799544 | controller | changed
2026-05-20 01:19:18.840650 | 
2026-05-20 01:19:18.840908 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-20 01:19:19.157492 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-20 01:19:19.400991 | controller | ERROR
2026-05-20 01:19:19.401184 | controller | {
2026-05-20 01:19:19.401236 | controller |   "delta": "0:00:00.009347",
2026-05-20 01:19:19.401264 | controller |   "end": "2026-05-20 01:19:19.158039",
2026-05-20 01:19:19.401286 | controller |   "msg": "non-zero return code",
2026-05-20 01:19:19.401315 | controller |   "rc": 1,
2026-05-20 01:19:19.401338 | controller |   "start": "2026-05-20 01:19:19.148692"
2026-05-20 01:19:19.401363 | controller | }
2026-05-20 01:19:19.401394 | controller | ERROR: Ignoring Errors
2026-05-20 01:19:19.410965 | 
2026-05-20 01:19:19.411437 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-20 01:19:19.713133 | controller | ok
2026-05-20 01:19:20.107173 | 
2026-05-20 01:19:20.107322 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-20 01:19:20.369909 | controller | /usr/bin/journalctl
2026-05-20 01:19:20.666474 | controller | ok: Runtime: 0:00:00.007453
2026-05-20 01:19:20.686210 | 
2026-05-20 01:19:20.686331 | TASK [start-fresh-logging : Get current date]
2026-05-20 01:19:20.998452 | controller | 2026-05-20 01:19:20
2026-05-20 01:19:21.467821 | controller | ok: Runtime: 0:00:00.009143
2026-05-20 01:19:21.477608 | 
2026-05-20 01:19:21.477857 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-20 01:19:22.104822 | controller | changed
2026-05-20 01:19:22.119010 | 
2026-05-20 01:19:22.119117 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-20 01:19:22.149880 | controller | skipping: Conditional result was False
2026-05-20 01:19:22.158647 | 
2026-05-20 01:19:22.158771 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-20 01:19:22.764915 | controller | skipping: Conditional result was False
2026-05-20 01:19:22.771376 | 
2026-05-20 01:19:22.771500 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-20 01:19:24.586828 | controller | skipping: Conditional result was False
2026-05-20 01:19:24.859737 | 
2026-05-20 01:19:24.859856 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-20 01:19:24.886540 | controller | skipping: Conditional result was False
2026-05-20 01:19:24.892705 | 
2026-05-20 01:19:24.892805 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-20 01:19:25.435334 | controller | skipping: Conditional result was False
2026-05-20 01:19:25.450257 | 
2026-05-20 01:19:25.450412 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-20 01:19:26.118850 | controller | skipping: Conditional result was False
2026-05-20 01:19:26.126195 | 
2026-05-20 01:19:26.126422 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-20 01:19:26.185497 | controller | skipping: Conditional result was False
2026-05-20 01:19:26.192298 | 
2026-05-20 01:19:26.192426 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-20 01:19:26.229815 | controller | skipping: Conditional result was False
2026-05-20 01:19:26.238644 | 
2026-05-20 01:19:26.238814 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-20 01:19:26.810015 | controller | skipping: Conditional result was False
2026-05-20 01:19:27.063205 | 
2026-05-20 01:19:27.063331 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-20 01:19:27.646889 | controller | skipping: Conditional result was False
2026-05-20 01:19:27.656506 | 
2026-05-20 01:19:27.656694 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-20 01:19:27.683548 | controller | skipping: Conditional result was False
2026-05-20 01:19:27.694629 | 
2026-05-20 01:19:27.694807 | TASK [start-fresh-logging : Start rsyslog]
2026-05-20 01:19:27.731935 | controller | skipping: Conditional result was False
2026-05-20 01:19:27.758384 | 
2026-05-20 01:19:27.758571 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-20 01:19:28.284058 | controller | ok
2026-05-20 01:19:28.336693 | 
2026-05-20 01:19:28.336814 | PLAY RECAP
2026-05-20 01:19:28.336877 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-20 01:19:28.336911 | 
2026-05-20 01:19:28.749719 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:19:28.799173 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:19:29.917841 | 
2026-05-20 01:19:29.917954 | PLAY [all]
2026-05-20 01:19:29.929419 | 
2026-05-20 01:19:29.929506 | TASK [Install curl]
2026-05-20 01:20:11.031406 | controller | ok
2026-05-20 01:20:11.174647 | 
2026-05-20 01:20:11.174852 | TASK [ensure-rust : Use rustup]
2026-05-20 01:20:11.221301 | controller | ok
2026-05-20 01:20:11.235680 | controller | included: /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-05-20 01:20:11.249389 | 
2026-05-20 01:20:11.252239 | TASK [ensure-rust : Install Rust]
2026-05-20 01:20:11.838438 | controller | info: downloading installer
2026-05-20 01:20:12.150891 | controller | warn: It looks like you have an existing rustup settings file at:
2026-05-20 01:20:12.150947 | controller | warn: /opt/rust/settings.toml
2026-05-20 01:20:12.150958 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-05-20 01:20:12.150974 | controller | warn: instead of the one inferred from the default host triple.
2026-05-20 01:20:12.181128 | controller | info: profile set to default
2026-05-20 01:20:12.181185 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-05-20 01:20:12.183099 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-05-20 01:20:12.429832 | controller | info: latest update on 2026-04-16 for version 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:20:12.429909 | controller | info: downloading 6 components
2026-05-20 01:20:25.848795 | controller | info: default toolchain set to stable-x86_64-unknown-linux-gnu
2026-05-20 01:20:25.848877 | controller |
2026-05-20 01:20:25.868316 | controller |   stable-x86_64-unknown-linux-gnu installed - rustc 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:20:25.868353 | controller |
2026-05-20 01:20:25.868371 | controller |
2026-05-20 01:20:25.868377 | controller | Rust is installed now. Great!
2026-05-20 01:20:25.868382 | controller |
2026-05-20 01:20:25.868388 | controller | To get started you need Cargo's bin directory (/opt/rust/bin) in your PATH
2026-05-20 01:20:25.868393 | controller | environment variable. This has not been done automatically.
2026-05-20 01:20:25.868398 | controller |
2026-05-20 01:20:25.868404 | controller | To configure your current shell, you need to source
2026-05-20 01:20:25.868409 | controller | the corresponding env file under /opt/rust.
2026-05-20 01:20:25.868414 | controller |
2026-05-20 01:20:25.868427 | controller | This is usually done by running one of the following (note the leading DOT):
2026-05-20 01:20:25.868434 | controller | . "/opt/rust/env"            # For sh/bash/zsh/ash/dash/pdksh
2026-05-20 01:20:25.868440 | controller | source "/opt/rust/env.fish"  # For fish
2026-05-20 01:20:25.868446 | controller | source "/opt/rust/env.nu"  # For nushell
2026-05-20 01:20:25.868452 | controller | source "/opt/rust/env.tcsh"  # For tcsh
2026-05-20 01:20:25.868457 | controller | . "/opt/rust/env.ps1"        # For pwsh
2026-05-20 01:20:25.868462 | controller | source "/opt/rust/env.xsh"   # For xonsh
2026-05-20 01:20:25.868514 | controller | warn: no default linker (`cc`) was found in your PATH
2026-05-20 01:20:25.868532 | controller | warn: many Rust crates require a system C toolchain to build
2026-05-20 01:20:26.308564 | controller | ok: Runtime: 0:00:14.181250
2026-05-20 01:20:26.315297 | 
2026-05-20 01:20:26.315380 | TASK [ensure-rust : Install wrapper helper script]
2026-05-20 01:20:27.260781 | controller | changed
2026-05-20 01:20:27.267653 | 
2026-05-20 01:20:27.267767 | TASK [ensure-rust : Run wrapper installation]
2026-05-20 01:20:27.548394 | controller | Install link for cargo
2026-05-20 01:20:27.552847 | controller | Install link for cargo-clippy
2026-05-20 01:20:27.557631 | controller | Install link for cargo-fmt
2026-05-20 01:20:27.561784 | controller | Install link for cargo-miri
2026-05-20 01:20:27.568297 | controller | Install link for clippy-driver
2026-05-20 01:20:27.572819 | controller | Install link for rls
2026-05-20 01:20:27.577056 | controller | Install link for rust-analyzer
2026-05-20 01:20:27.581597 | controller | Install link for rust-gdb
2026-05-20 01:20:27.587764 | controller | Install link for rust-gdbgui
2026-05-20 01:20:27.593868 | controller | Install link for rust-lldb
2026-05-20 01:20:27.598008 | controller | Install link for rustc
2026-05-20 01:20:27.602409 | controller | Install link for rustdoc
2026-05-20 01:20:27.607714 | controller | Install link for rustfmt
2026-05-20 01:20:27.613184 | controller | Install link for rustup
2026-05-20 01:20:27.819765 | controller | ok: Runtime: 0:00:00.086228
2026-05-20 01:20:27.832897 | 
2026-05-20 01:20:27.833032 | TASK [ensure-rust : Install packages]
2026-05-20 01:20:27.857830 | controller | skipping: Conditional result was False
2026-05-20 01:20:27.937492 | 
2026-05-20 01:20:27.938493 | PLAY RECAP
2026-05-20 01:20:27.938550 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:20:27.938574 | 
2026-05-20 01:20:28.145982 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:20:28.158745 | RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:20:28.990595 | 
2026-05-20 01:20:28.990793 | PLAY [all]
2026-05-20 01:20:29.027121 | 
2026-05-20 01:20:29.027302 | TASK [Install DevStack]
2026-05-20 01:20:29.067315 | controller | ok
2026-05-20 01:20:29.156502 | 
2026-05-20 01:20:29.156675 | TASK [Run devstack on the controller]
2026-05-20 01:20:29.178539 | controller | ok
2026-05-20 01:20:29.256154 | 
2026-05-20 01:20:29.258935 | TASK [run-devstack : Run devstack]
2026-05-20 01:20:30.420669 | controller | + unset GREP_OPTIONS
2026-05-20 01:20:30.420800 | controller | + unset LANG
2026-05-20 01:20:30.420814 | controller | + unset LANGUAGE
2026-05-20 01:20:30.420825 | controller | + LC_ALL=en_US.utf8
2026-05-20 01:20:30.425653 | controller | + export LC_ALL
2026-05-20 01:20:30.427497 | controller | ++ env
2026-05-20 01:20:30.427775 | controller | ++ grep -E '^OS_'
2026-05-20 01:20:30.428110 | controller | ++ cut -d = -f 1
2026-05-20 01:20:30.433517 | controller | + unset
2026-05-20 01:20:30.433576 | controller | + umask 022
2026-05-20 01:20:30.433601 | controller | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/usr/local/bin:/usr/local/sbin:/usr/sbin:/sbin
2026-05-20 01:20:30.435427 | controller | +++ dirname ./stack.sh
2026-05-20 01:20:30.439024 | controller | ++ cd .
2026-05-20 01:20:30.439060 | controller | ++ pwd
2026-05-20 01:20:30.439676 | controller | + TOP_DIR=/opt/stack/devstack
2026-05-20 01:20:30.439711 | controller | + NOUNSET=
2026-05-20 01:20:30.439721 | controller | + [[ -n '' ]]
2026-05-20 01:20:30.440515 | controller | ++ date +%s
2026-05-20 01:20:30.442609 | controller | + DEVSTACK_START_TIME=1779240030
2026-05-20 01:20:30.442643 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-05-20 01:20:30.442661 | controller | + FILES=/opt/stack/devstack/files
2026-05-20 01:20:30.442671 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-05-20 01:20:30.442680 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-05-20 01:20:30.442693 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-05-20 01:20:30.442706 | controller | + [[ '' == \y ]]
2026-05-20 01:20:30.442752 | controller | + [[ 1002 -eq 0 ]]
2026-05-20 01:20:30.442807 | controller | + [[ -n '' ]]
2026-05-20 01:20:30.442818 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-05-20 01:20:30.442831 | controller | + LAST_SPINNER_PID=
2026-05-20 01:20:30.442840 | controller | + source /opt/stack/devstack/functions
2026-05-20 01:20:30.442950 | controller | ++ [[ -z '' ]]
2026-05-20 01:20:30.442961 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-05-20 01:20:30.445260 | controller | ++++ dirname /opt/stack/devstack/functions
2026-05-20 01:20:30.447758 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:20:30.447796 | controller | +++ pwd
2026-05-20 01:20:30.448454 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-05-20 01:20:30.448480 | controller | ++ source /opt/stack/devstack/functions-common
2026-05-20 01:20:30.450128 | controller | ++++ set +o
2026-05-20 01:20:30.450437 | controller | ++++ grep xtrace
2026-05-20 01:20:30.453479 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-05-20 01:20:30.453512 | controller | +++ set +o xtrace
2026-05-20 01:20:30.465036 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-05-20 01:20:30.466761 | controller | ++++ set +o
2026-05-20 01:20:30.466830 | controller | ++++ grep xtrace
2026-05-20 01:20:30.469304 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-05-20 01:20:30.469337 | controller | +++ set +o xtrace
2026-05-20 01:20:30.470571 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-05-20 01:20:30.471967 | controller | ++++ set +o
2026-05-20 01:20:30.472082 | controller | ++++ grep xtrace
2026-05-20 01:20:30.475396 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-05-20 01:20:30.475428 | controller | +++ set +o xtrace
2026-05-20 01:20:30.475930 | controller | ++ source /opt/stack/devstack/inc/python
2026-05-20 01:20:30.477781 | controller | ++++ set +o
2026-05-20 01:20:30.477948 | controller | ++++ grep xtrace
2026-05-20 01:20:30.481266 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-05-20 01:20:30.481327 | controller | +++ set +o xtrace
2026-05-20 01:20:30.483158 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-05-20 01:20:30.485215 | controller | ++++ set +o
2026-05-20 01:20:30.485635 | controller | ++++ grep xtrace
2026-05-20 01:20:30.489230 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-05-20 01:20:30.489291 | controller | +++ set +o xtrace
2026-05-20 01:20:30.489848 | controller | ++ source /opt/stack/devstack/inc/async
2026-05-20 01:20:30.491122 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-05-20 01:20:30.491270 | controller | ++++ local xtrace
2026-05-20 01:20:30.493025 | controller | +++++ set +o
2026-05-20 01:20:30.493399 | controller | +++++ grep xtrace
2026-05-20 01:20:30.497077 | controller | ++++ xtrace='set -o xtrace'
2026-05-20 01:20:30.497092 | controller | ++++ set +o xtrace
2026-05-20 01:20:30.498063 | controller | +++ DEVSTACK_PARALLEL=True
2026-05-20 01:20:30.498098 | controller | +++ _ASYNC_BG_TIME=0
2026-05-20 01:20:30.501052 | controller | +++ set +o
2026-05-20 01:20:30.501252 | controller | +++ grep xtrace
2026-05-20 01:20:30.504200 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-05-20 01:20:30.504279 | controller | ++ set +o xtrace
2026-05-20 01:20:30.508175 | controller | + source /opt/stack/devstack/lib/stack
2026-05-20 01:20:30.508367 | controller | + GetDistro
2026-05-20 01:20:30.508408 | controller | + GetOSVersion
2026-05-20 01:20:30.508415 | controller | + source /etc/os-release
2026-05-20 01:20:30.508482 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-05-20 01:20:30.508488 | controller | ++ NAME=Ubuntu
2026-05-20 01:20:30.508495 | controller | ++ VERSION_ID=24.04
2026-05-20 01:20:30.508499 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-05-20 01:20:30.508506 | controller | ++ VERSION_CODENAME=noble
2026-05-20 01:20:30.508513 | controller | ++ ID=ubuntu
2026-05-20 01:20:30.508520 | controller | ++ ID_LIKE=debian
2026-05-20 01:20:30.508571 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-05-20 01:20:30.508581 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-05-20 01:20:30.508588 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-05-20 01:20:30.508614 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-05-20 01:20:30.508623 | controller | ++ UBUNTU_CODENAME=noble
2026-05-20 01:20:30.508628 | controller | ++ LOGO=ubuntu-logo
2026-05-20 01:20:30.508657 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-05-20 01:20:30.508724 | controller | + _ensure_lsb_release
2026-05-20 01:20:30.509775 | controller | ++ command -v lsb_release
2026-05-20 01:20:30.510390 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-05-20 01:20:30.510397 | controller | + return
2026-05-20 01:20:30.511392 | controller | ++ lsb_release -r -s
2026-05-20 01:20:30.531968 | controller | + os_RELEASE=24.04
2026-05-20 01:20:30.533109 | controller | ++ lsb_release -c -s
2026-05-20 01:20:30.552888 | controller | + os_CODENAME=noble
2026-05-20 01:20:30.554033 | controller | ++ lsb_release -i -s
2026-05-20 01:20:30.573981 | controller | + os_VENDOR=Ubuntu
2026-05-20 01:20:30.574021 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:20:30.574126 | controller | + os_PACKAGE=deb
2026-05-20 01:20:30.574136 | controller | + typeset -xr os_VENDOR
2026-05-20 01:20:30.574166 | controller | + typeset -xr os_RELEASE
2026-05-20 01:20:30.574174 | controller | + typeset -xr os_PACKAGE
2026-05-20 01:20:30.574199 | controller | + typeset -xr os_CODENAME
2026-05-20 01:20:30.574283 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-05-20 01:20:30.574321 | controller | + DISTRO=noble
2026-05-20 01:20:30.574329 | controller | + typeset -xr DISTRO
2026-05-20 01:20:30.574456 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:30.577294 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:30.577375 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-05-20 01:20:30.577384 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-05-20 01:20:30.577391 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-05-20 01:20:30.577413 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:30.578451 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-05-20 01:20:30.578622 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-05-20 01:20:30.578636 | controller | ++ local matchgroup=local
2026-05-20 01:20:30.578643 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:30.578734 | controller | ++ awk -v matchgroup=local '
2026-05-20 01:20:30.578739 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:20:30.578744 | controller |             gsub("[][]", "", $1);
2026-05-20 01:20:30.578749 | controller |             split($1, a, "|");
2026-05-20 01:20:30.578753 | controller |             if (a[1] == matchgroup)
2026-05-20 01:20:30.578758 | controller |                 print a[2]
2026-05-20 01:20:30.578762 | controller |         }
2026-05-20 01:20:30.578767 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:20:30.581222 | controller | + LRC=localrc
2026-05-20 01:20:30.581240 | controller | + for lfile in $LRC
2026-05-20 01:20:30.581266 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-05-20 01:20:30.581276 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-05-20 01:20:30.581345 | controller | + echo '# Generated file, do not edit'
2026-05-20 01:20:30.581584 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-05-20 01:20:30.581704 | controller | + local file=/opt/stack/devstack/local.conf
2026-05-20 01:20:30.581712 | controller | + local matchgroup=local
2026-05-20 01:20:30.581717 | controller | + local configfile=localrc
2026-05-20 01:20:30.581722 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:30.581729 | controller | + [[ -z localrc ]]
2026-05-20 01:20:30.581794 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-05-20 01:20:30.581799 | controller |         BEGIN { group = "" }
2026-05-20 01:20:30.581805 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:20:30.581834 | controller |             gsub("[][]", "", $1);
2026-05-20 01:20:30.581839 | controller |             split($1, a, "|");
2026-05-20 01:20:30.581844 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-05-20 01:20:30.581849 | controller |                 group=a[1]
2026-05-20 01:20:30.581853 | controller |             } else {
2026-05-20 01:20:30.581858 | controller |                 group=""
2026-05-20 01:20:30.581862 | controller |             }
2026-05-20 01:20:30.581867 | controller |             next
2026-05-20 01:20:30.581871 | controller |         }
2026-05-20 01:20:30.581876 | controller |         {
2026-05-20 01:20:30.581880 | controller |             if (group != "")
2026-05-20 01:20:30.581885 | controller |                 print $0
2026-05-20 01:20:30.581889 | controller |         }
2026-05-20 01:20:30.581894 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:20:30.585203 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-05-20 01:20:30.585215 | controller | + source /opt/stack/devstack/stackrc
2026-05-20 01:20:30.585359 | controller | ++ [[ -z '' ]]
2026-05-20 01:20:30.585369 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-05-20 01:20:30.587858 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-05-20 01:20:30.590064 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:20:30.590145 | controller | +++ pwd
2026-05-20 01:20:30.591020 | controller | ++ RC_DIR=/opt/stack/devstack
2026-05-20 01:20:30.591082 | controller | ++ source /opt/stack/devstack/functions
2026-05-20 01:20:30.591266 | controller | +++ [[ -z 1 ]]
2026-05-20 01:20:30.591272 | controller | +++ return 0
2026-05-20 01:20:30.591312 | controller | ++ TARGET_BRANCH=master
2026-05-20 01:20:30.591320 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-05-20 01:20:30.591325 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-05-20 01:20:30.591332 | controller | ++ DEST=/opt/stack
2026-05-20 01:20:30.591340 | controller | ++ DATA_DIR=/opt/stack/data
2026-05-20 01:20:30.591364 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-05-20 01:20:30.591372 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-05-20 01:20:30.591428 | controller | ++ [[ 1002 -eq 0 ]]
2026-05-20 01:20:30.592551 | controller | +++ whoami
2026-05-20 01:20:30.596445 | controller | ++ STACK_USER=stack
2026-05-20 01:20:30.596549 | controller | ++ REGION_NAME=RegionOne
2026-05-20 01:20:30.596561 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-05-20 01:20:30.596670 | controller | ++ isset ENABLED_SERVICES
2026-05-20 01:20:30.596695 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-05-20 01:20:30.596703 | controller | ++ ENABLED_SERVICES=key
2026-05-20 01:20:30.596725 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-05-20 01:20:30.596730 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-05-20 01:20:30.596738 | controller | ++ ENABLED_SERVICES+=,g-api
2026-05-20 01:20:30.596742 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-05-20 01:20:30.596750 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-05-20 01:20:30.596755 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-05-20 01:20:30.596762 | controller | ++ ENABLED_SERVICES+=,horizon
2026-05-20 01:20:30.596767 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-05-20 01:20:30.596799 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-05-20 01:20:30.596805 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-05-20 01:20:30.596847 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-05-20 01:20:30.596855 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-05-20 01:20:30.596877 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:30.596946 | controller | +++ disable_all_services
2026-05-20 01:20:30.596955 | controller | +++ ENABLED_SERVICES=
2026-05-20 01:20:30.596977 | controller | +++ enable_service c-api
2026-05-20 01:20:30.597028 | controller | +++ local xtrace
2026-05-20 01:20:30.598762 | controller | ++++ set +o
2026-05-20 01:20:30.599214 | controller | ++++ grep xtrace
2026-05-20 01:20:30.603032 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.603046 | controller | +++ set +o xtrace
2026-05-20 01:20:30.641542 | controller | +++ enable_service c-bak
2026-05-20 01:20:30.641568 | controller | +++ local xtrace
2026-05-20 01:20:30.643664 | controller | ++++ set +o
2026-05-20 01:20:30.644203 | controller | ++++ grep xtrace
2026-05-20 01:20:30.648409 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.648430 | controller | +++ set +o xtrace
2026-05-20 01:20:30.689018 | controller | +++ enable_service c-sch
2026-05-20 01:20:30.689033 | controller | +++ local xtrace
2026-05-20 01:20:30.690825 | controller | ++++ set +o
2026-05-20 01:20:30.691247 | controller | ++++ grep xtrace
2026-05-20 01:20:30.694781 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.694800 | controller | +++ set +o xtrace
2026-05-20 01:20:30.733040 | controller | +++ enable_service c-vol
2026-05-20 01:20:30.733059 | controller | +++ local xtrace
2026-05-20 01:20:30.735742 | controller | ++++ set +o
2026-05-20 01:20:30.735799 | controller | ++++ grep xtrace
2026-05-20 01:20:30.739255 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.739288 | controller | +++ set +o xtrace
2026-05-20 01:20:30.780981 | controller | +++ disable_service dstat
2026-05-20 01:20:30.781015 | controller | +++ local xtrace
2026-05-20 01:20:30.782791 | controller | ++++ set +o
2026-05-20 01:20:30.783093 | controller | ++++ grep xtrace
2026-05-20 01:20:30.785791 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.785850 | controller | +++ set +o xtrace
2026-05-20 01:20:30.812394 | controller | +++ enable_service etcd3
2026-05-20 01:20:30.812429 | controller | +++ local xtrace
2026-05-20 01:20:30.814088 | controller | ++++ set +o
2026-05-20 01:20:30.814577 | controller | ++++ grep xtrace
2026-05-20 01:20:30.818320 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.818342 | controller | +++ set +o xtrace
2026-05-20 01:20:30.858328 | controller | +++ enable_service file_tracker
2026-05-20 01:20:30.858361 | controller | +++ local xtrace
2026-05-20 01:20:30.861340 | controller | ++++ set +o
2026-05-20 01:20:30.861372 | controller | ++++ grep xtrace
2026-05-20 01:20:30.865224 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.865257 | controller | +++ set +o xtrace
2026-05-20 01:20:30.908254 | controller | +++ enable_service g-api
2026-05-20 01:20:30.908278 | controller | +++ local xtrace
2026-05-20 01:20:30.910300 | controller | ++++ set +o
2026-05-20 01:20:30.910669 | controller | ++++ grep xtrace
2026-05-20 01:20:30.913014 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.913033 | controller | +++ set +o xtrace
2026-05-20 01:20:30.953590 | controller | +++ disable_service horizon
2026-05-20 01:20:30.953615 | controller | +++ local xtrace
2026-05-20 01:20:30.955498 | controller | ++++ set +o
2026-05-20 01:20:30.955884 | controller | ++++ grep xtrace
2026-05-20 01:20:30.959466 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.959517 | controller | +++ set +o xtrace
2026-05-20 01:20:30.989230 | controller | +++ enable_service key
2026-05-20 01:20:30.989267 | controller | +++ local xtrace
2026-05-20 01:20:30.991416 | controller | ++++ set +o
2026-05-20 01:20:30.991432 | controller | ++++ grep xtrace
2026-05-20 01:20:30.995056 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:30.995108 | controller | +++ set +o xtrace
2026-05-20 01:20:31.041495 | controller | +++ enable_service memory_tracker
2026-05-20 01:20:31.041597 | controller | +++ local xtrace
2026-05-20 01:20:31.043373 | controller | ++++ set +o
2026-05-20 01:20:31.043737 | controller | ++++ grep xtrace
2026-05-20 01:20:31.047251 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.047284 | controller | +++ set +o xtrace
2026-05-20 01:20:31.089698 | controller | +++ enable_service mysql
2026-05-20 01:20:31.089765 | controller | +++ local xtrace
2026-05-20 01:20:31.091427 | controller | ++++ set +o
2026-05-20 01:20:31.091754 | controller | ++++ grep xtrace
2026-05-20 01:20:31.095324 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.095345 | controller | +++ set +o xtrace
2026-05-20 01:20:31.137197 | controller | +++ enable_service n-api
2026-05-20 01:20:31.137277 | controller | +++ local xtrace
2026-05-20 01:20:31.139048 | controller | ++++ set +o
2026-05-20 01:20:31.139492 | controller | ++++ grep xtrace
2026-05-20 01:20:31.143488 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.143504 | controller | +++ set +o xtrace
2026-05-20 01:20:31.182209 | controller | +++ enable_service n-api-meta
2026-05-20 01:20:31.182230 | controller | +++ local xtrace
2026-05-20 01:20:31.184303 | controller | ++++ set +o
2026-05-20 01:20:31.184320 | controller | ++++ grep xtrace
2026-05-20 01:20:31.186649 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.186665 | controller | +++ set +o xtrace
2026-05-20 01:20:31.221486 | controller | +++ enable_service n-cond
2026-05-20 01:20:31.221517 | controller | +++ local xtrace
2026-05-20 01:20:31.223233 | controller | ++++ set +o
2026-05-20 01:20:31.223594 | controller | ++++ grep xtrace
2026-05-20 01:20:31.227032 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.227064 | controller | +++ set +o xtrace
2026-05-20 01:20:31.267782 | controller | +++ enable_service n-cpu
2026-05-20 01:20:31.267820 | controller | +++ local xtrace
2026-05-20 01:20:31.269468 | controller | ++++ set +o
2026-05-20 01:20:31.269868 | controller | ++++ grep xtrace
2026-05-20 01:20:31.273306 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.273341 | controller | +++ set +o xtrace
2026-05-20 01:20:31.312827 | controller | +++ enable_service n-novnc
2026-05-20 01:20:31.312877 | controller | +++ local xtrace
2026-05-20 01:20:31.314810 | controller | ++++ set +o
2026-05-20 01:20:31.315185 | controller | ++++ grep xtrace
2026-05-20 01:20:31.319099 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.319132 | controller | +++ set +o xtrace
2026-05-20 01:20:31.362195 | controller | +++ enable_service n-sch
2026-05-20 01:20:31.362223 | controller | +++ local xtrace
2026-05-20 01:20:31.364238 | controller | ++++ set +o
2026-05-20 01:20:31.364566 | controller | ++++ grep xtrace
2026-05-20 01:20:31.367103 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.367135 | controller | +++ set +o xtrace
2026-05-20 01:20:31.410117 | controller | +++ enable_service o-api
2026-05-20 01:20:31.410167 | controller | +++ local xtrace
2026-05-20 01:20:31.411760 | controller | ++++ set +o
2026-05-20 01:20:31.412061 | controller | ++++ grep xtrace
2026-05-20 01:20:31.415360 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.415391 | controller | +++ set +o xtrace
2026-05-20 01:20:31.456217 | controller | +++ enable_service o-da
2026-05-20 01:20:31.456265 | controller | +++ local xtrace
2026-05-20 01:20:31.458734 | controller | ++++ set +o
2026-05-20 01:20:31.458820 | controller | ++++ grep xtrace
2026-05-20 01:20:31.462384 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.462408 | controller | +++ set +o xtrace
2026-05-20 01:20:31.502417 | controller | +++ enable_service o-hk
2026-05-20 01:20:31.502442 | controller | +++ local xtrace
2026-05-20 01:20:31.504117 | controller | ++++ set +o
2026-05-20 01:20:31.504724 | controller | ++++ grep xtrace
2026-05-20 01:20:31.508008 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.508034 | controller | +++ set +o xtrace
2026-05-20 01:20:31.547755 | controller | +++ enable_service octavia
2026-05-20 01:20:31.547790 | controller | +++ local xtrace
2026-05-20 01:20:31.549664 | controller | ++++ set +o
2026-05-20 01:20:31.550146 | controller | ++++ grep xtrace
2026-05-20 01:20:31.553545 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.553612 | controller | +++ set +o xtrace
2026-05-20 01:20:31.594479 | controller | +++ enable_service openstack-cli-server
2026-05-20 01:20:31.594503 | controller | +++ local xtrace
2026-05-20 01:20:31.596427 | controller | ++++ set +o
2026-05-20 01:20:31.597050 | controller | ++++ grep xtrace
2026-05-20 01:20:31.600629 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.600668 | controller | +++ set +o xtrace
2026-05-20 01:20:31.637919 | controller | +++ enable_service ovn-controller
2026-05-20 01:20:31.637952 | controller | +++ local xtrace
2026-05-20 01:20:31.639758 | controller | ++++ set +o
2026-05-20 01:20:31.639854 | controller | ++++ grep xtrace
2026-05-20 01:20:31.642752 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.642781 | controller | +++ set +o xtrace
2026-05-20 01:20:31.686199 | controller | +++ enable_service ovn-northd
2026-05-20 01:20:31.686239 | controller | +++ local xtrace
2026-05-20 01:20:31.687947 | controller | ++++ set +o
2026-05-20 01:20:31.688235 | controller | ++++ grep xtrace
2026-05-20 01:20:31.691389 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.691418 | controller | +++ set +o xtrace
2026-05-20 01:20:31.732607 | controller | +++ enable_service ovs-vswitchd
2026-05-20 01:20:31.732650 | controller | +++ local xtrace
2026-05-20 01:20:31.734763 | controller | ++++ set +o
2026-05-20 01:20:31.735211 | controller | ++++ grep xtrace
2026-05-20 01:20:31.738954 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.738988 | controller | +++ set +o xtrace
2026-05-20 01:20:31.782214 | controller | +++ enable_service ovsdb-server
2026-05-20 01:20:31.782271 | controller | +++ local xtrace
2026-05-20 01:20:31.783437 | controller | ++++ set +o
2026-05-20 01:20:31.783851 | controller | ++++ grep xtrace
2026-05-20 01:20:31.786840 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.786871 | controller | +++ set +o xtrace
2026-05-20 01:20:31.830345 | controller | +++ enable_service placement-api
2026-05-20 01:20:31.830411 | controller | +++ local xtrace
2026-05-20 01:20:31.832283 | controller | ++++ set +o
2026-05-20 01:20:31.832313 | controller | ++++ grep xtrace
2026-05-20 01:20:31.835404 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.835432 | controller | +++ set +o xtrace
2026-05-20 01:20:31.877600 | controller | +++ enable_service q-ovn-agent
2026-05-20 01:20:31.877639 | controller | +++ local xtrace
2026-05-20 01:20:31.879467 | controller | ++++ set +o
2026-05-20 01:20:31.879793 | controller | ++++ grep xtrace
2026-05-20 01:20:31.883175 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.883198 | controller | +++ set +o xtrace
2026-05-20 01:20:31.923087 | controller | +++ enable_service q-svc
2026-05-20 01:20:31.923119 | controller | +++ local xtrace
2026-05-20 01:20:31.924992 | controller | ++++ set +o
2026-05-20 01:20:31.925567 | controller | ++++ grep xtrace
2026-05-20 01:20:31.929237 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.929291 | controller | +++ set +o xtrace
2026-05-20 01:20:31.972059 | controller | +++ enable_service rabbit
2026-05-20 01:20:31.972091 | controller | +++ local xtrace
2026-05-20 01:20:31.973567 | controller | ++++ set +o
2026-05-20 01:20:31.973704 | controller | ++++ grep xtrace
2026-05-20 01:20:31.975772 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:31.975798 | controller | +++ set +o xtrace
2026-05-20 01:20:32.016126 | controller | +++ disable_service s-account
2026-05-20 01:20:32.016194 | controller | +++ local xtrace
2026-05-20 01:20:32.017757 | controller | ++++ set +o
2026-05-20 01:20:32.018085 | controller | ++++ grep xtrace
2026-05-20 01:20:32.020512 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.020558 | controller | +++ set +o xtrace
2026-05-20 01:20:32.046715 | controller | +++ disable_service s-container
2026-05-20 01:20:32.046745 | controller | +++ local xtrace
2026-05-20 01:20:32.048791 | controller | ++++ set +o
2026-05-20 01:20:32.048913 | controller | ++++ grep xtrace
2026-05-20 01:20:32.052267 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.052299 | controller | +++ set +o xtrace
2026-05-20 01:20:32.079063 | controller | +++ disable_service s-object
2026-05-20 01:20:32.079129 | controller | +++ local xtrace
2026-05-20 01:20:32.080671 | controller | ++++ set +o
2026-05-20 01:20:32.080703 | controller | ++++ grep xtrace
2026-05-20 01:20:32.082964 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.082997 | controller | +++ set +o xtrace
2026-05-20 01:20:32.108721 | controller | +++ disable_service s-proxy
2026-05-20 01:20:32.108789 | controller | +++ local xtrace
2026-05-20 01:20:32.110438 | controller | ++++ set +o
2026-05-20 01:20:32.110477 | controller | ++++ grep xtrace
2026-05-20 01:20:32.113795 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.113827 | controller | +++ set +o xtrace
2026-05-20 01:20:32.140738 | controller | +++ disable_service tempest
2026-05-20 01:20:32.140798 | controller | +++ local xtrace
2026-05-20 01:20:32.141972 | controller | ++++ set +o
2026-05-20 01:20:32.142141 | controller | ++++ grep xtrace
2026-05-20 01:20:32.144658 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.144670 | controller | +++ set +o xtrace
2026-05-20 01:20:32.171453 | controller | +++ enable_service tls-proxy
2026-05-20 01:20:32.171471 | controller | +++ local xtrace
2026-05-20 01:20:32.173755 | controller | ++++ set +o
2026-05-20 01:20:32.173870 | controller | ++++ grep xtrace
2026-05-20 01:20:32.176000 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.176044 | controller | +++ set +o xtrace
2026-05-20 01:20:32.214651 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-05-20 01:20:32.214735 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-05-20 01:20:32.214745 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-05-20 01:20:32.214754 | controller | +++ DISABLE_AMP_IMAGE_BUILD=True
2026-05-20 01:20:32.214771 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:20:32.214780 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:20:32.214788 | controller | +++ ENABLE_ZSWAP=True
2026-05-20 01:20:32.214797 | controller | +++ ERROR_ON_CLONE=True
2026-05-20 01:20:32.214806 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-05-20 01:20:32.214815 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:20:32.214823 | controller | +++ GIT_BASE=https://github.com
2026-05-20 01:20:32.214832 | controller | +++ HOST_IP=199.204.45.72
2026-05-20 01:20:32.214840 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:20:32.214849 | controller | +++ LIBVIRT_TYPE=qemu
2026-05-20 01:20:32.214857 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-05-20 01:20:32.214866 | controller | +++ LOG_COLOR=False
2026-05-20 01:20:32.214876 | controller | +++ MAGNUM_GUEST_IMAGE_URL=https://github.com/vexxhost/capo-image-elements/releases/latest/download/ubuntu-22.04-v1.35.4.qcow2
2026-05-20 01:20:32.214886 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS='snapshot_support=True create_share_from_snapshot_support=True'
2026-05-20 01:20:32.214894 | controller | +++ MANILA_ENABLED_BACKENDS=generic
2026-05-20 01:20:32.214903 | controller | +++ MANILA_USE_SERVICE_INSTANCE_PASSWORD=True
2026-05-20 01:20:32.214912 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-05-20 01:20:32.214920 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-05-20 01:20:32.214934 | controller | +++ NOVA_VNC_ENABLED=True
2026-05-20 01:20:32.214943 | controller | +++ OCTAVIA_NODE=api
2026-05-20 01:20:32.214951 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-05-20 01:20:32.214960 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-05-20 01:20:32.214968 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-05-20 01:20:32.214977 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-05-20 01:20:32.214985 | controller | +++ SERVICE_HOST=199.204.45.72
2026-05-20 01:20:32.214993 | controller | +++ SERVICE_PASSWORD=secretservice
2026-05-20 01:20:32.215002 | controller | +++ SWIFT_HASH=1234123412341234
2026-05-20 01:20:32.215010 | controller | +++ SWIFT_REPLICAS=1
2026-05-20 01:20:32.215019 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-05-20 01:20:32.215027 | controller | +++ VERBOSE=True
2026-05-20 01:20:32.215059 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:20:32.215069 | controller | +++ LIBS_FROM_GIT=novnc,barbican,cinder,devstack,glance,keystone,magnum,manila,neutron,nova,octavia,os-test-images,ovn-octavia-provider,placement,python-magnumclient,requirements,swift,magnum-cluster-api
2026-05-20 01:20:32.215079 | controller | +++ enable_plugin barbican https://github.com/openstack/barbican
2026-05-20 01:20:32.215091 | controller | +++ local name=barbican
2026-05-20 01:20:32.215100 | controller | +++ local url=https://github.com/openstack/barbican
2026-05-20 01:20:32.215108 | controller | +++ local branch=master
2026-05-20 01:20:32.215116 | controller | +++ is_plugin_enabled barbican
2026-05-20 01:20:32.215125 | controller | +++ local name=barbican
2026-05-20 01:20:32.215133 | controller | +++ [[ ,, =~ ,barbican, ]]
2026-05-20 01:20:32.215146 | controller | +++ return 1
2026-05-20 01:20:32.215154 | controller | +++ DEVSTACK_PLUGINS+=,barbican
2026-05-20 01:20:32.215163 | controller | +++ GITREPO[$name]=https://github.com/openstack/barbican
2026-05-20 01:20:32.215175 | controller | +++ GITDIR[$name]=/opt/stack/barbican
2026-05-20 01:20:32.215184 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:32.215198 | controller | +++ enable_plugin magnum https://review.opendev.org/openstack/magnum
2026-05-20 01:20:32.215238 | controller | +++ local name=magnum
2026-05-20 01:20:32.215247 | controller | +++ local url=https://review.opendev.org/openstack/magnum
2026-05-20 01:20:32.215259 | controller | +++ local branch=master
2026-05-20 01:20:32.215267 | controller | +++ is_plugin_enabled magnum
2026-05-20 01:20:32.215279 | controller | +++ local name=magnum
2026-05-20 01:20:32.215287 | controller | +++ [[ ,,barbican, =~ ,magnum, ]]
2026-05-20 01:20:32.215299 | controller | +++ return 1
2026-05-20 01:20:32.215311 | controller | +++ DEVSTACK_PLUGINS+=,magnum
2026-05-20 01:20:32.215323 | controller | +++ GITREPO[$name]=https://review.opendev.org/openstack/magnum
2026-05-20 01:20:32.215335 | controller | +++ GITDIR[$name]=/opt/stack/magnum
2026-05-20 01:20:32.215346 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:32.215394 | controller | +++ enable_plugin octavia https://github.com/openstack/octavia
2026-05-20 01:20:32.215407 | controller | +++ local name=octavia
2026-05-20 01:20:32.215415 | controller | +++ local url=https://github.com/openstack/octavia
2026-05-20 01:20:32.215423 | controller | +++ local branch=master
2026-05-20 01:20:32.215435 | controller | +++ is_plugin_enabled octavia
2026-05-20 01:20:32.215443 | controller | +++ local name=octavia
2026-05-20 01:20:32.215455 | controller | +++ [[ ,,barbican,magnum, =~ ,octavia, ]]
2026-05-20 01:20:32.215466 | controller | +++ return 1
2026-05-20 01:20:32.215478 | controller | +++ DEVSTACK_PLUGINS+=,octavia
2026-05-20 01:20:32.215490 | controller | +++ GITREPO[$name]=https://github.com/openstack/octavia
2026-05-20 01:20:32.215499 | controller | +++ GITDIR[$name]=/opt/stack/octavia
2026-05-20 01:20:32.215510 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:32.215578 | controller | +++ enable_plugin manila https://github.com/openstack/manila
2026-05-20 01:20:32.215592 | controller | +++ local name=manila
2026-05-20 01:20:32.215604 | controller | +++ local url=https://github.com/openstack/manila
2026-05-20 01:20:32.215613 | controller | +++ local branch=master
2026-05-20 01:20:32.215621 | controller | +++ is_plugin_enabled manila
2026-05-20 01:20:32.215633 | controller | +++ local name=manila
2026-05-20 01:20:32.215641 | controller | +++ [[ ,,barbican,magnum,octavia, =~ ,manila, ]]
2026-05-20 01:20:32.215653 | controller | +++ return 1
2026-05-20 01:20:32.215665 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-05-20 01:20:32.215677 | controller | +++ GITREPO[$name]=https://github.com/openstack/manila
2026-05-20 01:20:32.215688 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-05-20 01:20:32.215701 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:32.215713 | controller | +++ enable_plugin magnum-cluster-api https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:32.215726 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:20:32.215738 | controller | +++ local url=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:32.215749 | controller | +++ local branch=master
2026-05-20 01:20:32.215761 | controller | +++ is_plugin_enabled magnum-cluster-api
2026-05-20 01:20:32.215773 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:20:32.215810 | controller | +++ [[ ,,barbican,magnum,octavia,manila, =~ ,magnum-cluster-api, ]]
2026-05-20 01:20:32.215870 | controller | +++ return 1
2026-05-20 01:20:32.215880 | controller | +++ DEVSTACK_PLUGINS+=,magnum-cluster-api
2026-05-20 01:20:32.215893 | controller | +++ GITREPO[$name]=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:32.215911 | controller | +++ GITDIR[$name]=/opt/stack/magnum-cluster-api
2026-05-20 01:20:32.215920 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:32.215932 | controller | +++ enable_plugin ovn-octavia-provider https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:32.215945 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:20:32.215953 | controller | +++ local url=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:32.215965 | controller | +++ local branch=master
2026-05-20 01:20:32.215977 | controller | +++ is_plugin_enabled ovn-octavia-provider
2026-05-20 01:20:32.215989 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:20:32.216001 | controller | +++ [[ ,,barbican,magnum,octavia,manila,magnum-cluster-api, =~ ,ovn-octavia-provider, ]]
2026-05-20 01:20:32.216039 | controller | +++ return 1
2026-05-20 01:20:32.216052 | controller | +++ DEVSTACK_PLUGINS+=,ovn-octavia-provider
2026-05-20 01:20:32.216060 | controller | +++ GITREPO[$name]=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:32.216071 | controller | +++ GITDIR[$name]=/opt/stack/ovn-octavia-provider
2026-05-20 01:20:32.216080 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:32.216171 | controller | ++ CELLSV2_SETUP=superconductor
2026-05-20 01:20:32.216180 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-05-20 01:20:32.217073 | controller | +++ trueorfalse False USER_UNITS
2026-05-20 01:20:32.217233 | controller | +++ local xtrace
2026-05-20 01:20:32.218656 | controller | ++++ set +o
2026-05-20 01:20:32.218976 | controller | ++++ grep xtrace
2026-05-20 01:20:32.222307 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.222327 | controller | +++ set +o xtrace
2026-05-20 01:20:32.222958 | controller | ++ USER_UNITS=False
2026-05-20 01:20:32.223075 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:20:32.223086 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-05-20 01:20:32.223094 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-05-20 01:20:32.223106 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-05-20 01:20:32.223959 | controller | +++ _get_python_version python3
2026-05-20 01:20:32.224050 | controller | +++ local interp=python3
2026-05-20 01:20:32.224060 | controller | +++ local version
2026-05-20 01:20:32.224931 | controller | ++++ which python3
2026-05-20 01:20:32.228806 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-05-20 01:20:32.229771 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-05-20 01:20:32.248021 | controller | +++ version=3.12
2026-05-20 01:20:32.248056 | controller | +++ echo 3.12
2026-05-20 01:20:32.248629 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-05-20 01:20:32.248724 | controller | ++ export PYTHON3_VERSION=3.12
2026-05-20 01:20:32.248735 | controller | ++ PYTHON3_VERSION=3.12
2026-05-20 01:20:32.248747 | controller | ++ export 'VIRTUALENV_CMD=python3.12 -m venv'
2026-05-20 01:20:32.248755 | controller | ++ VIRTUALENV_CMD='python3.12 -m venv'
2026-05-20 01:20:32.248806 | controller | ++ '[' -t 1 ']'
2026-05-20 01:20:32.248815 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-05-20 01:20:32.249971 | controller | +++ trueorfalse False LOG_COLOR
2026-05-20 01:20:32.250138 | controller | +++ local xtrace
2026-05-20 01:20:32.251681 | controller | ++++ set +o
2026-05-20 01:20:32.251862 | controller | ++++ grep xtrace
2026-05-20 01:20:32.255403 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:32.255422 | controller | +++ set +o xtrace
2026-05-20 01:20:32.256166 | controller | ++ LOG_COLOR=False
2026-05-20 01:20:32.256283 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:20:32.256294 | controller | ++ export 'PS4=+ $(short_source):   '
2026-05-20 01:20:32.256302 | controller | ++ PS4='+ $(short_source):   '
2026-05-20 01:20:32.260499 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-05-20 01:20:32.264645 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:32.271204 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:32.271237 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:32.278849 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:32.281450 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:32.286159 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-05-20 01:20:32.289667 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-05-20 01:20:32.293666 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-05-20 01:20:32.299318 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-05-20 01:20:32.303617 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:32.310565 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:32.310709 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:32.318630 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:32.322179 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:32.327452 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-05-20 01:20:32.332619 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-05-20 01:20:32.337283 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:32.343707 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:32.343798 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:32.350251 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:32.354209 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:32.359850 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-05-20 01:20:32.363811 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-05-20 01:20:32.369453 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-05-20 01:20:32.373664 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:32.379613 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:32.379788 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:32.387262 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:32.390315 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:32.395628 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-05-20 01:20:32.401562 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-05-20 01:20:32.405690 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:32.411664 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:32.411817 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:32.419681 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:32.423019 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:32.429229 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-05-20 01:20:32.434460 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-05-20 01:20:32.438285 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:32.445668 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:32.446068 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:32.453636 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:32.457476 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:32.462766 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-05-20 01:20:32.466903 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-05-20 01:20:32.470482 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-05-20 01:20:32.474644 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-05-20 01:20:32.479031 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://github.com
2026-05-20 01:20:32.483004 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-05-20 01:20:32.486845 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.2
2026-05-20 01:20:32.490742 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://github.com/openstack/cinder.git
2026-05-20 01:20:32.495304 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-05-20 01:20:32.499217 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://github.com/openstack/glance.git
2026-05-20 01:20:32.503567 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-05-20 01:20:32.507437 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://github.com/openstack/horizon.git
2026-05-20 01:20:32.511191 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-05-20 01:20:32.514489 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://github.com/openstack/keystone.git
2026-05-20 01:20:32.518982 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-05-20 01:20:32.523255 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://github.com/openstack/neutron.git
2026-05-20 01:20:32.527381 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-05-20 01:20:32.530824 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://github.com/openstack/nova.git
2026-05-20 01:20:32.533729 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-05-20 01:20:32.536781 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://github.com/openstack/swift.git
2026-05-20 01:20:32.540133 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-05-20 01:20:32.543729 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://github.com/openstack/placement.git
2026-05-20 01:20:32.547315 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-05-20 01:20:32.550955 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://github.com/openstack/requirements.git
2026-05-20 01:20:32.553847 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-05-20 01:20:32.557904 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://github.com/openstack/tempest.git
2026-05-20 01:20:32.561988 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-05-20 01:20:32.566298 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-05-20 01:20:32.570178 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://github.com/openstack/os-test-images.git
2026-05-20 01:20:32.574076 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-05-20 01:20:32.578305 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-05-20 01:20:32.581889 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://github.com/openstack/python-cinderclient.git
2026-05-20 01:20:32.585565 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-05-20 01:20:32.589790 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://github.com/openstack/python-brick-cinderclient-ext.git
2026-05-20 01:20:32.594116 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-05-20 01:20:32.598081 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://github.com/openstack/python-barbicanclient.git
2026-05-20 01:20:32.602047 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-05-20 01:20:32.605590 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-05-20 01:20:32.609144 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://github.com/openstack/python-glanceclient.git
2026-05-20 01:20:32.612353 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-05-20 01:20:32.616460 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://github.com/openstack/python-ironicclient.git
2026-05-20 01:20:32.620251 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-05-20 01:20:32.624335 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-05-20 01:20:32.627926 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://github.com/openstack/keystoneauth.git
2026-05-20 01:20:32.631444 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-05-20 01:20:32.634854 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://github.com/openstack/python-keystoneclient.git
2026-05-20 01:20:32.638857 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-05-20 01:20:32.642767 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://github.com/openstack/python-neutronclient.git
2026-05-20 01:20:32.646961 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-05-20 01:20:32.650275 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://github.com/openstack/python-novaclient.git
2026-05-20 01:20:32.654111 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-05-20 01:20:32.657843 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://github.com/openstack/python-swiftclient.git
2026-05-20 01:20:32.661841 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-05-20 01:20:32.665896 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://github.com/openstack/python-openstackclient.git
2026-05-20 01:20:32.670055 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-05-20 01:20:32.673184 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-05-20 01:20:32.677408 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://github.com/openstack/osc-placement.git
2026-05-20 01:20:32.681320 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-05-20 01:20:32.685163 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://github.com/openstack/castellan.git
2026-05-20 01:20:32.689269 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-05-20 01:20:32.692682 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://github.com/openstack/cliff.git
2026-05-20 01:20:32.696212 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-05-20 01:20:32.698853 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://github.com/openstack/futurist.git
2026-05-20 01:20:32.701738 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-05-20 01:20:32.704925 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://github.com/openstack/debtcollector.git
2026-05-20 01:20:32.709092 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-05-20 01:20:32.713016 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://github.com/openstack/etcd3gw.git
2026-05-20 01:20:32.716804 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-05-20 01:20:32.720744 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://github.com/openstack/automaton.git
2026-05-20 01:20:32.724502 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-05-20 01:20:32.728031 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://github.com/openstack/oslo.cache.git
2026-05-20 01:20:32.731875 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-05-20 01:20:32.734275 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://github.com/openstack/oslo.concurrency.git
2026-05-20 01:20:32.737727 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-05-20 01:20:32.741700 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://github.com/openstack/oslo.config.git
2026-05-20 01:20:32.746035 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-05-20 01:20:32.750169 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://github.com/openstack/oslo.context.git
2026-05-20 01:20:32.753804 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-05-20 01:20:32.757851 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://github.com/openstack/oslo.db.git
2026-05-20 01:20:32.760913 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-05-20 01:20:32.764748 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://github.com/openstack/oslo.i18n.git
2026-05-20 01:20:32.767136 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-05-20 01:20:32.770827 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://github.com/openstack/oslo.limit.git
2026-05-20 01:20:32.774802 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-05-20 01:20:32.778797 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://github.com/openstack/oslo.log.git
2026-05-20 01:20:32.782307 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-05-20 01:20:32.786427 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://github.com/openstack/oslo.messaging.git
2026-05-20 01:20:32.790804 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-05-20 01:20:32.794602 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://github.com/openstack/oslo.middleware.git
2026-05-20 01:20:32.797789 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-05-20 01:20:32.801588 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://github.com/openstack/oslo.policy.git
2026-05-20 01:20:32.804770 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-05-20 01:20:32.808027 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://github.com/openstack/oslo.privsep.git
2026-05-20 01:20:32.812008 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-05-20 01:20:32.814812 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://github.com/openstack/oslo.reports.git
2026-05-20 01:20:32.817834 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-05-20 01:20:32.821819 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://github.com/openstack/oslo.rootwrap.git
2026-05-20 01:20:32.825817 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-05-20 01:20:32.829602 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://github.com/openstack/oslo.serialization.git
2026-05-20 01:20:32.833043 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-05-20 01:20:32.836323 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://github.com/openstack/oslo.service.git
2026-05-20 01:20:32.839333 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-05-20 01:20:32.842855 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://github.com/openstack/oslo.utils.git
2026-05-20 01:20:32.845862 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-05-20 01:20:32.850075 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://github.com/openstack/oslo.versionedobjects.git
2026-05-20 01:20:32.853934 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-05-20 01:20:32.858081 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://github.com/openstack/oslo.vmware.git
2026-05-20 01:20:32.862101 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-05-20 01:20:32.864911 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://github.com/openstack/osprofiler.git
2026-05-20 01:20:32.868389 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-05-20 01:20:32.872203 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://github.com/openstack/pycadf.git
2026-05-20 01:20:32.875991 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-05-20 01:20:32.879752 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://github.com/openstack/stevedore.git
2026-05-20 01:20:32.883504 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-05-20 01:20:32.887153 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://github.com/openstack/taskflow.git
2026-05-20 01:20:32.890143 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-05-20 01:20:32.893913 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://github.com/openstack/tooz.git
2026-05-20 01:20:32.897881 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-05-20 01:20:32.902093 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://github.com/openstack/pbr.git
2026-05-20 01:20:32.905921 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-05-20 01:20:32.910254 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://github.com/openstack/cursive.git
2026-05-20 01:20:32.914295 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-05-20 01:20:32.918606 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://github.com/openstack/glance_store.git
2026-05-20 01:20:32.922733 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-05-20 01:20:32.926633 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://github.com/openstack/keystonemiddleware.git
2026-05-20 01:20:32.930112 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-05-20 01:20:32.933502 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://github.com/openstack/ceilometermiddleware.git
2026-05-20 01:20:32.937567 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-05-20 01:20:32.941596 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-05-20 01:20:32.945863 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://github.com/openstack/openstacksdk.git
2026-05-20 01:20:32.950063 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-05-20 01:20:32.953039 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://github.com/openstack/os-brick.git
2026-05-20 01:20:32.956621 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-05-20 01:20:32.960731 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://github.com/openstack/os-client-config.git
2026-05-20 01:20:32.965018 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-05-20 01:20:32.969215 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-05-20 01:20:32.972656 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://github.com/openstack/os-vif.git
2026-05-20 01:20:32.976062 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-05-20 01:20:32.979813 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://github.com/openstack/osc-lib.git
2026-05-20 01:20:32.983966 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-05-20 01:20:32.988236 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://github.com/openstack/ironic-lib.git
2026-05-20 01:20:32.992015 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-05-20 01:20:32.995720 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-05-20 01:20:32.998989 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://github.com/openstack/diskimage-builder.git
2026-05-20 01:20:33.002716 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-05-20 01:20:33.006776 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-05-20 01:20:33.010570 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://github.com/openstack/neutron-lib.git
2026-05-20 01:20:33.013957 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-05-20 01:20:33.017577 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-05-20 01:20:33.021095 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://github.com/openstack/os-resource-classes.git
2026-05-20 01:20:33.025156 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-05-20 01:20:33.029120 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://github.com/openstack/os-traits.git
2026-05-20 01:20:33.032556 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-05-20 01:20:33.036660 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://github.com/openstack/ovsdbapp.git
2026-05-20 01:20:33.040822 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-05-20 01:20:33.044955 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-05-20 01:20:33.048858 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://github.com/openstack/os-ken.git
2026-05-20 01:20:33.053353 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-05-20 01:20:33.057064 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-05-20 01:20:33.061065 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://github.com/openstack/ironic-python-agent.git
2026-05-20 01:20:33.064823 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-05-20 01:20:33.068169 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-05-20 01:20:33.072213 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-05-20 01:20:33.075646 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-05-20 01:20:33.079787 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-05-20 01:20:33.084866 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-05-20 01:20:33.088320 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.095206 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.095295 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.101837 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.106030 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.111289 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-05-20 01:20:33.115185 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-05-20 01:20:33.118981 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-05-20 01:20:33.122451 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-05-20 01:20:33.126596 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-05-20 01:20:33.130511 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-05-20 01:20:33.134845 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-05-20 01:20:33.138824 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-05-20 01:20:33.142274 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:20:33.145471 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-05-20 01:20:33.149806 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-05-20 01:20:33.155458 | controller | +++ stackrc:source:668                       :   uname -m
2026-05-20 01:20:33.161491 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-05-20 01:20:33.167015 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-05-20 01:20:33.171236 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.177113 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.178227 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.185556 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.189568 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.194755 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-05-20 01:20:33.198561 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-05-20 01:20:33.202341 | controller | ++ stackrc:source:675                       :   [[ -n '' ]]
2026-05-20 01:20:33.206118 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-05-20 01:20:33.209891 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-05-20 01:20:33.213960 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-05-20 01:20:33.220905 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:33.225827 | controller | ++ stackrc:source:688                       :   IMAGE_URLS+=https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:33.229170 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-05-20 01:20:33.233746 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-05-20 01:20:33.237938 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-05-20 01:20:33.242733 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:20:33.246272 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-05-20 01:20:33.250251 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-05-20 01:20:33.254810 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-05-20 01:20:33.258998 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-05-20 01:20:33.264055 | controller | +++ functions-common:is_arch:519             :   uname -m
2026-05-20 01:20:33.270722 | controller | ++ functions-common:is_arch:519             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-05-20 01:20:33.275068 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-05-20 01:20:33.279056 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:20:33.282796 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-05-20 01:20:33.286399 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-05-20 01:20:33.290515 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-05-20 01:20:33.294464 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-05-20 01:20:33.298285 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:33.302572 | controller | ++ stackrc:source:739                       :   ETCD_DOWNLOAD_LOCATION=https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:33.306756 | controller | ++ stackrc:source:741                       :   EXTRA_CACHE_URLS+=,https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:33.310449 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-05-20 01:20:33.314801 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-05-20 01:20:33.318756 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-05-20 01:20:33.325724 | controller | +++ stackrc:source:749                       :   echo https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:33.326337 | controller | +++ stackrc:source:749                       :   grep -o -F https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:33.326503 | controller | +++ stackrc:source:749                       :   wc -l
2026-05-20 01:20:33.334659 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-05-20 01:20:33.337894 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=30G
2026-05-20 01:20:33.341373 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-05-20 01:20:33.345844 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-05-20 01:20:33.350169 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-05-20 01:20:33.354306 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-05-20 01:20:33.358563 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-05-20 01:20:33.362819 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-05-20 01:20:33.366735 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-05-20 01:20:33.372004 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:20:33.380278 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:20:33.386451 | controller | ++ stackrc:source:777                       :   API_WORKERS=4
2026-05-20 01:20:33.389831 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-05-20 01:20:33.393932 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-05-20 01:20:33.398045 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-05-20 01:20:33.402331 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-05-20 01:20:33.407311 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-05-20 01:20:33.412418 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.418228 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.419603 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.427555 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.431245 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.436103 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-05-20 01:20:33.440960 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-05-20 01:20:33.445105 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.451638 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.451829 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.459306 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.462638 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.467584 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-05-20 01:20:33.472609 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-05-20 01:20:33.476160 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.482997 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.483019 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.490658 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.494483 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.499517 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-05-20 01:20:33.503188 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:20:33.507362 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:20:33.510982 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-05-20 01:20:33.514617 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-05-20 01:20:33.518395 | controller | ++ stackrc:source:815                       :   HOST_IP=199.204.45.72
2026-05-20 01:20:33.522456 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-05-20 01:20:33.527378 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 199.204.45.72 inet
2026-05-20 01:20:33.531754 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=10.1.0.0/20
2026-05-20 01:20:33.535637 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=172.24.5.0/24
2026-05-20 01:20:33.539735 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:20:33.543578 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=199.204.45.72
2026-05-20 01:20:33.547717 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet
2026-05-20 01:20:33.551814 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z 199.204.45.72 -o 199.204.45.72 == dhcp ']'
2026-05-20 01:20:33.555832 | controller | +++ functions-common:get_default_host_ip:785 :   echo 199.204.45.72
2026-05-20 01:20:33.560198 | controller | ++ stackrc:source:818                       :   HOST_IP=199.204.45.72
2026-05-20 01:20:33.563941 | controller | ++ stackrc:source:819                       :   '[' 199.204.45.72 == '' ']'
2026-05-20 01:20:33.568806 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-05-20 01:20:33.572984 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=
2026-05-20 01:20:33.577119 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=
2026-05-20 01:20:33.580518 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:20:33.584613 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=
2026-05-20 01:20:33.588221 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet6
2026-05-20 01:20:33.592673 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z '' -o '' == dhcp ']'
2026-05-20 01:20:33.596486 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip=
2026-05-20 01:20:33.602867 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 route list match default table all
2026-05-20 01:20:33.602970 | controller | ++++ functions-common:get_default_host_ip:764 :   grep via
2026-05-20 01:20:33.604626 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/default/ {print $5}'
2026-05-20 01:20:33.605095 | controller | ++++ functions-common:get_default_host_ip:764 :   head -1
2026-05-20 01:20:33.613045 | controller | +++ functions-common:get_default_host_ip:764 :   host_ip_iface=
2026-05-20 01:20:33.616455 | controller | +++ functions-common:get_default_host_ip:765 :   local host_ips
2026-05-20 01:20:33.623050 | controller | ++++ functions-common:get_default_host_ip:766 :   LC_ALL=C
2026-05-20 01:20:33.623225 | controller | ++++ functions-common:get_default_host_ip:766 :   sed /temporary/d
2026-05-20 01:20:33.624229 | controller | ++++ functions-common:get_default_host_ip:766 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-05-20 01:20:33.627482 | controller | ++++ functions-common:get_default_host_ip:766 :   ip -f inet6 addr show
2026-05-20 01:20:33.636379 | controller | +++ functions-common:get_default_host_ip:766 :   host_ips='::1
2026-05-20 01:20:33.636409 | controller | 2604:e100:1:0:f816:3eff:fe7f:e283
2026-05-20 01:20:33.636445 | controller | fe80::f816:3eff:fe7f:e283'
2026-05-20 01:20:33.639517 | controller | +++ functions-common:get_default_host_ip:767 :   local ip
2026-05-20 01:20:33.643325 | controller | +++ functions-common:get_default_host_ip:768 :   for ip in $host_ips
2026-05-20 01:20:33.647124 | controller | +++ functions-common:get_default_host_ip:775 :   [[ inet6 == \i\n\e\t\6 ]]
2026-05-20 01:20:33.651259 | controller | +++ functions-common:get_default_host_ip:776 :   host_ip=::1
2026-05-20 01:20:33.655200 | controller | +++ functions-common:get_default_host_ip:777 :   break
2026-05-20 01:20:33.659227 | controller | +++ functions-common:get_default_host_ip:785 :   echo ::1
2026-05-20 01:20:33.663623 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-05-20 01:20:33.668417 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-05-20 01:20:33.672700 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.678358 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.678877 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.686993 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.690799 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.695437 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-05-20 01:20:33.699358 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-05-20 01:20:33.702924 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-05-20 01:20:33.707173 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-05-20 01:20:33.711084 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=199.204.45.72
2026-05-20 01:20:33.715349 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:20:33.718920 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:20:33.722964 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-05-20 01:20:33.726670 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:20:33.730300 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=199.204.45.72
2026-05-20 01:20:33.734567 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:20:33.738595 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-05-20 01:20:33.742789 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-05-20 01:20:33.747051 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-05-20 01:20:33.750803 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=199.204.45.72
2026-05-20 01:20:33.754716 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-05-20 01:20:33.759094 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=199.204.45.72
2026-05-20 01:20:33.764490 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-05-20 01:20:33.768558 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.774242 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.775940 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.783419 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.787635 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.791935 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-05-20 01:20:33.795391 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=199.204.45.72
2026-05-20 01:20:33.799358 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-05-20 01:20:33.803256 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-05-20 01:20:33.807773 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-05-20 01:20:33.811851 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:33.818348 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:33.818464 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:33.825654 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:33.828885 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:33.833494 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-05-20 01:20:33.837469 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-05-20 01:20:33.840926 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-05-20 01:20:33.844567 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:20:33.848841 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-05-20 01:20:33.853267 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-05-20 01:20:33.856998 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-05-20 01:20:33.860719 | controller | ++ stackrc:source:924                       :   [[ /opt/stack/logs == \d\e\v\s\t\a\c\k\l\o\g\.\t\x\t ]]
2026-05-20 01:20:33.864658 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-05-20 01:20:33.869004 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-05-20 01:20:33.872929 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-05-20 01:20:33.877899 | controller | + functions:write_devstack_version:876     :   cat -
2026-05-20 01:20:33.878347 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-05-20 01:20:33.882783 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-05-20 01:20:33.920003 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-05-20 01:20:33.924005 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-05-20 01:20:33.928795 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-05-20 01:20:33.933122 | controller | + functions-common:export_proxy_variables:2232 :   isset http_proxy
2026-05-20 01:20:33.937046 | controller | + functions-common:isset:266               :   [[ -v http_proxy ]]
2026-05-20 01:20:33.940917 | controller | + functions-common:export_proxy_variables:2235 :   isset https_proxy
2026-05-20 01:20:33.945231 | controller | + functions-common:isset:266               :   [[ -v https_proxy ]]
2026-05-20 01:20:33.949630 | controller | + functions-common:export_proxy_variables:2238 :   isset no_proxy
2026-05-20 01:20:33.953217 | controller | + functions-common:isset:266               :   [[ -v no_proxy ]]
2026-05-20 01:20:33.957275 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-05-20 01:20:33.961099 | controller | + functions-common:disable_negated_services:1937 :   local xtrace
2026-05-20 01:20:33.966947 | controller | ++ functions-common:disable_negated_services:1938 :   set +o
2026-05-20 01:20:33.968114 | controller | ++ functions-common:disable_negated_services:1938 :   grep xtrace
2026-05-20 01:20:33.975928 | controller | + functions-common:disable_negated_services:1938 :   xtrace='set -o xtrace'
2026-05-20 01:20:33.980229 | controller | + functions-common:disable_negated_services:1939 :   set +o xtrace
2026-05-20 01:20:34.004004 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-05-20 01:20:34.007055 | controller | + functions-common:is_package_installed:1441 :   [[ -z sudo ]]
2026-05-20 01:20:34.011022 | controller | + functions-common:is_package_installed:1445 :   [[ -z deb ]]
2026-05-20 01:20:34.015212 | controller | + functions-common:is_package_installed:1449 :   [[ deb = \d\e\b ]]
2026-05-20 01:20:34.019085 | controller | + functions-common:is_package_installed:1450 :   dpkg -s sudo
2026-05-20 01:20:34.039433 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-05-20 01:20:34.060026 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-05-20 01:20:34.060406 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-05-20 01:20:34.072451 | controller | #includedir /etc/sudoers.d
2026-05-20 01:20:34.078807 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-05-20 01:20:34.084567 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-05-20 01:20:34.091233 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.pT6AnOpIg5
2026-05-20 01:20:34.095251 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-05-20 01:20:34.099480 | controller | + ./stack.sh:main:277                      :   echo 'Defaults:stack secure_path=/sbin:/usr/sbin:/usr/bin:/bin:/usr/local/sbin:/usr/local/bin'
2026-05-20 01:20:34.103568 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-05-20 01:20:34.107599 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.pT6AnOpIg5
2026-05-20 01:20:34.114918 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.pT6AnOpIg5
2026-05-20 01:20:34.136736 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.pT6AnOpIg5 /etc/sudoers.d/50_stack_sh
2026-05-20 01:20:34.160553 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-05-20 01:20:34.163194 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:20:34.166867 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:20:34.172049 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-05-20 01:20:34.173099 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-05-20 01:20:34.192173 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-05-20 01:20:34.195967 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-05-20 01:20:34.199478 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-05-20 01:20:34.203299 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-05-20 01:20:34.221984 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-05-20 01:20:34.225912 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-05-20 01:20:34.230363 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:34.236517 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:34.236823 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:34.243554 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:34.246754 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:34.253875 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/logs
2026-05-20 01:20:34.273990 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-05-20 01:20:34.278259 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-05-20 01:20:34.282876 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:34.289000 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:34.289566 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:34.297107 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:34.300335 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:34.307924 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/logs
2026-05-20 01:20:34.328121 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-05-20 01:20:34.332105 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-05-20 01:20:34.335219 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-05-20 01:20:34.354457 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-05-20 01:20:34.358396 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-05-20 01:20:34.362223 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:34.367869 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:34.369204 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:34.376640 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:34.381018 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:34.389361 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/data
2026-05-20 01:20:34.409009 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-05-20 01:20:34.414023 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-05-20 01:20:34.418094 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:34.423571 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:34.425307 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:34.432796 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:34.436459 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:34.444947 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/data
2026-05-20 01:20:34.465157 | controller | + ./stack.sh:main:353                      :   async_init
2026-05-20 01:20:34.468495 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-05-20 01:20:34.473002 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-05-20 01:20:34.479355 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-05-20 01:20:34.487509 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-05-20 01:20:34.493665 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000175143
2026-05-20 01:20:34.497125 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000175143 /etc/hosts
2026-05-20 01:20:34.505558 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-05-20 01:20:34.509865 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:34.516315 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:34.516789 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:34.523798 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:34.527008 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:34.532196 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-05-20 01:20:34.536800 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-05-20 01:20:34.540832 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-05-20 01:20:34.544938 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-05-20 01:20:34.549358 | controller | + ./stack.sh:main:404                      :   install_python
2026-05-20 01:20:34.553612 | controller | + inc/python:install_python:482            :   install_python3
2026-05-20 01:20:34.557709 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-05-20 01:20:34.561767 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:20:34.566304 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:20:34.570445 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-05-20 01:20:34.574587 | controller | + functions-common:apt_get:1188            :   local xtrace result
2026-05-20 01:20:34.580612 | controller | ++ functions-common:apt_get:1189            :   set +o
2026-05-20 01:20:34.581585 | controller | ++ functions-common:apt_get:1189            :   grep xtrace
2026-05-20 01:20:34.588971 | controller | + functions-common:apt_get:1189            :   xtrace='set -o xtrace'
2026-05-20 01:20:34.592326 | controller | + functions-common:apt_get:1190            :   set +o xtrace
2026-05-20 01:20:34.603810 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install python3.12 python3.12-dev
2026-05-20 01:20:34.649399 | controller | Reading package lists...
2026-05-20 01:20:35.043993 | controller | Building dependency tree...
2026-05-20 01:20:35.044322 | controller | Reading state information...
2026-05-20 01:20:35.409745 | controller | python3.12 is already the newest version (3.12.3-1ubuntu0.13).
2026-05-20 01:20:35.409817 | controller | python3.12 set to manually installed.
2026-05-20 01:20:35.409829 | controller | The following additional packages will be installed:
2026-05-20 01:20:35.409919 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:20:35.411318 | controller |   libpython3.12t64 linux-libc-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:20:35.413592 | controller | Suggested packages:
2026-05-20 01:20:35.413611 | controller |   glibc-doc manpages-dev
2026-05-20 01:20:35.413620 | controller | Recommended packages:
2026-05-20 01:20:35.413628 | controller |   manpages manpages-dev libc-devtools
2026-05-20 01:20:35.471709 | controller | The following NEW packages will be installed:
2026-05-20 01:20:35.472067 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:20:35.474004 | controller |   libpython3.12t64 linux-libc-dev python3.12-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:20:35.809961 | controller | 0 upgraded, 10 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:20:35.810036 | controller | Need to get 13.4 MB of archives.
2026-05-20 01:20:35.810051 | controller | After this operation, 63.4 MB of additional disk space will be used.
2026-05-20 01:20:35.810064 | controller | Get:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libc-dev-bin amd64 2.39-0ubuntu8.7 [20.4 kB]
2026-05-20 01:21:06.954666 | controller | Get:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 linux-libc-dev amd64 6.8.0-117.117 [1,495 kB]
2026-05-20 01:21:10.137231 | controller | Get:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 libcrypt-dev amd64 1:4.4.36-4build1 [112 kB]
2026-05-20 01:21:12.444883 | controller | Get:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 rpcsvc-proto amd64 1.4.2-0ubuntu7 [67.4 kB]
2026-05-20 01:21:12.707619 | controller | Get:5 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libc6-dev amd64 2.39-0ubuntu8.7 [2,124 kB]
2026-05-20 01:21:17.521113 | controller | Get:6 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libexpat1-dev amd64 2.6.1-2ubuntu0.4 [140 kB]
2026-05-20 01:21:18.295454 | controller | Get:7 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12t64 amd64 3.12.3-1ubuntu0.13 [2,338 kB]
2026-05-20 01:21:26.558129 | controller | Get:8 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 zlib1g-dev amd64 1:1.3.dfsg-3.1ubuntu2.1 [894 kB]
2026-05-20 01:21:28.896682 | controller | Get:9 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12-dev amd64 3.12.3-1ubuntu0.13 [5,682 kB]
2026-05-20 01:21:40.224801 | controller | Get:10 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 python3.12-dev amd64 3.12.3-1ubuntu0.13 [498 kB]
2026-05-20 01:21:41.056738 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-05-20 01:21:41.104400 | controller | Fetched 13.4 MB in 1min 5s (205 kB/s)
2026-05-20 01:21:41.151655 | controller | Selecting previously unselected package libc-dev-bin.
2026-05-20 01:21:41.297936 | controller | (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 31308 files and directories currently installed.)
2026-05-20 01:21:41.299854 | controller | Preparing to unpack .../0-libc-dev-bin_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:41.307550 | controller | Unpacking libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:41.373817 | controller | Selecting previously unselected package linux-libc-dev:amd64.
2026-05-20 01:21:41.381267 | controller | Preparing to unpack .../1-linux-libc-dev_6.8.0-117.117_amd64.deb ...
2026-05-20 01:21:41.385304 | controller | Unpacking linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:41.759855 | controller | Selecting previously unselected package libcrypt-dev:amd64.
2026-05-20 01:21:41.767605 | controller | Preparing to unpack .../2-libcrypt-dev_1%3a4.4.36-4build1_amd64.deb ...
2026-05-20 01:21:41.787775 | controller | Unpacking libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:41.852918 | controller | Selecting previously unselected package rpcsvc-proto.
2026-05-20 01:21:41.860239 | controller | Preparing to unpack .../3-rpcsvc-proto_1.4.2-0ubuntu7_amd64.deb ...
2026-05-20 01:21:41.864559 | controller | Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:41.932724 | controller | Selecting previously unselected package libc6-dev:amd64.
2026-05-20 01:21:41.939554 | controller | Preparing to unpack .../4-libc6-dev_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:41.944162 | controller | Unpacking libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:42.202974 | controller | Selecting previously unselected package libexpat1-dev:amd64.
2026-05-20 01:21:42.209244 | controller | Preparing to unpack .../5-libexpat1-dev_2.6.1-2ubuntu0.4_amd64.deb ...
2026-05-20 01:21:42.213022 | controller | Unpacking libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:42.300255 | controller | Selecting previously unselected package libpython3.12t64:amd64.
2026-05-20 01:21:42.306811 | controller | Preparing to unpack .../6-libpython3.12t64_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:42.316815 | controller | Unpacking libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:42.447454 | controller | Selecting previously unselected package zlib1g-dev:amd64.
2026-05-20 01:21:42.454166 | controller | Preparing to unpack .../7-zlib1g-dev_1%3a1.3.dfsg-3.1ubuntu2.1_amd64.deb ...
2026-05-20 01:21:42.458968 | controller | Unpacking zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:42.528068 | controller | Selecting previously unselected package libpython3.12-dev:amd64.
2026-05-20 01:21:42.535419 | controller | Preparing to unpack .../8-libpython3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:42.540478 | controller | Unpacking libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:42.879117 | controller | Selecting previously unselected package python3.12-dev.
2026-05-20 01:21:42.887183 | controller | Preparing to unpack .../9-python3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:42.893625 | controller | Unpacking python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:42.964725 | controller | Setting up linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:42.981407 | controller | Setting up libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:42.995888 | controller | Setting up rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:43.011082 | controller | Setting up libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:43.038265 | controller | Setting up libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:43.055386 | controller | Setting up libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:43.075951 | controller | Setting up libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:43.089637 | controller | Setting up zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:43.103006 | controller | Setting up libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:43.117491 | controller | Setting up python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:43.135504 | controller | Processing triggers for libc-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:43.418405 | controller | + functions-common:apt_get:1205            :   result=0
2026-05-20 01:21:43.423401 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:21:43.426996 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:21:43.431723 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:21:43.435640 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:21:43.440151 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:21:43.444602 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:21:43.448515 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:21:43.451899 | controller | + functions-common:time_stop:2421          :   start_time=1779240034599
2026-05-20 01:21:43.455644 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240034599 ]]
2026-05-20 01:21:43.460460 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:21:43.466178 | controller | + functions-common:time_stop:2426          :   end_time=1779240103462
2026-05-20 01:21:43.469977 | controller | + functions-common:time_stop:2427          :   elapsed_time=68863
2026-05-20 01:21:43.474046 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:21:43.477676 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:21:43.481853 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=68863
2026-05-20 01:21:43.486281 | controller | + functions-common:apt_get:1209            :   return 0
2026-05-20 01:21:43.493091 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-05-20 01:21:43.499573 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-05-20 01:21:43.503832 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-05-20 01:21:43.509682 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-05-20 01:21:43.513964 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:43.520788 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:43.520821 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:43.530143 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:43.534511 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:43.540098 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-05-20 01:21:43.545366 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-05-20 01:21:43.550490 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:43.556882 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:43.557033 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:43.563585 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:43.568047 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:43.573611 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:43.578254 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-05-20 01:21:43.582155 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-05-20 01:21:43.587463 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-05-20 01:21:43.593784 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-05-20-012143
2026-05-20 01:21:43.598106 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:43.602699 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-05-20 01:21:43.606828 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-05-20 01:21:43.611072 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-05-20 01:21:43.617880 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-05-20 01:21:43.624759 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012143
2026-05-20 01:21:43.628821 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012143.summary.2026-05-20-012143
2026-05-20 01:21:43.632289 | controller | + ./stack.sh:main:476                      :   exec
2026-05-20 01:21:43.634896 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:43.638949 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-05-20 01:21:43.642563 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:43.646296 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-05-20 01:21:43.649150 | controller | + ./stack.sh:main:483                      :   exec
2026-05-20 01:21:43.654955 | controller | ++ ./stack.sh:main:483                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -v --no-timestamp -o /opt/stack/logs/devstacklog.txt.2026-05-20-012143
2026-05-20 01:21:43.686571 | controller | + ./stack.sh:main:485                      :   exec
2026-05-20 01:21:43.686645 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012143'
2026-05-20 01:21:43.686656 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-05-20-012143.summary.2026-05-20-012143
2026-05-20 01:21:43.686667 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-05-20 01:21:43.686676 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012143
2026-05-20 01:21:43.686685 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012143 /opt/stack/logs/devstacklog.txt
2026-05-20 01:21:43.686695 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012143.summary.2026-05-20-012143 /opt/stack/logs/devstacklog.txt.summary
2026-05-20 01:21:43.686705 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-05-20 01:21:43.687138 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-05-20 01:21:43.691306 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-05-20 01:21:43.698297 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-05-20 01:21:43.701955 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-05-20 01:21:43.708149 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-05-20 01:21:43.708396 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-05-20 01:21:43.715638 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:43.719853 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-05-20 01:21:43.724625 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-05-20 01:21:43.732369 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:43.736928 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:43.741725 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-05-20 01:21:43.747251 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-05-20 01:21:43.754975 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:43.759096 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-05-20 01:21:43.764127 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-05-20 01:21:43.767432 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-05-20 01:21:43.771509 | controller | + ./stack.sh:main:574                      :   uname -a
2026-05-20 01:21:43.774227 | controller | Linux np0000175143 6.8.0-111-generic #111-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 11 23:16:02 UTC 2026 x86_64 x86_64 x86_64 GNU/Linux
2026-05-20 01:21:43.779007 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-05-20 01:21:43.783224 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-05-20 01:21:43.788913 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-05-20 01:21:43.858458 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-05-20 01:21:43.890021 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-05-20 01:21:43.895009 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-05-20 01:21:43.921804 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-05-20 01:21:43.927487 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-05-20 01:21:43.955100 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-05-20 01:21:43.960794 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-05-20 01:21:43.988695 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-05-20 01:21:43.992417 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-05-20 01:21:43.996770 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-05-20 01:21:44.004554 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-05-20 01:21:44.033520 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:44.038993 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-05-20 01:21:44.065884 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:44.071172 | controller | + ./stack.sh:main:588                      :   tune_host
2026-05-20 01:21:44.075136 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-05-20 01:21:44.079462 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-05-20 01:21:44.083542 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:44.087825 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-05-20 01:21:44.091689 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:44.095842 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:44.101007 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-05-20 01:21:44.101518 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-05-20 01:21:44.114513 | controller | lz4
2026-05-20 01:21:44.120820 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-05-20 01:21:44.122190 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-05-20 01:21:44.135412 | controller | zsmalloc
2026-05-20 01:21:44.141662 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-05-20 01:21:44.142378 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-05-20 01:21:44.155345 | controller | 1
2026-05-20 01:21:44.181447 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-05-20 01:21:44.197273 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-05-20 01:21:44.197309 | controller | /sys/module/zswap/parameters/enabled:Y
2026-05-20 01:21:44.197320 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-05-20 01:21:44.197328 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-05-20 01:21:44.197340 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-05-20 01:21:44.197352 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-05-20 01:21:44.197410 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-05-20 01:21:44.197420 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-05-20 01:21:44.197431 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-05-20 01:21:44.204119 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-05-20 01:21:44.209459 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:44.212810 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-05-20 01:21:44.216640 | controller | + functions-common:install_package:1432    :   update_package_repo
2026-05-20 01:21:44.220445 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:21:44.224639 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=False
2026-05-20 01:21:44.227257 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=False
2026-05-20 01:21:44.231190 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:44.234345 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:21:44.237300 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:44.241240 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:44.244992 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:21:44.249002 | controller | + functions-common:apt_get_update:1160     :   [[ False == \T\r\u\e ]]
2026-05-20 01:21:44.253101 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:44.256726 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:21:44.261313 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:21:44.267298 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:21:44.270911 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:21:44.275225 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:21:44.278920 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:21:44.282808 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:21:44.287726 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:21:44.294432 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240104289
2026-05-20 01:21:44.297631 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:21:44.300947 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:21:44.304805 | controller | + functions-common:apt_get_update:1175     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-05-20 01:21:54.384423 | controller | Ign:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:21:54.386060 | controller | Ign:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:03.994885 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:22:10.402714 | controller | Ign:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:14.007788 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:22:24.020253 | controller | Ign:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:29.024834 | controller | Ign:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:39.042426 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:22:41.561883 | controller | Err:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:41.561933 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:54.064645 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:04.080372 | controller | Ign:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:14.156420 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:28.748946 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:42.736903 | controller | Err:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:42.736952 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:52.753386 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:52.753431 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:02.768768 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:24:02.768810 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:03.823189 | controller | Reading package lists...
2026-05-20 01:24:03.845838 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:03.845933 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-updates/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:03.846906 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-backports/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:03.847068 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:24:03.847086 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:24:03.856269 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:24:03.860782 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:24:03.865068 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:24:03.869807 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:24:03.874374 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:24:03.878127 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:24:03.882238 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:24:03.885442 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:24:03.889687 | controller | + functions-common:time_stop:2421          :   start_time=1779240104289
2026-05-20 01:24:03.893760 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240104289 ]]
2026-05-20 01:24:03.899677 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:24:03.906845 | controller | + functions-common:time_stop:2426          :   end_time=1779240243901
2026-05-20 01:24:03.911366 | controller | + functions-common:time_stop:2427          :   elapsed_time=139612
2026-05-20 01:24:03.915285 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:24:03.919032 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:24:03.923258 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=139612
2026-05-20 01:24:03.927160 | controller | + functions-common:install_package:1433    :   real_install_package ksmtuned
2026-05-20 01:24:03.931122 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:24:03.934502 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:24:03.938796 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:24:03.943454 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:24:03.978843 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-05-20 01:24:04.021973 | controller | Reading package lists...
2026-05-20 01:24:04.325883 | controller | Building dependency tree...
2026-05-20 01:24:04.326002 | controller | Reading state information...
2026-05-20 01:24:04.572806 | controller | Recommended packages:
2026-05-20 01:24:04.572907 | controller |   qemu-kvm
2026-05-20 01:24:04.602353 | controller | The following NEW packages will be installed:
2026-05-20 01:24:04.604884 | controller |   ksmtuned
2026-05-20 01:24:14.651363 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:24:14.651411 | controller | Need to get 7,444 B of archives.
2026-05-20 01:24:14.651421 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:24:14.651430 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:22.402114 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:34.417079 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:48.438060 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:48.438102 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:48.448910 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:48.448946 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:24:48.457838 | controller | + functions-common:apt_get:1205            :   result=100
2026-05-20 01:24:48.463084 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:24:48.468404 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:24:48.472594 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:24:48.477127 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:24:48.482677 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:24:48.486467 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:24:48.490407 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:24:48.495637 | controller | + functions-common:time_stop:2421          :   start_time=1779240243973
2026-05-20 01:24:48.499610 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240243973 ]]
2026-05-20 01:24:48.505593 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:24:48.512379 | controller | + functions-common:time_stop:2426          :   end_time=1779240288507
2026-05-20 01:24:48.516980 | controller | + functions-common:time_stop:2427          :   elapsed_time=44534
2026-05-20 01:24:48.520935 | controller | + functions-common:time_stop:2428          :   total=68863
2026-05-20 01:24:48.525806 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:24:48.529733 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=113397
2026-05-20 01:24:48.534643 | controller | + functions-common:apt_get:1209            :   return 100
2026-05-20 01:24:48.538749 | controller | + functions-common:install_package:1434    :   RETRY_UPDATE=True
2026-05-20 01:24:48.544055 | controller | + functions-common:install_package:1434    :   update_package_repo
2026-05-20 01:24:48.548621 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:24:48.553353 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=True
2026-05-20 01:24:48.557623 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=True
2026-05-20 01:24:48.562150 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:24:48.566919 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:24:48.570952 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:24:48.575522 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:24:48.579648 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:24:48.584087 | controller | + functions-common:apt_get_update:1160     :   [[ True == \T\r\u\e ]]
2026-05-20 01:24:48.588218 | controller | + functions-common:apt_get_update:1160     :   [[ True != \T\r\u\e ]]
2026-05-20 01:24:48.592130 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:24:48.596009 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:24:48.601700 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:24:48.607938 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:24:48.612405 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:24:48.616601 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:24:48.620819 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:24:48.624822 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:24:48.629831 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:24:48.636709 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240288631
2026-05-20 01:24:48.640833 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:24:48.646300 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:24:48.651586 | controller | + functions-common:apt_get_update:1175     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-05-20 01:24:55.403404 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:24:58.739354 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:05.418128 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:25:09.756463 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:20.431494 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:21.765769 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:30.436582 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:25:40.449281 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:25:40.786119 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:40.786156 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:25:45.458943 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:58.526175 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:08.539821 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:18.554177 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:28.564833 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:28.564882 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:38.578957 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:38.578994 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:48.593034 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:48.593075 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:49.611298 | controller | Reading package lists...
2026-05-20 01:26:49.630953 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:49.631100 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-updates/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:49.631212 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-backports/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:49.631320 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:26:49.632956 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:26:49.640902 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:26:49.644854 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:26:49.647987 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:26:49.651666 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:26:49.655108 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:26:49.658948 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:26:49.661590 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:26:49.665686 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:26:49.669279 | controller | + functions-common:time_stop:2421          :   start_time=1779240288631
2026-05-20 01:26:49.674087 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240288631 ]]
2026-05-20 01:26:49.678815 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:26:49.684369 | controller | + functions-common:time_stop:2426          :   end_time=1779240409679
2026-05-20 01:26:49.688112 | controller | + functions-common:time_stop:2427          :   elapsed_time=121048
2026-05-20 01:26:49.692052 | controller | + functions-common:time_stop:2428          :   total=139612
2026-05-20 01:26:49.695626 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:26:49.700041 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=260660
2026-05-20 01:26:49.703645 | controller | + functions-common:install_package:1434    :   real_install_package ksmtuned
2026-05-20 01:26:49.706945 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:26:49.711561 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:26:49.715625 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:26:49.719137 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:26:49.751969 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-05-20 01:26:49.798728 | controller | Reading package lists...
2026-05-20 01:26:50.055429 | controller | Building dependency tree...
2026-05-20 01:26:50.055775 | controller | Reading state information...
2026-05-20 01:26:50.312453 | controller | Recommended packages:
2026-05-20 01:26:50.312488 | controller |   qemu-kvm
2026-05-20 01:26:50.338862 | controller | The following NEW packages will be installed:
2026-05-20 01:26:50.340933 | controller |   ksmtuned
2026-05-20 01:27:00.387327 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:27:00.387399 | controller | Need to get 7,444 B of archives.
2026-05-20 01:27:00.387411 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:27:00.387420 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:11.404024 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:19.901972 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:33.922663 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:33.922740 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:33.927152 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:33.927250 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:27:33.934245 | controller | + functions-common:apt_get:1               :   exit_trap
2026-05-20 01:27:33.937177 | controller | + ./stack.sh:exit_trap:519                 :   local r=100
2026-05-20 01:27:33.942161 | controller | ++ ./stack.sh:exit_trap:520                 :   jobs -p
2026-05-20 01:27:33.947623 | controller | + ./stack.sh:exit_trap:520                 :   jobs=
2026-05-20 01:27:33.951177 | controller | + ./stack.sh:exit_trap:523                 :   [[ -n '' ]]
2026-05-20 01:27:33.955119 | controller | + ./stack.sh:exit_trap:529                 :   '[' -f '' ']'
2026-05-20 01:27:33.958058 | controller | + ./stack.sh:exit_trap:534                 :   kill_spinner
2026-05-20 01:27:33.961648 | controller | + ./stack.sh:kill_spinner:429              :   '[' '!' -z '' ']'
2026-05-20 01:27:33.965226 | controller | + ./stack.sh:exit_trap:536                 :   [[ 100 -ne 0 ]]
2026-05-20 01:27:33.969194 | controller | + ./stack.sh:exit_trap:537                 :   echo 'Error on exit'
2026-05-20 01:27:33.969257 | controller | Error on exit
2026-05-20 01:27:33.972560 | controller | + ./stack.sh:exit_trap:539                 :   type -p generate-subunit
2026-05-20 01:27:33.976334 | controller | + ./stack.sh:exit_trap:542                 :   [[ -z /opt/stack/logs ]]
2026-05-20 01:27:33.980452 | controller | + ./stack.sh:exit_trap:545                 :   /usr/bin/python3.12 /opt/stack/devstack/tools/worlddump.py -d /opt/stack/logs
2026-05-20 01:27:34.554370 | controller | + ./stack.sh:exit_trap:554                 :   exit 100
2026-05-20 01:27:34.555882 | controller | *** FINISHED ***
2026-05-20 01:27:34.830963 | controller | ERROR
2026-05-20 01:27:34.831257 | controller | {
2026-05-20 01:27:34.831303 | controller |   "delta": "0:07:04.161644",
2026-05-20 01:27:34.831334 | controller |   "end": "2026-05-20 01:27:34.572885",
2026-05-20 01:27:34.831370 | controller |   "msg": "non-zero return code",
2026-05-20 01:27:34.831413 | controller |   "rc": 100,
2026-05-20 01:27:34.831457 | controller |   "start": "2026-05-20 01:20:30.411241"
2026-05-20 01:27:34.831487 | controller | }
failure
2026-05-20 01:27:34.847650 | 
2026-05-20 01:27:34.847706 | PLAY RECAP
2026-05-20 01:27:34.847758 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:27:34.847780 | 
2026-05-20 01:27:35.008725 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:27:35.015888 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:27:35.719298 | 
2026-05-20 01:27:35.719460 | PLAY [all]
2026-05-20 01:27:35.731197 | 
2026-05-20 01:27:35.731281 | LOOP [Copy individual Hydrophone result files]
2026-05-20 01:27:36.340960 | controller | ERROR: Item: e2e.log
2026-05-20 01:27:36.341298 | controller | {
2026-05-20 01:27:36.341341 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:36.341370 | controller |   "item": "e2e.log",
2026-05-20 01:27:36.341428 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-20 01:27:36.341453 | controller | }
2026-05-20 01:27:36.602368 | controller | ERROR: Item: junit_01.xml
2026-05-20 01:27:36.602566 | controller | {
2026-05-20 01:27:36.602623 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:36.602662 | controller |   "item": "junit_01.xml",
2026-05-20 01:27:36.602696 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-20 01:27:36.602729 | controller | }
2026-05-20 01:27:36.614009 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:36.620568 | 
2026-05-20 01:27:36.620632 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-20 01:27:36.913634 | controller | ERROR
2026-05-20 01:27:36.913896 | controller | {
2026-05-20 01:27:36.913929 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-20 01:27:36.913951 | controller | }
2026-05-20 01:27:36.913976 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:36.925007 | 
2026-05-20 01:27:36.925077 | TASK [Return built artifacts to Zuul]
2026-05-20 01:27:37.000874 | controller | ok
2026-05-20 01:27:37.006340 | 
2026-05-20 01:27:37.006404 | TASK [Get the Docker volume ID]
2026-05-20 01:27:37.545901 | controller | ERROR
2026-05-20 01:27:37.546183 | controller | {
2026-05-20 01:27:37.546227 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-20 01:27:37.546257 | controller |   "rc": 2
2026-05-20 01:27:37.546285 | controller | }
2026-05-20 01:27:37.546325 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:37.552432 | 
2026-05-20 01:27:37.552501 | TASK [Copy all of the pod logs]
2026-05-20 01:27:37.588018 | controller | ERROR
2026-05-20 01:27:37.588221 | controller | {
2026-05-20 01:27:37.588262 | controller |   "msg": "The task includes an option with an undefined variable. The error was: list object has no element 0. list object has no element 0\n\nThe error appears to be in '/var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml': line 42, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-05-20 01:27:37.588294 | controller | }
2026-05-20 01:27:37.588328 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:37.589969 | 
2026-05-20 01:27:37.590039 | PLAY RECAP
2026-05-20 01:27:37.590100 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-20 01:27:37.590131 | 
2026-05-20 01:27:37.724928 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:27:37.732050 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:27:38.734695 | 
2026-05-20 01:27:38.735108 | PLAY [all]
2026-05-20 01:27:38.762073 | 
2026-05-20 01:27:38.762270 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-20 01:27:39.341201 | controller | changed
2026-05-20 01:27:39.347235 | 
2026-05-20 01:27:39.347316 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-20 01:27:40.387364 | controller | ok: Runtime: 0:00:00.455485
2026-05-20 01:27:40.394321 | 
2026-05-20 01:27:40.394391 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-20 01:27:40.933239 | controller | ok: Runtime: 0:00:00.023967
2026-05-20 01:27:40.940267 | 
2026-05-20 01:27:40.940334 | TASK [export-devstack-journal : Export journal]
2026-05-20 01:27:41.503346 | controller | ok: Runtime: 0:00:00.026138
2026-05-20 01:27:41.510722 | 
2026-05-20 01:27:41.510787 | TASK [export-devstack-journal : Save journal README]
2026-05-20 01:27:42.354650 | controller | changed
2026-05-20 01:27:42.368525 | 
2026-05-20 01:27:42.368602 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-20 01:27:42.604820 | controller | changed
2026-05-20 01:27:42.613183 | 
2026-05-20 01:27:42.613251 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:27:42.943638 | controller | Output suppressed because no_log was given
2026-05-20 01:27:42.950495 | 
2026-05-20 01:27:42.950596 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:27:42.985276 | 
2026-05-20 01:27:42.985460 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:27:43.018754 | 
2026-05-20 01:27:43.018909 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:27:43.050899 | 
2026-05-20 01:27:43.051042 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:27:43.082583 | 
2026-05-20 01:27:43.082750 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:27:43.123215 | 
2026-05-20 01:27:43.123346 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-20 01:27:43.360030 | controller | changed
2026-05-20 01:27:43.367447 | 
2026-05-20 01:27:43.367509 | TASK [apache-logs-conf : Define config paths]
2026-05-20 01:27:43.398838 | controller | ok
2026-05-20 01:27:43.404828 | 
2026-05-20 01:27:43.404889 | TASK [apache-logs-conf : Discover configurations]
2026-05-20 01:27:43.623216 | controller | Output suppressed because no_log was given
2026-05-20 01:27:43.644530 | 
2026-05-20 01:27:43.644677 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-20 01:27:43.670121 | 
2026-05-20 01:27:43.670554 | LOOP [apache-logs-conf : Link configurations]
2026-05-20 01:27:43.700257 | 
2026-05-20 01:27:43.700403 | TASK [capture-performance-data : Generate statistics]
2026-05-20 01:27:46.181683 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-20 01:27:46.199018 | controller | Using python 3.12 to install setuptools
2026-05-20 01:27:46.234111 | controller | /usr/bin/python3.12: No module named pip
2026-05-20 01:27:46.244683 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-20 01:27:46.741614 | controller | ERROR
2026-05-20 01:27:46.741913 | controller | {
2026-05-20 01:27:46.741963 | controller |   "delta": "0:00:02.291295",
2026-05-20 01:27:46.741992 | controller |   "end": "2026-05-20 01:27:46.246168",
2026-05-20 01:27:46.742018 | controller |   "msg": "non-zero return code",
2026-05-20 01:27:46.742051 | controller |   "rc": 127,
2026-05-20 01:27:46.742078 | controller |   "start": "2026-05-20 01:27:43.954873"
2026-05-20 01:27:46.742103 | controller | }
2026-05-20 01:27:46.742138 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:46.755696 | 
2026-05-20 01:27:46.755777 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-20 01:27:47.004730 | controller | changed
2026-05-20 01:27:47.011565 | 
2026-05-20 01:27:47.011639 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-20 01:27:50.724916 | controller | Output suppressed because no_log was given
2026-05-20 01:27:50.735578 | 
2026-05-20 01:27:50.735705 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-20 01:27:50.808655 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.808859 | 
2026-05-20 01:27:50.811142 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.814015 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.816856 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.819830 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.822668 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.825395 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.828348 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.831796 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.834494 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.836942 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.839572 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.842307 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.845140 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.847878 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.850801 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.853664 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.856165 | controller | skipping: Conditional result was False
2026-05-20 01:27:50.933034 | 
2026-05-20 01:27:50.933142 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-20 01:27:51.177139 | controller | ok
2026-05-20 01:27:51.182491 | 
2026-05-20 01:27:51.182735 | TASK [devstack-project-conf : Copy configuration files]
2026-05-20 01:27:51.715635 | controller | skipping: Conditional result was False
2026-05-20 01:27:51.733305 | 
2026-05-20 01:27:51.733424 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-20 01:27:52.031788 | controller | /usr/bin/python3: No module named pip
2026-05-20 01:27:53.050669 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-20 01:27:53.270150 | controller | ok: Runtime: 0:00:01.082630
2026-05-20 01:27:53.280820 | 
2026-05-20 01:27:53.280913 | LOOP [stage-output : Register sources]
2026-05-20 01:28:02.544142 | controller | Output suppressed because no_log was given
2026-05-20 01:28:02.560149 | 
2026-05-20 01:28:02.560246 | TASK [stage-output : Check sudo]
2026-05-20 01:28:03.120733 | controller | ok: Runtime: 0:00:00.028895
2026-05-20 01:28:03.130418 | 
2026-05-20 01:28:03.130559 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-20 01:28:03.227533 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.227910 | 
2026-05-20 01:28:03.229956 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.231991 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.234762 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.273256 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.273788 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.274278 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.277475 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.280143 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.282927 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.285867 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.290173 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.312038 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.318206 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.318700 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.321216 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.347847 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.348326 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.349070 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.386285 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.386813 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.494582 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.509437 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.532141 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.538071 | controller | Output suppressed because no_log was given
2026-05-20 01:28:03.553742 | 
2026-05-20 01:28:03.553834 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-20 01:28:03.618539 | controller | ok
2026-05-20 01:28:03.626618 | 
2026-05-20 01:28:03.626682 | LOOP [stage-output : Ensure target folders exist]
2026-05-20 01:28:03.875837 | controller | changed: "docs"
2026-05-20 01:28:04.084099 | controller | changed: "artifacts"
2026-05-20 01:28:04.292207 | controller | ok: "logs"
2026-05-20 01:28:04.308143 | 
2026-05-20 01:28:04.308340 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-20 01:28:04.592152 | controller | ok: Item: Runtime: 0:00:00.008929
2026-05-20 01:28:04.592357 | controller | changed: All items complete
2026-05-20 01:28:04.592385 | 
2026-05-20 01:28:04.813960 | controller | ok: Item: Runtime: 0:00:00.008507
2026-05-20 01:28:05.038153 | controller | ok: Item: Runtime: 0:00:00.008213
2026-05-20 01:28:05.257239 | controller | ok: Item: Runtime: 0:00:00.005301
2026-05-20 01:28:05.481054 | controller | ok: Item: Runtime: 0:00:00.008226
2026-05-20 01:28:05.697440 | controller | ok: Item: Runtime: 0:00:00.007237
2026-05-20 01:28:05.990885 | controller | ok: Item: Runtime: 0:00:00.006921
2026-05-20 01:28:06.176616 | controller | ok: Item: Runtime: 0:00:00.007477
2026-05-20 01:28:06.441608 | controller | ok: Item: Runtime: 0:00:00.010890
2026-05-20 01:28:06.676138 | controller | ok: Item: Runtime: 0:00:00.008359
2026-05-20 01:28:06.890807 | controller | ok: Item: Runtime: 0:00:00.005347
2026-05-20 01:28:07.111790 | controller | ok: Item: Runtime: 0:00:00.008166
2026-05-20 01:28:07.331999 | controller | ok: Item: Runtime: 0:00:00.007430
2026-05-20 01:28:07.560569 | controller | ok: Item: Runtime: 0:00:00.007729
2026-05-20 01:28:07.783378 | controller | ok: Item: Runtime: 0:00:00.005689
2026-05-20 01:28:08.015521 | controller | ok: Item: Runtime: 0:00:00.007671
2026-05-20 01:28:08.259213 | controller | ok: Item: Runtime: 0:00:00.008815
2026-05-20 01:28:08.487140 | controller | ok: Item: Runtime: 0:00:00.009293
2026-05-20 01:28:08.714736 | controller | ok: Item: Runtime: 0:00:00.007990
2026-05-20 01:28:08.946047 | controller | ok: Item: Runtime: 0:00:00.007973
2026-05-20 01:28:08.979479 | 
2026-05-20 01:28:08.979608 | TASK [stage-output : Make all log files readable]
2026-05-20 01:28:09.219474 | controller | changed
2026-05-20 01:28:09.226973 | 
2026-05-20 01:28:09.227040 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-20 01:28:09.856220 | controller | changed: Renamed files for staging.
2026-05-20 01:28:09.861907 | 
2026-05-20 01:28:09.861975 | TASK [stage-output : Discover log files for compression]
2026-05-20 01:28:09.888074 | controller | skipping: Conditional result was False
2026-05-20 01:28:09.897545 | 
2026-05-20 01:28:09.897692 | LOOP [stage-output : Archive everything from logs]
2026-05-20 01:28:09.943123 | 
2026-05-20 01:28:09.943281 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-20 01:28:10.972126 | controller | changed:
2026-05-20 01:28:10.972344 | controller | created directory /var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/work/logs/controller
2026-05-20 01:28:10.972372 | controller | cd+++++++++ logs/
2026-05-20 01:28:10.972393 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-05-20 01:28:10.972413 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-20 01:28:10.972432 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-20 01:28:10.972451 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-20 01:28:10.972469 | controller | >f+++++++++ logs/devstacklog.txt
2026-05-20 01:28:10.972487 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-05-20 01:28:10.972505 | controller | >f+++++++++ logs/df.txt
2026-05-20 01:28:10.972523 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-20 01:28:10.972540 | controller | >f+++++++++ logs/iptables.txt
2026-05-20 01:28:10.972558 | controller | >f+++++++++ logs/listen53.txt
2026-05-20 01:28:10.972575 | controller | >f+++++++++ logs/local_conf.txt
2026-05-20 01:28:10.972593 | controller | >f+++++++++ logs/mount.txt
2026-05-20 01:28:10.972610 | controller | >f+++++++++ logs/performance.json
2026-05-20 01:28:10.972634 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-20 01:28:10.972654 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-20 01:28:10.972672 | controller | >f+++++++++ logs/services.txt
2026-05-20 01:28:10.972691 | controller | >f+++++++++ logs/sudoers
2026-05-20 01:28:10.972708 | controller | >f+++++++++ logs/syslog.txt
2026-05-20 01:28:10.972726 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-05-20 01:28:10.972744 | controller | cd+++++++++ logs/apache/
2026-05-20 01:28:10.972762 | controller | cd+++++++++ logs/apache_config/
2026-05-20 01:28:10.972779 | controller | cd+++++++++ logs/etc/
2026-05-20 01:28:10.972797 | controller | cd+++++++++ logs/sudoers.d/
2026-05-20 01:28:10.972814 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-20 01:28:10.972833 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-20 01:28:10.972854 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-20 01:28:10.972892 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-20 01:28:10.972911 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-20 01:28:10.984021 | 
2026-05-20 01:28:10.984085 | TASK [Check if a tempest log exits]
2026-05-20 01:28:11.247645 | controller | ok
2026-05-20 01:28:11.255062 | 
2026-05-20 01:28:11.255133 | TASK [Link post-devstack tempest.log]
2026-05-20 01:28:11.280785 | controller | skipping: Conditional result was False
2026-05-20 01:28:11.292187 | 
2026-05-20 01:28:11.292256 | TASK [Capture most recent qemu crash dump, if any]
2026-05-20 01:28:11.543929 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-20 01:28:11.835470 | controller | ERROR
2026-05-20 01:28:11.835734 | controller | {
2026-05-20 01:28:11.835797 | controller |   "delta": "0:00:00.007666",
2026-05-20 01:28:11.835841 | controller |   "end": "2026-05-20 01:28:11.544468",
2026-05-20 01:28:11.835883 | controller |   "msg": "non-zero return code",
2026-05-20 01:28:11.835923 | controller |   "rc": 127,
2026-05-20 01:28:11.835963 | controller |   "start": "2026-05-20 01:28:11.536802"
2026-05-20 01:28:11.836001 | controller | }
2026-05-20 01:28:11.836051 | controller | ERROR: Ignoring Errors
2026-05-20 01:28:11.838547 | 
2026-05-20 01:28:11.838642 | PLAY RECAP
2026-05-20 01:28:11.838729 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-20 01:28:11.838777 | 
2026-05-20 01:28:12.015893 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:28:12.022413 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:12.828487 | 
2026-05-20 01:28:12.828689 | PLAY [all]
2026-05-20 01:28:12.843087 | 
2026-05-20 01:28:12.843196 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 01:28:12.898780 | controller | skipping: Conditional result was False
2026-05-20 01:28:12.934111 | 
2026-05-20 01:28:12.934297 | TASK [fetch-output : Set log path for single node]
2026-05-20 01:28:12.980522 | controller | ok
2026-05-20 01:28:13.066546 | 
2026-05-20 01:28:13.066675 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 01:28:13.698591 | controller -> localhost | ok: "/var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/work/logs"
2026-05-20 01:28:13.920887 | controller -> localhost | changed: "/var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/work/artifacts"
2026-05-20 01:28:14.323872 | controller -> localhost | changed: "/var/lib/zuul/builds/e83b3bba95ed4c16ba96d18210cbcf05/work/docs"
2026-05-20 01:28:14.340414 | 
2026-05-20 01:28:14.340546 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 01:28:14.987594 | controller | changed: .d..t...... ./
2026-05-20 01:28:14.987781 | controller | changed: All items complete
2026-05-20 01:28:14.987809 | 
2026-05-20 01:28:15.512252 | controller | changed: .d..t...... ./
2026-05-20 01:28:15.986676 | controller | changed: .d..t...... ./
2026-05-20 01:28:16.013020 | 
2026-05-20 01:28:16.013140 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 01:28:16.491235 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007764
2026-05-20 01:28:16.724897 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.010127
2026-05-20 01:28:16.738673 | 
2026-05-20 01:28:16.738760 | PLAY [all]
2026-05-20 01:28:16.744908 | 
2026-05-20 01:28:16.744988 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 01:28:17.636360 | controller | changed
2026-05-20 01:28:17.645494 | 
2026-05-20 01:28:17.645722 | PLAY RECAP
2026-05-20 01:28:17.645810 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:28:17.645840 | 
2026-05-20 01:28:17.812387 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:17.823462 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 01:28:18.687114 | 
2026-05-20 01:28:18.687605 | PLAY [localhost]
2026-05-20 01:28:18.703347 | 
2026-05-20 01:28:18.703479 | TASK [Generate Zuul manifest]
2026-05-20 01:28:18.725622 | localhost | ok
2026-05-20 01:28:18.926199 | 
2026-05-20 01:28:18.926399 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 01:28:19.365535 | localhost | changed
2026-05-20 01:28:19.378862 | 
2026-05-20 01:28:19.379072 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 01:28:19.442314 | localhost | ok
2026-05-20 01:28:19.453371 | 
2026-05-20 01:28:19.453518 | TASK [Upload logs]
2026-05-20 01:28:19.477928 | localhost | ok
2026-05-20 01:28:19.592366 | 
2026-05-20 01:28:19.592492 | TASK [Set zuul-log-path fact]
2026-05-20 01:28:19.613990 | localhost | ok
2026-05-20 01:28:19.630395 | 
2026-05-20 01:28:19.630463 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:28:19.663319 | localhost | ok
2026-05-20 01:28:19.675961 | 
2026-05-20 01:28:19.676033 | TASK [upload-logs : Create log directories]
2026-05-20 01:28:20.085284 | localhost | changed
2026-05-20 01:28:20.091665 | 
2026-05-20 01:28:20.091740 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 01:28:20.542625 | localhost -> localhost | ok: Runtime: 0:00:00.004409
2026-05-20 01:28:20.552584 | 
2026-05-20 01:28:20.552672 | TASK [upload-logs : Upload logs to log server]
2026-05-20 01:28:21.212745 | localhost | Output suppressed because no_log was given
2026-05-20 01:28:21.218710 | 
2026-05-20 01:28:21.218801 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 01:28:21.277301 | localhost | skipping: Conditional result was False
2026-05-20 01:28:21.290172 | localhost | skipping: Conditional result was False
2026-05-20 01:28:21.303048 | 
2026-05-20 01:28:21.313370 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 01:28:21.363869 | localhost | skipping: Conditional result was False
2026-05-20 01:28:21.364251 | 
2026-05-20 01:28:21.368586 | localhost | skipping: Conditional result was False
2026-05-20 01:28:21.389714 | 
2026-05-20 01:28:21.389910 | LOOP [upload-logs : Upload console log and json output]