2026-03-20 00:18:09.774654 | Job console starting
2026-03-20 00:18:09.784513 | Updating git repos
2026-03-20 00:18:11.642049 | Cloning repos into workspace
2026-03-20 00:18:32.692142 | Restoring repo states
2026-03-20 00:18:35.235084 | Merging changes
2026-03-20 00:18:35.804037 | Checking out repos
2026-03-20 00:18:44.097864 | Preparing playbooks
2026-03-20 00:18:58.146216 | Running Ansible setup
2026-03-20 00:19:02.118733 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-20 00:19:02.728060 | 
2026-03-20 00:19:02.728202 | PLAY [localhost]
2026-03-20 00:19:02.735912 | 
2026-03-20 00:19:02.735980 | TASK [Gathering Facts]
2026-03-20 00:19:03.699139 | localhost | ok
2026-03-20 00:19:03.706467 | 
2026-03-20 00:19:03.706545 | TASK [Setup log path fact]
2026-03-20 00:19:03.727743 | localhost | ok
2026-03-20 00:19:03.742726 | 
2026-03-20 00:19:03.742795 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-20 00:19:03.773124 | localhost | ok
2026-03-20 00:19:03.781949 | 
2026-03-20 00:19:03.782039 | TASK [emit-job-header : Print job information]
2026-03-20 00:19:03.823242 | # Job Information
2026-03-20 00:19:03.830319 | Ansible Version: 2.16.16
2026-03-20 00:19:03.830382 | Job: magnum-cluster-api-hydrophone-v1.35.2-calico
2026-03-20 00:19:03.830416 | Pipeline: check
2026-03-20 00:19:03.830444 | Executor: 0a8996d2b663
2026-03-20 00:19:03.830471 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/878
2026-03-20 00:19:03.830503 | Event ID: 2d5b9330-23f2-11f1-97f7-83b31b994005
2026-03-20 00:19:03.834254 | 
2026-03-20 00:19:03.834340 | LOOP [emit-job-header : Print node information]
2026-03-20 00:19:03.940770 | localhost | ok:
2026-03-20 00:19:03.941043 | localhost | # Node Information
2026-03-20 00:19:03.941092 | localhost | Inventory Hostname: controller
2026-03-20 00:19:03.941121 | localhost | Hostname: np0000160184
2026-03-20 00:19:03.941147 | localhost | Username: zuul
2026-03-20 00:19:03.941170 | localhost | Distro: Ubuntu 24.04
2026-03-20 00:19:03.941190 | localhost | Provider: yul1
2026-03-20 00:19:03.941209 | localhost | Region: ca-ymq-1
2026-03-20 00:19:03.941234 | localhost | Label: ubuntu-noble-16
2026-03-20 00:19:03.941259 | localhost | Product Name: OpenStack Nova
2026-03-20 00:19:03.941284 | localhost | Interface IP: 199.204.45.67
2026-03-20 00:19:03.952448 | 
2026-03-20 00:19:03.952624 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-20 00:19:04.323148 | localhost -> localhost | changed
2026-03-20 00:19:04.329562 | 
2026-03-20 00:19:04.329655 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-20 00:19:05.212814 | localhost -> localhost | changed
2026-03-20 00:19:05.222410 | 
2026-03-20 00:19:05.222511 | PLAY [all]
2026-03-20 00:19:05.232998 | 
2026-03-20 00:19:05.233077 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-20 00:19:05.439149 | controller -> localhost | ok
2026-03-20 00:19:05.450362 | 
2026-03-20 00:19:05.450450 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-20 00:19:05.485996 | controller | ok
2026-03-20 00:19:05.502471 | controller | included: /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-20 00:19:05.509629 | 
2026-03-20 00:19:05.509686 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-20 00:19:06.602962 | controller -> localhost | Generating public/private rsa key pair.
2026-03-20 00:19:06.603175 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/work/61b80808cc364a0194748b0aa1cc3c0d_id_rsa
2026-03-20 00:19:06.603215 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/work/61b80808cc364a0194748b0aa1cc3c0d_id_rsa.pub
2026-03-20 00:19:06.603247 | controller -> localhost | The key fingerprint is:
2026-03-20 00:19:06.603276 | controller -> localhost | SHA256:ptDgNaVjAimgw7kb/sgrBd3TQOEk8ANtFrASUS/zgKI zuul-build-sshkey
2026-03-20 00:19:06.603320 | controller -> localhost | The key's randomart image is:
2026-03-20 00:19:06.603350 | controller -> localhost | +---[RSA 3072]----+
2026-03-20 00:19:06.603382 | controller -> localhost | |O*=++.  .        |
2026-03-20 00:19:06.603411 | controller -> localhost | |+***.  o         |
2026-03-20 00:19:06.603439 | controller -> localhost | |BB*.=o*          |
2026-03-20 00:19:06.603466 | controller -> localhost | |=.oBo*.o         |
2026-03-20 00:19:06.603494 | controller -> localhost | |E+  +.. S        |
2026-03-20 00:19:06.603521 | controller -> localhost | |. +  . o         |
2026-03-20 00:19:06.603548 | controller -> localhost | | +    .          |
2026-03-20 00:19:06.603575 | controller -> localhost | |o o              |
2026-03-20 00:19:06.603606 | controller -> localhost | |.+..             |
2026-03-20 00:19:06.603633 | controller -> localhost | +----[SHA256]-----+
2026-03-20 00:19:06.603698 | controller -> localhost | ok: Runtime: 0:00:00.687019
2026-03-20 00:19:06.613619 | 
2026-03-20 00:19:06.613710 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-20 00:19:06.646711 | controller | ok
2026-03-20 00:19:06.657359 | controller | included: /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-20 00:19:06.673259 | 
2026-03-20 00:19:06.673338 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-20 00:19:06.699365 | controller | skipping: Conditional result was False
2026-03-20 00:19:06.723090 | 
2026-03-20 00:19:06.723197 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-20 00:19:07.261270 | controller | changed
2026-03-20 00:19:07.271040 | 
2026-03-20 00:19:07.271175 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-20 00:19:07.496688 | controller | ok
2026-03-20 00:19:07.586884 | 
2026-03-20 00:19:07.587048 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-20 00:19:08.150416 | controller | changed
2026-03-20 00:19:08.156457 | 
2026-03-20 00:19:08.156522 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-20 00:19:08.704867 | controller | changed
2026-03-20 00:19:08.710951 | 
2026-03-20 00:19:08.711035 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-20 00:19:08.749576 | controller | skipping: Conditional result was False
2026-03-20 00:19:08.761338 | 
2026-03-20 00:19:08.761422 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-20 00:19:09.109446 | controller -> localhost | changed
2026-03-20 00:19:09.174232 | 
2026-03-20 00:19:09.174375 | TASK [add-build-sshkey : Add back temp key]
2026-03-20 00:19:09.470255 | controller -> localhost | Identity added: /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/work/61b80808cc364a0194748b0aa1cc3c0d_id_rsa (zuul-build-sshkey)
2026-03-20 00:19:09.470862 | controller -> localhost | ok: Runtime: 0:00:00.014901
2026-03-20 00:19:09.483600 | 
2026-03-20 00:19:09.483782 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-20 00:19:09.799302 | controller | ok
2026-03-20 00:19:09.804842 | 
2026-03-20 00:19:09.804906 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-20 00:19:09.829214 | controller | skipping: Conditional result was False
2026-03-20 00:19:09.846853 | 
2026-03-20 00:19:09.846921 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-20 00:19:10.170737 | controller | ok
2026-03-20 00:19:10.185222 | 
2026-03-20 00:19:10.185311 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-20 00:19:30.045074 | controller | Output suppressed because no_log was given
2026-03-20 00:19:30.058375 | 
2026-03-20 00:19:30.058434 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-20 00:19:30.285511 | controller | ok: "logs"
2026-03-20 00:19:30.285844 | controller | ok: All items complete
2026-03-20 00:19:30.285877 | 
2026-03-20 00:19:30.460765 | controller | ok: "artifacts"
2026-03-20 00:19:30.646112 | controller | ok: "docs"
2026-03-20 00:19:30.662321 | 
2026-03-20 00:19:30.662474 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-20 00:19:30.888038 | controller | changed: "logs"
2026-03-20 00:19:31.136616 | controller | changed: "artifacts"
2026-03-20 00:19:31.256411 | controller | changed: "docs"
2026-03-20 00:19:31.276852 | 
2026-03-20 00:19:31.277502 | PLAY RECAP
2026-03-20 00:19:31.277592 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-20 00:19:31.277637 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-20 00:19:31.277669 | 
2026-03-20 00:19:31.435337 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-20 00:19:31.444603 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-03-20 00:19:32.105804 | 
2026-03-20 00:19:32.105968 | PLAY [all]
2026-03-20 00:19:32.126882 | 
2026-03-20 00:19:32.127022 | TASK [Fix the permissions of the zuul home directory]
2026-03-20 00:19:32.513143 | controller | changed
2026-03-20 00:19:32.518812 | 
2026-03-20 00:19:32.518911 | TASK [Gather minimum local MTU]
2026-03-20 00:19:32.596736 | controller | ok
2026-03-20 00:19:32.602144 | 
2026-03-20 00:19:32.602208 | TASK [Calculate external_bridge_mtu]
2026-03-20 00:19:32.668728 | controller | ok
2026-03-20 00:19:32.673744 | 
2026-03-20 00:19:32.673808 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-03-20 00:19:32.709338 | controller | skipping: Conditional result was False
2026-03-20 00:19:32.719618 | 
2026-03-20 00:19:32.719684 | TASK [configure-swap : Get ephemeral0 device node]
2026-03-20 00:19:33.260031 | controller | ok: Runtime: 0:00:00.009432
2026-03-20 00:19:33.267161 | 
2026-03-20 00:19:33.267227 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-03-20 00:19:33.292397 | controller | skipping: Conditional result was False
2026-03-20 00:19:33.304135 | 
2026-03-20 00:19:33.304208 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-03-20 00:19:33.327534 | controller | skipping: Conditional result was False
2026-03-20 00:19:33.337115 | 
2026-03-20 00:19:33.337209 | TASK [configure-swap : Setup swap file on root device]
2026-03-20 00:19:33.390376 | controller | ok
2026-03-20 00:19:33.401493 | controller | included: /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-03-20 00:19:33.408440 | 
2026-03-20 00:19:33.408505 | TASK [configure-swap : Calculate required swap]
2026-03-20 00:19:33.461278 | controller | ok
2026-03-20 00:19:33.468391 | 
2026-03-20 00:19:33.468454 | TASK [configure-swap : Get root filesystem]
2026-03-20 00:19:33.748024 | controller | ext4
2026-03-20 00:19:34.038941 | controller | ok: Runtime: 0:00:00.016848
2026-03-20 00:19:34.044710 | 
2026-03-20 00:19:34.044774 | TASK [configure-swap : Save root filesystem]
2026-03-20 00:19:34.081445 | controller | ok
2026-03-20 00:19:34.086939 | 
2026-03-20 00:19:34.087007 | TASK [configure-swap : Debug the root_filesystem variable]
2026-03-20 00:19:34.118882 | controller | ok:
2026-03-20 00:19:34.119101 | controller | {
2026-03-20 00:19:34.119161 | controller |   "root_filesystem": "ext4"
2026-03-20 00:19:34.119196 | controller | }
2026-03-20 00:19:34.126719 | 
2026-03-20 00:19:34.126789 | TASK [configure-swap : Create swap backing file]
2026-03-20 00:19:44.549270 | controller | 8192+0 records in
2026-03-20 00:19:44.549444 | controller | 8192+0 records out
2026-03-20 00:19:44.549470 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 10.1198 s, 849 MB/s
2026-03-20 00:19:44.691012 | controller | ok: Runtime: 0:00:10.136691
2026-03-20 00:19:44.696642 | 
2026-03-20 00:19:44.696699 | TASK [configure-swap : Ensure swapfile perms]
2026-03-20 00:19:44.935202 | controller | changed
2026-03-20 00:19:44.941190 | 
2026-03-20 00:19:44.941275 | TASK [configure-swap : Make swapfile]
2026-03-20 00:20:08.119161 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-03-20 00:20:08.119350 | controller | no label, UUID=befb6242-1c20-42b2-abbf-c21f11f3854f
2026-03-20 00:20:08.246225 | controller | ok: Runtime: 0:00:22.915844
2026-03-20 00:20:08.251964 | 
2026-03-20 00:20:08.252035 | TASK [configure-swap : Write swap to fstab]
2026-03-20 00:20:08.664631 | controller | changed
2026-03-20 00:20:08.670293 | 
2026-03-20 00:20:08.670362 | TASK [configure-swap : Add all swap]
2026-03-20 00:20:09.209604 | controller | ok: Runtime: 0:00:00.013625
2026-03-20 00:20:09.217614 | 
2026-03-20 00:20:09.217710 | TASK [configure-swap : Debug the swap_required variable]
2026-03-20 00:20:09.253955 | controller | ok:
2026-03-20 00:20:09.254121 | controller | {
2026-03-20 00:20:09.254157 | controller |   "swap_required": "8192"
2026-03-20 00:20:09.254185 | controller | }
2026-03-20 00:20:09.262271 | 
2026-03-20 00:20:09.262359 | TASK [configure-swap : Set swappiness]
2026-03-20 00:20:09.651493 | controller | changed
2026-03-20 00:20:09.658930 | 
2026-03-20 00:20:09.659022 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-03-20 00:20:09.696553 | controller | ok:
2026-03-20 00:20:09.696852 | controller | {
2026-03-20 00:20:09.696912 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-03-20 00:20:09.697008 | controller | }
2026-03-20 00:20:09.709167 | 
2026-03-20 00:20:09.840090 | TASK [setup-stack-user : Create stack group]
2026-03-20 00:20:10.280585 | controller | changed
2026-03-20 00:20:10.287621 | 
2026-03-20 00:20:10.287714 | TASK [setup-stack-user : Create the stack user home folder]
2026-03-20 00:20:10.527410 | controller | changed
2026-03-20 00:20:10.561656 | 
2026-03-20 00:20:10.561747 | TASK [setup-stack-user : Create stack user]
2026-03-20 00:20:11.087597 | controller | changed
2026-03-20 00:20:11.153412 | 
2026-03-20 00:20:11.153482 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-03-20 00:20:11.390943 | controller | changed
2026-03-20 00:20:11.399668 | 
2026-03-20 00:20:11.399734 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-03-20 00:20:12.466190 | controller | changed
2026-03-20 00:20:12.474645 | 
2026-03-20 00:20:12.474727 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-03-20 00:20:12.723953 | controller | changed
2026-03-20 00:20:12.736150 | 
2026-03-20 00:20:12.736239 | TASK [setup-tempest-user : Create tempest group]
2026-03-20 00:20:13.029995 | controller | changed
2026-03-20 00:20:13.037703 | 
2026-03-20 00:20:13.038627 | TASK [setup-tempest-user : Create tempest user]
2026-03-20 00:20:13.443982 | controller | changed
2026-03-20 00:20:13.449502 | 
2026-03-20 00:20:13.449572 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-03-20 00:20:14.233303 | controller | changed
2026-03-20 00:20:14.245654 | 
2026-03-20 00:20:14.245721 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-03-20 00:20:14.574964 | controller | ok: Not all paths examined, check warnings for details
2026-03-20 00:20:14.590164 | 
2026-03-20 00:20:14.590594 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-03-20 00:20:15.058032 | controller | ok: Item: Runtime: 0:00:00.183999
2026-03-20 00:20:15.790212 | controller | ok: Item: Runtime: 0:00:00.519329
2026-03-20 00:20:16.132807 | controller | ok: Item: Runtime: 0:00:00.125932
2026-03-20 00:20:17.602373 | controller | ok: Item: Runtime: 0:00:01.246879
2026-03-20 00:20:18.382702 | controller | ok: Item: Runtime: 0:00:00.566755
2026-03-20 00:20:18.653416 | controller | ok: Item: Runtime: 0:00:00.061548
2026-03-20 00:20:19.363946 | controller | ok: Item: Runtime: 0:00:00.490199
2026-03-20 00:20:19.861068 | controller | ok: Item: Runtime: 0:00:00.283572
2026-03-20 00:20:20.356362 | controller | ok: Item: Runtime: 0:00:00.287601
2026-03-20 00:20:21.781435 | controller | ok: Item: Runtime: 0:00:01.202838
2026-03-20 00:20:22.232538 | controller | ok: Item: Runtime: 0:00:00.240743
2026-03-20 00:20:25.485466 | controller | ok: Item: Runtime: 0:00:03.030682
2026-03-20 00:20:26.024402 | controller | ok: Item: Runtime: 0:00:00.318126
2026-03-20 00:20:26.370288 | controller | ok: Item: Runtime: 0:00:00.137701
2026-03-20 00:20:26.760798 | controller | ok: Item: Runtime: 0:00:00.176160
2026-03-20 00:20:27.090779 | controller | ok: Item: Runtime: 0:00:00.103058
2026-03-20 00:20:27.124324 | 
2026-03-20 00:20:27.124393 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-03-20 00:20:27.342386 | controller | ok: All paths examined
2026-03-20 00:20:27.349156 | 
2026-03-20 00:20:27.349215 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-03-20 00:20:27.596277 | controller | ok: All paths examined
2026-03-20 00:20:27.603211 | 
2026-03-20 00:20:27.603272 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-03-20 00:20:28.029744 | controller | ok: Item: Runtime: 0:00:00.144995
2026-03-20 00:20:28.030089 | controller | changed: All items complete
2026-03-20 00:20:28.030121 | 
2026-03-20 00:20:28.427952 | controller | ok: Item: Runtime: 0:00:00.183137
2026-03-20 00:20:28.441294 | 
2026-03-20 00:20:28.441453 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-03-20 00:20:28.487411 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.490035 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.493345 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.496582 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.499913 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.503407 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.507218 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.510928 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.514376 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.517435 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.520856 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.524266 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.528039 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.531475 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.534636 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.538013 | controller | skipping: Conditional result was False
2026-03-20 00:20:28.561751 | 
2026-03-20 00:20:28.561827 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-03-20 00:20:30.876131 | controller | changed
2026-03-20 00:20:30.886432 | 
2026-03-20 00:20:30.886499 | TASK [setup-devstack-log-dir : Create logs directory]
2026-03-20 00:20:31.178056 | controller | changed
2026-03-20 00:20:31.187940 | 
2026-03-20 00:20:31.188007 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-03-20 00:20:31.433250 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-03-20 00:20:31.728372 | controller | ERROR
2026-03-20 00:20:31.728690 | controller | {
2026-03-20 00:20:31.728753 | controller |   "delta": "0:00:00.010036",
2026-03-20 00:20:31.728799 | controller |   "end": "2026-03-20 00:20:31.434005",
2026-03-20 00:20:31.728841 | controller |   "msg": "non-zero return code",
2026-03-20 00:20:31.728882 | controller |   "rc": 1,
2026-03-20 00:20:31.728922 | controller |   "start": "2026-03-20 00:20:31.423969"
2026-03-20 00:20:31.728992 | controller | }
2026-03-20 00:20:31.729053 | controller | ERROR: Ignoring Errors
2026-03-20 00:20:31.735134 | 
2026-03-20 00:20:31.735201 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-03-20 00:20:31.992165 | controller | ok
2026-03-20 00:20:32.004706 | 
2026-03-20 00:20:32.004782 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-03-20 00:20:32.260126 | controller | /usr/bin/journalctl
2026-03-20 00:20:32.545628 | controller | ok: Runtime: 0:00:00.006373
2026-03-20 00:20:32.551768 | 
2026-03-20 00:20:32.551833 | TASK [start-fresh-logging : Get current date]
2026-03-20 00:20:32.790217 | controller | 2026-03-20 00:20:32
2026-03-20 00:20:33.092110 | controller | ok: Runtime: 0:00:00.007666
2026-03-20 00:20:33.099756 | 
2026-03-20 00:20:33.099842 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-03-20 00:20:33.676810 | controller | changed
2026-03-20 00:20:33.683169 | 
2026-03-20 00:20:33.683234 | TASK [start-fresh-logging : Stop rsyslog]
2026-03-20 00:20:33.708711 | controller | skipping: Conditional result was False
2026-03-20 00:20:33.720805 | 
2026-03-20 00:20:33.720892 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-03-20 00:20:34.252164 | controller | skipping: Conditional result was False
2026-03-20 00:20:34.265077 | 
2026-03-20 00:20:34.265238 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-03-20 00:20:34.797327 | controller | skipping: Conditional result was False
2026-03-20 00:20:34.809065 | 
2026-03-20 00:20:34.809225 | TASK [start-fresh-logging : Recreate syslog file]
2026-03-20 00:20:34.834918 | controller | skipping: Conditional result was False
2026-03-20 00:20:34.846205 | 
2026-03-20 00:20:34.846306 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-03-20 00:20:35.379894 | controller | skipping: Conditional result was False
2026-03-20 00:20:35.391823 | 
2026-03-20 00:20:35.391896 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-03-20 00:20:35.922433 | controller | skipping: Conditional result was False
2026-03-20 00:20:35.933140 | 
2026-03-20 00:20:35.933248 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-03-20 00:20:35.959103 | controller | skipping: Conditional result was False
2026-03-20 00:20:35.970718 | 
2026-03-20 00:20:35.970831 | TASK [start-fresh-logging : Recreate kern.log file]
2026-03-20 00:20:35.996894 | controller | skipping: Conditional result was False
2026-03-20 00:20:36.057591 | 
2026-03-20 00:20:36.057750 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-03-20 00:20:36.588045 | controller | skipping: Conditional result was False
2026-03-20 00:20:36.601240 | 
2026-03-20 00:20:36.601320 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-03-20 00:20:37.131652 | controller | skipping: Conditional result was False
2026-03-20 00:20:37.144206 | 
2026-03-20 00:20:37.144287 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-03-20 00:20:37.169690 | controller | skipping: Conditional result was False
2026-03-20 00:20:37.180646 | 
2026-03-20 00:20:37.180740 | TASK [start-fresh-logging : Start rsyslog]
2026-03-20 00:20:37.207137 | controller | skipping: Conditional result was False
2026-03-20 00:20:37.226741 | 
2026-03-20 00:20:37.226906 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-03-20 00:20:37.643642 | controller | ok
2026-03-20 00:20:37.654205 | 
2026-03-20 00:20:37.654259 | PLAY RECAP
2026-03-20 00:20:37.654310 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-03-20 00:20:37.654332 | 
2026-03-20 00:20:37.813257 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-03-20 00:20:37.823360 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-03-20 00:20:38.443861 | 
2026-03-20 00:20:38.443998 | PLAY [all]
2026-03-20 00:20:38.455217 | 
2026-03-20 00:20:38.455289 | TASK [Install curl]
2026-03-20 00:20:46.260631 | controller | ok
2026-03-20 00:20:46.267344 | 
2026-03-20 00:20:46.267424 | TASK [ensure-rust : Use rustup]
2026-03-20 00:20:46.301526 | controller | ok
2026-03-20 00:20:46.308657 | controller | included: /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-03-20 00:20:46.314286 | 
2026-03-20 00:20:46.314351 | TASK [ensure-rust : Install Rust]
2026-03-20 00:20:46.788725 | controller | info: downloading installer
2026-03-20 00:20:47.127107 | controller | warn: It looks like you have an existing rustup settings file at:
2026-03-20 00:20:47.127180 | controller | warn: /opt/rust/settings.toml
2026-03-20 00:20:47.127192 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-03-20 00:20:47.127208 | controller | warn: instead of the one inferred from the default host triple.
2026-03-20 00:20:47.159742 | controller | info: profile set to default
2026-03-20 00:20:47.159779 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-03-20 00:20:47.161662 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-03-20 00:20:47.410287 | controller | info: latest update on 2026-03-05 for version 1.94.0 (4a4ef493e 2026-03-02)
2026-03-20 00:20:47.410361 | controller | info: downloading 6 components
2026-03-20 00:21:00.117255 | controller | info: default toolchain set to stable-x86_64-unknown-linux-gnu
2026-03-20 00:21:00.117413 | controller |
2026-03-20 00:21:00.135654 | controller |   stable-x86_64-unknown-linux-gnu installed - rustc 1.94.0 (4a4ef493e 2026-03-02)
2026-03-20 00:21:00.135721 | controller |
2026-03-20 00:21:00.135743 | controller |
2026-03-20 00:21:00.135755 | controller | Rust is installed now. Great!
2026-03-20 00:21:00.135765 | controller |
2026-03-20 00:21:00.135774 | controller | To get started you need Cargo's bin directory (/opt/rust/bin) in your PATH
2026-03-20 00:21:00.135783 | controller | environment variable. This has not been done automatically.
2026-03-20 00:21:00.135792 | controller |
2026-03-20 00:21:00.135800 | controller | To configure your current shell, you need to source
2026-03-20 00:21:00.135817 | controller | the corresponding env file under /opt/rust.
2026-03-20 00:21:00.135826 | controller |
2026-03-20 00:21:00.135848 | controller | This is usually done by running one of the following (note the leading DOT):
2026-03-20 00:21:00.135857 | controller | . "/opt/rust/env"            # For sh/bash/zsh/ash/dash/pdksh
2026-03-20 00:21:00.135865 | controller | source "/opt/rust/env.fish"  # For fish
2026-03-20 00:21:00.135876 | controller | source "/opt/rust/env.nu"  # For nushell
2026-03-20 00:21:00.135885 | controller | source "/opt/rust/env.tcsh"  # For tcsh
2026-03-20 00:21:00.135893 | controller | . "/opt/rust/env.ps1"        # For pwsh
2026-03-20 00:21:00.135902 | controller | source "/opt/rust/env.xsh"   # For xonsh
2026-03-20 00:21:00.135910 | controller | warn: no default linker (`cc`) was found in your PATH
2026-03-20 00:21:00.135918 | controller | warn: many Rust crates require a system C toolchain to build
2026-03-20 00:21:00.572713 | controller | ok: Runtime: 0:00:13.493092
2026-03-20 00:21:00.579781 | 
2026-03-20 00:21:00.579853 | TASK [ensure-rust : Install wrapper helper script]
2026-03-20 00:21:01.374962 | controller | changed
2026-03-20 00:21:01.380326 | 
2026-03-20 00:21:01.380391 | TASK [ensure-rust : Run wrapper installation]
2026-03-20 00:21:01.634459 | controller | Install link for cargo
2026-03-20 00:21:01.639577 | controller | Install link for cargo-clippy
2026-03-20 00:21:01.643417 | controller | Install link for cargo-fmt
2026-03-20 00:21:01.649521 | controller | Install link for cargo-miri
2026-03-20 00:21:01.654620 | controller | Install link for clippy-driver
2026-03-20 00:21:01.659338 | controller | Install link for rls
2026-03-20 00:21:01.664697 | controller | Install link for rust-analyzer
2026-03-20 00:21:01.670350 | controller | Install link for rust-gdb
2026-03-20 00:21:01.676027 | controller | Install link for rust-gdbgui
2026-03-20 00:21:01.680422 | controller | Install link for rust-lldb
2026-03-20 00:21:01.685516 | controller | Install link for rustc
2026-03-20 00:21:01.690663 | controller | Install link for rustdoc
2026-03-20 00:21:01.695410 | controller | Install link for rustfmt
2026-03-20 00:21:01.701337 | controller | Install link for rustup
2026-03-20 00:21:01.923852 | controller | ok: Runtime: 0:00:00.090056
2026-03-20 00:21:01.931039 | 
2026-03-20 00:21:01.931141 | TASK [ensure-rust : Install packages]
2026-03-20 00:21:01.957269 | controller | skipping: Conditional result was False
2026-03-20 00:21:01.969747 | 
2026-03-20 00:21:01.969797 | PLAY RECAP
2026-03-20 00:21:01.969841 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-20 00:21:01.969865 | 
2026-03-20 00:21:02.108844 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-03-20 00:21:02.114132 | RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-03-20 00:21:02.684076 | 
2026-03-20 00:21:02.684536 | PLAY [all]
2026-03-20 00:21:02.695927 | 
2026-03-20 00:21:02.696000 | TASK [Install DevStack]
2026-03-20 00:21:02.729241 | controller | ok
2026-03-20 00:21:02.749577 | 
2026-03-20 00:21:02.749653 | TASK [Run devstack on the controller]
2026-03-20 00:21:02.783553 | controller | ok
2026-03-20 00:21:02.799190 | 
2026-03-20 00:21:02.799254 | TASK [run-devstack : Run devstack]
2026-03-20 00:21:03.185884 | controller | + unset GREP_OPTIONS
2026-03-20 00:21:03.185965 | controller | + unset LANG
2026-03-20 00:21:03.185975 | controller | + unset LANGUAGE
2026-03-20 00:21:03.185981 | controller | + LC_ALL=en_US.utf8
2026-03-20 00:21:03.190542 | controller | + export LC_ALL
2026-03-20 00:21:03.192057 | controller | ++ env
2026-03-20 00:21:03.192636 | controller | ++ grep -E '^OS_'
2026-03-20 00:21:03.193256 | controller | ++ cut -d = -f 1
2026-03-20 00:21:03.198915 | controller | + unset
2026-03-20 00:21:03.198947 | controller | + umask 022
2026-03-20 00:21:03.198967 | 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-03-20 00:21:03.200589 | controller | +++ dirname ./stack.sh
2026-03-20 00:21:03.203990 | controller | ++ cd .
2026-03-20 00:21:03.204017 | controller | ++ pwd
2026-03-20 00:21:03.204679 | controller | + TOP_DIR=/opt/stack/devstack
2026-03-20 00:21:03.204706 | controller | + NOUNSET=
2026-03-20 00:21:03.204719 | controller | + [[ -n '' ]]
2026-03-20 00:21:03.205761 | controller | ++ date +%s
2026-03-20 00:21:03.208309 | controller | + DEVSTACK_START_TIME=1773966063
2026-03-20 00:21:03.208339 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-03-20 00:21:03.208354 | controller | + FILES=/opt/stack/devstack/files
2026-03-20 00:21:03.208401 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-03-20 00:21:03.208490 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-03-20 00:21:03.208503 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-03-20 00:21:03.208572 | controller | + [[ '' == \y ]]
2026-03-20 00:21:03.208609 | controller | + [[ 1002 -eq 0 ]]
2026-03-20 00:21:03.208694 | controller | + [[ -n '' ]]
2026-03-20 00:21:03.208708 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-03-20 00:21:03.208758 | controller | + LAST_SPINNER_PID=
2026-03-20 00:21:03.208771 | controller | + source /opt/stack/devstack/functions
2026-03-20 00:21:03.208947 | controller | ++ [[ -z '' ]]
2026-03-20 00:21:03.208964 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-03-20 00:21:03.210780 | controller | ++++ dirname /opt/stack/devstack/functions
2026-03-20 00:21:03.212762 | controller | +++ cd /opt/stack/devstack
2026-03-20 00:21:03.212791 | controller | +++ pwd
2026-03-20 00:21:03.213522 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-03-20 00:21:03.213552 | controller | ++ source /opt/stack/devstack/functions-common
2026-03-20 00:21:03.215053 | controller | ++++ set +o
2026-03-20 00:21:03.215187 | controller | ++++ grep xtrace
2026-03-20 00:21:03.217932 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-03-20 00:21:03.217965 | controller | +++ set +o xtrace
2026-03-20 00:21:03.233454 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-03-20 00:21:03.234912 | controller | ++++ set +o
2026-03-20 00:21:03.235231 | controller | ++++ grep xtrace
2026-03-20 00:21:03.238651 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-03-20 00:21:03.238683 | controller | +++ set +o xtrace
2026-03-20 00:21:03.239827 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-03-20 00:21:03.241620 | controller | ++++ set +o
2026-03-20 00:21:03.242134 | controller | ++++ grep xtrace
2026-03-20 00:21:03.244551 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-03-20 00:21:03.244586 | controller | +++ set +o xtrace
2026-03-20 00:21:03.245227 | controller | ++ source /opt/stack/devstack/inc/python
2026-03-20 00:21:03.246830 | controller | ++++ set +o
2026-03-20 00:21:03.247399 | controller | ++++ grep xtrace
2026-03-20 00:21:03.250741 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-03-20 00:21:03.250774 | controller | +++ set +o xtrace
2026-03-20 00:21:03.252765 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-03-20 00:21:03.254897 | controller | ++++ set +o
2026-03-20 00:21:03.255104 | controller | ++++ grep xtrace
2026-03-20 00:21:03.257836 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-03-20 00:21:03.257869 | controller | +++ set +o xtrace
2026-03-20 00:21:03.258254 | controller | ++ source /opt/stack/devstack/inc/async
2026-03-20 00:21:03.259274 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-03-20 00:21:03.259387 | controller | ++++ local xtrace
2026-03-20 00:21:03.261414 | controller | +++++ set +o
2026-03-20 00:21:03.261806 | controller | +++++ grep xtrace
2026-03-20 00:21:03.265415 | controller | ++++ xtrace='set -o xtrace'
2026-03-20 00:21:03.265448 | controller | ++++ set +o xtrace
2026-03-20 00:21:03.266422 | controller | +++ DEVSTACK_PARALLEL=True
2026-03-20 00:21:03.266454 | controller | +++ _ASYNC_BG_TIME=0
2026-03-20 00:21:03.269435 | controller | +++ set +o
2026-03-20 00:21:03.269884 | controller | +++ grep xtrace
2026-03-20 00:21:03.272621 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-03-20 00:21:03.272660 | controller | ++ set +o xtrace
2026-03-20 00:21:03.276601 | controller | + source /opt/stack/devstack/lib/stack
2026-03-20 00:21:03.276779 | controller | + GetDistro
2026-03-20 00:21:03.276809 | controller | + GetOSVersion
2026-03-20 00:21:03.276822 | controller | + source /etc/os-release
2026-03-20 00:21:03.276867 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-03-20 00:21:03.276877 | controller | ++ NAME=Ubuntu
2026-03-20 00:21:03.276885 | controller | ++ VERSION_ID=24.04
2026-03-20 00:21:03.276893 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-03-20 00:21:03.276905 | controller | ++ VERSION_CODENAME=noble
2026-03-20 00:21:03.276914 | controller | ++ ID=ubuntu
2026-03-20 00:21:03.276922 | controller | ++ ID_LIKE=debian
2026-03-20 00:21:03.276930 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-03-20 00:21:03.276938 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-03-20 00:21:03.276947 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-03-20 00:21:03.276959 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-03-20 00:21:03.276967 | controller | ++ UBUNTU_CODENAME=noble
2026-03-20 00:21:03.276975 | controller | ++ LOGO=ubuntu-logo
2026-03-20 00:21:03.276983 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-03-20 00:21:03.276995 | controller | + _ensure_lsb_release
2026-03-20 00:21:03.278095 | controller | ++ command -v lsb_release
2026-03-20 00:21:03.278914 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-03-20 00:21:03.278943 | controller | + return
2026-03-20 00:21:03.279886 | controller | ++ lsb_release -r -s
2026-03-20 00:21:03.299209 | controller | + os_RELEASE=24.04
2026-03-20 00:21:03.300315 | controller | ++ lsb_release -c -s
2026-03-20 00:21:03.319011 | controller | + os_CODENAME=noble
2026-03-20 00:21:03.320270 | controller | ++ lsb_release -i -s
2026-03-20 00:21:03.340453 | controller | + os_VENDOR=Ubuntu
2026-03-20 00:21:03.340492 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-03-20 00:21:03.340610 | controller | + os_PACKAGE=deb
2026-03-20 00:21:03.340620 | controller | + typeset -xr os_VENDOR
2026-03-20 00:21:03.340633 | controller | + typeset -xr os_RELEASE
2026-03-20 00:21:03.340646 | controller | + typeset -xr os_PACKAGE
2026-03-20 00:21:03.340658 | controller | + typeset -xr os_CODENAME
2026-03-20 00:21:03.340747 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-03-20 00:21:03.340785 | controller | + DISTRO=noble
2026-03-20 00:21:03.340795 | controller | + typeset -xr DISTRO
2026-03-20 00:21:03.340909 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-03-20 00:21:03.342857 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-03-20 00:21:03.342938 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-03-20 00:21:03.342948 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-03-20 00:21:03.342961 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-03-20 00:21:03.342970 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-03-20 00:21:03.344045 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-03-20 00:21:03.344196 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-03-20 00:21:03.344209 | controller | ++ local matchgroup=local
2026-03-20 00:21:03.344223 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-03-20 00:21:03.344312 | controller | ++ awk -v matchgroup=local '
2026-03-20 00:21:03.344322 | controller |         /^\[\[.+\|.*\]\]/ {
2026-03-20 00:21:03.344331 | controller |             gsub("[][]", "", $1);
2026-03-20 00:21:03.344340 | controller |             split($1, a, "|");
2026-03-20 00:21:03.344349 | controller |             if (a[1] == matchgroup)
2026-03-20 00:21:03.344358 | controller |                 print a[2]
2026-03-20 00:21:03.344410 | controller |         }
2026-03-20 00:21:03.344420 | controller |     ' /opt/stack/devstack/local.conf
2026-03-20 00:21:03.346964 | controller | + LRC=localrc
2026-03-20 00:21:03.346978 | controller | + for lfile in $LRC
2026-03-20 00:21:03.347016 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-03-20 00:21:03.347022 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-03-20 00:21:03.347047 | controller | + echo '# Generated file, do not edit'
2026-03-20 00:21:03.347197 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-03-20 00:21:03.347262 | controller | + local file=/opt/stack/devstack/local.conf
2026-03-20 00:21:03.347267 | controller | + local matchgroup=local
2026-03-20 00:21:03.347275 | controller | + local configfile=localrc
2026-03-20 00:21:03.347282 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-03-20 00:21:03.347290 | controller | + [[ -z localrc ]]
2026-03-20 00:21:03.347332 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-03-20 00:21:03.347338 | controller |         BEGIN { group = "" }
2026-03-20 00:21:03.347343 | controller |         /^\[\[.+\|.*\]\]/ {
2026-03-20 00:21:03.347358 | controller |             gsub("[][]", "", $1);
2026-03-20 00:21:03.347367 | controller |             split($1, a, "|");
2026-03-20 00:21:03.347383 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-03-20 00:21:03.347389 | controller |                 group=a[1]
2026-03-20 00:21:03.347394 | controller |             } else {
2026-03-20 00:21:03.347399 | controller |                 group=""
2026-03-20 00:21:03.347404 | controller |             }
2026-03-20 00:21:03.347413 | controller |             next
2026-03-20 00:21:03.347419 | controller |         }
2026-03-20 00:21:03.347424 | controller |         {
2026-03-20 00:21:03.347429 | controller |             if (group != "")
2026-03-20 00:21:03.347434 | controller |                 print $0
2026-03-20 00:21:03.347439 | controller |         }
2026-03-20 00:21:03.347444 | controller |     ' /opt/stack/devstack/local.conf
2026-03-20 00:21:03.349439 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-03-20 00:21:03.349460 | controller | + source /opt/stack/devstack/stackrc
2026-03-20 00:21:03.349537 | controller | ++ [[ -z '' ]]
2026-03-20 00:21:03.349545 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-03-20 00:21:03.350943 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-03-20 00:21:03.352413 | controller | +++ cd /opt/stack/devstack
2026-03-20 00:21:03.352466 | controller | +++ pwd
2026-03-20 00:21:03.353571 | controller | ++ RC_DIR=/opt/stack/devstack
2026-03-20 00:21:03.353628 | controller | ++ source /opt/stack/devstack/functions
2026-03-20 00:21:03.353639 | controller | +++ [[ -z 1 ]]
2026-03-20 00:21:03.353648 | controller | +++ return 0
2026-03-20 00:21:03.353671 | controller | ++ TARGET_BRANCH=master
2026-03-20 00:21:03.353680 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-03-20 00:21:03.353688 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-03-20 00:21:03.353697 | controller | ++ DEST=/opt/stack
2026-03-20 00:21:03.353705 | controller | ++ DATA_DIR=/opt/stack/data
2026-03-20 00:21:03.353713 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-03-20 00:21:03.353721 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-03-20 00:21:03.353729 | controller | ++ [[ 1002 -eq 0 ]]
2026-03-20 00:21:03.354252 | controller | +++ whoami
2026-03-20 00:21:03.358258 | controller | ++ STACK_USER=stack
2026-03-20 00:21:03.358296 | controller | ++ REGION_NAME=RegionOne
2026-03-20 00:21:03.358310 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-03-20 00:21:03.358517 | controller | ++ isset ENABLED_SERVICES
2026-03-20 00:21:03.358550 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-03-20 00:21:03.358560 | controller | ++ ENABLED_SERVICES=key
2026-03-20 00:21:03.358574 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-03-20 00:21:03.358583 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-03-20 00:21:03.358592 | controller | ++ ENABLED_SERVICES+=,g-api
2026-03-20 00:21:03.358600 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-03-20 00:21:03.358609 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-03-20 00:21:03.358618 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-03-20 00:21:03.358626 | controller | ++ ENABLED_SERVICES+=,horizon
2026-03-20 00:21:03.358634 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-03-20 00:21:03.358642 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-03-20 00:21:03.358654 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-03-20 00:21:03.358662 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-03-20 00:21:03.358670 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-03-20 00:21:03.358678 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-03-20 00:21:03.358734 | controller | +++ disable_all_services
2026-03-20 00:21:03.358747 | controller | +++ ENABLED_SERVICES=
2026-03-20 00:21:03.358780 | controller | +++ enable_service c-api
2026-03-20 00:21:03.358831 | controller | +++ local xtrace
2026-03-20 00:21:03.360690 | controller | ++++ set +o
2026-03-20 00:21:03.361078 | controller | ++++ grep xtrace
2026-03-20 00:21:03.364897 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.364930 | controller | +++ set +o xtrace
2026-03-20 00:21:03.404352 | controller | +++ enable_service c-bak
2026-03-20 00:21:03.404408 | controller | +++ local xtrace
2026-03-20 00:21:03.406138 | controller | ++++ set +o
2026-03-20 00:21:03.406568 | controller | ++++ grep xtrace
2026-03-20 00:21:03.409967 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.409985 | controller | +++ set +o xtrace
2026-03-20 00:21:03.451808 | controller | +++ enable_service c-sch
2026-03-20 00:21:03.451841 | controller | +++ local xtrace
2026-03-20 00:21:03.453649 | controller | ++++ set +o
2026-03-20 00:21:03.454019 | controller | ++++ grep xtrace
2026-03-20 00:21:03.457321 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.457352 | controller | +++ set +o xtrace
2026-03-20 00:21:03.496570 | controller | +++ enable_service c-vol
2026-03-20 00:21:03.496596 | controller | +++ local xtrace
2026-03-20 00:21:03.498507 | controller | ++++ set +o
2026-03-20 00:21:03.498754 | controller | ++++ grep xtrace
2026-03-20 00:21:03.502004 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.502047 | controller | +++ set +o xtrace
2026-03-20 00:21:03.542697 | controller | +++ disable_service dstat
2026-03-20 00:21:03.542720 | controller | +++ local xtrace
2026-03-20 00:21:03.544309 | controller | ++++ set +o
2026-03-20 00:21:03.544902 | controller | ++++ grep xtrace
2026-03-20 00:21:03.548210 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.548229 | controller | +++ set +o xtrace
2026-03-20 00:21:03.578463 | controller | +++ enable_service etcd3
2026-03-20 00:21:03.578485 | controller | +++ local xtrace
2026-03-20 00:21:03.580323 | controller | ++++ set +o
2026-03-20 00:21:03.580754 | controller | ++++ grep xtrace
2026-03-20 00:21:03.584454 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.584482 | controller | +++ set +o xtrace
2026-03-20 00:21:03.622405 | controller | +++ enable_service file_tracker
2026-03-20 00:21:03.622429 | controller | +++ local xtrace
2026-03-20 00:21:03.624270 | controller | ++++ set +o
2026-03-20 00:21:03.624501 | controller | ++++ grep xtrace
2026-03-20 00:21:03.627225 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.627262 | controller | +++ set +o xtrace
2026-03-20 00:21:03.667948 | controller | +++ enable_service g-api
2026-03-20 00:21:03.667979 | controller | +++ local xtrace
2026-03-20 00:21:03.669594 | controller | ++++ set +o
2026-03-20 00:21:03.670230 | controller | ++++ grep xtrace
2026-03-20 00:21:03.673776 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.673796 | controller | +++ set +o xtrace
2026-03-20 00:21:03.712450 | controller | +++ disable_service horizon
2026-03-20 00:21:03.712485 | controller | +++ local xtrace
2026-03-20 00:21:03.714194 | controller | ++++ set +o
2026-03-20 00:21:03.714572 | controller | ++++ grep xtrace
2026-03-20 00:21:03.717890 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.717919 | controller | +++ set +o xtrace
2026-03-20 00:21:03.746796 | controller | +++ enable_service key
2026-03-20 00:21:03.746827 | controller | +++ local xtrace
2026-03-20 00:21:03.748756 | controller | ++++ set +o
2026-03-20 00:21:03.749102 | controller | ++++ grep xtrace
2026-03-20 00:21:03.752405 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.752430 | controller | +++ set +o xtrace
2026-03-20 00:21:03.789292 | controller | +++ enable_service memory_tracker
2026-03-20 00:21:03.789327 | controller | +++ local xtrace
2026-03-20 00:21:03.790965 | controller | ++++ set +o
2026-03-20 00:21:03.791472 | controller | ++++ grep xtrace
2026-03-20 00:21:03.795013 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.795040 | controller | +++ set +o xtrace
2026-03-20 00:21:03.834587 | controller | +++ enable_service mysql
2026-03-20 00:21:03.834615 | controller | +++ local xtrace
2026-03-20 00:21:03.836198 | controller | ++++ set +o
2026-03-20 00:21:03.836443 | controller | ++++ grep xtrace
2026-03-20 00:21:03.839821 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.839848 | controller | +++ set +o xtrace
2026-03-20 00:21:03.880883 | controller | +++ enable_service n-api
2026-03-20 00:21:03.880952 | controller | +++ local xtrace
2026-03-20 00:21:03.883023 | controller | ++++ set +o
2026-03-20 00:21:03.883050 | controller | ++++ grep xtrace
2026-03-20 00:21:03.886948 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.886972 | controller | +++ set +o xtrace
2026-03-20 00:21:03.928081 | controller | +++ enable_service n-api-meta
2026-03-20 00:21:03.928111 | controller | +++ local xtrace
2026-03-20 00:21:03.930111 | controller | ++++ set +o
2026-03-20 00:21:03.930608 | controller | ++++ grep xtrace
2026-03-20 00:21:03.934114 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.934133 | controller | +++ set +o xtrace
2026-03-20 00:21:03.974248 | controller | +++ enable_service n-cond
2026-03-20 00:21:03.974282 | controller | +++ local xtrace
2026-03-20 00:21:03.976115 | controller | ++++ set +o
2026-03-20 00:21:03.976542 | controller | ++++ grep xtrace
2026-03-20 00:21:03.980093 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:03.980116 | controller | +++ set +o xtrace
2026-03-20 00:21:04.022111 | controller | +++ enable_service n-cpu
2026-03-20 00:21:04.022142 | controller | +++ local xtrace
2026-03-20 00:21:04.023814 | controller | ++++ set +o
2026-03-20 00:21:04.024249 | controller | ++++ grep xtrace
2026-03-20 00:21:04.027290 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.027315 | controller | +++ set +o xtrace
2026-03-20 00:21:04.066648 | controller | +++ enable_service n-novnc
2026-03-20 00:21:04.066688 | controller | +++ local xtrace
2026-03-20 00:21:04.068611 | controller | ++++ set +o
2026-03-20 00:21:04.068887 | controller | ++++ grep xtrace
2026-03-20 00:21:04.072550 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.072591 | controller | +++ set +o xtrace
2026-03-20 00:21:04.112641 | controller | +++ enable_service n-sch
2026-03-20 00:21:04.112674 | controller | +++ local xtrace
2026-03-20 00:21:04.114670 | controller | ++++ set +o
2026-03-20 00:21:04.115009 | controller | ++++ grep xtrace
2026-03-20 00:21:04.118638 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.118665 | controller | +++ set +o xtrace
2026-03-20 00:21:04.160769 | controller | +++ enable_service o-api
2026-03-20 00:21:04.160820 | controller | +++ local xtrace
2026-03-20 00:21:04.162593 | controller | ++++ set +o
2026-03-20 00:21:04.162944 | controller | ++++ grep xtrace
2026-03-20 00:21:04.166271 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.166295 | controller | +++ set +o xtrace
2026-03-20 00:21:04.207846 | controller | +++ enable_service o-da
2026-03-20 00:21:04.207898 | controller | +++ local xtrace
2026-03-20 00:21:04.209621 | controller | ++++ set +o
2026-03-20 00:21:04.210069 | controller | ++++ grep xtrace
2026-03-20 00:21:04.213470 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.213493 | controller | +++ set +o xtrace
2026-03-20 00:21:04.254667 | controller | +++ enable_service o-hk
2026-03-20 00:21:04.254699 | controller | +++ local xtrace
2026-03-20 00:21:04.256546 | controller | ++++ set +o
2026-03-20 00:21:04.256822 | controller | ++++ grep xtrace
2026-03-20 00:21:04.260153 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.260178 | controller | +++ set +o xtrace
2026-03-20 00:21:04.301054 | controller | +++ enable_service octavia
2026-03-20 00:21:04.301097 | controller | +++ local xtrace
2026-03-20 00:21:04.302835 | controller | ++++ set +o
2026-03-20 00:21:04.303078 | controller | ++++ grep xtrace
2026-03-20 00:21:04.305575 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.305617 | controller | +++ set +o xtrace
2026-03-20 00:21:04.346751 | controller | +++ enable_service openstack-cli-server
2026-03-20 00:21:04.346784 | controller | +++ local xtrace
2026-03-20 00:21:04.348568 | controller | ++++ set +o
2026-03-20 00:21:04.348673 | controller | ++++ grep xtrace
2026-03-20 00:21:04.352086 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.352113 | controller | +++ set +o xtrace
2026-03-20 00:21:04.390021 | controller | +++ enable_service ovn-controller
2026-03-20 00:21:04.390046 | controller | +++ local xtrace
2026-03-20 00:21:04.391702 | controller | ++++ set +o
2026-03-20 00:21:04.392142 | controller | ++++ grep xtrace
2026-03-20 00:21:04.395402 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.395452 | controller | +++ set +o xtrace
2026-03-20 00:21:04.438338 | controller | +++ enable_service ovn-northd
2026-03-20 00:21:04.438437 | controller | +++ local xtrace
2026-03-20 00:21:04.440113 | controller | ++++ set +o
2026-03-20 00:21:04.440573 | controller | ++++ grep xtrace
2026-03-20 00:21:04.444097 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.444133 | controller | +++ set +o xtrace
2026-03-20 00:21:04.484225 | controller | +++ enable_service ovs-vswitchd
2026-03-20 00:21:04.484247 | controller | +++ local xtrace
2026-03-20 00:21:04.485886 | controller | ++++ set +o
2026-03-20 00:21:04.486103 | controller | ++++ grep xtrace
2026-03-20 00:21:04.488582 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.488618 | controller | +++ set +o xtrace
2026-03-20 00:21:04.529061 | controller | +++ enable_service ovsdb-server
2026-03-20 00:21:04.529081 | controller | +++ local xtrace
2026-03-20 00:21:04.530822 | controller | ++++ set +o
2026-03-20 00:21:04.531237 | controller | ++++ grep xtrace
2026-03-20 00:21:04.534715 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.534741 | controller | +++ set +o xtrace
2026-03-20 00:21:04.576598 | controller | +++ enable_service placement-api
2026-03-20 00:21:04.576623 | controller | +++ local xtrace
2026-03-20 00:21:04.578533 | controller | ++++ set +o
2026-03-20 00:21:04.578923 | controller | ++++ grep xtrace
2026-03-20 00:21:04.582588 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.582616 | controller | +++ set +o xtrace
2026-03-20 00:21:04.623775 | controller | +++ enable_service q-ovn-agent
2026-03-20 00:21:04.623829 | controller | +++ local xtrace
2026-03-20 00:21:04.625647 | controller | ++++ set +o
2026-03-20 00:21:04.626041 | controller | ++++ grep xtrace
2026-03-20 00:21:04.629449 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.629467 | controller | +++ set +o xtrace
2026-03-20 00:21:04.671661 | controller | +++ enable_service q-svc
2026-03-20 00:21:04.671697 | controller | +++ local xtrace
2026-03-20 00:21:04.673620 | controller | ++++ set +o
2026-03-20 00:21:04.674067 | controller | ++++ grep xtrace
2026-03-20 00:21:04.677840 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.677884 | controller | +++ set +o xtrace
2026-03-20 00:21:04.718851 | controller | +++ enable_service rabbit
2026-03-20 00:21:04.718873 | controller | +++ local xtrace
2026-03-20 00:21:04.720645 | controller | ++++ set +o
2026-03-20 00:21:04.721299 | controller | ++++ grep xtrace
2026-03-20 00:21:04.725264 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.725291 | controller | +++ set +o xtrace
2026-03-20 00:21:04.765413 | controller | +++ disable_service s-account
2026-03-20 00:21:04.765438 | controller | +++ local xtrace
2026-03-20 00:21:04.767221 | controller | ++++ set +o
2026-03-20 00:21:04.767584 | controller | ++++ grep xtrace
2026-03-20 00:21:04.770909 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.770937 | controller | +++ set +o xtrace
2026-03-20 00:21:04.799165 | controller | +++ disable_service s-container
2026-03-20 00:21:04.799196 | controller | +++ local xtrace
2026-03-20 00:21:04.801548 | controller | ++++ set +o
2026-03-20 00:21:04.801639 | controller | ++++ grep xtrace
2026-03-20 00:21:04.804864 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.804894 | controller | +++ set +o xtrace
2026-03-20 00:21:04.836589 | controller | +++ disable_service s-object
2026-03-20 00:21:04.836627 | controller | +++ local xtrace
2026-03-20 00:21:04.838461 | controller | ++++ set +o
2026-03-20 00:21:04.838848 | controller | ++++ grep xtrace
2026-03-20 00:21:04.842338 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.842361 | controller | +++ set +o xtrace
2026-03-20 00:21:04.870082 | controller | +++ disable_service s-proxy
2026-03-20 00:21:04.870118 | controller | +++ local xtrace
2026-03-20 00:21:04.871895 | controller | ++++ set +o
2026-03-20 00:21:04.872151 | controller | ++++ grep xtrace
2026-03-20 00:21:04.874513 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.874539 | controller | +++ set +o xtrace
2026-03-20 00:21:04.904320 | controller | +++ disable_service tempest
2026-03-20 00:21:04.904359 | controller | +++ local xtrace
2026-03-20 00:21:04.906267 | controller | ++++ set +o
2026-03-20 00:21:04.906466 | controller | ++++ grep xtrace
2026-03-20 00:21:04.909597 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.909629 | controller | +++ set +o xtrace
2026-03-20 00:21:04.939507 | controller | +++ enable_service tls-proxy
2026-03-20 00:21:04.939540 | controller | +++ local xtrace
2026-03-20 00:21:04.941445 | controller | ++++ set +o
2026-03-20 00:21:04.941883 | controller | ++++ grep xtrace
2026-03-20 00:21:04.945395 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.945468 | controller | +++ set +o xtrace
2026-03-20 00:21:04.989733 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-03-20 00:21:04.989767 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-03-20 00:21:04.989777 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-03-20 00:21:04.989786 | controller | +++ DISABLE_AMP_IMAGE_BUILD=True
2026-03-20 00:21:04.989794 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-03-20 00:21:04.989802 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-03-20 00:21:04.989816 | controller | +++ ENABLE_ZSWAP=True
2026-03-20 00:21:04.989825 | controller | +++ ERROR_ON_CLONE=True
2026-03-20 00:21:04.989833 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-03-20 00:21:04.989842 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-03-20 00:21:04.989850 | controller | +++ GIT_BASE=https://github.com
2026-03-20 00:21:04.989859 | controller | +++ HOST_IP=199.204.45.67
2026-03-20 00:21:04.989867 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-03-20 00:21:04.989876 | controller | +++ LIBVIRT_TYPE=qemu
2026-03-20 00:21:04.989884 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-03-20 00:21:04.989892 | controller | +++ LOG_COLOR=False
2026-03-20 00:21:04.989905 | controller | +++ MAGNUM_GUEST_IMAGE_URL=https://github.com/vexxhost/capo-image-elements/releases/latest/download/ubuntu-22.04-v1.35.2.qcow2
2026-03-20 00:21:04.989914 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS='snapshot_support=True create_share_from_snapshot_support=True'
2026-03-20 00:21:04.989923 | controller | +++ MANILA_ENABLED_BACKENDS=generic
2026-03-20 00:21:04.989931 | controller | +++ MANILA_USE_SERVICE_INSTANCE_PASSWORD=True
2026-03-20 00:21:04.989940 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-03-20 00:21:04.989948 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-03-20 00:21:04.989961 | controller | +++ NOVA_VNC_ENABLED=True
2026-03-20 00:21:04.989969 | controller | +++ OCTAVIA_NODE=api
2026-03-20 00:21:04.989978 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-03-20 00:21:04.989986 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-03-20 00:21:04.989994 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-03-20 00:21:04.990002 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-03-20 00:21:04.990014 | controller | +++ SERVICE_HOST=199.204.45.67
2026-03-20 00:21:04.990022 | controller | +++ SERVICE_PASSWORD=secretservice
2026-03-20 00:21:04.990031 | controller | +++ SWIFT_HASH=1234123412341234
2026-03-20 00:21:04.990039 | controller | +++ SWIFT_REPLICAS=1
2026-03-20 00:21:04.990047 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-03-20 00:21:04.990059 | controller | +++ VERBOSE=True
2026-03-20 00:21:04.990085 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-03-20 00:21:04.990095 | 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-03-20 00:21:04.990107 | controller | +++ enable_plugin barbican https://github.com/openstack/barbican
2026-03-20 00:21:04.990147 | controller | +++ local name=barbican
2026-03-20 00:21:04.990156 | controller | +++ local url=https://github.com/openstack/barbican
2026-03-20 00:21:04.990168 | controller | +++ local branch=master
2026-03-20 00:21:04.990179 | controller | +++ is_plugin_enabled barbican
2026-03-20 00:21:04.990224 | controller | +++ local name=barbican
2026-03-20 00:21:04.990237 | controller | +++ [[ ,, =~ ,barbican, ]]
2026-03-20 00:21:04.990315 | controller | +++ return 1
2026-03-20 00:21:04.990325 | controller | +++ DEVSTACK_PLUGINS+=,barbican
2026-03-20 00:21:04.990338 | controller | +++ GITREPO[$name]=https://github.com/openstack/barbican
2026-03-20 00:21:04.990347 | controller | +++ GITDIR[$name]=/opt/stack/barbican
2026-03-20 00:21:04.990358 | controller | +++ GITBRANCH[$name]=master
2026-03-20 00:21:04.990461 | controller | +++ enable_plugin magnum https://review.opendev.org/openstack/magnum
2026-03-20 00:21:04.990472 | controller | +++ local name=magnum
2026-03-20 00:21:04.990484 | controller | +++ local url=https://review.opendev.org/openstack/magnum
2026-03-20 00:21:04.990492 | controller | +++ local branch=master
2026-03-20 00:21:04.990504 | controller | +++ is_plugin_enabled magnum
2026-03-20 00:21:04.990512 | controller | +++ local name=magnum
2026-03-20 00:21:04.990523 | controller | +++ [[ ,,barbican, =~ ,magnum, ]]
2026-03-20 00:21:04.990562 | controller | +++ return 1
2026-03-20 00:21:04.990571 | controller | +++ DEVSTACK_PLUGINS+=,magnum
2026-03-20 00:21:04.990582 | controller | +++ GITREPO[$name]=https://review.opendev.org/openstack/magnum
2026-03-20 00:21:04.990594 | controller | +++ GITDIR[$name]=/opt/stack/magnum
2026-03-20 00:21:04.990602 | controller | +++ GITBRANCH[$name]=master
2026-03-20 00:21:04.990692 | controller | +++ enable_plugin octavia https://github.com/openstack/octavia
2026-03-20 00:21:04.990702 | controller | +++ local name=octavia
2026-03-20 00:21:04.990714 | controller | +++ local url=https://github.com/openstack/octavia
2026-03-20 00:21:04.990723 | controller | +++ local branch=master
2026-03-20 00:21:04.990731 | controller | +++ is_plugin_enabled octavia
2026-03-20 00:21:04.990742 | controller | +++ local name=octavia
2026-03-20 00:21:04.990751 | controller | +++ [[ ,,barbican,magnum, =~ ,octavia, ]]
2026-03-20 00:21:04.990762 | controller | +++ return 1
2026-03-20 00:21:04.990814 | controller | +++ DEVSTACK_PLUGINS+=,octavia
2026-03-20 00:21:04.990823 | controller | +++ GITREPO[$name]=https://github.com/openstack/octavia
2026-03-20 00:21:04.990832 | controller | +++ GITDIR[$name]=/opt/stack/octavia
2026-03-20 00:21:04.990842 | controller | +++ GITBRANCH[$name]=master
2026-03-20 00:21:04.990854 | controller | +++ enable_plugin manila https://github.com/openstack/manila
2026-03-20 00:21:04.990867 | controller | +++ local name=manila
2026-03-20 00:21:04.990915 | controller | +++ local url=https://github.com/openstack/manila
2026-03-20 00:21:04.990924 | controller | +++ local branch=master
2026-03-20 00:21:04.990932 | controller | +++ is_plugin_enabled manila
2026-03-20 00:21:04.990944 | controller | +++ local name=manila
2026-03-20 00:21:04.990952 | controller | +++ [[ ,,barbican,magnum,octavia, =~ ,manila, ]]
2026-03-20 00:21:04.990990 | controller | +++ return 1
2026-03-20 00:21:04.990999 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-03-20 00:21:04.991011 | controller | +++ GITREPO[$name]=https://github.com/openstack/manila
2026-03-20 00:21:04.991021 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-03-20 00:21:04.991033 | controller | +++ GITBRANCH[$name]=master
2026-03-20 00:21:04.991082 | controller | +++ enable_plugin magnum-cluster-api https://github.com/vexxhost/magnum-cluster-api
2026-03-20 00:21:04.991095 | controller | +++ local name=magnum-cluster-api
2026-03-20 00:21:04.991103 | controller | +++ local url=https://github.com/vexxhost/magnum-cluster-api
2026-03-20 00:21:04.991115 | controller | +++ local branch=master
2026-03-20 00:21:04.991126 | controller | +++ is_plugin_enabled magnum-cluster-api
2026-03-20 00:21:04.991138 | controller | +++ local name=magnum-cluster-api
2026-03-20 00:21:04.991170 | controller | +++ [[ ,,barbican,magnum,octavia,manila, =~ ,magnum-cluster-api, ]]
2026-03-20 00:21:04.991230 | controller | +++ return 1
2026-03-20 00:21:04.991239 | controller | +++ DEVSTACK_PLUGINS+=,magnum-cluster-api
2026-03-20 00:21:04.991251 | controller | +++ GITREPO[$name]=https://github.com/vexxhost/magnum-cluster-api
2026-03-20 00:21:04.991271 | controller | +++ GITDIR[$name]=/opt/stack/magnum-cluster-api
2026-03-20 00:21:04.991282 | controller | +++ GITBRANCH[$name]=master
2026-03-20 00:21:04.991294 | controller | +++ enable_plugin ovn-octavia-provider https://github.com/openstack/ovn-octavia-provider
2026-03-20 00:21:04.991305 | controller | +++ local name=ovn-octavia-provider
2026-03-20 00:21:04.991317 | controller | +++ local url=https://github.com/openstack/ovn-octavia-provider
2026-03-20 00:21:04.991328 | controller | +++ local branch=master
2026-03-20 00:21:04.991340 | controller | +++ is_plugin_enabled ovn-octavia-provider
2026-03-20 00:21:04.991352 | controller | +++ local name=ovn-octavia-provider
2026-03-20 00:21:04.991413 | controller | +++ [[ ,,barbican,magnum,octavia,manila,magnum-cluster-api, =~ ,ovn-octavia-provider, ]]
2026-03-20 00:21:04.991461 | controller | +++ return 1
2026-03-20 00:21:04.991470 | controller | +++ DEVSTACK_PLUGINS+=,ovn-octavia-provider
2026-03-20 00:21:04.991482 | controller | +++ GITREPO[$name]=https://github.com/openstack/ovn-octavia-provider
2026-03-20 00:21:04.991490 | controller | +++ GITDIR[$name]=/opt/stack/ovn-octavia-provider
2026-03-20 00:21:04.991498 | controller | +++ GITBRANCH[$name]=master
2026-03-20 00:21:04.991624 | controller | ++ CELLSV2_SETUP=superconductor
2026-03-20 00:21:04.991634 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-03-20 00:21:04.992736 | controller | +++ trueorfalse False USER_UNITS
2026-03-20 00:21:04.992976 | controller | +++ local xtrace
2026-03-20 00:21:04.994900 | controller | ++++ set +o
2026-03-20 00:21:04.995153 | controller | ++++ grep xtrace
2026-03-20 00:21:04.997770 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:04.997803 | controller | +++ set +o xtrace
2026-03-20 00:21:04.998779 | controller | ++ USER_UNITS=False
2026-03-20 00:21:04.998804 | controller | ++ [[ False == \T\r\u\e ]]
2026-03-20 00:21:04.998814 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-03-20 00:21:04.998827 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-03-20 00:21:04.998836 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-03-20 00:21:04.999952 | controller | +++ _get_python_version python3
2026-03-20 00:21:05.000164 | controller | +++ local interp=python3
2026-03-20 00:21:05.000177 | controller | +++ local version
2026-03-20 00:21:05.001459 | controller | ++++ which python3
2026-03-20 00:21:05.005254 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-03-20 00:21:05.006481 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-03-20 00:21:05.024178 | controller | +++ version=3.12
2026-03-20 00:21:05.024209 | controller | +++ echo 3.12
2026-03-20 00:21:05.025182 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-03-20 00:21:05.025268 | controller | ++ export PYTHON3_VERSION=3.12
2026-03-20 00:21:05.025280 | controller | ++ PYTHON3_VERSION=3.12
2026-03-20 00:21:05.025292 | controller | ++ export 'VIRTUALENV_CMD=python3.12 -m venv'
2026-03-20 00:21:05.025301 | controller | ++ VIRTUALENV_CMD='python3.12 -m venv'
2026-03-20 00:21:05.025400 | controller | ++ '[' -t 1 ']'
2026-03-20 00:21:05.025420 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-03-20 00:21:05.026780 | controller | +++ trueorfalse False LOG_COLOR
2026-03-20 00:21:05.027068 | controller | +++ local xtrace
2026-03-20 00:21:05.028988 | controller | ++++ set +o
2026-03-20 00:21:05.029492 | controller | ++++ grep xtrace
2026-03-20 00:21:05.033003 | controller | +++ xtrace='set -o xtrace'
2026-03-20 00:21:05.033023 | controller | +++ set +o xtrace
2026-03-20 00:21:05.034046 | controller | ++ LOG_COLOR=False
2026-03-20 00:21:05.034167 | controller | ++ [[ False == \T\r\u\e ]]
2026-03-20 00:21:05.034178 | controller | ++ export 'PS4=+ $(short_source):   '
2026-03-20 00:21:05.034187 | controller | ++ PS4='+ $(short_source):   '
2026-03-20 00:21:05.039923 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-03-20 00:21:05.044699 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.050719 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.051609 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.058411 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.061557 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.066878 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-03-20 00:21:05.071043 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-03-20 00:21:05.075192 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-03-20 00:21:05.080569 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-03-20 00:21:05.084047 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.090328 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.090870 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.098827 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.102769 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.108023 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-03-20 00:21:05.113748 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-03-20 00:21:05.117955 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.123342 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.124400 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.130825 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.134690 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.140155 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-03-20 00:21:05.144548 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-03-20 00:21:05.149797 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-03-20 00:21:05.154396 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.159953 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.160252 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.167261 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.170344 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.175339 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-03-20 00:21:05.180540 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-03-20 00:21:05.184573 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.191267 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.192034 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.198314 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.201407 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.206414 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-03-20 00:21:05.211144 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-03-20 00:21:05.215218 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.220601 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.221600 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.228350 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.232143 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.237205 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-03-20 00:21:05.241837 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-03-20 00:21:05.246094 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-03-20 00:21:05.250043 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-03-20 00:21:05.253760 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://github.com
2026-03-20 00:21:05.257757 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-03-20 00:21:05.262263 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.1
2026-03-20 00:21:05.266384 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://github.com/openstack/cinder.git
2026-03-20 00:21:05.269745 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-03-20 00:21:05.273691 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://github.com/openstack/glance.git
2026-03-20 00:21:05.278447 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-03-20 00:21:05.282638 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://github.com/openstack/horizon.git
2026-03-20 00:21:05.286511 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-03-20 00:21:05.290438 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://github.com/openstack/keystone.git
2026-03-20 00:21:05.294390 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-03-20 00:21:05.297674 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://github.com/openstack/neutron.git
2026-03-20 00:21:05.301226 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-03-20 00:21:05.304887 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://github.com/openstack/nova.git
2026-03-20 00:21:05.309025 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-03-20 00:21:05.313468 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://github.com/openstack/swift.git
2026-03-20 00:21:05.317418 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-03-20 00:21:05.322407 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://github.com/openstack/placement.git
2026-03-20 00:21:05.327067 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-03-20 00:21:05.331293 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://github.com/openstack/requirements.git
2026-03-20 00:21:05.334898 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-03-20 00:21:05.338758 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://github.com/openstack/tempest.git
2026-03-20 00:21:05.342540 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-03-20 00:21:05.346491 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-03-20 00:21:05.350551 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://github.com/openstack/os-test-images.git
2026-03-20 00:21:05.355082 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-03-20 00:21:05.359475 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-03-20 00:21:05.363475 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://github.com/openstack/python-cinderclient.git
2026-03-20 00:21:05.367403 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-03-20 00:21:05.371306 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://github.com/openstack/python-brick-cinderclient-ext.git
2026-03-20 00:21:05.375599 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-03-20 00:21:05.379664 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://github.com/openstack/python-barbicanclient.git
2026-03-20 00:21:05.383278 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-03-20 00:21:05.387136 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-03-20 00:21:05.391663 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://github.com/openstack/python-glanceclient.git
2026-03-20 00:21:05.396260 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-03-20 00:21:05.400129 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://github.com/openstack/python-ironicclient.git
2026-03-20 00:21:05.404131 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-03-20 00:21:05.407970 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-03-20 00:21:05.411476 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://github.com/openstack/keystoneauth.git
2026-03-20 00:21:05.415429 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-03-20 00:21:05.419695 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://github.com/openstack/python-keystoneclient.git
2026-03-20 00:21:05.423573 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-03-20 00:21:05.427758 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://github.com/openstack/python-neutronclient.git
2026-03-20 00:21:05.431768 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-03-20 00:21:05.436104 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://github.com/openstack/python-novaclient.git
2026-03-20 00:21:05.440059 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-03-20 00:21:05.443278 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://github.com/openstack/python-swiftclient.git
2026-03-20 00:21:05.447065 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-03-20 00:21:05.451331 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://github.com/openstack/python-openstackclient.git
2026-03-20 00:21:05.455379 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-03-20 00:21:05.460276 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-03-20 00:21:05.464598 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://github.com/openstack/osc-placement.git
2026-03-20 00:21:05.468602 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-03-20 00:21:05.471643 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://github.com/openstack/castellan.git
2026-03-20 00:21:05.475730 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-03-20 00:21:05.480035 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://github.com/openstack/cliff.git
2026-03-20 00:21:05.484571 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-03-20 00:21:05.488544 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://github.com/openstack/futurist.git
2026-03-20 00:21:05.492199 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-03-20 00:21:05.495841 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://github.com/openstack/debtcollector.git
2026-03-20 00:21:05.499928 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-03-20 00:21:05.503769 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://github.com/openstack/etcd3gw.git
2026-03-20 00:21:05.507781 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-03-20 00:21:05.511970 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://github.com/openstack/automaton.git
2026-03-20 00:21:05.516843 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-03-20 00:21:05.521149 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://github.com/openstack/oslo.cache.git
2026-03-20 00:21:05.524887 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-03-20 00:21:05.528701 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://github.com/openstack/oslo.concurrency.git
2026-03-20 00:21:05.532077 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-03-20 00:21:05.536160 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://github.com/openstack/oslo.config.git
2026-03-20 00:21:05.539879 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-03-20 00:21:05.543614 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://github.com/openstack/oslo.context.git
2026-03-20 00:21:05.547049 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-03-20 00:21:05.550474 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://github.com/openstack/oslo.db.git
2026-03-20 00:21:05.554230 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-03-20 00:21:05.558062 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://github.com/openstack/oslo.i18n.git
2026-03-20 00:21:05.561600 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-03-20 00:21:05.564698 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://github.com/openstack/oslo.limit.git
2026-03-20 00:21:05.568794 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-03-20 00:21:05.573098 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://github.com/openstack/oslo.log.git
2026-03-20 00:21:05.577045 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-03-20 00:21:05.580950 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://github.com/openstack/oslo.messaging.git
2026-03-20 00:21:05.584749 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-03-20 00:21:05.588569 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://github.com/openstack/oslo.middleware.git
2026-03-20 00:21:05.592955 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-03-20 00:21:05.596852 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://github.com/openstack/oslo.policy.git
2026-03-20 00:21:05.600621 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-03-20 00:21:05.604574 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://github.com/openstack/oslo.privsep.git
2026-03-20 00:21:05.608579 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-03-20 00:21:05.613095 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://github.com/openstack/oslo.reports.git
2026-03-20 00:21:05.617175 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-03-20 00:21:05.621029 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://github.com/openstack/oslo.rootwrap.git
2026-03-20 00:21:05.625037 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-03-20 00:21:05.628984 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://github.com/openstack/oslo.serialization.git
2026-03-20 00:21:05.632926 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-03-20 00:21:05.636890 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://github.com/openstack/oslo.service.git
2026-03-20 00:21:05.640739 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-03-20 00:21:05.644886 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://github.com/openstack/oslo.utils.git
2026-03-20 00:21:05.648825 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-03-20 00:21:05.653314 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://github.com/openstack/oslo.versionedobjects.git
2026-03-20 00:21:05.657185 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-03-20 00:21:05.661072 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://github.com/openstack/oslo.vmware.git
2026-03-20 00:21:05.664977 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-03-20 00:21:05.669213 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://github.com/openstack/osprofiler.git
2026-03-20 00:21:05.673289 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-03-20 00:21:05.677154 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://github.com/openstack/pycadf.git
2026-03-20 00:21:05.680303 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-03-20 00:21:05.683471 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://github.com/openstack/stevedore.git
2026-03-20 00:21:05.687452 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-03-20 00:21:05.691408 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://github.com/openstack/taskflow.git
2026-03-20 00:21:05.695401 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-03-20 00:21:05.699287 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://github.com/openstack/tooz.git
2026-03-20 00:21:05.703147 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-03-20 00:21:05.706699 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://github.com/openstack/pbr.git
2026-03-20 00:21:05.709942 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-03-20 00:21:05.714683 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://github.com/openstack/cursive.git
2026-03-20 00:21:05.718298 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-03-20 00:21:05.722052 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://github.com/openstack/glance_store.git
2026-03-20 00:21:05.726033 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-03-20 00:21:05.729851 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://github.com/openstack/keystonemiddleware.git
2026-03-20 00:21:05.733813 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-03-20 00:21:05.737689 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://github.com/openstack/ceilometermiddleware.git
2026-03-20 00:21:05.741678 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-03-20 00:21:05.745621 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-03-20 00:21:05.748866 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://github.com/openstack/openstacksdk.git
2026-03-20 00:21:05.752773 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-03-20 00:21:05.756418 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://github.com/openstack/os-brick.git
2026-03-20 00:21:05.760353 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-03-20 00:21:05.764541 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://github.com/openstack/os-client-config.git
2026-03-20 00:21:05.768531 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-03-20 00:21:05.772279 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-03-20 00:21:05.776080 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://github.com/openstack/os-vif.git
2026-03-20 00:21:05.779899 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-03-20 00:21:05.783561 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://github.com/openstack/osc-lib.git
2026-03-20 00:21:05.787111 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-03-20 00:21:05.791018 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://github.com/openstack/ironic-lib.git
2026-03-20 00:21:05.794834 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-03-20 00:21:05.798960 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-03-20 00:21:05.802886 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://github.com/openstack/diskimage-builder.git
2026-03-20 00:21:05.806745 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-03-20 00:21:05.811566 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-03-20 00:21:05.815228 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://github.com/openstack/neutron-lib.git
2026-03-20 00:21:05.818992 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-03-20 00:21:05.822787 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-03-20 00:21:05.826705 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://github.com/openstack/os-resource-classes.git
2026-03-20 00:21:05.830706 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-03-20 00:21:05.834398 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://github.com/openstack/os-traits.git
2026-03-20 00:21:05.838464 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-03-20 00:21:05.842281 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://github.com/openstack/ovsdbapp.git
2026-03-20 00:21:05.846013 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-03-20 00:21:05.849803 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-03-20 00:21:05.854130 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://github.com/openstack/os-ken.git
2026-03-20 00:21:05.858246 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-03-20 00:21:05.862210 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-03-20 00:21:05.866186 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://github.com/openstack/ironic-python-agent.git
2026-03-20 00:21:05.869774 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-03-20 00:21:05.873609 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-03-20 00:21:05.877523 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-03-20 00:21:05.881321 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-03-20 00:21:05.885112 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-03-20 00:21:05.890458 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-03-20 00:21:05.894498 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.900480 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.900680 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.908248 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.911940 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.916534 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-03-20 00:21:05.919984 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-03-20 00:21:05.924391 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-03-20 00:21:05.928550 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-03-20 00:21:05.932459 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-03-20 00:21:05.936080 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-03-20 00:21:05.939703 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-03-20 00:21:05.943317 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-03-20 00:21:05.946719 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-03-20 00:21:05.951397 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-03-20 00:21:05.956180 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-03-20 00:21:05.962399 | controller | +++ stackrc:source:668                       :   uname -m
2026-03-20 00:21:05.969302 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-03-20 00:21:05.974000 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-03-20 00:21:05.977847 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:05.983908 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:05.984170 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:05.990946 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:05.994793 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:05.999446 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-03-20 00:21:06.003781 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-03-20 00:21:06.007490 | controller | ++ stackrc:source:675                       :   [[ -n '' ]]
2026-03-20 00:21:06.011246 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-03-20 00:21:06.015030 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-03-20 00:21:06.018911 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-03-20 00:21:06.022632 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-03-20 00:21:06.027055 | 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-03-20 00:21:06.030799 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-03-20 00:21:06.034674 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-03-20 00:21:06.038397 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-03-20 00:21:06.042285 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-03-20 00:21:06.046507 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-03-20 00:21:06.050621 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-03-20 00:21:06.054361 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-03-20 00:21:06.058329 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-03-20 00:21:06.063418 | controller | +++ functions-common:is_arch:517             :   uname -m
2026-03-20 00:21:06.068796 | controller | ++ functions-common:is_arch:517             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-03-20 00:21:06.072604 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-03-20 00:21:06.076406 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-03-20 00:21:06.080461 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-03-20 00:21:06.084583 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-03-20 00:21:06.088789 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-03-20 00:21:06.093141 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-03-20 00:21:06.095929 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-03-20 00:21:06.100513 | 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-03-20 00:21:06.104416 | 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-03-20 00:21:06.107953 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-03-20 00:21:06.111872 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-03-20 00:21:06.115814 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-03-20 00:21:06.121914 | 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-03-20 00:21:06.122217 | 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-03-20 00:21:06.122842 | controller | +++ stackrc:source:749                       :   wc -l
2026-03-20 00:21:06.134130 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-03-20 00:21:06.137846 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=30G
2026-03-20 00:21:06.141576 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-03-20 00:21:06.145713 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-03-20 00:21:06.149585 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-03-20 00:21:06.153410 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-03-20 00:21:06.157323 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-03-20 00:21:06.161351 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-03-20 00:21:06.165382 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-03-20 00:21:06.171092 | controller | +++ stackrc:source:777                       :   nproc
2026-03-20 00:21:06.183446 | controller | +++ stackrc:source:777                       :   nproc
2026-03-20 00:21:06.188794 | controller | ++ stackrc:source:777                       :   API_WORKERS=4
2026-03-20 00:21:06.192573 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-03-20 00:21:06.196588 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-03-20 00:21:06.200437 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-03-20 00:21:06.203821 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-03-20 00:21:06.208472 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-03-20 00:21:06.212597 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:06.218245 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:06.219679 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:06.226723 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:06.230649 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:06.235574 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-03-20 00:21:06.240531 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-03-20 00:21:06.244614 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:06.250489 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:06.250798 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:06.258249 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:06.261491 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:06.266316 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-03-20 00:21:06.270822 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-03-20 00:21:06.275086 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:06.281368 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:06.281484 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:06.288150 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:06.291794 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:06.295621 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-03-20 00:21:06.297985 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-03-20 00:21:06.301578 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-03-20 00:21:06.304233 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-03-20 00:21:06.308151 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-03-20 00:21:06.312025 | controller | ++ stackrc:source:815                       :   HOST_IP=199.204.45.67
2026-03-20 00:21:06.315840 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-03-20 00:21:06.321056 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 199.204.45.67 inet
2026-03-20 00:21:06.323930 | controller | +++ functions-common:get_default_host_ip:752 :   local fixed_range=10.1.0.0/20
2026-03-20 00:21:06.327680 | controller | +++ functions-common:get_default_host_ip:753 :   local floating_range=172.24.5.0/24
2026-03-20 00:21:06.331730 | controller | +++ functions-common:get_default_host_ip:754 :   local host_ip_iface=
2026-03-20 00:21:06.334707 | controller | +++ functions-common:get_default_host_ip:755 :   local host_ip=199.204.45.67
2026-03-20 00:21:06.338463 | controller | +++ functions-common:get_default_host_ip:756 :   local af=inet
2026-03-20 00:21:06.342537 | controller | +++ functions-common:get_default_host_ip:759 :   '[' -z 199.204.45.67 -o 199.204.45.67 == dhcp ']'
2026-03-20 00:21:06.346340 | controller | +++ functions-common:get_default_host_ip:783 :   echo 199.204.45.67
2026-03-20 00:21:06.350922 | controller | ++ stackrc:source:818                       :   HOST_IP=199.204.45.67
2026-03-20 00:21:06.354131 | controller | ++ stackrc:source:819                       :   '[' 199.204.45.67 == '' ']'
2026-03-20 00:21:06.359596 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-03-20 00:21:06.363945 | controller | +++ functions-common:get_default_host_ip:752 :   local fixed_range=
2026-03-20 00:21:06.367499 | controller | +++ functions-common:get_default_host_ip:753 :   local floating_range=
2026-03-20 00:21:06.370504 | controller | +++ functions-common:get_default_host_ip:754 :   local host_ip_iface=
2026-03-20 00:21:06.374687 | controller | +++ functions-common:get_default_host_ip:755 :   local host_ip=
2026-03-20 00:21:06.378929 | controller | +++ functions-common:get_default_host_ip:756 :   local af=inet6
2026-03-20 00:21:06.382416 | controller | +++ functions-common:get_default_host_ip:759 :   '[' -z '' -o '' == dhcp ']'
2026-03-20 00:21:06.386488 | controller | +++ functions-common:get_default_host_ip:760 :   host_ip=
2026-03-20 00:21:06.392502 | controller | ++++ functions-common:get_default_host_ip:762 :   head -1
2026-03-20 00:21:06.393063 | controller | ++++ functions-common:get_default_host_ip:762 :   awk '/default/ {print $5}'
2026-03-20 00:21:06.393085 | controller | ++++ functions-common:get_default_host_ip:762 :   grep via
2026-03-20 00:21:06.393094 | controller | ++++ functions-common:get_default_host_ip:762 :   ip -f inet6 route list match default table all
2026-03-20 00:21:06.401584 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip_iface=
2026-03-20 00:21:06.404261 | controller | +++ functions-common:get_default_host_ip:763 :   local host_ips
2026-03-20 00:21:06.410078 | controller | ++++ functions-common:get_default_host_ip:764 :   sed /temporary/d
2026-03-20 00:21:06.410649 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-03-20 00:21:06.410687 | controller | ++++ functions-common:get_default_host_ip:764 :   LC_ALL=C
2026-03-20 00:21:06.414141 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 addr show
2026-03-20 00:21:06.423710 | controller | +++ functions-common:get_default_host_ip:764 :   host_ips='::1
2026-03-20 00:21:06.423738 | controller | 2604:e100:1:0:f816:3eff:fe4d:a35
2026-03-20 00:21:06.423747 | controller | fe80::f816:3eff:fe4d:a35'
2026-03-20 00:21:06.427806 | controller | +++ functions-common:get_default_host_ip:765 :   local ip
2026-03-20 00:21:06.431833 | controller | +++ functions-common:get_default_host_ip:766 :   for ip in $host_ips
2026-03-20 00:21:06.435157 | controller | +++ functions-common:get_default_host_ip:773 :   [[ inet6 == \i\n\e\t\6 ]]
2026-03-20 00:21:06.438454 | controller | +++ functions-common:get_default_host_ip:774 :   host_ip=::1
2026-03-20 00:21:06.442332 | controller | +++ functions-common:get_default_host_ip:775 :   break
2026-03-20 00:21:06.445640 | controller | +++ functions-common:get_default_host_ip:783 :   echo ::1
2026-03-20 00:21:06.449987 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-03-20 00:21:06.453479 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-03-20 00:21:06.457172 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:06.462294 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:06.463057 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:06.469545 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:06.473300 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:06.477297 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-03-20 00:21:06.480913 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-03-20 00:21:06.485169 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-03-20 00:21:06.488240 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-03-20 00:21:06.491671 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=199.204.45.67
2026-03-20 00:21:06.495359 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-03-20 00:21:06.499039 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-03-20 00:21:06.502602 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-03-20 00:21:06.506279 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-03-20 00:21:06.509883 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=199.204.45.67
2026-03-20 00:21:06.513991 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-03-20 00:21:06.517759 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-03-20 00:21:06.520299 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-03-20 00:21:06.523913 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-03-20 00:21:06.527102 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=199.204.45.67
2026-03-20 00:21:06.530939 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-03-20 00:21:06.534838 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=199.204.45.67
2026-03-20 00:21:06.538965 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-03-20 00:21:06.542982 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:06.549497 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:06.549624 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:06.556811 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:06.560622 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:06.565150 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-03-20 00:21:06.568408 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=199.204.45.67
2026-03-20 00:21:06.572592 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-03-20 00:21:06.576402 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-03-20 00:21:06.581620 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-03-20 00:21:06.585655 | controller | +++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:06.592115 | controller | ++++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:06.592487 | controller | ++++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:06.600124 | controller | +++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:06.603814 | controller | +++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:06.608739 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-03-20 00:21:06.613156 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-03-20 00:21:06.617068 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-03-20 00:21:06.620192 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-03-20 00:21:06.623940 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-03-20 00:21:06.627858 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-03-20 00:21:06.630680 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-03-20 00:21:06.634389 | controller | ++ stackrc:source:924                       :   [[ /opt/stack/logs == \d\e\v\s\t\a\c\k\l\o\g\.\t\x\t ]]
2026-03-20 00:21:06.637817 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-03-20 00:21:06.640952 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-03-20 00:21:06.644548 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-03-20 00:21:06.648732 | controller | + functions:write_devstack_version:876     :   cat -
2026-03-20 00:21:06.650429 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-03-20 00:21:06.653685 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-03-20 00:21:06.694117 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-03-20 00:21:06.697698 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-03-20 00:21:06.701461 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-03-20 00:21:06.705142 | controller | + functions-common:export_proxy_variables:2230 :   isset http_proxy
2026-03-20 00:21:06.709006 | controller | + functions-common:isset:264               :   [[ -v http_proxy ]]
2026-03-20 00:21:06.712956 | controller | + functions-common:export_proxy_variables:2233 :   isset https_proxy
2026-03-20 00:21:06.717088 | controller | + functions-common:isset:264               :   [[ -v https_proxy ]]
2026-03-20 00:21:06.720678 | controller | + functions-common:export_proxy_variables:2236 :   isset no_proxy
2026-03-20 00:21:06.724422 | controller | + functions-common:isset:264               :   [[ -v no_proxy ]]
2026-03-20 00:21:06.728656 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-03-20 00:21:06.732362 | controller | + functions-common:disable_negated_services:1935 :   local xtrace
2026-03-20 00:21:06.738947 | controller | ++ functions-common:disable_negated_services:1936 :   set +o
2026-03-20 00:21:06.739296 | controller | ++ functions-common:disable_negated_services:1936 :   grep xtrace
2026-03-20 00:21:06.747010 | controller | + functions-common:disable_negated_services:1936 :   xtrace='set -o xtrace'
2026-03-20 00:21:06.750965 | controller | + functions-common:disable_negated_services:1937 :   set +o xtrace
2026-03-20 00:21:06.774138 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-03-20 00:21:06.778185 | controller | + functions-common:is_package_installed:1439 :   [[ -z sudo ]]
2026-03-20 00:21:06.781983 | controller | + functions-common:is_package_installed:1443 :   [[ -z deb ]]
2026-03-20 00:21:06.785857 | controller | + functions-common:is_package_installed:1447 :   [[ deb = \d\e\b ]]
2026-03-20 00:21:06.789715 | controller | + functions-common:is_package_installed:1448 :   dpkg -s sudo
2026-03-20 00:21:06.808567 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-03-20 00:21:06.830895 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-03-20 00:21:06.830927 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-03-20 00:21:06.842574 | controller | #includedir /etc/sudoers.d
2026-03-20 00:21:06.848762 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-03-20 00:21:06.854291 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-03-20 00:21:06.860537 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.UjHyy1vvM7
2026-03-20 00:21:06.864102 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-03-20 00:21:06.868196 | controller | + ./stack.sh:main:277                      :   echo 'Defaults:stack secure_path=/sbin:/usr/sbin:/usr/bin:/bin:/usr/local/sbin:/usr/local/bin'
2026-03-20 00:21:06.872252 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-03-20 00:21:06.876252 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.UjHyy1vvM7
2026-03-20 00:21:06.882113 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.UjHyy1vvM7
2026-03-20 00:21:06.902442 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.UjHyy1vvM7 /etc/sudoers.d/50_stack_sh
2026-03-20 00:21:06.923308 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-03-20 00:21:06.927904 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-03-20 00:21:06.932055 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-03-20 00:21:06.937092 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-03-20 00:21:06.937699 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-03-20 00:21:06.957324 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-03-20 00:21:06.961200 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-03-20 00:21:06.965157 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-03-20 00:21:06.969897 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-03-20 00:21:06.989953 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-03-20 00:21:06.993701 | controller | + functions-common:safe_chown:2313         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-03-20 00:21:06.997567 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-03-20 00:21:07.003240 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-03-20 00:21:07.003591 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-03-20 00:21:07.009630 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-03-20 00:21:07.013349 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-03-20 00:21:07.021219 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chown -R stack /opt/stack/logs
2026-03-20 00:21:07.035733 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-03-20 00:21:07.039230 | controller | + functions-common:safe_chmod:2307         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-03-20 00:21:07.042594 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-03-20 00:21:07.048824 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-03-20 00:21:07.048858 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-03-20 00:21:07.056179 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-03-20 00:21:07.060198 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-03-20 00:21:07.067458 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chmod 0755 /opt/stack/logs
2026-03-20 00:21:07.086179 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-03-20 00:21:07.090307 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-03-20 00:21:07.094834 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-03-20 00:21:07.113575 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-03-20 00:21:07.117308 | controller | + functions-common:safe_chown:2313         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-03-20 00:21:07.121144 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-03-20 00:21:07.126823 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-03-20 00:21:07.126853 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-03-20 00:21:07.134182 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-03-20 00:21:07.137333 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-03-20 00:21:07.145265 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chown -R stack /opt/stack/data
2026-03-20 00:21:07.164138 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-03-20 00:21:07.168094 | controller | + functions-common:safe_chmod:2307         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-03-20 00:21:07.171838 | controller | + functions-common:_safe_permission_operation:2138 :   local xtrace
2026-03-20 00:21:07.177811 | controller | ++ functions-common:_safe_permission_operation:2139 :   set +o
2026-03-20 00:21:07.178163 | controller | ++ functions-common:_safe_permission_operation:2139 :   grep xtrace
2026-03-20 00:21:07.185613 | controller | + functions-common:_safe_permission_operation:2139 :   xtrace='set -o xtrace'
2026-03-20 00:21:07.188983 | controller | + functions-common:_safe_permission_operation:2140 :   set +o xtrace
2026-03-20 00:21:07.196527 | controller | + functions-common:_safe_permission_operation:2161 :   sudo chmod 0755 /opt/stack/data
2026-03-20 00:21:07.215052 | controller | + ./stack.sh:main:353                      :   async_init
2026-03-20 00:21:07.219005 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-03-20 00:21:07.222708 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-03-20 00:21:07.229056 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-03-20 00:21:07.237800 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-03-20 00:21:07.244518 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000160184
2026-03-20 00:21:07.248550 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000160184 /etc/hosts
2026-03-20 00:21:07.256528 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-03-20 00:21:07.260535 | controller | ++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:07.266354 | controller | +++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:07.266460 | controller | +++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:07.274072 | controller | ++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:07.278036 | controller | ++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:07.282608 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-03-20 00:21:07.287086 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-03-20 00:21:07.290908 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-03-20 00:21:07.294865 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-03-20 00:21:07.298714 | controller | + ./stack.sh:main:404                      :   install_python
2026-03-20 00:21:07.303087 | controller | + inc/python:install_python:482            :   install_python3
2026-03-20 00:21:07.306936 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-03-20 00:21:07.310824 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-03-20 00:21:07.314886 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-03-20 00:21:07.318139 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-03-20 00:21:07.322150 | controller | + functions-common:apt_get:1186            :   local xtrace result
2026-03-20 00:21:07.327764 | controller | ++ functions-common:apt_get:1187            :   set +o
2026-03-20 00:21:07.327959 | controller | ++ functions-common:apt_get:1187            :   grep xtrace
2026-03-20 00:21:07.335348 | controller | + functions-common:apt_get:1187            :   xtrace='set -o xtrace'
2026-03-20 00:21:07.339133 | controller | + functions-common:apt_get:1188            :   set +o xtrace
2026-03-20 00:21:07.352167 | controller | + functions-common:apt_get:1199            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install python3.12 python3.12-dev
2026-03-20 00:21:07.394430 | controller | Reading package lists...
2026-03-20 00:21:07.622685 | controller | Building dependency tree...
2026-03-20 00:21:07.622942 | controller | Reading state information...
2026-03-20 00:21:07.851645 | controller | python3.12 is already the newest version (3.12.3-1ubuntu0.12).
2026-03-20 00:21:07.851679 | controller | python3.12 set to manually installed.
2026-03-20 00:21:07.851689 | controller | The following additional packages will be installed:
2026-03-20 00:21:07.851703 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-03-20 00:21:07.852416 | controller |   libpython3.12t64 linux-libc-dev rpcsvc-proto zlib1g-dev
2026-03-20 00:21:07.853397 | controller | Suggested packages:
2026-03-20 00:21:07.853416 | controller |   glibc-doc manpages-dev
2026-03-20 00:21:07.853425 | controller | Recommended packages:
2026-03-20 00:21:07.853433 | controller |   manpages manpages-dev libc-devtools
2026-03-20 00:21:07.894705 | controller | The following NEW packages will be installed:
2026-03-20 00:21:07.894820 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-03-20 00:21:07.895655 | controller |   libpython3.12t64 linux-libc-dev python3.12-dev rpcsvc-proto zlib1g-dev
2026-03-20 00:21:07.961113 | controller | 0 upgraded, 10 newly installed, 0 to remove and 0 not upgraded.
2026-03-20 00:21:07.961147 | controller | Need to get 14.0 MB of archives.
2026-03-20 00:21:07.961157 | controller | After this operation, 63.6 MB of additional disk space will be used.
2026-03-20 00:21:07.961166 | 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-03-20 00:21:08.003896 | controller | Get:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 linux-libc-dev amd64 6.8.0-106.106 [2,095 kB]
2026-03-20 00:21:08.144349 | controller | Get:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 libcrypt-dev amd64 1:4.4.36-4build1 [112 kB]
2026-03-20 00:21:08.151207 | controller | Get:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 rpcsvc-proto amd64 1.4.2-0ubuntu7 [67.4 kB]
2026-03-20 00:21:08.154659 | 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-03-20 00:21:08.281170 | 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-03-20 00:21:08.308071 | controller | Get:7 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12t64 amd64 3.12.3-1ubuntu0.12 [2,345 kB]
2026-03-20 00:21:08.557807 | 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-03-20 00:21:08.673694 | controller | Get:9 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12-dev amd64 3.12.3-1ubuntu0.12 [5,681 kB]
2026-03-20 00:21:09.151239 | controller | Get:10 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 python3.12-dev amd64 3.12.3-1ubuntu0.12 [498 kB]
2026-03-20 00:21:09.960913 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-03-20 00:21:09.998722 | controller | Fetched 14.0 MB in 2s (7,445 kB/s)
2026-03-20 00:21:10.047466 | controller | Selecting previously unselected package libc-dev-bin.
2026-03-20 00:21:10.181781 | 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 ... 30635 files and directories currently installed.)
2026-03-20 00:21:10.183254 | controller | Preparing to unpack .../0-libc-dev-bin_2.39-0ubuntu8.7_amd64.deb ...
2026-03-20 00:21:10.189648 | controller | Unpacking libc-dev-bin (2.39-0ubuntu8.7) ...
2026-03-20 00:21:10.249614 | controller | Selecting previously unselected package linux-libc-dev:amd64.
2026-03-20 00:21:10.254431 | controller | Preparing to unpack .../1-linux-libc-dev_6.8.0-106.106_amd64.deb ...
2026-03-20 00:21:10.260758 | controller | Unpacking linux-libc-dev:amd64 (6.8.0-106.106) ...
2026-03-20 00:21:10.609727 | controller | Selecting previously unselected package libcrypt-dev:amd64.
2026-03-20 00:21:10.614880 | controller | Preparing to unpack .../2-libcrypt-dev_1%3a4.4.36-4build1_amd64.deb ...
2026-03-20 00:21:10.633325 | controller | Unpacking libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-03-20 00:21:10.697647 | controller | Selecting previously unselected package rpcsvc-proto.
2026-03-20 00:21:10.703163 | controller | Preparing to unpack .../3-rpcsvc-proto_1.4.2-0ubuntu7_amd64.deb ...
2026-03-20 00:21:10.709145 | controller | Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-03-20 00:21:10.774648 | controller | Selecting previously unselected package libc6-dev:amd64.
2026-03-20 00:21:10.780114 | controller | Preparing to unpack .../4-libc6-dev_2.39-0ubuntu8.7_amd64.deb ...
2026-03-20 00:21:10.785327 | controller | Unpacking libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-03-20 00:21:11.034238 | controller | Selecting previously unselected package libexpat1-dev:amd64.
2026-03-20 00:21:11.039932 | controller | Preparing to unpack .../5-libexpat1-dev_2.6.1-2ubuntu0.4_amd64.deb ...
2026-03-20 00:21:11.046081 | controller | Unpacking libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-03-20 00:21:11.113648 | controller | Selecting previously unselected package libpython3.12t64:amd64.
2026-03-20 00:21:11.115681 | controller | Preparing to unpack .../6-libpython3.12t64_3.12.3-1ubuntu0.12_amd64.deb ...
2026-03-20 00:21:11.125643 | controller | Unpacking libpython3.12t64:amd64 (3.12.3-1ubuntu0.12) ...
2026-03-20 00:21:11.249609 | controller | Selecting previously unselected package zlib1g-dev:amd64.
2026-03-20 00:21:11.255550 | controller | Preparing to unpack .../7-zlib1g-dev_1%3a1.3.dfsg-3.1ubuntu2.1_amd64.deb ...
2026-03-20 00:21:11.260767 | controller | Unpacking zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-03-20 00:21:11.335609 | controller | Selecting previously unselected package libpython3.12-dev:amd64.
2026-03-20 00:21:11.341835 | controller | Preparing to unpack .../8-libpython3.12-dev_3.12.3-1ubuntu0.12_amd64.deb ...
2026-03-20 00:21:11.346884 | controller | Unpacking libpython3.12-dev:amd64 (3.12.3-1ubuntu0.12) ...
2026-03-20 00:21:11.615657 | controller | Selecting previously unselected package python3.12-dev.
2026-03-20 00:21:11.621664 | controller | Preparing to unpack .../9-python3.12-dev_3.12.3-1ubuntu0.12_amd64.deb ...
2026-03-20 00:21:11.627396 | controller | Unpacking python3.12-dev (3.12.3-1ubuntu0.12) ...
2026-03-20 00:21:11.839147 | controller | Setting up linux-libc-dev:amd64 (6.8.0-106.106) ...
2026-03-20 00:21:11.951338 | controller | Setting up libpython3.12t64:amd64 (3.12.3-1ubuntu0.12) ...
2026-03-20 00:21:12.107233 | controller | Setting up rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-03-20 00:21:12.214642 | controller | Setting up libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-03-20 00:21:12.331565 | controller | Setting up libc-dev-bin (2.39-0ubuntu8.7) ...
2026-03-20 00:21:12.346739 | controller | Setting up libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-03-20 00:21:12.361449 | controller | Setting up libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-03-20 00:21:12.377321 | controller | Setting up zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-03-20 00:21:12.397479 | controller | Setting up libpython3.12-dev:amd64 (3.12.3-1ubuntu0.12) ...
2026-03-20 00:21:12.412900 | controller | Setting up python3.12-dev (3.12.3-1ubuntu0.12) ...
2026-03-20 00:21:12.430275 | controller | Processing triggers for libc-bin (2.39-0ubuntu8.7) ...
2026-03-20 00:21:12.692427 | controller | + functions-common:apt_get:1203            :   result=0
2026-03-20 00:21:12.695806 | controller | + functions-common:apt_get:1206            :   time_stop apt-get
2026-03-20 00:21:12.700188 | controller | + functions-common:time_stop:2412          :   local name
2026-03-20 00:21:12.704403 | controller | + functions-common:time_stop:2413          :   local end_time
2026-03-20 00:21:12.708958 | controller | + functions-common:time_stop:2414          :   local elapsed_time
2026-03-20 00:21:12.712594 | controller | + functions-common:time_stop:2415          :   local total
2026-03-20 00:21:12.716361 | controller | + functions-common:time_stop:2416          :   local start_time
2026-03-20 00:21:12.720032 | controller | + functions-common:time_stop:2418          :   name=apt-get
2026-03-20 00:21:12.723563 | controller | + functions-common:time_stop:2419          :   start_time=1773966067347
2026-03-20 00:21:12.727863 | controller | + functions-common:time_stop:2421          :   [[ -z 1773966067347 ]]
2026-03-20 00:21:12.732923 | controller | ++ functions-common:time_stop:2424          :   date +%s%3N
2026-03-20 00:21:12.738982 | controller | + functions-common:time_stop:2424          :   end_time=1773966072734
2026-03-20 00:21:12.742789 | controller | + functions-common:time_stop:2425          :   elapsed_time=5387
2026-03-20 00:21:12.746330 | controller | + functions-common:time_stop:2426          :   total=0
2026-03-20 00:21:12.750888 | controller | + functions-common:time_stop:2428          :   _TIME_START[$name]=
2026-03-20 00:21:12.754974 | controller | + functions-common:time_stop:2429          :   _TIME_TOTAL[$name]=5387
2026-03-20 00:21:12.759071 | controller | + functions-common:apt_get:1207            :   return 0
2026-03-20 00:21:12.764742 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-03-20 00:21:12.772167 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-03-20 00:21:12.776284 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-03-20 00:21:12.781890 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-03-20 00:21:12.786183 | controller | ++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:12.792137 | controller | +++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:12.793082 | controller | +++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:12.800486 | controller | ++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:12.804130 | controller | ++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:12.808962 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-03-20 00:21:12.814854 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-03-20 00:21:12.819093 | controller | ++ functions-common:trueorfalse:222         :   local xtrace
2026-03-20 00:21:12.825698 | controller | +++ functions-common:trueorfalse:223         :   set +o
2026-03-20 00:21:12.825964 | controller | +++ functions-common:trueorfalse:223         :   grep xtrace
2026-03-20 00:21:12.834245 | controller | ++ functions-common:trueorfalse:223         :   xtrace='set -o xtrace'
2026-03-20 00:21:12.838262 | controller | ++ functions-common:trueorfalse:224         :   set +o xtrace
2026-03-20 00:21:12.842985 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-03-20 00:21:12.847594 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-03-20 00:21:12.850606 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-03-20 00:21:12.855861 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-03-20 00:21:12.862021 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-03-20-002112
2026-03-20 00:21:12.865237 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-03-20 00:21:12.869266 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-03-20 00:21:12.872850 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-03-20 00:21:12.877191 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-03-20 00:21:12.884021 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-03-20 00:21:12.891562 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-03-20-002112
2026-03-20 00:21:12.895230 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-03-20-002112.summary.2026-03-20-002112
2026-03-20 00:21:12.898702 | controller | + ./stack.sh:main:476                      :   exec
2026-03-20 00:21:12.902230 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-03-20 00:21:12.906311 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-03-20 00:21:12.909815 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-03-20 00:21:12.913781 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-03-20 00:21:12.917878 | controller | + ./stack.sh:main:483                      :   exec
2026-03-20 00:21:12.922631 | 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-03-20-002112
2026-03-20 00:21:12.951132 | controller | + ./stack.sh:main:485                      :   exec
2026-03-20 00:21:12.951182 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-03-20-002112'
2026-03-20 00:21:12.951193 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-03-20-002112.summary.2026-03-20-002112
2026-03-20 00:21:12.951202 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-03-20 00:21:12.951210 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-03-20-002112
2026-03-20 00:21:12.951219 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-03-20-002112 /opt/stack/logs/devstacklog.txt
2026-03-20 00:21:12.951228 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-03-20-002112.summary.2026-03-20-002112 /opt/stack/logs/devstacklog.txt.summary
2026-03-20 00:21:12.951556 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-03-20 00:21:12.955825 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-03-20 00:21:12.960766 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-03-20 00:21:12.967702 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-03-20 00:21:12.971576 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-03-20 00:21:12.977995 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-03-20 00:21:12.978128 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-03-20 00:21:12.984601 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-03-20 00:21:12.988464 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-03-20 00:21:12.993356 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-03-20 00:21:12.999885 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-03-20 00:21:13.004531 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-03-20 00:21:13.008889 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-03-20 00:21:13.014346 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-03-20 00:21:13.021506 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-03-20 00:21:13.025159 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-03-20 00:21:13.029329 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-03-20 00:21:13.033975 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-03-20 00:21:13.038319 | controller | + ./stack.sh:main:574                      :   uname -a
2026-03-20 00:21:13.040417 | controller | Linux np0000160184 6.8.0-106-generic #106-Ubuntu SMP PREEMPT_DYNAMIC Fri Mar  6 07:58:08 UTC 2026 x86_64 x86_64 x86_64 GNU/Linux
2026-03-20 00:21:13.044016 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-03-20 00:21:13.047220 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-03-20 00:21:13.054148 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-03-20 00:21:13.142630 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-03-20 00:21:13.173242 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-03-20 00:21:13.178235 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-03-20 00:21:13.204926 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-03-20 00:21:13.211274 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-03-20 00:21:13.238197 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-03-20 00:21:13.242643 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-03-20 00:21:13.269080 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-03-20 00:21:13.273118 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-03-20 00:21:13.276918 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-03-20 00:21:13.282083 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-03-20 00:21:13.307763 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-03-20 00:21:13.312576 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-03-20 00:21:13.339648 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-03-20 00:21:13.343626 | controller | + ./stack.sh:main:588                      :   tune_host
2026-03-20 00:21:13.347609 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-03-20 00:21:13.351732 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-03-20 00:21:13.355401 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-03-20 00:21:13.359449 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-03-20 00:21:13.363649 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-03-20 00:21:13.367338 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-03-20 00:21:13.371913 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-03-20 00:21:13.372574 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-03-20 00:21:13.385528 | controller | lz4
2026-03-20 00:21:13.392555 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-03-20 00:21:13.392590 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-03-20 00:21:13.406510 | controller | zsmalloc
2026-03-20 00:21:13.412385 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-03-20 00:21:13.412984 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-03-20 00:21:13.426198 | controller | 1
2026-03-20 00:21:13.452153 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-03-20 00:21:13.464902 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-03-20 00:21:13.464926 | controller | /sys/module/zswap/parameters/enabled:Y
2026-03-20 00:21:13.464941 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-03-20 00:21:13.464951 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-03-20 00:21:13.464964 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-03-20 00:21:13.464977 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-03-20 00:21:13.465034 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-03-20 00:21:13.465045 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-03-20 00:21:13.465058 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-03-20 00:21:13.469735 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-03-20 00:21:13.473229 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-03-20 00:21:13.476911 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-03-20 00:21:13.480308 | controller | + functions-common:install_package:1430    :   update_package_repo
2026-03-20 00:21:13.483973 | controller | + functions-common:update_package_repo:1404 :   NO_UPDATE_REPOS=False
2026-03-20 00:21:13.487185 | controller | + functions-common:update_package_repo:1405 :   REPOS_UPDATED=False
2026-03-20 00:21:13.491292 | controller | + functions-common:update_package_repo:1406 :   RETRY_UPDATE=False
2026-03-20 00:21:13.493981 | controller | + functions-common:update_package_repo:1408 :   [[ False = \T\r\u\e ]]
2026-03-20 00:21:13.497256 | controller | + functions-common:update_package_repo:1412 :   is_ubuntu
2026-03-20 00:21:13.500844 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-03-20 00:21:13.504837 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-03-20 00:21:13.508021 | controller | + functions-common:update_package_repo:1413 :   apt_get_update
2026-03-20 00:21:13.510889 | controller | + functions-common:apt_get_update:1158     :   [[ False == \T\r\u\e ]]
2026-03-20 00:21:13.513189 | controller | + functions-common:apt_get_update:1163     :   [[ False = \T\r\u\e ]]
2026-03-20 00:21:13.516269 | controller | + functions-common:apt_get_update:1165     :   local sudo=sudo
2026-03-20 00:21:13.521018 | controller | ++ functions-common:apt_get_update:1166     :   id -u
2026-03-20 00:21:13.527214 | controller | + functions-common:apt_get_update:1166     :   [[ 1002 = \0 ]]
2026-03-20 00:21:13.531399 | controller | + functions-common:apt_get_update:1169     :   time_start apt-get-update
2026-03-20 00:21:13.534829 | controller | + functions-common:time_start:2398         :   local name=apt-get-update
2026-03-20 00:21:13.538354 | controller | + functions-common:time_start:2399         :   local start_time=
2026-03-20 00:21:13.541393 | controller | + functions-common:time_start:2400         :   [[ -n '' ]]
2026-03-20 00:21:13.546532 | controller | ++ functions-common:time_start:2403         :   date +%s%3N
2026-03-20 00:21:13.553241 | controller | + functions-common:time_start:2403         :   _TIME_START[$name]=1773966073548
2026-03-20 00:21:13.556772 | controller | + functions-common:apt_get_update:1171     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-03-20 00:21:13.559759 | controller | + functions-common:apt_get_update:1172     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-03-20 00:21:13.562939 | controller | + functions-common:apt_get_update:1173     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-03-20 00:21:13.710047 | controller | Hit:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-03-20 00:21:13.711871 | controller | Hit:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-03-20 00:21:13.743830 | controller | Hit:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-03-20 00:21:13.805781 | controller | Hit:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-03-20 00:21:15.117306 | controller | Reading package lists...
2026-03-20 00:21:15.150554 | controller | + functions-common:apt_get_update:1177     :   REPOS_UPDATED=True
2026-03-20 00:21:15.154905 | controller | + functions-common:apt_get_update:1179     :   time_stop apt-get-update
2026-03-20 00:21:15.158988 | controller | + functions-common:time_stop:2412          :   local name
2026-03-20 00:21:15.162760 | controller | + functions-common:time_stop:2413          :   local end_time
2026-03-20 00:21:15.166313 | controller | + functions-common:time_stop:2414          :   local elapsed_time
2026-03-20 00:21:15.170724 | controller | + functions-common:time_stop:2415          :   local total
2026-03-20 00:21:15.173783 | controller | + functions-common:time_stop:2416          :   local start_time
2026-03-20 00:21:15.177604 | controller | + functions-common:time_stop:2418          :   name=apt-get-update
2026-03-20 00:21:15.180712 | controller | + functions-common:time_stop:2419          :   start_time=1773966073548
2026-03-20 00:21:15.183682 | controller | + functions-common:time_stop:2421          :   [[ -z 1773966073548 ]]
2026-03-20 00:21:15.188625 | controller | ++ functions-common:time_stop:2424          :   date +%s%3N
2026-03-20 00:21:15.195579 | controller | + functions-common:time_stop:2424          :   end_time=1773966075190
2026-03-20 00:21:15.199475 | controller | + functions-common:time_stop:2425          :   elapsed_time=1642
2026-03-20 00:21:15.203282 | controller | + functions-common:time_stop:2426          :   total=0
2026-03-20 00:21:15.207707 | controller | + functions-common:time_stop:2428          :   _TIME_START[$name]=
2026-03-20 00:21:15.211569 | controller | + functions-common:time_stop:2429          :   _TIME_TOTAL[$name]=1642
2026-03-20 00:21:15.216483 | controller | + functions-common:install_package:1431    :   real_install_package ksmtuned
2026-03-20 00:21:15.220217 | controller | + functions-common:real_install_package:1418 :   is_ubuntu
2026-03-20 00:21:15.223669 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-03-20 00:21:15.227402 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-03-20 00:21:15.230711 | controller | + functions-common:real_install_package:1419 :   apt_get install ksmtuned
2026-03-20 00:21:15.263529 | controller | + functions-common:apt_get:1199            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-03-20 00:21:15.303889 | controller | Reading package lists...
2026-03-20 00:21:15.533841 | controller | Building dependency tree...
2026-03-20 00:21:15.533881 | controller | Reading state information...
2026-03-20 00:21:15.757709 | controller | Recommended packages:
2026-03-20 00:21:15.757784 | controller |   qemu-kvm
2026-03-20 00:21:15.783351 | controller | The following NEW packages will be installed:
2026-03-20 00:21:15.784815 | controller |   ksmtuned
2026-03-20 00:21:15.972360 | controller | 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
2026-03-20 00:21:15.972450 | controller | Need to get 7,444 B of archives.
2026-03-20 00:21:15.972460 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-03-20 00:21:15.972469 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:17.129098 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:19.286103 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:23.450634 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:23.450670 | controller |   503  Service Unavailable [IP: 2620:2d:4000:1::94 80]
2026-03-20 00:21:23.464009 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  503  Service Unavailable [IP: 2620:2d:4000:1::94 80]
2026-03-20 00:21:23.464063 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-03-20 00:21:23.471538 | controller | + functions-common:apt_get:1203            :   result=100
2026-03-20 00:21:23.474921 | controller | + functions-common:apt_get:1206            :   time_stop apt-get
2026-03-20 00:21:23.478063 | controller | + functions-common:time_stop:2412          :   local name
2026-03-20 00:21:23.482360 | controller | + functions-common:time_stop:2413          :   local end_time
2026-03-20 00:21:23.486716 | controller | + functions-common:time_stop:2414          :   local elapsed_time
2026-03-20 00:21:23.490836 | controller | + functions-common:time_stop:2415          :   local total
2026-03-20 00:21:23.494678 | controller | + functions-common:time_stop:2416          :   local start_time
2026-03-20 00:21:23.498716 | controller | + functions-common:time_stop:2418          :   name=apt-get
2026-03-20 00:21:23.502839 | controller | + functions-common:time_stop:2419          :   start_time=1773966075258
2026-03-20 00:21:23.506702 | controller | + functions-common:time_stop:2421          :   [[ -z 1773966075258 ]]
2026-03-20 00:21:23.512231 | controller | ++ functions-common:time_stop:2424          :   date +%s%3N
2026-03-20 00:21:23.518726 | controller | + functions-common:time_stop:2424          :   end_time=1773966083513
2026-03-20 00:21:23.521255 | controller | + functions-common:time_stop:2425          :   elapsed_time=8255
2026-03-20 00:21:23.524672 | controller | + functions-common:time_stop:2426          :   total=5387
2026-03-20 00:21:23.528800 | controller | + functions-common:time_stop:2428          :   _TIME_START[$name]=
2026-03-20 00:21:23.532641 | controller | + functions-common:time_stop:2429          :   _TIME_TOTAL[$name]=13642
2026-03-20 00:21:23.536210 | controller | + functions-common:apt_get:1207            :   return 100
2026-03-20 00:21:23.540299 | controller | + functions-common:install_package:1432    :   RETRY_UPDATE=True
2026-03-20 00:21:23.544222 | controller | + functions-common:install_package:1432    :   update_package_repo
2026-03-20 00:21:23.548717 | controller | + functions-common:update_package_repo:1404 :   NO_UPDATE_REPOS=False
2026-03-20 00:21:23.552641 | controller | + functions-common:update_package_repo:1405 :   REPOS_UPDATED=True
2026-03-20 00:21:23.556642 | controller | + functions-common:update_package_repo:1406 :   RETRY_UPDATE=True
2026-03-20 00:21:23.560519 | controller | + functions-common:update_package_repo:1408 :   [[ False = \T\r\u\e ]]
2026-03-20 00:21:23.565620 | controller | + functions-common:update_package_repo:1412 :   is_ubuntu
2026-03-20 00:21:23.569914 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-03-20 00:21:23.574313 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-03-20 00:21:23.577718 | controller | + functions-common:update_package_repo:1413 :   apt_get_update
2026-03-20 00:21:23.581465 | controller | + functions-common:apt_get_update:1158     :   [[ True == \T\r\u\e ]]
2026-03-20 00:21:23.585901 | controller | + functions-common:apt_get_update:1158     :   [[ True != \T\r\u\e ]]
2026-03-20 00:21:23.588683 | controller | + functions-common:apt_get_update:1163     :   [[ False = \T\r\u\e ]]
2026-03-20 00:21:23.591600 | controller | + functions-common:apt_get_update:1165     :   local sudo=sudo
2026-03-20 00:21:23.597782 | controller | ++ functions-common:apt_get_update:1166     :   id -u
2026-03-20 00:21:23.605222 | controller | + functions-common:apt_get_update:1166     :   [[ 1002 = \0 ]]
2026-03-20 00:21:23.608904 | controller | + functions-common:apt_get_update:1169     :   time_start apt-get-update
2026-03-20 00:21:23.613567 | controller | + functions-common:time_start:2398         :   local name=apt-get-update
2026-03-20 00:21:23.617106 | controller | + functions-common:time_start:2399         :   local start_time=
2026-03-20 00:21:23.620660 | controller | + functions-common:time_start:2400         :   [[ -n '' ]]
2026-03-20 00:21:23.625676 | controller | ++ functions-common:time_start:2403         :   date +%s%3N
2026-03-20 00:21:23.632655 | controller | + functions-common:time_start:2403         :   _TIME_START[$name]=1773966083627
2026-03-20 00:21:23.636821 | controller | + functions-common:apt_get_update:1171     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-03-20 00:21:23.640696 | controller | + functions-common:apt_get_update:1172     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-03-20 00:21:23.643679 | controller | + functions-common:apt_get_update:1173     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-03-20 00:21:23.884932 | controller | Hit:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-03-20 00:21:23.944258 | controller | Hit:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-03-20 00:21:23.993504 | controller | Hit:3 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-03-20 00:21:24.022575 | controller | Hit:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-03-20 00:21:25.356148 | controller | Reading package lists...
2026-03-20 00:21:25.389892 | controller | + functions-common:apt_get_update:1177     :   REPOS_UPDATED=True
2026-03-20 00:21:25.393774 | controller | + functions-common:apt_get_update:1179     :   time_stop apt-get-update
2026-03-20 00:21:25.398256 | controller | + functions-common:time_stop:2412          :   local name
2026-03-20 00:21:25.402724 | controller | + functions-common:time_stop:2413          :   local end_time
2026-03-20 00:21:25.406709 | controller | + functions-common:time_stop:2414          :   local elapsed_time
2026-03-20 00:21:25.411283 | controller | + functions-common:time_stop:2415          :   local total
2026-03-20 00:21:25.415488 | controller | + functions-common:time_stop:2416          :   local start_time
2026-03-20 00:21:25.418855 | controller | + functions-common:time_stop:2418          :   name=apt-get-update
2026-03-20 00:21:25.423516 | controller | + functions-common:time_stop:2419          :   start_time=1773966083627
2026-03-20 00:21:25.427206 | controller | + functions-common:time_stop:2421          :   [[ -z 1773966083627 ]]
2026-03-20 00:21:25.432112 | controller | ++ functions-common:time_stop:2424          :   date +%s%3N
2026-03-20 00:21:25.439591 | controller | + functions-common:time_stop:2424          :   end_time=1773966085433
2026-03-20 00:21:25.443844 | controller | + functions-common:time_stop:2425          :   elapsed_time=1806
2026-03-20 00:21:25.447987 | controller | + functions-common:time_stop:2426          :   total=1642
2026-03-20 00:21:25.451092 | controller | + functions-common:time_stop:2428          :   _TIME_START[$name]=
2026-03-20 00:21:25.454406 | controller | + functions-common:time_stop:2429          :   _TIME_TOTAL[$name]=3448
2026-03-20 00:21:25.459072 | controller | + functions-common:install_package:1432    :   real_install_package ksmtuned
2026-03-20 00:21:25.463028 | controller | + functions-common:real_install_package:1418 :   is_ubuntu
2026-03-20 00:21:25.467224 | controller | + functions-common:is_ubuntu:544           :   [[ -z deb ]]
2026-03-20 00:21:25.471326 | controller | + functions-common:is_ubuntu:547           :   '[' deb = deb ']'
2026-03-20 00:21:25.475331 | controller | + functions-common:real_install_package:1419 :   apt_get install ksmtuned
2026-03-20 00:21:25.509568 | controller | + functions-common:apt_get:1199            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-03-20 00:21:25.556208 | controller | Reading package lists...
2026-03-20 00:21:25.901587 | controller | Building dependency tree...
2026-03-20 00:21:25.901649 | controller | Reading state information...
2026-03-20 00:21:26.244803 | controller | Recommended packages:
2026-03-20 00:21:26.244854 | controller |   qemu-kvm
2026-03-20 00:21:26.278521 | controller | The following NEW packages will be installed:
2026-03-20 00:21:26.280447 | controller |   ksmtuned
2026-03-20 00:21:26.468566 | controller | 0 upgraded, 1 newly installed, 0 to remove and 0 not upgraded.
2026-03-20 00:21:26.468641 | controller | Need to get 7,444 B of archives.
2026-03-20 00:21:26.468648 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-03-20 00:21:26.468655 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:27.629965 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:29.785744 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:33.945776 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-03-20 00:21:33.945859 | controller |   503  Service Unavailable [IP: 2620:2d:4000:1::94 80]
2026-03-20 00:21:33.955743 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  503  Service Unavailable [IP: 2620:2d:4000:1::94 80]
2026-03-20 00:21:33.955773 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-03-20 00:21:33.962818 | controller | + functions-common:apt_get:1               :   exit_trap
2026-03-20 00:21:33.966667 | controller | + ./stack.sh:exit_trap:519                 :   local r=100
2026-03-20 00:21:33.972931 | controller | ++ ./stack.sh:exit_trap:520                 :   jobs -p
2026-03-20 00:21:33.978468 | controller | + ./stack.sh:exit_trap:520                 :   jobs=
2026-03-20 00:21:33.982295 | controller | + ./stack.sh:exit_trap:523                 :   [[ -n '' ]]
2026-03-20 00:21:33.986775 | controller | + ./stack.sh:exit_trap:529                 :   '[' -f '' ']'
2026-03-20 00:21:33.990669 | controller | + ./stack.sh:exit_trap:534                 :   kill_spinner
2026-03-20 00:21:33.995242 | controller | + ./stack.sh:kill_spinner:429              :   '[' '!' -z '' ']'
2026-03-20 00:21:33.999868 | controller | + ./stack.sh:exit_trap:536                 :   [[ 100 -ne 0 ]]
2026-03-20 00:21:34.003854 | controller | + ./stack.sh:exit_trap:537                 :   echo 'Error on exit'
2026-03-20 00:21:34.003877 | controller | Error on exit
2026-03-20 00:21:34.007956 | controller | + ./stack.sh:exit_trap:539                 :   type -p generate-subunit
2026-03-20 00:21:34.011521 | controller | + ./stack.sh:exit_trap:542                 :   [[ -z /opt/stack/logs ]]
2026-03-20 00:21:34.015682 | controller | + ./stack.sh:exit_trap:545                 :   /usr/bin/python3.12 /opt/stack/devstack/tools/worlddump.py -d /opt/stack/logs
2026-03-20 00:21:34.555140 | controller | + ./stack.sh:exit_trap:554                 :   exit 100
2026-03-20 00:21:34.556277 | controller | *** FINISHED ***
2026-03-20 00:21:34.902898 | controller | ERROR
2026-03-20 00:21:34.903237 | controller | {
2026-03-20 00:21:34.903302 | controller |   "delta": "0:00:31.399215",
2026-03-20 00:21:34.903349 | controller |   "end": "2026-03-20 00:21:34.573472",
2026-03-20 00:21:34.903390 | controller |   "msg": "non-zero return code",
2026-03-20 00:21:34.903431 | controller |   "rc": 100,
2026-03-20 00:21:34.903475 | controller |   "start": "2026-03-20 00:21:03.174257"
2026-03-20 00:21:34.903515 | controller | }
failure
2026-03-20 00:21:34.917100 | 
2026-03-20 00:21:34.917150 | PLAY RECAP
2026-03-20 00:21:34.917191 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-03-20 00:21:34.917213 | 
2026-03-20 00:21:35.035257 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-03-20 00:21:35.042640 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-03-20 00:21:35.600122 | 
2026-03-20 00:21:35.600647 | PLAY [all]
2026-03-20 00:21:35.611676 | 
2026-03-20 00:21:35.611747 | LOOP [Copy individual Hydrophone result files]
2026-03-20 00:21:36.068979 | controller | ERROR: Item: e2e.log
2026-03-20 00:21:36.069383 | controller | {
2026-03-20 00:21:36.069439 | controller |   "ansible_loop_var": "item",
2026-03-20 00:21:36.069481 | controller |   "item": "e2e.log",
2026-03-20 00:21:36.069551 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-03-20 00:21:36.069586 | controller | }
2026-03-20 00:21:36.331673 | controller | ERROR: Item: junit_01.xml
2026-03-20 00:21:36.331844 | controller | {
2026-03-20 00:21:36.331877 | controller |   "ansible_loop_var": "item",
2026-03-20 00:21:36.331899 | controller |   "item": "junit_01.xml",
2026-03-20 00:21:36.331917 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-03-20 00:21:36.331936 | controller | }
2026-03-20 00:21:36.349118 | controller | ERROR: Ignoring Errors
2026-03-20 00:21:36.355924 | 
2026-03-20 00:21:36.355990 | TASK [Copy Hydrophone results tarball to output folder]
2026-03-20 00:21:36.654615 | controller | ERROR
2026-03-20 00:21:36.654952 | controller | {
2026-03-20 00:21:36.655016 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-03-20 00:21:36.655066 | controller | }
2026-03-20 00:21:36.655120 | controller | ERROR: Ignoring Errors
2026-03-20 00:21:36.667137 | 
2026-03-20 00:21:36.667209 | TASK [Return built artifacts to Zuul]
2026-03-20 00:21:36.701503 | controller | ok
2026-03-20 00:21:36.708585 | 
2026-03-20 00:21:36.708644 | TASK [Get the Docker volume ID]
2026-03-20 00:21:37.247579 | controller | ERROR
2026-03-20 00:21:37.248029 | controller | {
2026-03-20 00:21:37.248096 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-03-20 00:21:37.248142 | controller |   "rc": 2
2026-03-20 00:21:37.248184 | controller | }
2026-03-20 00:21:37.248241 | controller | ERROR: Ignoring Errors
2026-03-20 00:21:37.254009 | 
2026-03-20 00:21:37.254109 | TASK [Copy all of the pod logs]
2026-03-20 00:21:37.287997 | controller | ERROR
2026-03-20 00:21:37.288295 | controller | {
2026-03-20 00:21:37.288335 | 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/61b80808cc364a0194748b0aa1cc3c0d/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-03-20 00:21:37.288392 | controller | }
2026-03-20 00:21:37.288429 | controller | ERROR: Ignoring Errors
2026-03-20 00:21:37.289849 | 
2026-03-20 00:21:37.289912 | PLAY RECAP
2026-03-20 00:21:37.289973 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-03-20 00:21:37.290004 | 
2026-03-20 00:21:37.457605 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-03-20 00:21:37.462829 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-03-20 00:21:38.075867 | 
2026-03-20 00:21:38.076011 | PLAY [all]
2026-03-20 00:21:38.095250 | 
2026-03-20 00:21:38.095331 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-03-20 00:21:38.490266 | controller | changed
2026-03-20 00:21:38.496214 | 
2026-03-20 00:21:38.496295 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-03-20 00:21:39.538333 | controller | ok: Runtime: 0:00:00.456594
2026-03-20 00:21:39.545673 | 
2026-03-20 00:21:39.545747 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-03-20 00:21:40.087113 | controller | ok: Runtime: 0:00:00.021999
2026-03-20 00:21:40.092816 | 
2026-03-20 00:21:40.092888 | TASK [export-devstack-journal : Export journal]
2026-03-20 00:21:40.633140 | controller | ok: Runtime: 0:00:00.027268
2026-03-20 00:21:40.640553 | 
2026-03-20 00:21:40.640644 | TASK [export-devstack-journal : Save journal README]
2026-03-20 00:21:41.539934 | controller | changed
2026-03-20 00:21:41.550882 | 
2026-03-20 00:21:41.550955 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-03-20 00:21:41.797650 | controller | changed
2026-03-20 00:21:41.803929 | 
2026-03-20 00:21:41.803991 | TASK [apache-logs-conf : Find logs]
2026-03-20 00:21:42.123035 | controller | Output suppressed because no_log was given
2026-03-20 00:21:42.129112 | 
2026-03-20 00:21:42.129175 | LOOP [apache-logs-conf : Dereference files]
2026-03-20 00:21:42.161501 | 
2026-03-20 00:21:42.161668 | LOOP [apache-logs-conf : Create hard links]
2026-03-20 00:21:42.197620 | 
2026-03-20 00:21:42.197741 | TASK [apache-logs-conf : Find logs]
2026-03-20 00:21:42.231842 | 
2026-03-20 00:21:42.232000 | LOOP [apache-logs-conf : Dereference files]
2026-03-20 00:21:42.267678 | 
2026-03-20 00:21:42.267926 | LOOP [apache-logs-conf : Create hard links]
2026-03-20 00:21:42.304158 | 
2026-03-20 00:21:42.304291 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-03-20 00:21:42.542427 | controller | changed
2026-03-20 00:21:42.550298 | 
2026-03-20 00:21:42.550359 | TASK [apache-logs-conf : Define config paths]
2026-03-20 00:21:42.585537 | controller | ok
2026-03-20 00:21:42.594120 | 
2026-03-20 00:21:42.594177 | TASK [apache-logs-conf : Discover configurations]
2026-03-20 00:21:42.830900 | controller | Output suppressed because no_log was given
2026-03-20 00:21:42.836175 | 
2026-03-20 00:21:42.836236 | LOOP [apache-logs-conf : Dereference configurations]
2026-03-20 00:21:42.870369 | 
2026-03-20 00:21:42.870531 | LOOP [apache-logs-conf : Link configurations]
2026-03-20 00:21:42.912276 | 
2026-03-20 00:21:42.912409 | TASK [capture-performance-data : Generate statistics]
2026-03-20 00:21:45.352590 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-03-20 00:21:45.376338 | controller | Using python 3.12 to install setuptools
2026-03-20 00:21:45.403471 | controller | /usr/bin/python3.12: No module named pip
2026-03-20 00:21:45.412876 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-03-20 00:21:45.470039 | controller | ERROR
2026-03-20 00:21:45.470288 | controller | {
2026-03-20 00:21:45.470337 | controller |   "delta": "0:00:02.250662",
2026-03-20 00:21:45.470359 | controller |   "end": "2026-03-20 00:21:45.415095",
2026-03-20 00:21:45.470378 | controller |   "msg": "non-zero return code",
2026-03-20 00:21:45.470402 | controller |   "rc": 127,
2026-03-20 00:21:45.470421 | controller |   "start": "2026-03-20 00:21:43.164433"
2026-03-20 00:21:45.470438 | controller | }
2026-03-20 00:21:45.470463 | controller | ERROR: Ignoring Errors
2026-03-20 00:21:45.482607 | 
2026-03-20 00:21:45.482702 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-03-20 00:21:45.712129 | controller | changed
2026-03-20 00:21:45.720672 | 
2026-03-20 00:21:45.720742 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-03-20 00:21:49.343676 | controller | Output suppressed because no_log was given
2026-03-20 00:21:49.356407 | 
2026-03-20 00:21:49.356493 | LOOP [devstack-project-conf : Copy configuration files]
2026-03-20 00:21:49.388892 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.389402 | 
2026-03-20 00:21:49.391287 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.393478 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.396154 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.398845 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.401638 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.404404 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.407208 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.409919 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.412814 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.415507 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.418456 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.421288 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.424047 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.426434 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.429280 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.431964 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.435329 | controller | skipping: Conditional result was False
2026-03-20 00:21:49.470577 | 
2026-03-20 00:21:49.470696 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-03-20 00:21:49.696671 | controller | ok
2026-03-20 00:21:49.703300 | 
2026-03-20 00:21:49.703399 | TASK [devstack-project-conf : Copy configuration files]
2026-03-20 00:21:50.277172 | controller | skipping: Conditional result was False
2026-03-20 00:21:50.294044 | 
2026-03-20 00:21:50.294128 | TASK [capture-system-logs : Stage various logs and reports]
2026-03-20 00:21:50.611762 | controller | /usr/bin/python3: No module named pip
2026-03-20 00:21:51.753948 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-03-20 00:21:51.838074 | controller | ok: Runtime: 0:00:01.207842
2026-03-20 00:21:51.853411 | 
2026-03-20 00:21:51.853474 | LOOP [stage-output : Register sources]
2026-03-20 00:22:01.164860 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.176975 | 
2026-03-20 00:22:01.177078 | TASK [stage-output : Check sudo]
2026-03-20 00:22:01.752793 | controller | ok: Runtime: 0:00:00.020205
2026-03-20 00:22:01.760655 | 
2026-03-20 00:22:01.760727 | LOOP [stage-output : Set source and destination for files and folders]
2026-03-20 00:22:01.855096 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.855496 | 
2026-03-20 00:22:01.856810 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.859140 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.861583 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.897254 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.897736 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.899289 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.901903 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.904408 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.907057 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.909558 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.911991 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.931437 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.937652 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.938027 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.940540 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.964875 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.965260 | controller | Output suppressed because no_log was given
2026-03-20 00:22:01.967222 | controller | Output suppressed because no_log was given
2026-03-20 00:22:02.000627 | controller | Output suppressed because no_log was given
2026-03-20 00:22:02.001188 | controller | Output suppressed because no_log was given
2026-03-20 00:22:02.080776 | controller | Output suppressed because no_log was given
2026-03-20 00:22:02.097655 | controller | Output suppressed because no_log was given
2026-03-20 00:22:02.104674 | controller | Output suppressed because no_log was given
2026-03-20 00:22:02.115613 | controller | Output suppressed because no_log was given
2026-03-20 00:22:02.130954 | 
2026-03-20 00:22:02.131032 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-03-20 00:22:02.196675 | controller | ok
2026-03-20 00:22:02.205238 | 
2026-03-20 00:22:02.205304 | LOOP [stage-output : Ensure target folders exist]
2026-03-20 00:22:02.443831 | controller | changed: "docs"
2026-03-20 00:22:02.672092 | controller | changed: "artifacts"
2026-03-20 00:22:02.912565 | controller | ok: "logs"
2026-03-20 00:22:02.933607 | 
2026-03-20 00:22:02.933777 | LOOP [stage-output : Copy files and folders to staging folder]
2026-03-20 00:22:03.208436 | controller | ok: Item: Runtime: 0:00:00.009622
2026-03-20 00:22:03.208659 | controller | changed: All items complete
2026-03-20 00:22:03.208687 | 
2026-03-20 00:22:03.434801 | controller | ok: Item: Runtime: 0:00:00.007214
2026-03-20 00:22:03.660802 | controller | ok: Item: Runtime: 0:00:00.007959
2026-03-20 00:22:03.883563 | controller | ok: Item: Runtime: 0:00:00.007107
2026-03-20 00:22:04.108764 | controller | ok: Item: Runtime: 0:00:00.007325
2026-03-20 00:22:04.337743 | controller | ok: Item: Runtime: 0:00:00.008065
2026-03-20 00:22:04.558288 | controller | ok: Item: Runtime: 0:00:00.005664
2026-03-20 00:22:04.778654 | controller | ok: Item: Runtime: 0:00:00.007600
2026-03-20 00:22:04.993885 | controller | ok: Item: Runtime: 0:00:00.007443
2026-03-20 00:22:05.237533 | controller | ok: Item: Runtime: 0:00:00.005395
2026-03-20 00:22:05.518650 | controller | ok: Item: Runtime: 0:00:00.007386
2026-03-20 00:22:05.693084 | controller | ok: Item: Runtime: 0:00:00.008442
2026-03-20 00:22:05.915912 | controller | ok: Item: Runtime: 0:00:00.007928
2026-03-20 00:22:06.128937 | controller | ok: Item: Runtime: 0:00:00.005764
2026-03-20 00:22:06.341075 | controller | ok: Item: Runtime: 0:00:00.009305
2026-03-20 00:22:06.560767 | controller | ok: Item: Runtime: 0:00:00.006992
2026-03-20 00:22:06.784876 | controller | ok: Item: Runtime: 0:00:00.008047
2026-03-20 00:22:07.008782 | controller | ok: Item: Runtime: 0:00:00.007754
2026-03-20 00:22:07.219212 | controller | ok: Item: Runtime: 0:00:00.008041
2026-03-20 00:22:07.442694 | controller | ok: Item: Runtime: 0:00:00.007246
2026-03-20 00:22:07.465769 | 
2026-03-20 00:22:07.465842 | TASK [stage-output : Make all log files readable]
2026-03-20 00:22:07.702400 | controller | changed
2026-03-20 00:22:07.708182 | 
2026-03-20 00:22:07.708245 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-03-20 00:22:08.097888 | controller | changed: Renamed files for staging.
2026-03-20 00:22:08.104725 | 
2026-03-20 00:22:08.104786 | TASK [stage-output : Discover log files for compression]
2026-03-20 00:22:08.129690 | controller | skipping: Conditional result was False
2026-03-20 00:22:08.141444 | 
2026-03-20 00:22:08.141537 | LOOP [stage-output : Archive everything from logs]
2026-03-20 00:22:08.183602 | 
2026-03-20 00:22:08.183721 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-03-20 00:22:08.864317 | controller | changed:
2026-03-20 00:22:08.864489 | controller | created directory /var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/work/logs/controller
2026-03-20 00:22:08.864518 | controller | cd+++++++++ logs/
2026-03-20 00:22:08.864540 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-03-20 00:22:08.864560 | controller | >f+++++++++ logs/deprecations_log.txt
2026-03-20 00:22:08.864579 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-03-20 00:22:08.864597 | controller | >f+++++++++ logs/devstack.journal.gz
2026-03-20 00:22:08.864615 | controller | >f+++++++++ logs/devstacklog.txt
2026-03-20 00:22:08.864632 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-03-20 00:22:08.864650 | controller | >f+++++++++ logs/df.txt
2026-03-20 00:22:08.864668 | controller | >f+++++++++ logs/dpkg-l.txt
2026-03-20 00:22:08.864687 | controller | >f+++++++++ logs/iptables.txt
2026-03-20 00:22:08.864705 | controller | >f+++++++++ logs/listen53.txt
2026-03-20 00:22:08.864723 | controller | >f+++++++++ logs/local_conf.txt
2026-03-20 00:22:08.864740 | controller | >f+++++++++ logs/mount.txt
2026-03-20 00:22:08.864758 | controller | >f+++++++++ logs/performance.json
2026-03-20 00:22:08.864778 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-03-20 00:22:08.864797 | controller | >f+++++++++ logs/resolv_conf.txt
2026-03-20 00:22:08.864815 | controller | >f+++++++++ logs/services.txt
2026-03-20 00:22:08.864834 | controller | >f+++++++++ logs/sudoers
2026-03-20 00:22:08.864851 | controller | >f+++++++++ logs/syslog.txt
2026-03-20 00:22:08.864869 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-03-20 00:22:08.864886 | controller | cd+++++++++ logs/apache/
2026-03-20 00:22:08.864903 | controller | cd+++++++++ logs/apache_config/
2026-03-20 00:22:08.864921 | controller | cd+++++++++ logs/etc/
2026-03-20 00:22:08.864938 | controller | cd+++++++++ logs/sudoers.d/
2026-03-20 00:22:08.864974 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-03-20 00:22:08.864995 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-03-20 00:22:08.865015 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-03-20 00:22:08.865032 | controller | >f+++++++++ logs/sudoers.d/README
2026-03-20 00:22:08.865051 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-03-20 00:22:08.875087 | 
2026-03-20 00:22:08.875148 | TASK [Check if a tempest log exits]
2026-03-20 00:22:09.134629 | controller | ok
2026-03-20 00:22:09.141182 | 
2026-03-20 00:22:09.141242 | TASK [Link post-devstack tempest.log]
2026-03-20 00:22:09.165594 | controller | skipping: Conditional result was False
2026-03-20 00:22:09.176565 | 
2026-03-20 00:22:09.176630 | TASK [Capture most recent qemu crash dump, if any]
2026-03-20 00:22:09.442993 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-03-20 00:22:09.712281 | controller | ERROR
2026-03-20 00:22:09.712524 | controller | {
2026-03-20 00:22:09.712556 | controller |   "delta": "0:00:00.007717",
2026-03-20 00:22:09.712576 | controller |   "end": "2026-03-20 00:22:09.443610",
2026-03-20 00:22:09.712595 | controller |   "msg": "non-zero return code",
2026-03-20 00:22:09.712613 | controller |   "rc": 127,
2026-03-20 00:22:09.712631 | controller |   "start": "2026-03-20 00:22:09.435893"
2026-03-20 00:22:09.712648 | controller | }
2026-03-20 00:22:09.712671 | controller | ERROR: Ignoring Errors
2026-03-20 00:22:09.714506 | 
2026-03-20 00:22:09.714561 | PLAY RECAP
2026-03-20 00:22:09.714612 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-03-20 00:22:09.714634 | 
2026-03-20 00:22:09.891971 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-03-20 00:22:09.899166 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-20 00:22:10.474052 | 
2026-03-20 00:22:10.474181 | PLAY [all]
2026-03-20 00:22:10.486051 | 
2026-03-20 00:22:10.486125 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-20 00:22:10.530049 | controller | skipping: Conditional result was False
2026-03-20 00:22:10.535798 | 
2026-03-20 00:22:10.535878 | TASK [fetch-output : Set log path for single node]
2026-03-20 00:22:10.574762 | controller | ok
2026-03-20 00:22:10.579767 | 
2026-03-20 00:22:10.579832 | LOOP [fetch-output : Ensure local output dirs]
2026-03-20 00:22:10.983930 | controller -> localhost | ok: "/var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/work/logs"
2026-03-20 00:22:11.185349 | controller -> localhost | changed: "/var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/work/artifacts"
2026-03-20 00:22:11.579610 | controller -> localhost | changed: "/var/lib/zuul/builds/61b80808cc364a0194748b0aa1cc3c0d/work/docs"
2026-03-20 00:22:11.597148 | 
2026-03-20 00:22:11.597307 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-20 00:22:12.236757 | controller | changed: .d..t...... ./
2026-03-20 00:22:12.237045 | controller | changed: All items complete
2026-03-20 00:22:12.237083 | 
2026-03-20 00:22:12.708562 | controller | changed: .d..t...... ./
2026-03-20 00:22:13.172153 | controller | changed: .d..t...... ./
2026-03-20 00:22:13.196235 | 
2026-03-20 00:22:13.196376 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-20 00:22:13.828116 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006877
2026-03-20 00:22:14.064575 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007484
2026-03-20 00:22:14.087018 | 
2026-03-20 00:22:14.087157 | PLAY [all]
2026-03-20 00:22:14.095866 | 
2026-03-20 00:22:14.095951 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-20 00:22:14.568007 | controller | changed
2026-03-20 00:22:14.574377 | 
2026-03-20 00:22:14.574426 | PLAY RECAP
2026-03-20 00:22:14.574475 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-20 00:22:14.574499 | 
2026-03-20 00:22:14.676317 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-20 00:22:14.685511 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-20 00:22:15.287081 | 
2026-03-20 00:22:15.287240 | PLAY [localhost]
2026-03-20 00:22:15.297383 | 
2026-03-20 00:22:15.297458 | TASK [Generate Zuul manifest]
2026-03-20 00:22:15.320860 | localhost | ok
2026-03-20 00:22:15.338807 | 
2026-03-20 00:22:15.338908 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-20 00:22:15.735647 | localhost | changed
2026-03-20 00:22:15.748682 | 
2026-03-20 00:22:15.748778 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-20 00:22:15.782603 | localhost | ok
2026-03-20 00:22:15.790575 | 
2026-03-20 00:22:15.790638 | TASK [Upload logs]
2026-03-20 00:22:15.812210 | localhost | ok
2026-03-20 00:22:15.871267 | 
2026-03-20 00:22:15.871400 | TASK [Set zuul-log-path fact]
2026-03-20 00:22:15.894191 | localhost | ok
2026-03-20 00:22:15.909670 | 
2026-03-20 00:22:15.909739 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-20 00:22:16.015505 | localhost | ok
2026-03-20 00:22:16.021942 | 
2026-03-20 00:22:16.022009 | TASK [upload-logs : Create log directories]
2026-03-20 00:22:16.410130 | localhost | changed
2026-03-20 00:22:16.415708 | 
2026-03-20 00:22:16.415779 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-20 00:22:16.790957 | localhost -> localhost | ok: Runtime: 0:00:00.006637
2026-03-20 00:22:16.797814 | 
2026-03-20 00:22:16.797908 | TASK [upload-logs : Upload logs to log server]
2026-03-20 00:22:17.236518 | localhost | Output suppressed because no_log was given
2026-03-20 00:22:17.240244 | 
2026-03-20 00:22:17.240311 | LOOP [upload-logs : Compress console log and json output]
2026-03-20 00:22:17.289144 | localhost | skipping: Conditional result was False
2026-03-20 00:22:17.296732 | localhost | skipping: Conditional result was False
2026-03-20 00:22:17.311821 | 
2026-03-20 00:22:17.312042 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-20 00:22:17.351668 | localhost | skipping: Conditional result was False
2026-03-20 00:22:17.352033 | 
2026-03-20 00:22:17.355730 | localhost | skipping: Conditional result was False
2026-03-20 00:22:17.367047 | 
2026-03-20 00:22:17.367236 | LOOP [upload-logs : Upload console log and json output]