2026-05-20 01:17:28.971237 | Job console starting
2026-05-20 01:17:29.134930 | Updating git repos
2026-05-20 01:17:33.919217 | Cloning repos into workspace
2026-05-20 01:17:49.591538 | Restoring repo states
2026-05-20 01:17:50.534440 | Merging changes
2026-05-20 01:17:51.932149 | Checking out repos
2026-05-20 01:18:09.056086 | Preparing playbooks
2026-05-20 01:18:16.230757 | Running Ansible setup
2026-05-20 01:18:21.352594 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:18:22.150921 | 
2026-05-20 01:18:22.151052 | PLAY [localhost]
2026-05-20 01:18:22.158654 | 
2026-05-20 01:18:22.158730 | TASK [Gathering Facts]
2026-05-20 01:18:23.238154 | localhost | ok
2026-05-20 01:18:23.249565 | 
2026-05-20 01:18:23.249737 | TASK [Setup log path fact]
2026-05-20 01:18:23.279327 | localhost | ok
2026-05-20 01:18:23.297062 | 
2026-05-20 01:18:23.297172 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:18:23.337703 | localhost | ok
2026-05-20 01:18:23.346781 | 
2026-05-20 01:18:23.346894 | TASK [emit-job-header : Print job information]
2026-05-20 01:18:23.386177 | # Job Information
2026-05-20 01:18:23.386350 | Ansible Version: 2.16.18
2026-05-20 01:18:23.386387 | Job: magnum-cluster-api-hydrophone-v1.35.4-cilium
2026-05-20 01:18:23.386410 | Pipeline: check
2026-05-20 01:18:23.386430 | Executor: 2d72f0692154
2026-05-20 01:18:23.386451 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1012
2026-05-20 01:18:23.386475 | Event ID: 4b7fd430-53e9-11f1-89c4-9a5670eb8187
2026-05-20 01:18:23.393541 | 
2026-05-20 01:18:23.393721 | LOOP [emit-job-header : Print node information]
2026-05-20 01:18:23.529600 | localhost | ok:
2026-05-20 01:18:23.601048 | localhost | # Node Information
2026-05-20 01:18:23.601216 | localhost | Inventory Hostname: controller
2026-05-20 01:18:23.601278 | localhost | Hostname: np0000175167
2026-05-20 01:18:23.601325 | localhost | Username: zuul
2026-05-20 01:18:23.601384 | localhost | Distro: Ubuntu 24.04
2026-05-20 01:18:23.601431 | localhost | Provider: yul1
2026-05-20 01:18:23.601514 | localhost | Region: ca-ymq-1
2026-05-20 01:18:23.601560 | localhost | Label: ubuntu-noble-16
2026-05-20 01:18:23.601602 | localhost | Product Name: OpenStack Nova
2026-05-20 01:18:23.601642 | localhost | Interface IP: 162.253.55.186
2026-05-20 01:18:23.619955 | 
2026-05-20 01:18:23.620269 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 01:18:24.102915 | localhost -> localhost | changed
2026-05-20 01:18:24.111248 | 
2026-05-20 01:18:24.111383 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 01:18:25.234133 | localhost -> localhost | changed
2026-05-20 01:18:25.244662 | 
2026-05-20 01:18:25.244781 | PLAY [all]
2026-05-20 01:18:25.254054 | 
2026-05-20 01:18:25.254119 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 01:18:25.622661 | controller -> localhost | ok
2026-05-20 01:18:25.630363 | 
2026-05-20 01:18:25.630532 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 01:18:25.664645 | controller | ok
2026-05-20 01:18:25.679657 | controller | included: /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 01:18:25.686540 | 
2026-05-20 01:18:25.686604 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 01:18:26.881531 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 01:18:26.881691 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/work/f90d311f64ae4551a03729355695db42_id_rsa
2026-05-20 01:18:26.881722 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/work/f90d311f64ae4551a03729355695db42_id_rsa.pub
2026-05-20 01:18:26.881747 | controller -> localhost | The key fingerprint is:
2026-05-20 01:18:26.881770 | controller -> localhost | SHA256:2y6en8QL1C8Zdj8Jwfm3Gxq3Foyga8lgQOOXrrVvmPA zuul-build-sshkey
2026-05-20 01:18:26.881820 | controller -> localhost | The key's randomart image is:
2026-05-20 01:18:26.881847 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 01:18:26.881872 | controller -> localhost | |                 |
2026-05-20 01:18:26.881895 | controller -> localhost | |     o     . .   |
2026-05-20 01:18:26.881916 | controller -> localhost | |    o . .   +    |
2026-05-20 01:18:26.881938 | controller -> localhost | |     o o . . o   |
2026-05-20 01:18:26.881959 | controller -> localhost | |      + S = + + .|
2026-05-20 01:18:26.881982 | controller -> localhost | |     . * * = + =.|
2026-05-20 01:18:26.882003 | controller -> localhost | |      * O O ..++.|
2026-05-20 01:18:26.882025 | controller -> localhost | |     . E.@ +  +o+|
2026-05-20 01:18:26.882050 | controller -> localhost | |       .*+=  ..o |
2026-05-20 01:18:26.882071 | controller -> localhost | +----[SHA256]-----+
2026-05-20 01:18:26.882170 | controller -> localhost | ok: Runtime: 0:00:00.734613
2026-05-20 01:18:26.896333 | 
2026-05-20 01:18:26.896446 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 01:18:26.933592 | controller | ok
2026-05-20 01:18:26.946644 | controller | included: /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 01:18:26.959264 | 
2026-05-20 01:18:26.959467 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 01:18:26.985921 | controller | skipping: Conditional result was False
2026-05-20 01:18:26.998622 | 
2026-05-20 01:18:26.998759 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 01:18:27.615501 | controller | changed
2026-05-20 01:18:27.621881 | 
2026-05-20 01:18:27.621956 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 01:18:27.850507 | controller | ok
2026-05-20 01:18:27.940602 | 
2026-05-20 01:18:27.940781 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 01:18:28.573677 | controller | changed
2026-05-20 01:18:28.638882 | 
2026-05-20 01:18:28.639034 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 01:18:29.338855 | controller | changed
2026-05-20 01:18:29.344936 | 
2026-05-20 01:18:29.345048 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 01:18:29.380997 | controller | skipping: Conditional result was False
2026-05-20 01:18:29.392275 | 
2026-05-20 01:18:29.392378 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 01:18:29.782522 | controller -> localhost | changed
2026-05-20 01:18:29.808335 | 
2026-05-20 01:18:29.808489 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 01:18:30.100668 | controller -> localhost | Identity added: /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/work/f90d311f64ae4551a03729355695db42_id_rsa (zuul-build-sshkey)
2026-05-20 01:18:30.100923 | controller -> localhost | ok: Runtime: 0:00:00.012837
2026-05-20 01:18:30.109582 | 
2026-05-20 01:18:30.109649 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 01:18:30.984948 | controller | ok
2026-05-20 01:18:30.992852 | 
2026-05-20 01:18:30.993088 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 01:18:31.022102 | controller | skipping: Conditional result was False
2026-05-20 01:18:31.038384 | 
2026-05-20 01:18:31.038460 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 01:18:31.381025 | controller | ok
2026-05-20 01:18:31.394256 | 
2026-05-20 01:18:31.394395 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 01:19:42.597446 | controller | Output suppressed because no_log was given
2026-05-20 01:19:42.613104 | 
2026-05-20 01:19:42.613213 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 01:19:43.165715 | controller | ok: "logs"
2026-05-20 01:19:43.165951 | controller | ok: All items complete
2026-05-20 01:19:43.165982 | 
2026-05-20 01:19:43.341831 | controller | ok: "artifacts"
2026-05-20 01:19:43.543984 | controller | ok: "docs"
2026-05-20 01:19:43.562123 | 
2026-05-20 01:19:43.562237 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 01:19:43.831295 | controller | changed: "logs"
2026-05-20 01:19:44.032476 | controller | changed: "artifacts"
2026-05-20 01:19:44.256825 | controller | changed: "docs"
2026-05-20 01:19:44.278903 | 
2026-05-20 01:19:44.279055 | PLAY RECAP
2026-05-20 01:19:44.279114 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 01:19:44.279145 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:19:44.279167 | 
2026-05-20 01:19:44.781916 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:19:44.837073 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:19:45.960285 | 
2026-05-20 01:19:45.960453 | PLAY [all]
2026-05-20 01:19:46.006498 | 
2026-05-20 01:19:46.006632 | TASK [Fix the permissions of the zuul home directory]
2026-05-20 01:19:46.733187 | controller | changed
2026-05-20 01:19:46.846252 | 
2026-05-20 01:19:46.846367 | TASK [Gather minimum local MTU]
2026-05-20 01:19:46.922545 | controller | ok
2026-05-20 01:19:46.928702 | 
2026-05-20 01:19:46.928769 | TASK [Calculate external_bridge_mtu]
2026-05-20 01:19:46.978026 | controller | ok
2026-05-20 01:19:46.987463 | 
2026-05-20 01:19:46.987564 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-20 01:19:47.011907 | controller | skipping: Conditional result was False
2026-05-20 01:19:47.022399 | 
2026-05-20 01:19:47.022539 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-20 01:19:47.562940 | controller | ok: Runtime: 0:00:00.009552
2026-05-20 01:19:47.570809 | 
2026-05-20 01:19:47.570899 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-20 01:19:47.601267 | controller | skipping: Conditional result was False
2026-05-20 01:19:47.609363 | 
2026-05-20 01:19:47.609478 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-20 01:19:47.643974 | controller | skipping: Conditional result was False
2026-05-20 01:19:47.657295 | 
2026-05-20 01:19:47.657393 | TASK [configure-swap : Setup swap file on root device]
2026-05-20 01:19:47.732693 | controller | ok
2026-05-20 01:19:47.766572 | controller | included: /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-20 01:19:47.773539 | 
2026-05-20 01:19:47.773632 | TASK [configure-swap : Calculate required swap]
2026-05-20 01:19:47.886103 | controller | ok
2026-05-20 01:19:47.931403 | 
2026-05-20 01:19:47.931512 | TASK [configure-swap : Get root filesystem]
2026-05-20 01:19:48.211779 | controller | ext4
2026-05-20 01:19:48.675106 | controller | ok: Runtime: 0:00:00.012474
2026-05-20 01:19:48.684701 | 
2026-05-20 01:19:48.684830 | TASK [configure-swap : Save root filesystem]
2026-05-20 01:19:48.738961 | controller | ok
2026-05-20 01:19:48.745401 | 
2026-05-20 01:19:48.745508 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-20 01:19:48.827723 | controller | ok:
2026-05-20 01:19:48.827869 | controller | {
2026-05-20 01:19:48.827896 | controller |   "root_filesystem": "ext4"
2026-05-20 01:19:48.827917 | controller | }
2026-05-20 01:19:48.834159 | 
2026-05-20 01:19:48.834229 | TASK [configure-swap : Create swap backing file]
2026-05-20 01:19:58.615875 | controller | 8192+0 records in
2026-05-20 01:19:58.616032 | controller | 8192+0 records out
2026-05-20 01:19:58.616052 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.48555 s, 906 MB/s
2026-05-20 01:19:58.885565 | controller | ok: Runtime: 0:00:09.495547
2026-05-20 01:19:58.896438 | 
2026-05-20 01:19:58.896621 | TASK [configure-swap : Ensure swapfile perms]
2026-05-20 01:19:59.156319 | controller | changed
2026-05-20 01:19:59.162227 | 
2026-05-20 01:19:59.162309 | TASK [configure-swap : Make swapfile]
2026-05-20 01:20:10.059663 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-20 01:20:10.059890 | controller | no label, UUID=c6674280-d115-4ac3-8ddb-bf8e4f30141a
2026-05-20 01:20:10.421426 | controller | ok: Runtime: 0:00:10.643132
2026-05-20 01:20:10.427969 | 
2026-05-20 01:20:10.428042 | TASK [configure-swap : Write swap to fstab]
2026-05-20 01:20:10.853155 | controller | changed
2026-05-20 01:20:10.867366 | 
2026-05-20 01:20:10.867513 | TASK [configure-swap : Add all swap]
2026-05-20 01:20:11.416678 | controller | ok: Runtime: 0:00:00.018123
2026-05-20 01:20:11.423753 | 
2026-05-20 01:20:11.423830 | TASK [configure-swap : Debug the swap_required variable]
2026-05-20 01:20:11.478233 | controller | ok:
2026-05-20 01:20:11.478499 | controller | {
2026-05-20 01:20:11.478530 | controller |   "swap_required": "8192"
2026-05-20 01:20:11.478553 | controller | }
2026-05-20 01:20:11.487691 | 
2026-05-20 01:20:11.487805 | TASK [configure-swap : Set swappiness]
2026-05-20 01:20:11.945782 | controller | changed
2026-05-20 01:20:11.951930 | 
2026-05-20 01:20:11.951995 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-20 01:20:11.992412 | controller | ok:
2026-05-20 01:20:11.992574 | controller | {
2026-05-20 01:20:11.992600 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-20 01:20:11.992623 | controller | }
2026-05-20 01:20:12.014860 | 
2026-05-20 01:20:12.015014 | TASK [setup-stack-user : Create stack group]
2026-05-20 01:20:12.488382 | controller | changed
2026-05-20 01:20:12.495705 | 
2026-05-20 01:20:12.495789 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-20 01:20:12.748536 | controller | changed
2026-05-20 01:20:12.756845 | 
2026-05-20 01:20:12.757020 | TASK [setup-stack-user : Create stack user]
2026-05-20 01:20:13.427528 | controller | changed
2026-05-20 01:20:13.434291 | 
2026-05-20 01:20:13.434402 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-20 01:20:13.674324 | controller | changed
2026-05-20 01:20:13.683773 | 
2026-05-20 01:20:13.684027 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-20 01:20:14.860030 | controller | changed
2026-05-20 01:20:14.872513 | 
2026-05-20 01:20:14.872666 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-20 01:20:15.173853 | controller | changed
2026-05-20 01:20:15.236103 | 
2026-05-20 01:20:15.236204 | TASK [setup-tempest-user : Create tempest group]
2026-05-20 01:20:15.528691 | controller | changed
2026-05-20 01:20:15.534890 | 
2026-05-20 01:20:15.534968 | TASK [setup-tempest-user : Create tempest user]
2026-05-20 01:20:15.920318 | controller | changed
2026-05-20 01:20:15.929323 | 
2026-05-20 01:20:15.929422 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-20 01:20:16.741603 | controller | changed
2026-05-20 01:20:16.751940 | 
2026-05-20 01:20:16.752003 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-20 01:20:17.110629 | controller | ok: Not all paths examined, check warnings for details
2026-05-20 01:20:17.126506 | 
2026-05-20 01:20:17.126608 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-20 01:20:17.626679 | controller | ok: Item: Runtime: 0:00:00.203696
2026-05-20 01:20:17.986923 | controller | ok: Item: Runtime: 0:00:00.141524
2026-05-20 01:20:18.265572 | controller | ok: Item: Runtime: 0:00:00.062977
2026-05-20 01:20:18.799563 | controller | ok: Item: Runtime: 0:00:00.310144
2026-05-20 01:20:19.188520 | controller | ok: Item: Runtime: 0:00:00.164600
2026-05-20 01:20:19.737839 | controller | ok: Item: Runtime: 0:00:00.331542
2026-05-20 01:20:20.458682 | controller | ok: Item: Runtime: 0:00:00.506647
2026-05-20 01:20:21.279589 | controller | ok: Item: Runtime: 0:00:00.606739
2026-05-20 01:20:22.105225 | controller | ok: Item: Runtime: 0:00:00.587027
2026-05-20 01:20:23.530286 | controller | ok: Item: Runtime: 0:00:01.193343
2026-05-20 01:20:23.875427 | controller | ok: Item: Runtime: 0:00:00.105057
2026-05-20 01:20:24.319393 | controller | ok: Item: Runtime: 0:00:00.213933
2026-05-20 01:20:24.893566 | controller | ok: Item: Runtime: 0:00:00.346970
2026-05-20 01:20:28.240547 | controller | ok: Item: Runtime: 0:00:03.109931
2026-05-20 01:20:30.105057 | controller | ok: Item: Runtime: 0:00:01.168707
2026-05-20 01:20:30.632508 | controller | ok: Item: Runtime: 0:00:00.316365
2026-05-20 01:20:30.655690 | 
2026-05-20 01:20:30.655818 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-20 01:20:30.910567 | controller | ok: All paths examined
2026-05-20 01:20:30.941059 | 
2026-05-20 01:20:30.942650 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-20 01:20:31.286970 | controller | ok: All paths examined
2026-05-20 01:20:31.295238 | 
2026-05-20 01:20:31.295357 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-20 01:20:31.747122 | controller | ok: Item: Runtime: 0:00:00.148452
2026-05-20 01:20:31.747319 | controller | changed: All items complete
2026-05-20 01:20:31.747349 | 
2026-05-20 01:20:32.220432 | controller | ok: Item: Runtime: 0:00:00.227150
2026-05-20 01:20:32.232203 | 
2026-05-20 01:20:32.232356 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-20 01:20:32.358491 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.359545 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.360409 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.361686 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.362406 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.363086 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.366839 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.370448 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.375119 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.379540 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.383765 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.388097 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.391823 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.395744 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.400327 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.403857 | controller | skipping: Conditional result was False
2026-05-20 01:20:32.495175 | 
2026-05-20 01:20:35.998830 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-20 01:20:38.690639 | controller | changed
2026-05-20 01:20:39.546445 | 
2026-05-20 01:20:39.546558 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-20 01:20:39.834252 | controller | changed
2026-05-20 01:20:39.864863 | 
2026-05-20 01:20:39.870289 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-20 01:20:40.194259 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-20 01:20:40.474292 | controller | ERROR
2026-05-20 01:20:40.474494 | controller | {
2026-05-20 01:20:40.474573 | controller |   "delta": "0:00:00.010299",
2026-05-20 01:20:40.474605 | controller |   "end": "2026-05-20 01:20:40.194711",
2026-05-20 01:20:40.474632 | controller |   "msg": "non-zero return code",
2026-05-20 01:20:40.474658 | controller |   "rc": 1,
2026-05-20 01:20:40.474683 | controller |   "start": "2026-05-20 01:20:40.184412"
2026-05-20 01:20:40.474707 | controller | }
2026-05-20 01:20:40.474743 | controller | ERROR: Ignoring Errors
2026-05-20 01:20:40.483654 | 
2026-05-20 01:20:40.483718 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-20 01:20:40.726054 | controller | ok
2026-05-20 01:20:40.748503 | 
2026-05-20 01:20:40.748588 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-20 01:20:41.019330 | controller | /usr/bin/journalctl
2026-05-20 01:20:41.288629 | controller | ok: Runtime: 0:00:00.007590
2026-05-20 01:20:41.295620 | 
2026-05-20 01:20:41.295698 | TASK [start-fresh-logging : Get current date]
2026-05-20 01:20:41.563729 | controller | 2026-05-20 01:20:41
2026-05-20 01:20:41.839817 | controller | ok: Runtime: 0:00:00.008824
2026-05-20 01:20:41.850852 | 
2026-05-20 01:20:41.850996 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-20 01:20:42.463244 | controller | changed
2026-05-20 01:20:42.472292 | 
2026-05-20 01:20:42.472410 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-20 01:20:42.498441 | controller | skipping: Conditional result was False
2026-05-20 01:20:42.508988 | 
2026-05-20 01:20:42.509134 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-20 01:20:43.057406 | controller | skipping: Conditional result was False
2026-05-20 01:20:43.078989 | 
2026-05-20 01:20:43.079155 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-20 01:20:43.618153 | controller | skipping: Conditional result was False
2026-05-20 01:20:43.624476 | 
2026-05-20 01:20:43.624541 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-20 01:20:43.649569 | controller | skipping: Conditional result was False
2026-05-20 01:20:43.661200 | 
2026-05-20 01:20:43.661302 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-20 01:20:44.203588 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.214571 | 
2026-05-20 01:20:44.214638 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-20 01:20:44.787546 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.802875 | 
2026-05-20 01:20:44.802954 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-20 01:20:44.827395 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.838845 | 
2026-05-20 01:20:44.839515 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-20 01:20:44.874009 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.889677 | 
2026-05-20 01:20:44.889764 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-20 01:20:45.424337 | controller | skipping: Conditional result was False
2026-05-20 01:20:45.432171 | 
2026-05-20 01:20:45.432254 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-20 01:20:45.962946 | controller | skipping: Conditional result was False
2026-05-20 01:20:46.036414 | 
2026-05-20 01:20:46.036543 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-20 01:20:46.063525 | controller | skipping: Conditional result was False
2026-05-20 01:20:46.072555 | 
2026-05-20 01:20:46.072632 | TASK [start-fresh-logging : Start rsyslog]
2026-05-20 01:20:46.097661 | controller | skipping: Conditional result was False
2026-05-20 01:20:46.117581 | 
2026-05-20 01:20:46.117671 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-20 01:20:46.650582 | controller | ok
2026-05-20 01:20:46.672587 | 
2026-05-20 01:20:46.672762 | PLAY RECAP
2026-05-20 01:20:46.672824 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-20 01:20:46.672852 | 
2026-05-20 01:20:46.964500 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:20:46.976328 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:20:48.956819 | 
2026-05-20 01:20:48.957126 | PLAY [all]
2026-05-20 01:20:48.997778 | 
2026-05-20 01:20:48.998020 | TASK [Install curl]
2026-05-20 01:21:01.775090 | controller | ok
2026-05-20 01:21:01.781933 | 
2026-05-20 01:21:01.782020 | TASK [ensure-rust : Use rustup]
2026-05-20 01:21:01.825493 | controller | ok
2026-05-20 01:21:01.867049 | controller | included: /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-05-20 01:21:01.873612 | 
2026-05-20 01:21:01.873680 | TASK [ensure-rust : Install Rust]
2026-05-20 01:21:02.367684 | controller | info: downloading installer
2026-05-20 01:21:02.688346 | controller | warn: It looks like you have an existing rustup settings file at:
2026-05-20 01:21:02.688428 | controller | warn: /opt/rust/settings.toml
2026-05-20 01:21:02.688441 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-05-20 01:21:02.688460 | controller | warn: instead of the one inferred from the default host triple.
2026-05-20 01:21:02.719872 | controller | info: profile set to default
2026-05-20 01:21:02.719922 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-05-20 01:21:02.721618 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-05-20 01:21:02.962241 | controller | info: latest update on 2026-04-16 for version 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:21:02.962341 | controller | info: downloading 6 components
2026-05-20 01:21:16.232033 | controller | info: default toolchain set to stable-x86_64-unknown-linux-gnu
2026-05-20 01:21:16.232144 | controller |
2026-05-20 01:21:16.252730 | controller |   stable-x86_64-unknown-linux-gnu installed - rustc 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:21:16.252767 | controller |
2026-05-20 01:21:16.252781 | controller |
2026-05-20 01:21:16.252793 | controller | Rust is installed now. Great!
2026-05-20 01:21:16.252804 | controller |
2026-05-20 01:21:16.252817 | controller | To get started you need Cargo's bin directory (/opt/rust/bin) in your PATH
2026-05-20 01:21:16.252829 | controller | environment variable. This has not been done automatically.
2026-05-20 01:21:16.252842 | controller |
2026-05-20 01:21:16.252854 | controller | To configure your current shell, you need to source
2026-05-20 01:21:16.252875 | controller | the corresponding env file under /opt/rust.
2026-05-20 01:21:16.252889 | controller |
2026-05-20 01:21:16.252921 | controller | This is usually done by running one of the following (note the leading DOT):
2026-05-20 01:21:16.252934 | controller | . "/opt/rust/env"            # For sh/bash/zsh/ash/dash/pdksh
2026-05-20 01:21:16.252946 | controller | source "/opt/rust/env.fish"  # For fish
2026-05-20 01:21:16.252960 | controller | source "/opt/rust/env.nu"  # For nushell
2026-05-20 01:21:16.252972 | controller | source "/opt/rust/env.tcsh"  # For tcsh
2026-05-20 01:21:16.252984 | controller | . "/opt/rust/env.ps1"        # For pwsh
2026-05-20 01:21:16.252997 | controller | source "/opt/rust/env.xsh"   # For xonsh
2026-05-20 01:21:16.253009 | controller | warn: no default linker (`cc`) was found in your PATH
2026-05-20 01:21:16.253020 | controller | warn: many Rust crates require a system C toolchain to build
2026-05-20 01:21:16.422546 | controller | ok: Runtime: 0:00:14.030374
2026-05-20 01:21:16.429056 | 
2026-05-20 01:21:16.429127 | TASK [ensure-rust : Install wrapper helper script]
2026-05-20 01:21:17.393923 | controller | changed
2026-05-20 01:21:17.400862 | 
2026-05-20 01:21:17.401011 | TASK [ensure-rust : Run wrapper installation]
2026-05-20 01:21:17.678668 | controller | Install link for cargo
2026-05-20 01:21:17.683984 | controller | Install link for cargo-clippy
2026-05-20 01:21:17.689109 | controller | Install link for cargo-fmt
2026-05-20 01:21:17.694605 | controller | Install link for cargo-miri
2026-05-20 01:21:17.699729 | controller | Install link for clippy-driver
2026-05-20 01:21:17.705002 | controller | Install link for rls
2026-05-20 01:21:17.710199 | controller | Install link for rust-analyzer
2026-05-20 01:21:17.715808 | controller | Install link for rust-gdb
2026-05-20 01:21:17.720496 | controller | Install link for rust-gdbgui
2026-05-20 01:21:17.726198 | controller | Install link for rust-lldb
2026-05-20 01:21:17.731828 | controller | Install link for rustc
2026-05-20 01:21:17.736811 | controller | Install link for rustdoc
2026-05-20 01:21:17.741483 | controller | Install link for rustfmt
2026-05-20 01:21:17.747344 | controller | Install link for rustup
2026-05-20 01:21:17.963845 | controller | ok: Runtime: 0:00:00.092509
2026-05-20 01:21:17.983926 | 
2026-05-20 01:21:17.984080 | TASK [ensure-rust : Install packages]
2026-05-20 01:21:18.024415 | controller | skipping: Conditional result was False
2026-05-20 01:21:18.097214 | 
2026-05-20 01:21:18.097328 | PLAY RECAP
2026-05-20 01:21:18.097381 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:21:18.097412 | 
2026-05-20 01:21:18.279008 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:21:18.323550 | RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:21:19.113608 | 
2026-05-20 01:21:19.113835 | PLAY [all]
2026-05-20 01:21:19.161879 | 
2026-05-20 01:21:19.162060 | TASK [Install DevStack]
2026-05-20 01:21:19.189106 | controller | ok
2026-05-20 01:21:19.253334 | 
2026-05-20 01:21:19.253455 | TASK [Run devstack on the controller]
2026-05-20 01:21:19.297416 | controller | ok
2026-05-20 01:21:19.417473 | 
2026-05-20 01:21:19.417592 | TASK [run-devstack : Run devstack]
2026-05-20 01:21:20.029803 | controller | + unset GREP_OPTIONS
2026-05-20 01:21:20.029926 | controller | + unset LANG
2026-05-20 01:21:20.029936 | controller | + unset LANGUAGE
2026-05-20 01:21:20.029943 | controller | + LC_ALL=en_US.utf8
2026-05-20 01:21:20.037365 | controller | + export LC_ALL
2026-05-20 01:21:20.038899 | controller | ++ env
2026-05-20 01:21:20.039104 | controller | ++ grep -E '^OS_'
2026-05-20 01:21:20.039589 | controller | ++ cut -d = -f 1
2026-05-20 01:21:20.044724 | controller | + unset
2026-05-20 01:21:20.044745 | controller | + umask 022
2026-05-20 01:21:20.044766 | controller | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/usr/local/bin:/usr/local/sbin:/usr/sbin:/sbin
2026-05-20 01:21:20.046337 | controller | +++ dirname ./stack.sh
2026-05-20 01:21:20.049616 | controller | ++ cd .
2026-05-20 01:21:20.049634 | controller | ++ pwd
2026-05-20 01:21:20.050146 | controller | + TOP_DIR=/opt/stack/devstack
2026-05-20 01:21:20.050160 | controller | + NOUNSET=
2026-05-20 01:21:20.050216 | controller | + [[ -n '' ]]
2026-05-20 01:21:20.050994 | controller | ++ date +%s
2026-05-20 01:21:20.052994 | controller | + DEVSTACK_START_TIME=1779240080
2026-05-20 01:21:20.053012 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-05-20 01:21:20.053094 | controller | + FILES=/opt/stack/devstack/files
2026-05-20 01:21:20.053107 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-05-20 01:21:20.053184 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-05-20 01:21:20.053198 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-05-20 01:21:20.053256 | controller | + [[ '' == \y ]]
2026-05-20 01:21:20.053315 | controller | + [[ 1002 -eq 0 ]]
2026-05-20 01:21:20.053416 | controller | + [[ -n '' ]]
2026-05-20 01:21:20.053430 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-05-20 01:21:20.053483 | controller | + LAST_SPINNER_PID=
2026-05-20 01:21:20.053493 | controller | + source /opt/stack/devstack/functions
2026-05-20 01:21:20.053638 | controller | ++ [[ -z '' ]]
2026-05-20 01:21:20.053649 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-05-20 01:21:20.055595 | controller | ++++ dirname /opt/stack/devstack/functions
2026-05-20 01:21:20.057578 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:21:20.057601 | controller | +++ pwd
2026-05-20 01:21:20.058196 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-05-20 01:21:20.058247 | controller | ++ source /opt/stack/devstack/functions-common
2026-05-20 01:21:20.059977 | controller | ++++ set +o
2026-05-20 01:21:20.060391 | controller | ++++ grep xtrace
2026-05-20 01:21:20.063597 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-05-20 01:21:20.063623 | controller | +++ set +o xtrace
2026-05-20 01:21:20.077766 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-05-20 01:21:20.079567 | controller | ++++ set +o
2026-05-20 01:21:20.079855 | controller | ++++ grep xtrace
2026-05-20 01:21:20.083264 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-05-20 01:21:20.083320 | controller | +++ set +o xtrace
2026-05-20 01:21:20.084511 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-05-20 01:21:20.086280 | controller | ++++ set +o
2026-05-20 01:21:20.086583 | controller | ++++ grep xtrace
2026-05-20 01:21:20.089282 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-05-20 01:21:20.089332 | controller | +++ set +o xtrace
2026-05-20 01:21:20.089812 | controller | ++ source /opt/stack/devstack/inc/python
2026-05-20 01:21:20.091123 | controller | ++++ set +o
2026-05-20 01:21:20.091342 | controller | ++++ grep xtrace
2026-05-20 01:21:20.093602 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-05-20 01:21:20.093643 | controller | +++ set +o xtrace
2026-05-20 01:21:20.095405 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-05-20 01:21:20.097108 | controller | ++++ set +o
2026-05-20 01:21:20.097723 | controller | ++++ grep xtrace
2026-05-20 01:21:20.101363 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-05-20 01:21:20.101398 | controller | +++ set +o xtrace
2026-05-20 01:21:20.101727 | controller | ++ source /opt/stack/devstack/inc/async
2026-05-20 01:21:20.102501 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-05-20 01:21:20.102578 | controller | ++++ local xtrace
2026-05-20 01:21:20.104671 | controller | +++++ set +o
2026-05-20 01:21:20.104768 | controller | +++++ grep xtrace
2026-05-20 01:21:20.108155 | controller | ++++ xtrace='set -o xtrace'
2026-05-20 01:21:20.108190 | controller | ++++ set +o xtrace
2026-05-20 01:21:20.109115 | controller | +++ DEVSTACK_PARALLEL=True
2026-05-20 01:21:20.109152 | controller | +++ _ASYNC_BG_TIME=0
2026-05-20 01:21:20.112212 | controller | +++ set +o
2026-05-20 01:21:20.112685 | controller | +++ grep xtrace
2026-05-20 01:21:20.116795 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-05-20 01:21:20.116833 | controller | ++ set +o xtrace
2026-05-20 01:21:20.120759 | controller | + source /opt/stack/devstack/lib/stack
2026-05-20 01:21:20.120947 | controller | + GetDistro
2026-05-20 01:21:20.120990 | controller | + GetOSVersion
2026-05-20 01:21:20.121003 | controller | + source /etc/os-release
2026-05-20 01:21:20.121047 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-05-20 01:21:20.121057 | controller | ++ NAME=Ubuntu
2026-05-20 01:21:20.121066 | controller | ++ VERSION_ID=24.04
2026-05-20 01:21:20.121078 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-05-20 01:21:20.121086 | controller | ++ VERSION_CODENAME=noble
2026-05-20 01:21:20.121095 | controller | ++ ID=ubuntu
2026-05-20 01:21:20.121107 | controller | ++ ID_LIKE=debian
2026-05-20 01:21:20.121115 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-05-20 01:21:20.121124 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-05-20 01:21:20.121137 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-05-20 01:21:20.121146 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-05-20 01:21:20.121159 | controller | ++ UBUNTU_CODENAME=noble
2026-05-20 01:21:20.121167 | controller | ++ LOGO=ubuntu-logo
2026-05-20 01:21:20.121179 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-05-20 01:21:20.121259 | controller | + _ensure_lsb_release
2026-05-20 01:21:20.122386 | controller | ++ command -v lsb_release
2026-05-20 01:21:20.123105 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-05-20 01:21:20.123115 | controller | + return
2026-05-20 01:21:20.124086 | controller | ++ lsb_release -r -s
2026-05-20 01:21:20.144475 | controller | + os_RELEASE=24.04
2026-05-20 01:21:20.145498 | controller | ++ lsb_release -c -s
2026-05-20 01:21:20.163800 | controller | + os_CODENAME=noble
2026-05-20 01:21:20.165357 | controller | ++ lsb_release -i -s
2026-05-20 01:21:20.184938 | controller | + os_VENDOR=Ubuntu
2026-05-20 01:21:20.184976 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:21:20.185109 | controller | + os_PACKAGE=deb
2026-05-20 01:21:20.185124 | controller | + typeset -xr os_VENDOR
2026-05-20 01:21:20.185137 | controller | + typeset -xr os_RELEASE
2026-05-20 01:21:20.185148 | controller | + typeset -xr os_PACKAGE
2026-05-20 01:21:20.185185 | controller | + typeset -xr os_CODENAME
2026-05-20 01:21:20.185276 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-05-20 01:21:20.185342 | controller | + DISTRO=noble
2026-05-20 01:21:20.185363 | controller | + typeset -xr DISTRO
2026-05-20 01:21:20.185493 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:20.188104 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:20.188184 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-05-20 01:21:20.188195 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-05-20 01:21:20.188208 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-05-20 01:21:20.188216 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:20.189131 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-05-20 01:21:20.189237 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-05-20 01:21:20.189248 | controller | ++ local matchgroup=local
2026-05-20 01:21:20.189260 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:20.189323 | controller | ++ awk -v matchgroup=local '
2026-05-20 01:21:20.189338 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:21:20.189346 | controller |             gsub("[][]", "", $1);
2026-05-20 01:21:20.189355 | controller |             split($1, a, "|");
2026-05-20 01:21:20.189363 | controller |             if (a[1] == matchgroup)
2026-05-20 01:21:20.189371 | controller |                 print a[2]
2026-05-20 01:21:20.189380 | controller |         }
2026-05-20 01:21:20.189388 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:21:20.191019 | controller | + LRC=localrc
2026-05-20 01:21:20.191049 | controller | + for lfile in $LRC
2026-05-20 01:21:20.191058 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-05-20 01:21:20.191073 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-05-20 01:21:20.191081 | controller | + echo '# Generated file, do not edit'
2026-05-20 01:21:20.191257 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-05-20 01:21:20.191319 | controller | + local file=/opt/stack/devstack/local.conf
2026-05-20 01:21:20.191339 | controller | + local matchgroup=local
2026-05-20 01:21:20.191348 | controller | + local configfile=localrc
2026-05-20 01:21:20.191360 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:21:20.191368 | controller | + [[ -z localrc ]]
2026-05-20 01:21:20.191410 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-05-20 01:21:20.191420 | controller |         BEGIN { group = "" }
2026-05-20 01:21:20.191429 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:21:20.191461 | controller |             gsub("[][]", "", $1);
2026-05-20 01:21:20.191469 | controller |             split($1, a, "|");
2026-05-20 01:21:20.191478 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-05-20 01:21:20.191486 | controller |                 group=a[1]
2026-05-20 01:21:20.191494 | controller |             } else {
2026-05-20 01:21:20.191502 | controller |                 group=""
2026-05-20 01:21:20.191511 | controller |             }
2026-05-20 01:21:20.191519 | controller |             next
2026-05-20 01:21:20.191527 | controller |         }
2026-05-20 01:21:20.191535 | controller |         {
2026-05-20 01:21:20.191543 | controller |             if (group != "")
2026-05-20 01:21:20.191551 | controller |                 print $0
2026-05-20 01:21:20.191559 | controller |         }
2026-05-20 01:21:20.191567 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:21:20.194419 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-05-20 01:21:20.194449 | controller | + source /opt/stack/devstack/stackrc
2026-05-20 01:21:20.194496 | controller | ++ [[ -z '' ]]
2026-05-20 01:21:20.194506 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-05-20 01:21:20.196712 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-05-20 01:21:20.198974 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:21:20.198995 | controller | +++ pwd
2026-05-20 01:21:20.199549 | controller | ++ RC_DIR=/opt/stack/devstack
2026-05-20 01:21:20.199609 | controller | ++ source /opt/stack/devstack/functions
2026-05-20 01:21:20.199849 | controller | +++ [[ -z 1 ]]
2026-05-20 01:21:20.199876 | controller | +++ return 0
2026-05-20 01:21:20.199900 | controller | ++ TARGET_BRANCH=master
2026-05-20 01:21:20.199909 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-05-20 01:21:20.199923 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-05-20 01:21:20.199931 | controller | ++ DEST=/opt/stack
2026-05-20 01:21:20.199939 | controller | ++ DATA_DIR=/opt/stack/data
2026-05-20 01:21:20.199948 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-05-20 01:21:20.199956 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-05-20 01:21:20.199964 | controller | ++ [[ 1002 -eq 0 ]]
2026-05-20 01:21:20.200794 | controller | +++ whoami
2026-05-20 01:21:20.206082 | controller | ++ STACK_USER=stack
2026-05-20 01:21:20.206123 | controller | ++ REGION_NAME=RegionOne
2026-05-20 01:21:20.206137 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-05-20 01:21:20.206255 | controller | ++ isset ENABLED_SERVICES
2026-05-20 01:21:20.206272 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-05-20 01:21:20.206285 | controller | ++ ENABLED_SERVICES=key
2026-05-20 01:21:20.206339 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-05-20 01:21:20.206349 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-05-20 01:21:20.206358 | controller | ++ ENABLED_SERVICES+=,g-api
2026-05-20 01:21:20.206367 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-05-20 01:21:20.206382 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-05-20 01:21:20.206392 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-05-20 01:21:20.206401 | controller | ++ ENABLED_SERVICES+=,horizon
2026-05-20 01:21:20.206410 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-05-20 01:21:20.206419 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-05-20 01:21:20.206432 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-05-20 01:21:20.206441 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-05-20 01:21:20.206454 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-05-20 01:21:20.206463 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-05-20 01:21:20.206549 | controller | +++ disable_all_services
2026-05-20 01:21:20.206561 | controller | +++ ENABLED_SERVICES=
2026-05-20 01:21:20.206574 | controller | +++ enable_service c-api
2026-05-20 01:21:20.206633 | controller | +++ local xtrace
2026-05-20 01:21:20.208341 | controller | ++++ set +o
2026-05-20 01:21:20.208678 | controller | ++++ grep xtrace
2026-05-20 01:21:20.212289 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.212346 | controller | +++ set +o xtrace
2026-05-20 01:21:20.250165 | controller | +++ enable_service c-bak
2026-05-20 01:21:20.250215 | controller | +++ local xtrace
2026-05-20 01:21:20.251821 | controller | ++++ set +o
2026-05-20 01:21:20.252398 | controller | ++++ grep xtrace
2026-05-20 01:21:20.256112 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.256131 | controller | +++ set +o xtrace
2026-05-20 01:21:20.293935 | controller | +++ enable_service c-sch
2026-05-20 01:21:20.293983 | controller | +++ local xtrace
2026-05-20 01:21:20.296241 | controller | ++++ set +o
2026-05-20 01:21:20.296693 | controller | ++++ grep xtrace
2026-05-20 01:21:20.300417 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.300450 | controller | +++ set +o xtrace
2026-05-20 01:21:20.341172 | controller | +++ enable_service c-vol
2026-05-20 01:21:20.341244 | controller | +++ local xtrace
2026-05-20 01:21:20.343140 | controller | ++++ set +o
2026-05-20 01:21:20.343385 | controller | ++++ grep xtrace
2026-05-20 01:21:20.347012 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.347036 | controller | +++ set +o xtrace
2026-05-20 01:21:20.384505 | controller | +++ disable_service dstat
2026-05-20 01:21:20.384544 | controller | +++ local xtrace
2026-05-20 01:21:20.386024 | controller | ++++ set +o
2026-05-20 01:21:20.386102 | controller | ++++ grep xtrace
2026-05-20 01:21:20.388474 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.388504 | controller | +++ set +o xtrace
2026-05-20 01:21:20.418263 | controller | +++ enable_service etcd3
2026-05-20 01:21:20.418286 | controller | +++ local xtrace
2026-05-20 01:21:20.420121 | controller | ++++ set +o
2026-05-20 01:21:20.420573 | controller | ++++ grep xtrace
2026-05-20 01:21:20.424963 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.424986 | controller | +++ set +o xtrace
2026-05-20 01:21:20.467252 | controller | +++ enable_service file_tracker
2026-05-20 01:21:20.467323 | controller | +++ local xtrace
2026-05-20 01:21:20.469256 | controller | ++++ set +o
2026-05-20 01:21:20.469330 | controller | ++++ grep xtrace
2026-05-20 01:21:20.472095 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.472127 | controller | +++ set +o xtrace
2026-05-20 01:21:20.513076 | controller | +++ enable_service g-api
2026-05-20 01:21:20.513110 | controller | +++ local xtrace
2026-05-20 01:21:20.515587 | controller | ++++ set +o
2026-05-20 01:21:20.515677 | controller | ++++ grep xtrace
2026-05-20 01:21:20.519374 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.519414 | controller | +++ set +o xtrace
2026-05-20 01:21:20.559121 | controller | +++ disable_service horizon
2026-05-20 01:21:20.559164 | controller | +++ local xtrace
2026-05-20 01:21:20.560921 | controller | ++++ set +o
2026-05-20 01:21:20.561331 | controller | ++++ grep xtrace
2026-05-20 01:21:20.564780 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.564812 | controller | +++ set +o xtrace
2026-05-20 01:21:20.593902 | controller | +++ enable_service key
2026-05-20 01:21:20.593933 | controller | +++ local xtrace
2026-05-20 01:21:20.595811 | controller | ++++ set +o
2026-05-20 01:21:20.596029 | controller | ++++ grep xtrace
2026-05-20 01:21:20.598625 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.598656 | controller | +++ set +o xtrace
2026-05-20 01:21:20.640738 | controller | +++ enable_service memory_tracker
2026-05-20 01:21:20.640776 | controller | +++ local xtrace
2026-05-20 01:21:20.642101 | controller | ++++ set +o
2026-05-20 01:21:20.642394 | controller | ++++ grep xtrace
2026-05-20 01:21:20.645536 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.645562 | controller | +++ set +o xtrace
2026-05-20 01:21:20.688512 | controller | +++ enable_service mysql
2026-05-20 01:21:20.688567 | controller | +++ local xtrace
2026-05-20 01:21:20.690531 | controller | ++++ set +o
2026-05-20 01:21:20.690642 | controller | ++++ grep xtrace
2026-05-20 01:21:20.694386 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.694407 | controller | +++ set +o xtrace
2026-05-20 01:21:20.736149 | controller | +++ enable_service n-api
2026-05-20 01:21:20.736185 | controller | +++ local xtrace
2026-05-20 01:21:20.737815 | controller | ++++ set +o
2026-05-20 01:21:20.738389 | controller | ++++ grep xtrace
2026-05-20 01:21:20.742238 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.742262 | controller | +++ set +o xtrace
2026-05-20 01:21:20.789392 | controller | +++ enable_service n-api-meta
2026-05-20 01:21:20.789461 | controller | +++ local xtrace
2026-05-20 01:21:20.791033 | controller | ++++ set +o
2026-05-20 01:21:20.791070 | controller | ++++ grep xtrace
2026-05-20 01:21:20.793942 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.793969 | controller | +++ set +o xtrace
2026-05-20 01:21:20.839335 | controller | +++ enable_service n-cond
2026-05-20 01:21:20.839385 | controller | +++ local xtrace
2026-05-20 01:21:20.841367 | controller | ++++ set +o
2026-05-20 01:21:20.841498 | controller | ++++ grep xtrace
2026-05-20 01:21:20.845820 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.845858 | controller | +++ set +o xtrace
2026-05-20 01:21:20.888527 | controller | +++ enable_service n-cpu
2026-05-20 01:21:20.888560 | controller | +++ local xtrace
2026-05-20 01:21:20.891520 | controller | ++++ set +o
2026-05-20 01:21:20.891628 | controller | ++++ grep xtrace
2026-05-20 01:21:20.895367 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.895407 | controller | +++ set +o xtrace
2026-05-20 01:21:20.941421 | controller | +++ enable_service n-novnc
2026-05-20 01:21:20.941496 | controller | +++ local xtrace
2026-05-20 01:21:20.942806 | controller | ++++ set +o
2026-05-20 01:21:20.943116 | controller | ++++ grep xtrace
2026-05-20 01:21:20.945658 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:20.945687 | controller | +++ set +o xtrace
2026-05-20 01:21:20.994482 | controller | +++ enable_service n-sch
2026-05-20 01:21:20.994563 | controller | +++ local xtrace
2026-05-20 01:21:20.996575 | controller | ++++ set +o
2026-05-20 01:21:20.996907 | controller | ++++ grep xtrace
2026-05-20 01:21:21.000736 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.000761 | controller | +++ set +o xtrace
2026-05-20 01:21:21.047430 | controller | +++ enable_service o-api
2026-05-20 01:21:21.047457 | controller | +++ local xtrace
2026-05-20 01:21:21.049014 | controller | ++++ set +o
2026-05-20 01:21:21.049104 | controller | ++++ grep xtrace
2026-05-20 01:21:21.052626 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.052646 | controller | +++ set +o xtrace
2026-05-20 01:21:21.097791 | controller | +++ enable_service o-da
2026-05-20 01:21:21.097840 | controller | +++ local xtrace
2026-05-20 01:21:21.099367 | controller | ++++ set +o
2026-05-20 01:21:21.099986 | controller | ++++ grep xtrace
2026-05-20 01:21:21.103842 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.103869 | controller | +++ set +o xtrace
2026-05-20 01:21:21.146781 | controller | +++ enable_service o-hk
2026-05-20 01:21:21.146830 | controller | +++ local xtrace
2026-05-20 01:21:21.148453 | controller | ++++ set +o
2026-05-20 01:21:21.149125 | controller | ++++ grep xtrace
2026-05-20 01:21:21.153129 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.153162 | controller | +++ set +o xtrace
2026-05-20 01:21:21.198902 | controller | +++ enable_service octavia
2026-05-20 01:21:21.198935 | controller | +++ local xtrace
2026-05-20 01:21:21.200838 | controller | ++++ set +o
2026-05-20 01:21:21.201436 | controller | ++++ grep xtrace
2026-05-20 01:21:21.205631 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.205665 | controller | +++ set +o xtrace
2026-05-20 01:21:21.248643 | controller | +++ enable_service openstack-cli-server
2026-05-20 01:21:21.248700 | controller | +++ local xtrace
2026-05-20 01:21:21.250486 | controller | ++++ set +o
2026-05-20 01:21:21.251088 | controller | ++++ grep xtrace
2026-05-20 01:21:21.255454 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.255476 | controller | +++ set +o xtrace
2026-05-20 01:21:21.299105 | controller | +++ enable_service ovn-controller
2026-05-20 01:21:21.299142 | controller | +++ local xtrace
2026-05-20 01:21:21.301505 | controller | ++++ set +o
2026-05-20 01:21:21.301571 | controller | ++++ grep xtrace
2026-05-20 01:21:21.305339 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.305374 | controller | +++ set +o xtrace
2026-05-20 01:21:21.351158 | controller | +++ enable_service ovn-northd
2026-05-20 01:21:21.351243 | controller | +++ local xtrace
2026-05-20 01:21:21.352881 | controller | ++++ set +o
2026-05-20 01:21:21.353065 | controller | ++++ grep xtrace
2026-05-20 01:21:21.355849 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.355889 | controller | +++ set +o xtrace
2026-05-20 01:21:21.401720 | controller | +++ enable_service ovs-vswitchd
2026-05-20 01:21:21.401784 | controller | +++ local xtrace
2026-05-20 01:21:21.403267 | controller | ++++ set +o
2026-05-20 01:21:21.403973 | controller | ++++ grep xtrace
2026-05-20 01:21:21.407777 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.407798 | controller | +++ set +o xtrace
2026-05-20 01:21:21.451776 | controller | +++ enable_service ovsdb-server
2026-05-20 01:21:21.451817 | controller | +++ local xtrace
2026-05-20 01:21:21.454238 | controller | ++++ set +o
2026-05-20 01:21:21.454269 | controller | ++++ grep xtrace
2026-05-20 01:21:21.457813 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.457848 | controller | +++ set +o xtrace
2026-05-20 01:21:21.503440 | controller | +++ enable_service placement-api
2026-05-20 01:21:21.503473 | controller | +++ local xtrace
2026-05-20 01:21:21.505179 | controller | ++++ set +o
2026-05-20 01:21:21.505836 | controller | ++++ grep xtrace
2026-05-20 01:21:21.509412 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.509433 | controller | +++ set +o xtrace
2026-05-20 01:21:21.552459 | controller | +++ enable_service q-ovn-agent
2026-05-20 01:21:21.552502 | controller | +++ local xtrace
2026-05-20 01:21:21.554454 | controller | ++++ set +o
2026-05-20 01:21:21.554489 | controller | ++++ grep xtrace
2026-05-20 01:21:21.558279 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.558368 | controller | +++ set +o xtrace
2026-05-20 01:21:21.601446 | controller | +++ enable_service q-svc
2026-05-20 01:21:21.601490 | controller | +++ local xtrace
2026-05-20 01:21:21.603470 | controller | ++++ set +o
2026-05-20 01:21:21.603850 | controller | ++++ grep xtrace
2026-05-20 01:21:21.607095 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.607117 | controller | +++ set +o xtrace
2026-05-20 01:21:21.649529 | controller | +++ enable_service rabbit
2026-05-20 01:21:21.649565 | controller | +++ local xtrace
2026-05-20 01:21:21.651421 | controller | ++++ set +o
2026-05-20 01:21:21.651865 | controller | ++++ grep xtrace
2026-05-20 01:21:21.656165 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.656209 | controller | +++ set +o xtrace
2026-05-20 01:21:21.704163 | controller | +++ disable_service s-account
2026-05-20 01:21:21.704197 | controller | +++ local xtrace
2026-05-20 01:21:21.706452 | controller | ++++ set +o
2026-05-20 01:21:21.707096 | controller | ++++ grep xtrace
2026-05-20 01:21:21.710998 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.711044 | controller | +++ set +o xtrace
2026-05-20 01:21:21.738749 | controller | +++ disable_service s-container
2026-05-20 01:21:21.738784 | controller | +++ local xtrace
2026-05-20 01:21:21.740690 | controller | ++++ set +o
2026-05-20 01:21:21.741214 | controller | ++++ grep xtrace
2026-05-20 01:21:21.745190 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.745216 | controller | +++ set +o xtrace
2026-05-20 01:21:21.779502 | controller | +++ disable_service s-object
2026-05-20 01:21:21.779541 | controller | +++ local xtrace
2026-05-20 01:21:21.781552 | controller | ++++ set +o
2026-05-20 01:21:21.781645 | controller | ++++ grep xtrace
2026-05-20 01:21:21.784245 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.784279 | controller | +++ set +o xtrace
2026-05-20 01:21:21.814140 | controller | +++ disable_service s-proxy
2026-05-20 01:21:21.814175 | controller | +++ local xtrace
2026-05-20 01:21:21.816612 | controller | ++++ set +o
2026-05-20 01:21:21.817101 | controller | ++++ grep xtrace
2026-05-20 01:21:21.820865 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.820893 | controller | +++ set +o xtrace
2026-05-20 01:21:21.851170 | controller | +++ disable_service tempest
2026-05-20 01:21:21.851200 | controller | +++ local xtrace
2026-05-20 01:21:21.853023 | controller | ++++ set +o
2026-05-20 01:21:21.853542 | controller | ++++ grep xtrace
2026-05-20 01:21:21.857379 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.857414 | controller | +++ set +o xtrace
2026-05-20 01:21:21.884957 | controller | +++ enable_service tls-proxy
2026-05-20 01:21:21.885006 | controller | +++ local xtrace
2026-05-20 01:21:21.886829 | controller | ++++ set +o
2026-05-20 01:21:21.887258 | controller | ++++ grep xtrace
2026-05-20 01:21:21.891145 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.891174 | controller | +++ set +o xtrace
2026-05-20 01:21:21.933673 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-05-20 01:21:21.933717 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-05-20 01:21:21.933728 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-05-20 01:21:21.933736 | controller | +++ DISABLE_AMP_IMAGE_BUILD=True
2026-05-20 01:21:21.933745 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:21.933753 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:21.933767 | controller | +++ ENABLE_ZSWAP=True
2026-05-20 01:21:21.933776 | controller | +++ ERROR_ON_CLONE=True
2026-05-20 01:21:21.933785 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-05-20 01:21:21.933793 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:21:21.933801 | controller | +++ GIT_BASE=https://github.com
2026-05-20 01:21:21.933809 | controller | +++ HOST_IP=162.253.55.186
2026-05-20 01:21:21.933817 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:21:21.933825 | controller | +++ LIBVIRT_TYPE=qemu
2026-05-20 01:21:21.933834 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-05-20 01:21:21.933842 | controller | +++ LOG_COLOR=False
2026-05-20 01:21:21.933855 | controller | +++ MAGNUM_GUEST_IMAGE_URL=https://github.com/vexxhost/capo-image-elements/releases/latest/download/ubuntu-22.04-v1.35.4.qcow2
2026-05-20 01:21:21.933864 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS='snapshot_support=True create_share_from_snapshot_support=True'
2026-05-20 01:21:21.933874 | controller | +++ MANILA_ENABLED_BACKENDS=generic
2026-05-20 01:21:21.933885 | controller | +++ MANILA_USE_SERVICE_INSTANCE_PASSWORD=True
2026-05-20 01:21:21.933896 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-05-20 01:21:21.933916 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-05-20 01:21:21.933940 | controller | +++ NOVA_VNC_ENABLED=True
2026-05-20 01:21:21.933952 | controller | +++ OCTAVIA_NODE=api
2026-05-20 01:21:21.933973 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-05-20 01:21:21.933985 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-05-20 01:21:21.933997 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-05-20 01:21:21.934009 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-05-20 01:21:21.934021 | controller | +++ SERVICE_HOST=162.253.55.186
2026-05-20 01:21:21.934033 | controller | +++ SERVICE_PASSWORD=secretservice
2026-05-20 01:21:21.934044 | controller | +++ SWIFT_HASH=1234123412341234
2026-05-20 01:21:21.934054 | controller | +++ SWIFT_REPLICAS=1
2026-05-20 01:21:21.934063 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-05-20 01:21:21.934071 | controller | +++ VERBOSE=True
2026-05-20 01:21:21.934100 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:21.934114 | controller | +++ LIBS_FROM_GIT=novnc,barbican,cinder,devstack,glance,keystone,magnum,manila,neutron,nova,octavia,os-test-images,ovn-octavia-provider,placement,python-magnumclient,requirements,swift,magnum-cluster-api
2026-05-20 01:21:21.934123 | controller | +++ enable_plugin barbican https://github.com/openstack/barbican
2026-05-20 01:21:21.934131 | controller | +++ local name=barbican
2026-05-20 01:21:21.934139 | controller | +++ local url=https://github.com/openstack/barbican
2026-05-20 01:21:21.934148 | controller | +++ local branch=master
2026-05-20 01:21:21.934156 | controller | +++ is_plugin_enabled barbican
2026-05-20 01:21:21.934167 | controller | +++ local name=barbican
2026-05-20 01:21:21.934175 | controller | +++ [[ ,, =~ ,barbican, ]]
2026-05-20 01:21:21.934289 | controller | +++ return 1
2026-05-20 01:21:21.934342 | controller | +++ DEVSTACK_PLUGINS+=,barbican
2026-05-20 01:21:21.934356 | controller | +++ GITREPO[$name]=https://github.com/openstack/barbican
2026-05-20 01:21:21.934364 | controller | +++ GITDIR[$name]=/opt/stack/barbican
2026-05-20 01:21:21.934376 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:21.934415 | controller | +++ enable_plugin magnum https://review.opendev.org/openstack/magnum
2026-05-20 01:21:21.934425 | controller | +++ local name=magnum
2026-05-20 01:21:21.934436 | controller | +++ local url=https://review.opendev.org/openstack/magnum
2026-05-20 01:21:21.934448 | controller | +++ local branch=master
2026-05-20 01:21:21.934459 | controller | +++ is_plugin_enabled magnum
2026-05-20 01:21:21.934470 | controller | +++ local name=magnum
2026-05-20 01:21:21.934482 | controller | +++ [[ ,,barbican, =~ ,magnum, ]]
2026-05-20 01:21:21.934527 | controller | +++ return 1
2026-05-20 01:21:21.934537 | controller | +++ DEVSTACK_PLUGINS+=,magnum
2026-05-20 01:21:21.934548 | controller | +++ GITREPO[$name]=https://review.opendev.org/openstack/magnum
2026-05-20 01:21:21.934557 | controller | +++ GITDIR[$name]=/opt/stack/magnum
2026-05-20 01:21:21.934568 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:21.934613 | controller | +++ enable_plugin manila https://github.com/openstack/manila
2026-05-20 01:21:21.934626 | controller | +++ local name=manila
2026-05-20 01:21:21.934635 | controller | +++ local url=https://github.com/openstack/manila
2026-05-20 01:21:21.934643 | controller | +++ local branch=master
2026-05-20 01:21:21.934654 | controller | +++ is_plugin_enabled manila
2026-05-20 01:21:21.934663 | controller | +++ local name=manila
2026-05-20 01:21:21.934674 | controller | +++ [[ ,,barbican,magnum, =~ ,manila, ]]
2026-05-20 01:21:21.934710 | controller | +++ return 1
2026-05-20 01:21:21.934719 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-05-20 01:21:21.934730 | controller | +++ GITREPO[$name]=https://github.com/openstack/manila
2026-05-20 01:21:21.934788 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-05-20 01:21:21.934800 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:21.934809 | controller | +++ enable_plugin octavia https://github.com/openstack/octavia
2026-05-20 01:21:21.934817 | controller | +++ local name=octavia
2026-05-20 01:21:21.934828 | controller | +++ local url=https://github.com/openstack/octavia
2026-05-20 01:21:21.934837 | controller | +++ local branch=master
2026-05-20 01:21:21.934848 | controller | +++ is_plugin_enabled octavia
2026-05-20 01:21:21.934856 | controller | +++ local name=octavia
2026-05-20 01:21:21.934867 | controller | +++ [[ ,,barbican,magnum,manila, =~ ,octavia, ]]
2026-05-20 01:21:21.934905 | controller | +++ return 1
2026-05-20 01:21:21.934914 | controller | +++ DEVSTACK_PLUGINS+=,octavia
2026-05-20 01:21:21.934926 | controller | +++ GITREPO[$name]=https://github.com/openstack/octavia
2026-05-20 01:21:21.934936 | controller | +++ GITDIR[$name]=/opt/stack/octavia
2026-05-20 01:21:21.934948 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:21.934963 | controller | +++ enable_plugin magnum-cluster-api https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:21.935000 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:21:21.935008 | controller | +++ local url=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:21.935020 | controller | +++ local branch=master
2026-05-20 01:21:21.935028 | controller | +++ is_plugin_enabled magnum-cluster-api
2026-05-20 01:21:21.935039 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:21:21.935051 | controller | +++ [[ ,,barbican,magnum,manila,octavia, =~ ,magnum-cluster-api, ]]
2026-05-20 01:21:21.935142 | controller | +++ return 1
2026-05-20 01:21:21.935152 | controller | +++ DEVSTACK_PLUGINS+=,magnum-cluster-api
2026-05-20 01:21:21.935160 | controller | +++ GITREPO[$name]=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:21:21.935185 | controller | +++ GITDIR[$name]=/opt/stack/magnum-cluster-api
2026-05-20 01:21:21.935193 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:21.935204 | controller | +++ enable_plugin ovn-octavia-provider https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:21.935216 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:21:21.935227 | controller | +++ local url=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:21.935239 | controller | +++ local branch=master
2026-05-20 01:21:21.935250 | controller | +++ is_plugin_enabled ovn-octavia-provider
2026-05-20 01:21:21.935262 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:21:21.935274 | controller | +++ [[ ,,barbican,magnum,manila,octavia,magnum-cluster-api, =~ ,ovn-octavia-provider, ]]
2026-05-20 01:21:21.935361 | controller | +++ return 1
2026-05-20 01:21:21.935376 | controller | +++ DEVSTACK_PLUGINS+=,ovn-octavia-provider
2026-05-20 01:21:21.935388 | controller | +++ GITREPO[$name]=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:21:21.935397 | controller | +++ GITDIR[$name]=/opt/stack/ovn-octavia-provider
2026-05-20 01:21:21.935405 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:21:21.935505 | controller | ++ CELLSV2_SETUP=superconductor
2026-05-20 01:21:21.935515 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-05-20 01:21:21.936884 | controller | +++ trueorfalse False USER_UNITS
2026-05-20 01:21:21.937099 | controller | +++ local xtrace
2026-05-20 01:21:21.939435 | controller | ++++ set +o
2026-05-20 01:21:21.939733 | controller | ++++ grep xtrace
2026-05-20 01:21:21.944219 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.944261 | controller | +++ set +o xtrace
2026-05-20 01:21:21.945484 | controller | ++ USER_UNITS=False
2026-05-20 01:21:21.945529 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:21:21.945538 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-05-20 01:21:21.945550 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-05-20 01:21:21.945562 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-05-20 01:21:21.946827 | controller | +++ _get_python_version python3
2026-05-20 01:21:21.947013 | controller | +++ local interp=python3
2026-05-20 01:21:21.947028 | controller | +++ local version
2026-05-20 01:21:21.948235 | controller | ++++ which python3
2026-05-20 01:21:21.952208 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-05-20 01:21:21.953642 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-05-20 01:21:21.970022 | controller | +++ version=3.12
2026-05-20 01:21:21.970061 | controller | +++ echo 3.12
2026-05-20 01:21:21.971008 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-05-20 01:21:21.971034 | controller | ++ export PYTHON3_VERSION=3.12
2026-05-20 01:21:21.971047 | controller | ++ PYTHON3_VERSION=3.12
2026-05-20 01:21:21.971085 | controller | ++ export 'VIRTUALENV_CMD=python3.12 -m venv'
2026-05-20 01:21:21.971095 | controller | ++ VIRTUALENV_CMD='python3.12 -m venv'
2026-05-20 01:21:21.971107 | controller | ++ '[' -t 1 ']'
2026-05-20 01:21:21.971120 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-05-20 01:21:21.972235 | controller | +++ trueorfalse False LOG_COLOR
2026-05-20 01:21:21.972465 | controller | +++ local xtrace
2026-05-20 01:21:21.974363 | controller | ++++ set +o
2026-05-20 01:21:21.974948 | controller | ++++ grep xtrace
2026-05-20 01:21:21.978690 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:21:21.978710 | controller | +++ set +o xtrace
2026-05-20 01:21:21.979572 | controller | ++ LOG_COLOR=False
2026-05-20 01:21:21.979728 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:21:21.979748 | controller | ++ export 'PS4=+ $(short_source):   '
2026-05-20 01:21:21.979758 | controller | ++ PS4='+ $(short_source):   '
2026-05-20 01:21:21.985031 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-05-20 01:21:21.988925 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:21.994519 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:21.994633 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.001018 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.005626 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.010538 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-05-20 01:21:22.014444 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-05-20 01:21:22.019686 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-05-20 01:21:22.024850 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-05-20 01:21:22.028803 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:22.034664 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:22.035443 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.043320 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.047120 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.052368 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-05-20 01:21:22.057364 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-05-20 01:21:22.061206 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:22.066995 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:22.067909 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.076797 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.079652 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.084713 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-05-20 01:21:22.088609 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-05-20 01:21:22.094084 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-05-20 01:21:22.099323 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:22.105369 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:22.105637 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.114324 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.119166 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.123393 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-05-20 01:21:22.127489 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-05-20 01:21:22.132249 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:22.137843 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:22.138886 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.147282 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.151687 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.156269 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-05-20 01:21:22.161903 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-05-20 01:21:22.166426 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:22.171754 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:22.172808 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.180155 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.183902 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.189172 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-05-20 01:21:22.193779 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-05-20 01:21:22.198690 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-05-20 01:21:22.202225 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-05-20 01:21:22.206108 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://github.com
2026-05-20 01:21:22.210120 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-05-20 01:21:22.214153 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.2
2026-05-20 01:21:22.218796 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://github.com/openstack/cinder.git
2026-05-20 01:21:22.222915 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-05-20 01:21:22.226976 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://github.com/openstack/glance.git
2026-05-20 01:21:22.230129 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-05-20 01:21:22.234002 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://github.com/openstack/horizon.git
2026-05-20 01:21:22.238022 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-05-20 01:21:22.241894 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://github.com/openstack/keystone.git
2026-05-20 01:21:22.246419 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-05-20 01:21:22.250687 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://github.com/openstack/neutron.git
2026-05-20 01:21:22.254849 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-05-20 01:21:22.258286 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://github.com/openstack/nova.git
2026-05-20 01:21:22.262449 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-05-20 01:21:22.266950 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://github.com/openstack/swift.git
2026-05-20 01:21:22.270213 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-05-20 01:21:22.274235 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://github.com/openstack/placement.git
2026-05-20 01:21:22.277826 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-05-20 01:21:22.281325 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://github.com/openstack/requirements.git
2026-05-20 01:21:22.284996 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-05-20 01:21:22.288554 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://github.com/openstack/tempest.git
2026-05-20 01:21:22.292396 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-05-20 01:21:22.295789 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-05-20 01:21:22.299013 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://github.com/openstack/os-test-images.git
2026-05-20 01:21:22.302995 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-05-20 01:21:22.306785 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-05-20 01:21:22.310847 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://github.com/openstack/python-cinderclient.git
2026-05-20 01:21:22.313285 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-05-20 01:21:22.316498 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://github.com/openstack/python-brick-cinderclient-ext.git
2026-05-20 01:21:22.319891 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-05-20 01:21:22.324159 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://github.com/openstack/python-barbicanclient.git
2026-05-20 01:21:22.328063 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-05-20 01:21:22.332231 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-05-20 01:21:22.336560 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://github.com/openstack/python-glanceclient.git
2026-05-20 01:21:22.340794 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-05-20 01:21:22.344353 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://github.com/openstack/python-ironicclient.git
2026-05-20 01:21:22.348487 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-05-20 01:21:22.352377 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-05-20 01:21:22.356449 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://github.com/openstack/keystoneauth.git
2026-05-20 01:21:22.360101 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-05-20 01:21:22.364103 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://github.com/openstack/python-keystoneclient.git
2026-05-20 01:21:22.368504 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-05-20 01:21:22.372504 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://github.com/openstack/python-neutronclient.git
2026-05-20 01:21:22.375580 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-05-20 01:21:22.379458 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://github.com/openstack/python-novaclient.git
2026-05-20 01:21:22.382510 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-05-20 01:21:22.386592 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://github.com/openstack/python-swiftclient.git
2026-05-20 01:21:22.390167 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-05-20 01:21:22.394363 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://github.com/openstack/python-openstackclient.git
2026-05-20 01:21:22.398064 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-05-20 01:21:22.401963 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-05-20 01:21:22.405593 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://github.com/openstack/osc-placement.git
2026-05-20 01:21:22.409767 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-05-20 01:21:22.413969 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://github.com/openstack/castellan.git
2026-05-20 01:21:22.417885 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-05-20 01:21:22.421917 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://github.com/openstack/cliff.git
2026-05-20 01:21:22.425427 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-05-20 01:21:22.429151 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://github.com/openstack/futurist.git
2026-05-20 01:21:22.433066 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-05-20 01:21:22.436809 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://github.com/openstack/debtcollector.git
2026-05-20 01:21:22.440623 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-05-20 01:21:22.444987 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://github.com/openstack/etcd3gw.git
2026-05-20 01:21:22.448734 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-05-20 01:21:22.452234 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://github.com/openstack/automaton.git
2026-05-20 01:21:22.456065 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-05-20 01:21:22.459215 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://github.com/openstack/oslo.cache.git
2026-05-20 01:21:22.463176 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-05-20 01:21:22.466676 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://github.com/openstack/oslo.concurrency.git
2026-05-20 01:21:22.470380 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-05-20 01:21:22.474089 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://github.com/openstack/oslo.config.git
2026-05-20 01:21:22.477910 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-05-20 01:21:22.482058 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://github.com/openstack/oslo.context.git
2026-05-20 01:21:22.486024 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-05-20 01:21:22.489732 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://github.com/openstack/oslo.db.git
2026-05-20 01:21:22.494020 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-05-20 01:21:22.497652 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://github.com/openstack/oslo.i18n.git
2026-05-20 01:21:22.501128 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-05-20 01:21:22.504846 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://github.com/openstack/oslo.limit.git
2026-05-20 01:21:22.508953 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-05-20 01:21:22.512349 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://github.com/openstack/oslo.log.git
2026-05-20 01:21:22.516195 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-05-20 01:21:22.519831 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://github.com/openstack/oslo.messaging.git
2026-05-20 01:21:22.523760 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-05-20 01:21:22.527475 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://github.com/openstack/oslo.middleware.git
2026-05-20 01:21:22.531846 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-05-20 01:21:22.535780 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://github.com/openstack/oslo.policy.git
2026-05-20 01:21:22.539478 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-05-20 01:21:22.543663 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://github.com/openstack/oslo.privsep.git
2026-05-20 01:21:22.547644 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-05-20 01:21:22.551740 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://github.com/openstack/oslo.reports.git
2026-05-20 01:21:22.555264 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-05-20 01:21:22.559235 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://github.com/openstack/oslo.rootwrap.git
2026-05-20 01:21:22.562794 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-05-20 01:21:22.566096 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://github.com/openstack/oslo.serialization.git
2026-05-20 01:21:22.570041 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-05-20 01:21:22.574065 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://github.com/openstack/oslo.service.git
2026-05-20 01:21:22.577678 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-05-20 01:21:22.581441 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://github.com/openstack/oslo.utils.git
2026-05-20 01:21:22.583969 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-05-20 01:21:22.587263 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://github.com/openstack/oslo.versionedobjects.git
2026-05-20 01:21:22.590921 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-05-20 01:21:22.594799 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://github.com/openstack/oslo.vmware.git
2026-05-20 01:21:22.598852 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-05-20 01:21:22.602866 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://github.com/openstack/osprofiler.git
2026-05-20 01:21:22.607961 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-05-20 01:21:22.612910 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://github.com/openstack/pycadf.git
2026-05-20 01:21:22.617522 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-05-20 01:21:22.621085 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://github.com/openstack/stevedore.git
2026-05-20 01:21:22.624693 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-05-20 01:21:22.628673 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://github.com/openstack/taskflow.git
2026-05-20 01:21:22.632653 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-05-20 01:21:22.636027 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://github.com/openstack/tooz.git
2026-05-20 01:21:22.639437 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-05-20 01:21:22.642967 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://github.com/openstack/pbr.git
2026-05-20 01:21:22.646580 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-05-20 01:21:22.650607 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://github.com/openstack/cursive.git
2026-05-20 01:21:22.654320 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-05-20 01:21:22.658265 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://github.com/openstack/glance_store.git
2026-05-20 01:21:22.662691 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-05-20 01:21:22.666683 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://github.com/openstack/keystonemiddleware.git
2026-05-20 01:21:22.670783 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-05-20 01:21:22.674620 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://github.com/openstack/ceilometermiddleware.git
2026-05-20 01:21:22.677826 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-05-20 01:21:22.681379 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-05-20 01:21:22.685543 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://github.com/openstack/openstacksdk.git
2026-05-20 01:21:22.689770 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-05-20 01:21:22.693406 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://github.com/openstack/os-brick.git
2026-05-20 01:21:22.697226 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-05-20 01:21:22.701088 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://github.com/openstack/os-client-config.git
2026-05-20 01:21:22.704917 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-05-20 01:21:22.708979 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-05-20 01:21:22.712489 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://github.com/openstack/os-vif.git
2026-05-20 01:21:22.716635 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-05-20 01:21:22.720127 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://github.com/openstack/osc-lib.git
2026-05-20 01:21:22.724334 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-05-20 01:21:22.727626 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://github.com/openstack/ironic-lib.git
2026-05-20 01:21:22.730628 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-05-20 01:21:22.733934 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-05-20 01:21:22.737600 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://github.com/openstack/diskimage-builder.git
2026-05-20 01:21:22.741276 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-05-20 01:21:22.745253 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-05-20 01:21:22.749062 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://github.com/openstack/neutron-lib.git
2026-05-20 01:21:22.752630 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-05-20 01:21:22.756371 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-05-20 01:21:22.760774 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://github.com/openstack/os-resource-classes.git
2026-05-20 01:21:22.764751 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-05-20 01:21:22.768371 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://github.com/openstack/os-traits.git
2026-05-20 01:21:22.772370 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-05-20 01:21:22.776505 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://github.com/openstack/ovsdbapp.git
2026-05-20 01:21:22.779844 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-05-20 01:21:22.783791 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-05-20 01:21:22.787729 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://github.com/openstack/os-ken.git
2026-05-20 01:21:22.791647 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-05-20 01:21:22.795170 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-05-20 01:21:22.799138 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://github.com/openstack/ironic-python-agent.git
2026-05-20 01:21:22.803039 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-05-20 01:21:22.806984 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-05-20 01:21:22.810195 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-05-20 01:21:22.814142 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-05-20 01:21:22.817607 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-05-20 01:21:22.822049 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-05-20 01:21:22.825727 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:22.831830 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.831864 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:22.838283 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.840704 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.844052 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-05-20 01:21:22.846724 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-05-20 01:21:22.849581 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-05-20 01:21:22.853028 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-05-20 01:21:22.856229 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-05-20 01:21:22.859114 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-05-20 01:21:22.863060 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-05-20 01:21:22.867230 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-05-20 01:21:22.870542 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:21:22.874152 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-05-20 01:21:22.877997 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-05-20 01:21:22.882759 | controller | +++ stackrc:source:668                       :   uname -m
2026-05-20 01:21:22.888239 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-05-20 01:21:22.893398 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-05-20 01:21:22.897358 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:22.901902 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:22.902944 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:22.907907 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:22.911800 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:22.917153 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-05-20 01:21:22.921265 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:22.925010 | controller | ++ stackrc:source:675                       :   [[ -n '' ]]
2026-05-20 01:21:22.929042 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-05-20 01:21:22.932425 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-05-20 01:21:22.936173 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-05-20 01:21:22.939718 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-05-20 01:21:22.943119 | controller | ++ stackrc:source:688                       :   IMAGE_URLS+=https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:21:22.946574 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-05-20 01:21:22.950378 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-05-20 01:21:22.954541 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-05-20 01:21:22.958630 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:21:22.964612 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-05-20 01:21:22.968718 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-05-20 01:21:22.972280 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-05-20 01:21:22.976407 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-05-20 01:21:22.981836 | controller | +++ functions-common:is_arch:519             :   uname -m
2026-05-20 01:21:22.987563 | controller | ++ functions-common:is_arch:519             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-05-20 01:21:22.991434 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-05-20 01:21:22.994350 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:21:22.998057 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-05-20 01:21:23.001768 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-05-20 01:21:23.005647 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-05-20 01:21:23.009132 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-05-20 01:21:23.013634 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:21:23.017040 | controller | ++ stackrc:source:739                       :   ETCD_DOWNLOAD_LOCATION=https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:21:23.020977 | controller | ++ stackrc:source:741                       :   EXTRA_CACHE_URLS+=,https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:21:23.024901 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-05-20 01:21:23.028396 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-05-20 01:21:23.032751 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-05-20 01:21:23.038958 | controller | +++ stackrc:source:749                       :   echo https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:21:23.039542 | controller | +++ stackrc:source:749                       :   grep -o -F https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:21:23.039894 | controller | +++ stackrc:source:749                       :   wc -l
2026-05-20 01:21:23.076320 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-05-20 01:21:23.080060 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=30G
2026-05-20 01:21:23.083360 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-05-20 01:21:23.087127 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-05-20 01:21:23.090684 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-05-20 01:21:23.094424 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-05-20 01:21:23.099135 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-05-20 01:21:23.102713 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-05-20 01:21:23.106661 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-05-20 01:21:23.111108 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:21:23.118677 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:21:23.125027 | controller | ++ stackrc:source:777                       :   API_WORKERS=4
2026-05-20 01:21:23.128827 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-05-20 01:21:23.132775 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-05-20 01:21:23.136771 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-05-20 01:21:23.140528 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-05-20 01:21:23.145179 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-05-20 01:21:23.148682 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:23.154327 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:23.154849 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:23.161340 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:23.164951 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:23.169441 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-05-20 01:21:23.174463 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-05-20 01:21:23.178453 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:23.184176 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:23.184894 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:23.190447 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:23.194082 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:23.199345 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-05-20 01:21:23.204003 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-05-20 01:21:23.208029 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:23.213406 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:23.213660 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:23.220062 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:23.223026 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:23.227090 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-05-20 01:21:23.230919 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:21:23.234851 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:21:23.238035 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-05-20 01:21:23.242008 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-05-20 01:21:23.246166 | controller | ++ stackrc:source:815                       :   HOST_IP=162.253.55.186
2026-05-20 01:21:23.250551 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-05-20 01:21:23.255274 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 162.253.55.186 inet
2026-05-20 01:21:23.259450 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=10.1.0.0/20
2026-05-20 01:21:23.263031 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=172.24.5.0/24
2026-05-20 01:21:23.266605 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:21:23.269434 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=162.253.55.186
2026-05-20 01:21:23.272231 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet
2026-05-20 01:21:23.274734 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z 162.253.55.186 -o 162.253.55.186 == dhcp ']'
2026-05-20 01:21:23.277435 | controller | +++ functions-common:get_default_host_ip:785 :   echo 162.253.55.186
2026-05-20 01:21:23.281796 | controller | ++ stackrc:source:818                       :   HOST_IP=162.253.55.186
2026-05-20 01:21:23.284793 | controller | ++ stackrc:source:819                       :   '[' 162.253.55.186 == '' ']'
2026-05-20 01:21:23.289879 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-05-20 01:21:23.293482 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=
2026-05-20 01:21:23.296670 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=
2026-05-20 01:21:23.300327 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:21:23.303620 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=
2026-05-20 01:21:23.307561 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet6
2026-05-20 01:21:23.311050 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z '' -o '' == dhcp ']'
2026-05-20 01:21:23.314574 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip=
2026-05-20 01:21:23.321018 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 route list match default table all
2026-05-20 01:21:23.321150 | controller | ++++ functions-common:get_default_host_ip:764 :   grep via
2026-05-20 01:21:23.321168 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/default/ {print $5}'
2026-05-20 01:21:23.321408 | controller | ++++ functions-common:get_default_host_ip:764 :   head -1
2026-05-20 01:21:23.329036 | controller | +++ functions-common:get_default_host_ip:764 :   host_ip_iface=
2026-05-20 01:21:23.332667 | controller | +++ functions-common:get_default_host_ip:765 :   local host_ips
2026-05-20 01:21:23.338756 | controller | ++++ functions-common:get_default_host_ip:766 :   LC_ALL=C
2026-05-20 01:21:23.340206 | controller | ++++ functions-common:get_default_host_ip:766 :   sed /temporary/d
2026-05-20 01:21:23.340241 | controller | ++++ functions-common:get_default_host_ip:766 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-05-20 01:21:23.342750 | controller | ++++ functions-common:get_default_host_ip:766 :   ip -f inet6 addr show
2026-05-20 01:21:23.349837 | controller | +++ functions-common:get_default_host_ip:766 :   host_ips='::1
2026-05-20 01:21:23.349867 | controller | 2604:e100:1:0:f816:3eff:fec1:5366
2026-05-20 01:21:23.349877 | controller | fe80::f816:3eff:fec1:5366'
2026-05-20 01:21:23.352512 | controller | +++ functions-common:get_default_host_ip:767 :   local ip
2026-05-20 01:21:23.356263 | controller | +++ functions-common:get_default_host_ip:768 :   for ip in $host_ips
2026-05-20 01:21:23.359894 | controller | +++ functions-common:get_default_host_ip:775 :   [[ inet6 == \i\n\e\t\6 ]]
2026-05-20 01:21:23.363589 | controller | +++ functions-common:get_default_host_ip:776 :   host_ip=::1
2026-05-20 01:21:23.367434 | controller | +++ functions-common:get_default_host_ip:777 :   break
2026-05-20 01:21:23.371459 | controller | +++ functions-common:get_default_host_ip:785 :   echo ::1
2026-05-20 01:21:23.376057 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-05-20 01:21:23.380481 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-05-20 01:21:23.384984 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:23.390538 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:23.391120 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:23.397950 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:23.402019 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:23.407738 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-05-20 01:21:23.411119 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-05-20 01:21:23.414341 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-05-20 01:21:23.417870 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-05-20 01:21:23.420496 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=162.253.55.186
2026-05-20 01:21:23.424582 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:21:23.428520 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:21:23.432502 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-05-20 01:21:23.436535 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:21:23.441083 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=162.253.55.186
2026-05-20 01:21:23.444417 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:21:23.447387 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-05-20 01:21:23.450570 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-05-20 01:21:23.454137 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-05-20 01:21:23.458061 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=162.253.55.186
2026-05-20 01:21:23.462333 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-05-20 01:21:23.466831 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=162.253.55.186
2026-05-20 01:21:23.472269 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-05-20 01:21:23.476263 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:23.481389 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:23.482621 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:23.488618 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:23.491696 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:23.496823 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-05-20 01:21:23.501082 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=162.253.55.186
2026-05-20 01:21:23.504856 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-05-20 01:21:23.508642 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-05-20 01:21:23.513777 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-05-20 01:21:23.518447 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:23.524415 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:23.524732 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:23.531627 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:23.535315 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:23.540005 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-05-20 01:21:23.543656 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-05-20 01:21:23.547611 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-05-20 01:21:23.551593 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:23.555190 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-05-20 01:21:23.558620 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-05-20 01:21:23.563055 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-05-20 01:21:23.566553 | controller | ++ stackrc:source:924                       :   [[ /opt/stack/logs == \d\e\v\s\t\a\c\k\l\o\g\.\t\x\t ]]
2026-05-20 01:21:23.570680 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-05-20 01:21:23.574828 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-05-20 01:21:23.578949 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-05-20 01:21:23.583849 | controller | + functions:write_devstack_version:876     :   cat -
2026-05-20 01:21:23.584492 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-05-20 01:21:23.588716 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-05-20 01:21:23.626660 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-05-20 01:21:23.630805 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-05-20 01:21:23.634728 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-05-20 01:21:23.638388 | controller | + functions-common:export_proxy_variables:2232 :   isset http_proxy
2026-05-20 01:21:23.642251 | controller | + functions-common:isset:266               :   [[ -v http_proxy ]]
2026-05-20 01:21:23.645939 | controller | + functions-common:export_proxy_variables:2235 :   isset https_proxy
2026-05-20 01:21:23.649866 | controller | + functions-common:isset:266               :   [[ -v https_proxy ]]
2026-05-20 01:21:23.653804 | controller | + functions-common:export_proxy_variables:2238 :   isset no_proxy
2026-05-20 01:21:23.656685 | controller | + functions-common:isset:266               :   [[ -v no_proxy ]]
2026-05-20 01:21:23.660492 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-05-20 01:21:23.664001 | controller | + functions-common:disable_negated_services:1937 :   local xtrace
2026-05-20 01:21:23.670182 | controller | ++ functions-common:disable_negated_services:1938 :   set +o
2026-05-20 01:21:23.671933 | controller | ++ functions-common:disable_negated_services:1938 :   grep xtrace
2026-05-20 01:21:23.679321 | controller | + functions-common:disable_negated_services:1938 :   xtrace='set -o xtrace'
2026-05-20 01:21:23.682880 | controller | + functions-common:disable_negated_services:1939 :   set +o xtrace
2026-05-20 01:21:23.706227 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-05-20 01:21:23.710375 | controller | + functions-common:is_package_installed:1441 :   [[ -z sudo ]]
2026-05-20 01:21:23.714554 | controller | + functions-common:is_package_installed:1445 :   [[ -z deb ]]
2026-05-20 01:21:23.718196 | controller | + functions-common:is_package_installed:1449 :   [[ deb = \d\e\b ]]
2026-05-20 01:21:23.722033 | controller | + functions-common:is_package_installed:1450 :   dpkg -s sudo
2026-05-20 01:21:23.737310 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-05-20 01:21:23.758661 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-05-20 01:21:23.758713 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-05-20 01:21:23.771303 | controller | #includedir /etc/sudoers.d
2026-05-20 01:21:23.777447 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-05-20 01:21:23.782831 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-05-20 01:21:23.790137 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.YiZ4rbYGVu
2026-05-20 01:21:23.794024 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-05-20 01:21:23.798101 | controller | + ./stack.sh:main:277                      :   echo 'Defaults:stack secure_path=/sbin:/usr/sbin:/usr/bin:/bin:/usr/local/sbin:/usr/local/bin'
2026-05-20 01:21:23.802381 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-05-20 01:21:23.806610 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.YiZ4rbYGVu
2026-05-20 01:21:23.813674 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.YiZ4rbYGVu
2026-05-20 01:21:23.833214 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.YiZ4rbYGVu /etc/sudoers.d/50_stack_sh
2026-05-20 01:21:23.853069 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-05-20 01:21:23.856889 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:23.861073 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:23.866168 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-05-20 01:21:23.866285 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-05-20 01:21:23.882018 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-05-20 01:21:23.886281 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-05-20 01:21:23.889857 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-05-20 01:21:23.893309 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-05-20 01:21:23.912261 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-05-20 01:21:23.916855 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-05-20 01:21:23.920723 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:23.926424 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:23.926684 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:23.934283 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:23.938007 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:23.946342 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/logs
2026-05-20 01:21:23.968370 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-05-20 01:21:23.971626 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-05-20 01:21:23.976220 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:23.981421 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:23.982459 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:23.988920 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:23.992672 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:24.000746 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/logs
2026-05-20 01:21:24.017794 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-05-20 01:21:24.021945 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-05-20 01:21:24.025814 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-05-20 01:21:24.044414 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-05-20 01:21:24.048464 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-05-20 01:21:24.051720 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:24.057502 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:24.057927 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:24.065432 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:24.068862 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:24.076481 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/data
2026-05-20 01:21:24.094824 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-05-20 01:21:24.098867 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-05-20 01:21:24.102510 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:21:24.108632 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:21:24.109096 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:21:24.114693 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:21:24.118566 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:21:24.125520 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/data
2026-05-20 01:21:24.142484 | controller | + ./stack.sh:main:353                      :   async_init
2026-05-20 01:21:24.147219 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-05-20 01:21:24.150827 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-05-20 01:21:24.157233 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-05-20 01:21:24.165842 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-05-20 01:21:24.172313 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000175167
2026-05-20 01:21:24.176459 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000175167 /etc/hosts
2026-05-20 01:21:24.184828 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-05-20 01:21:24.188274 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:24.194527 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:24.194975 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:24.202938 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:24.206559 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:24.212048 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-05-20 01:21:24.216435 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-05-20 01:21:24.220456 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-05-20 01:21:24.224697 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-05-20 01:21:24.228800 | controller | + ./stack.sh:main:404                      :   install_python
2026-05-20 01:21:24.232881 | controller | + inc/python:install_python:482            :   install_python3
2026-05-20 01:21:24.237054 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-05-20 01:21:24.241185 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:24.245508 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:24.249316 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-05-20 01:21:24.253383 | controller | + functions-common:apt_get:1188            :   local xtrace result
2026-05-20 01:21:24.258435 | controller | ++ functions-common:apt_get:1189            :   set +o
2026-05-20 01:21:24.258816 | controller | ++ functions-common:apt_get:1189            :   grep xtrace
2026-05-20 01:21:24.266347 | controller | + functions-common:apt_get:1189            :   xtrace='set -o xtrace'
2026-05-20 01:21:24.270229 | controller | + functions-common:apt_get:1190            :   set +o xtrace
2026-05-20 01:21:24.283174 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install python3.12 python3.12-dev
2026-05-20 01:21:24.326343 | controller | Reading package lists...
2026-05-20 01:21:24.577265 | controller | Building dependency tree...
2026-05-20 01:21:24.577428 | controller | Reading state information...
2026-05-20 01:21:24.805466 | controller | python3.12 is already the newest version (3.12.3-1ubuntu0.13).
2026-05-20 01:21:24.805538 | controller | python3.12 set to manually installed.
2026-05-20 01:21:24.805549 | controller | The following additional packages will be installed:
2026-05-20 01:21:24.805565 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:21:24.806260 | controller |   libpython3.12t64 linux-libc-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:21:24.807374 | controller | Suggested packages:
2026-05-20 01:21:24.807408 | controller |   glibc-doc manpages-dev
2026-05-20 01:21:24.807419 | controller | Recommended packages:
2026-05-20 01:21:24.807428 | controller |   manpages manpages-dev libc-devtools
2026-05-20 01:21:24.850283 | controller | The following NEW packages will be installed:
2026-05-20 01:21:24.850504 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:21:24.851516 | controller |   libpython3.12t64 linux-libc-dev python3.12-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:21:26.225518 | controller | 0 upgraded, 10 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:21:26.225610 | controller | Need to get 13.4 MB of archives.
2026-05-20 01:21:26.225618 | controller | After this operation, 63.4 MB of additional disk space will be used.
2026-05-20 01:21:26.225627 | controller | Get:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libc-dev-bin amd64 2.39-0ubuntu8.7 [20.4 kB]
2026-05-20 01:21:26.417860 | controller | Get:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 linux-libc-dev amd64 6.8.0-117.117 [1,495 kB]
2026-05-20 01:21:26.587246 | controller | Get:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 libcrypt-dev amd64 1:4.4.36-4build1 [112 kB]
2026-05-20 01:21:26.589914 | controller | Get:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble/main amd64 rpcsvc-proto amd64 1.4.2-0ubuntu7 [67.4 kB]
2026-05-20 01:21:26.592742 | controller | Get:5 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libc6-dev amd64 2.39-0ubuntu8.7 [2,124 kB]
2026-05-20 01:21:26.703824 | controller | Get:6 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libexpat1-dev amd64 2.6.1-2ubuntu0.4 [140 kB]
2026-05-20 01:21:26.706178 | controller | Get:7 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12t64 amd64 3.12.3-1ubuntu0.13 [2,338 kB]
2026-05-20 01:21:26.853753 | controller | Get:8 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 zlib1g-dev amd64 1:1.3.dfsg-3.1ubuntu2.1 [894 kB]
2026-05-20 01:21:26.961007 | controller | Get:9 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 libpython3.12-dev amd64 3.12.3-1ubuntu0.13 [5,682 kB]
2026-05-20 01:21:27.381036 | controller | Get:10 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 python3.12-dev amd64 3.12.3-1ubuntu0.13 [498 kB]
2026-05-20 01:21:27.599884 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-05-20 01:21:27.645511 | controller | Fetched 13.4 MB in 3s (5,258 kB/s)
2026-05-20 01:21:27.695099 | controller | Selecting previously unselected package libc-dev-bin.
2026-05-20 01:21:27.822660 | controller | (Reading database ... 
(Reading database ... 5%
(Reading database ... 10%
(Reading database ... 15%
(Reading database ... 20%
(Reading database ... 25%
(Reading database ... 30%
(Reading database ... 35%
(Reading database ... 40%
(Reading database ... 45%
(Reading database ... 50%
(Reading database ... 55%
(Reading database ... 60%
(Reading database ... 65%
(Reading database ... 70%
(Reading database ... 75%
(Reading database ... 80%
(Reading database ... 85%
(Reading database ... 90%
(Reading database ... 95%
(Reading database ... 100%
(Reading database ... 31308 files and directories currently installed.)
2026-05-20 01:21:27.824155 | controller | Preparing to unpack .../0-libc-dev-bin_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:27.832048 | controller | Unpacking libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:27.890547 | controller | Selecting previously unselected package linux-libc-dev:amd64.
2026-05-20 01:21:27.897796 | controller | Preparing to unpack .../1-linux-libc-dev_6.8.0-117.117_amd64.deb ...
2026-05-20 01:21:27.902457 | controller | Unpacking linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:28.249588 | controller | Selecting previously unselected package libcrypt-dev:amd64.
2026-05-20 01:21:28.255540 | controller | Preparing to unpack .../2-libcrypt-dev_1%3a4.4.36-4build1_amd64.deb ...
2026-05-20 01:21:28.274550 | controller | Unpacking libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:28.432547 | controller | Selecting previously unselected package rpcsvc-proto.
2026-05-20 01:21:28.437482 | controller | Preparing to unpack .../3-rpcsvc-proto_1.4.2-0ubuntu7_amd64.deb ...
2026-05-20 01:21:28.468588 | controller | Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:28.685630 | controller | Selecting previously unselected package libc6-dev:amd64.
2026-05-20 01:21:28.691079 | controller | Preparing to unpack .../4-libc6-dev_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:28.725551 | controller | Unpacking libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:29.248531 | controller | Selecting previously unselected package libexpat1-dev:amd64.
2026-05-20 01:21:29.254078 | controller | Preparing to unpack .../5-libexpat1-dev_2.6.1-2ubuntu0.4_amd64.deb ...
2026-05-20 01:21:29.259627 | controller | Unpacking libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:29.333570 | controller | Selecting previously unselected package libpython3.12t64:amd64.
2026-05-20 01:21:29.339232 | controller | Preparing to unpack .../6-libpython3.12t64_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:29.350586 | controller | Unpacking libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:29.478592 | controller | Selecting previously unselected package zlib1g-dev:amd64.
2026-05-20 01:21:29.484477 | controller | Preparing to unpack .../7-zlib1g-dev_1%3a1.3.dfsg-3.1ubuntu2.1_amd64.deb ...
2026-05-20 01:21:29.487812 | controller | Unpacking zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:29.555515 | controller | Selecting previously unselected package libpython3.12-dev:amd64.
2026-05-20 01:21:29.561634 | controller | Preparing to unpack .../8-libpython3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:29.566694 | controller | Unpacking libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:29.846090 | controller | Selecting previously unselected package python3.12-dev.
2026-05-20 01:21:29.852343 | controller | Preparing to unpack .../9-python3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:29.857493 | controller | Unpacking python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:29.929623 | controller | Setting up linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:29.945650 | controller | Setting up libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:29.960842 | controller | Setting up rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:29.975789 | controller | Setting up libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:30.003480 | controller | Setting up libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:30.017879 | controller | Setting up libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:30.031692 | controller | Setting up libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:30.046132 | controller | Setting up zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:30.059003 | controller | Setting up libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:30.072950 | controller | Setting up python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:30.087755 | controller | Processing triggers for libc-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:30.372910 | controller | + functions-common:apt_get:1205            :   result=0
2026-05-20 01:21:30.377440 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:21:30.381246 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:21:30.385214 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:21:30.389169 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:21:30.392956 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:21:30.397750 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:21:30.401616 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:21:30.405435 | controller | + functions-common:time_stop:2421          :   start_time=1779240084278
2026-05-20 01:21:30.408979 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240084278 ]]
2026-05-20 01:21:30.414099 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:21:30.421501 | controller | + functions-common:time_stop:2426          :   end_time=1779240090416
2026-05-20 01:21:30.425340 | controller | + functions-common:time_stop:2427          :   elapsed_time=6138
2026-05-20 01:21:30.429127 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:21:30.432555 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:21:30.436266 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=6138
2026-05-20 01:21:30.439990 | controller | + functions-common:apt_get:1209            :   return 0
2026-05-20 01:21:30.445317 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-05-20 01:21:30.453209 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-05-20 01:21:30.457535 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-05-20 01:21:30.462927 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-05-20 01:21:30.467239 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:30.473718 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:30.473938 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:30.481682 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:30.485936 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:30.491143 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-05-20 01:21:30.496127 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-05-20 01:21:30.500010 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:30.505924 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:30.507107 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:30.515110 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:30.519649 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:30.524762 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:30.528831 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-05-20 01:21:30.533215 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-05-20 01:21:30.538431 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-05-20 01:21:30.544879 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-05-20-012130
2026-05-20 01:21:30.549491 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:30.553282 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-05-20 01:21:30.556925 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-05-20 01:21:30.561345 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-05-20 01:21:30.568456 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-05-20 01:21:30.578168 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012130
2026-05-20 01:21:30.582343 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012130.summary.2026-05-20-012130
2026-05-20 01:21:30.586607 | controller | + ./stack.sh:main:476                      :   exec
2026-05-20 01:21:30.590543 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:30.594197 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-05-20 01:21:30.597846 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:30.601658 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-05-20 01:21:30.605637 | controller | + ./stack.sh:main:483                      :   exec
2026-05-20 01:21:30.610666 | controller | ++ ./stack.sh:main:483                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -v --no-timestamp -o /opt/stack/logs/devstacklog.txt.2026-05-20-012130
2026-05-20 01:21:30.639434 | controller | + ./stack.sh:main:485                      :   exec
2026-05-20 01:21:30.639475 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012130'
2026-05-20 01:21:30.639485 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-05-20-012130.summary.2026-05-20-012130
2026-05-20 01:21:30.639495 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-05-20 01:21:30.639507 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012130
2026-05-20 01:21:30.639516 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012130 /opt/stack/logs/devstacklog.txt
2026-05-20 01:21:30.639524 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012130.summary.2026-05-20-012130 /opt/stack/logs/devstacklog.txt.summary
2026-05-20 01:21:30.639733 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-05-20 01:21:30.643160 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-05-20 01:21:30.647850 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-05-20 01:21:30.653755 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-05-20 01:21:30.657435 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-05-20 01:21:30.663772 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-05-20 01:21:30.664185 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-05-20 01:21:30.671417 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:30.675800 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-05-20 01:21:30.680987 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-05-20 01:21:30.687821 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:30.692676 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:30.696895 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-05-20 01:21:30.702793 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-05-20 01:21:30.709739 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:30.713566 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-05-20 01:21:30.718215 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-05-20 01:21:30.722846 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-05-20 01:21:30.726914 | controller | + ./stack.sh:main:574                      :   uname -a
2026-05-20 01:21:30.729111 | controller | Linux np0000175167 6.8.0-111-generic #111-Ubuntu SMP PREEMPT_DYNAMIC Sat Apr 11 23:16:02 UTC 2026 x86_64 x86_64 x86_64 GNU/Linux
2026-05-20 01:21:30.733638 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-05-20 01:21:30.737771 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-05-20 01:21:30.744605 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-05-20 01:21:30.816006 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-05-20 01:21:30.846404 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-05-20 01:21:30.851700 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-05-20 01:21:30.880024 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-05-20 01:21:30.885224 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-05-20 01:21:30.911888 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-05-20 01:21:30.917109 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-05-20 01:21:30.944541 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-05-20 01:21:30.948407 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-05-20 01:21:30.952135 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-05-20 01:21:30.957348 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-05-20 01:21:30.984254 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:30.990633 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-05-20 01:21:31.017827 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:31.022584 | controller | + ./stack.sh:main:588                      :   tune_host
2026-05-20 01:21:31.026733 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-05-20 01:21:31.031370 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-05-20 01:21:31.035260 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:31.039446 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-05-20 01:21:31.043044 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:31.046490 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:31.051204 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-05-20 01:21:31.053805 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-05-20 01:21:31.063968 | controller | lz4
2026-05-20 01:21:31.071555 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-05-20 01:21:31.071581 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-05-20 01:21:31.083338 | controller | zsmalloc
2026-05-20 01:21:31.090240 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-05-20 01:21:31.090917 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-05-20 01:21:31.105865 | controller | 1
2026-05-20 01:21:31.212367 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-05-20 01:21:31.223079 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-05-20 01:21:31.223105 | controller | /sys/module/zswap/parameters/enabled:Y
2026-05-20 01:21:31.223112 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-05-20 01:21:31.223117 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-05-20 01:21:31.223125 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-05-20 01:21:31.223132 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-05-20 01:21:31.223157 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-05-20 01:21:31.223164 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-05-20 01:21:31.223186 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-05-20 01:21:31.229850 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-05-20 01:21:31.233891 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:31.237876 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-05-20 01:21:31.241503 | controller | + functions-common:install_package:1432    :   update_package_repo
2026-05-20 01:21:31.244858 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:21:31.249032 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=False
2026-05-20 01:21:31.252465 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=False
2026-05-20 01:21:31.256594 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:31.260582 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:21:31.264158 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:31.268190 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:31.271420 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:21:31.274686 | controller | + functions-common:apt_get_update:1160     :   [[ False == \T\r\u\e ]]
2026-05-20 01:21:31.277822 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:31.281436 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:21:31.285954 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:21:31.293926 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:21:31.298181 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:21:31.301384 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:21:31.304979 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:21:31.308343 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:21:31.312609 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:21:31.318460 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240091314
2026-05-20 01:21:31.322501 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:21:31.326751 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:21:31.331249 | controller | + functions-common:apt_get_update:1175     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-05-20 01:21:41.418848 | controller | Ign:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:21:41.419497 | controller | Ign:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:21:51.423194 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:21:56.434554 | controller | Ign:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:01.437761 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:22:10.948480 | controller | Ign:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:11.440635 | controller | Ign:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:26.456079 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:22:27.362784 | controller | Err:1 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:27.362832 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:36.469687 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:22:46.481756 | controller | Ign:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:22:56.493957 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:06.507463 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:16.529805 | controller | Err:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:16.529867 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:26.546634 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:26.546687 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:36.563466 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:36.563583 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:37.564490 | controller | Reading package lists...
2026-05-20 01:23:37.590868 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:37.590895 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-updates/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:37.590908 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-backports/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:37.590919 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:23:37.590932 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:23:37.601068 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:23:37.605663 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:23:37.610491 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:23:37.615526 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:23:37.619600 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:23:37.623749 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:23:37.627876 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:23:37.632180 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:23:37.636564 | controller | + functions-common:time_stop:2421          :   start_time=1779240091314
2026-05-20 01:23:37.641831 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240091314 ]]
2026-05-20 01:23:37.646651 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:23:37.653359 | controller | + functions-common:time_stop:2426          :   end_time=1779240217648
2026-05-20 01:23:37.657664 | controller | + functions-common:time_stop:2427          :   elapsed_time=126334
2026-05-20 01:23:37.662046 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:23:37.666502 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:23:37.669794 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=126334
2026-05-20 01:23:37.673943 | controller | + functions-common:install_package:1433    :   real_install_package ksmtuned
2026-05-20 01:23:37.677621 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:23:37.681473 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:23:37.685490 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:23:37.689596 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:23:37.727690 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-05-20 01:23:37.771551 | controller | Reading package lists...
2026-05-20 01:23:38.021073 | controller | Building dependency tree...
2026-05-20 01:23:38.021359 | controller | Reading state information...
2026-05-20 01:23:38.275551 | controller | Recommended packages:
2026-05-20 01:23:38.276534 | controller |   qemu-kvm
2026-05-20 01:23:38.307689 | controller | The following NEW packages will be installed:
2026-05-20 01:23:38.309243 | controller |   ksmtuned
2026-05-20 01:23:53.354368 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:23:53.354437 | controller | Need to get 7,444 B of archives.
2026-05-20 01:23:53.354512 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:23:53.354540 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:04.364274 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:16.381487 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:35.412549 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:24:35.413644 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:35.423756 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:24:35.423788 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:24:35.432359 | controller | + functions-common:apt_get:1205            :   result=100
2026-05-20 01:24:35.436561 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:24:35.441194 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:24:35.445507 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:24:35.450367 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:24:35.454319 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:24:35.459590 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:24:35.463580 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:24:35.467106 | controller | + functions-common:time_stop:2421          :   start_time=1779240217721
2026-05-20 01:24:35.470587 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240217721 ]]
2026-05-20 01:24:35.475657 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:24:35.482609 | controller | + functions-common:time_stop:2426          :   end_time=1779240275477
2026-05-20 01:24:35.486513 | controller | + functions-common:time_stop:2427          :   elapsed_time=57756
2026-05-20 01:24:35.490663 | controller | + functions-common:time_stop:2428          :   total=6138
2026-05-20 01:24:35.494392 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:24:35.498529 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=63894
2026-05-20 01:24:35.502514 | controller | + functions-common:apt_get:1209            :   return 100
2026-05-20 01:24:35.507636 | controller | + functions-common:install_package:1434    :   RETRY_UPDATE=True
2026-05-20 01:24:35.511899 | controller | + functions-common:install_package:1434    :   update_package_repo
2026-05-20 01:24:35.515494 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:24:35.519485 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=True
2026-05-20 01:24:35.523898 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=True
2026-05-20 01:24:35.528062 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:24:35.532435 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:24:35.535492 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:24:35.539617 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:24:35.543733 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:24:35.548451 | controller | + functions-common:apt_get_update:1160     :   [[ True == \T\r\u\e ]]
2026-05-20 01:24:35.551513 | controller | + functions-common:apt_get_update:1160     :   [[ True != \T\r\u\e ]]
2026-05-20 01:24:35.554889 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:24:35.558539 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:24:35.563937 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:24:35.571258 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:24:35.574563 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:24:35.579051 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:24:35.583054 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:24:35.587107 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:24:35.591557 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:24:35.598447 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240275593
2026-05-20 01:24:35.602655 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:24:35.606590 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:24:35.610544 | controller | + functions-common:apt_get_update:1175     :   timeout 300 sh -c 'while ! sudo http_proxy= https_proxy= no_proxy=  apt-get update; do sleep 30; done'
2026-05-20 01:24:44.663286 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:24:45.683281 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:24:54.677117 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:24:56.699211 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:08.713054 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:09.693217 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:19.706632 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:25:25.079204 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:25:25.079249 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:25:29.715053 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:25:39.728950 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:49.740711 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:04.764344 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:14.773487 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:29.793617 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:26:29.793703 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:43.546034 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:26:43.546115 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:53.560412 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:26:53.560509 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:54.538986 | controller | Reading package lists...
2026-05-20 01:26:54.567613 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:54.568759 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-updates/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:54.568783 | controller | W: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/dists/noble-backports/InRelease  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:54.568792 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:26:54.568801 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:26:54.577715 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:26:54.581899 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:26:54.585237 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:26:54.589419 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:26:54.593405 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:26:54.596528 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:26:54.600425 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:26:54.603489 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:26:54.607222 | controller | + functions-common:time_stop:2421          :   start_time=1779240275593
2026-05-20 01:26:54.611346 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240275593 ]]
2026-05-20 01:26:54.615961 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:26:54.622049 | controller | + functions-common:time_stop:2426          :   end_time=1779240414617
2026-05-20 01:26:54.625602 | controller | + functions-common:time_stop:2427          :   elapsed_time=139024
2026-05-20 01:26:54.629241 | controller | + functions-common:time_stop:2428          :   total=126334
2026-05-20 01:26:54.633418 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:26:54.636580 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=265358
2026-05-20 01:26:54.640376 | controller | + functions-common:install_package:1434    :   real_install_package ksmtuned
2026-05-20 01:26:54.644405 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:26:54.647573 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:26:54.652325 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:26:54.655586 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:26:54.689509 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install ksmtuned
2026-05-20 01:26:54.736696 | controller | Reading package lists...
2026-05-20 01:26:54.991707 | controller | Building dependency tree...
2026-05-20 01:26:54.991738 | controller | Reading state information...
2026-05-20 01:26:55.258697 | controller | Recommended packages:
2026-05-20 01:26:55.258731 | controller |   qemu-kvm
2026-05-20 01:26:55.288581 | controller | The following NEW packages will be installed:
2026-05-20 01:26:55.290533 | controller |   ksmtuned
2026-05-20 01:27:05.333848 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:27:05.333931 | controller | Need to get 7,444 B of archives.
2026-05-20 01:27:05.333953 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:27:05.333965 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:16.348714 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:28.378034 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:47.415904 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:27:47.415948 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:47.428870 | controller | E: Failed to fetch http://nova.clouds.archive.ubuntu.com/ubuntu/pool/universe/k/ksmtuned/ksmtuned_4.20150326_amd64.deb  Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:27:47.428959 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:27:47.438890 | controller | + functions-common:apt_get:1               :   exit_trap
2026-05-20 01:27:47.444220 | controller | + ./stack.sh:exit_trap:519                 :   local r=100
2026-05-20 01:27:47.450526 | controller | ++ ./stack.sh:exit_trap:520                 :   jobs -p
2026-05-20 01:27:47.456015 | controller | + ./stack.sh:exit_trap:520                 :   jobs=
2026-05-20 01:27:47.460749 | controller | + ./stack.sh:exit_trap:523                 :   [[ -n '' ]]
2026-05-20 01:27:47.465665 | controller | + ./stack.sh:exit_trap:529                 :   '[' -f '' ']'
2026-05-20 01:27:47.470714 | controller | + ./stack.sh:exit_trap:534                 :   kill_spinner
2026-05-20 01:27:47.475072 | controller | + ./stack.sh:kill_spinner:429              :   '[' '!' -z '' ']'
2026-05-20 01:27:47.478693 | controller | + ./stack.sh:exit_trap:536                 :   [[ 100 -ne 0 ]]
2026-05-20 01:27:47.483066 | controller | + ./stack.sh:exit_trap:537                 :   echo 'Error on exit'
2026-05-20 01:27:47.483085 | controller | Error on exit
2026-05-20 01:27:47.486497 | controller | + ./stack.sh:exit_trap:539                 :   type -p generate-subunit
2026-05-20 01:27:47.491349 | controller | + ./stack.sh:exit_trap:542                 :   [[ -z /opt/stack/logs ]]
2026-05-20 01:27:47.495661 | controller | + ./stack.sh:exit_trap:545                 :   /usr/bin/python3.12 /opt/stack/devstack/tools/worlddump.py -d /opt/stack/logs
2026-05-20 01:27:48.038623 | controller | + ./stack.sh:exit_trap:554                 :   exit 100
2026-05-20 01:27:48.040091 | controller | *** FINISHED ***
2026-05-20 01:27:48.305148 | controller | ERROR
2026-05-20 01:27:48.305326 | controller | {
2026-05-20 01:27:48.305358 | controller |   "delta": "0:06:28.038418",
2026-05-20 01:27:48.305381 | controller |   "end": "2026-05-20 01:27:48.057009",
2026-05-20 01:27:48.305401 | controller |   "msg": "non-zero return code",
2026-05-20 01:27:48.305420 | controller |   "rc": 100,
2026-05-20 01:27:48.305441 | controller |   "start": "2026-05-20 01:21:20.018591"
2026-05-20 01:27:48.305460 | controller | }
failure
2026-05-20 01:27:48.330636 | 
2026-05-20 01:27:48.330700 | PLAY RECAP
2026-05-20 01:27:48.330745 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:27:48.330767 | 
2026-05-20 01:27:48.559421 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:27:48.586531 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:27:49.235103 | 
2026-05-20 01:27:49.235504 | PLAY [all]
2026-05-20 01:27:49.258378 | 
2026-05-20 01:27:49.258518 | LOOP [Copy individual Hydrophone result files]
2026-05-20 01:27:50.035224 | controller | ERROR: Item: e2e.log
2026-05-20 01:27:50.035551 | controller | {
2026-05-20 01:27:50.035609 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:50.035653 | controller |   "item": "e2e.log",
2026-05-20 01:27:50.035736 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-20 01:27:50.035774 | controller | }
2026-05-20 01:27:50.299626 | controller | ERROR: Item: junit_01.xml
2026-05-20 01:27:50.299754 | controller | {
2026-05-20 01:27:50.299789 | controller |   "ansible_loop_var": "item",
2026-05-20 01:27:50.299811 | controller |   "item": "junit_01.xml",
2026-05-20 01:27:50.299830 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-20 01:27:50.299849 | controller | }
2026-05-20 01:27:50.317242 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:50.325157 | 
2026-05-20 01:27:50.325245 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-20 01:27:50.631662 | controller | ERROR
2026-05-20 01:27:50.631966 | controller | {
2026-05-20 01:27:50.632011 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-20 01:27:50.632042 | controller | }
2026-05-20 01:27:50.632077 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:50.652422 | 
2026-05-20 01:27:50.652559 | TASK [Return built artifacts to Zuul]
2026-05-20 01:27:50.694056 | controller | ok
2026-05-20 01:27:50.699769 | 
2026-05-20 01:27:50.699834 | TASK [Get the Docker volume ID]
2026-05-20 01:27:51.237414 | controller | ERROR
2026-05-20 01:27:51.237685 | controller | {
2026-05-20 01:27:51.237738 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-20 01:27:51.237774 | controller |   "rc": 2
2026-05-20 01:27:51.237804 | controller | }
2026-05-20 01:27:51.237843 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:51.243955 | 
2026-05-20 01:27:51.244020 | TASK [Copy all of the pod logs]
2026-05-20 01:27:51.273796 | controller | ERROR
2026-05-20 01:27:51.273955 | controller | {
2026-05-20 01:27:51.273983 | 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/f90d311f64ae4551a03729355695db42/untrusted/project_5/github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml': line 42, column 11, but may\nbe elsewhere in the file depending on the exact syntax problem.\n\nThe offending line appears to be:\n\n\n        - name: Copy all of the pod logs\n          ^ here\n"
2026-05-20 01:27:51.274007 | controller | }
2026-05-20 01:27:51.274031 | controller | ERROR: Ignoring Errors
2026-05-20 01:27:51.274902 | 
2026-05-20 01:27:51.274954 | PLAY RECAP
2026-05-20 01:27:51.274994 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-20 01:27:51.275016 | 
2026-05-20 01:27:51.398184 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:27:51.427245 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:27:52.414167 | 
2026-05-20 01:27:52.414434 | PLAY [all]
2026-05-20 01:27:52.504101 | 
2026-05-20 01:27:52.504247 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-20 01:27:53.171782 | controller | changed
2026-05-20 01:27:53.180471 | 
2026-05-20 01:27:53.180690 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-20 01:27:54.223135 | controller | ok: Runtime: 0:00:00.459719
2026-05-20 01:27:54.228947 | 
2026-05-20 01:27:54.229033 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-20 01:27:54.783443 | controller | ok: Runtime: 0:00:00.023044
2026-05-20 01:27:54.788879 | 
2026-05-20 01:27:54.790674 | TASK [export-devstack-journal : Export journal]
2026-05-20 01:27:55.362879 | controller | ok: Runtime: 0:00:00.028707
2026-05-20 01:27:55.370202 | 
2026-05-20 01:27:55.370266 | TASK [export-devstack-journal : Save journal README]
2026-05-20 01:27:56.192619 | controller | changed
2026-05-20 01:27:56.204077 | 
2026-05-20 01:27:56.204149 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-20 01:27:56.460986 | controller | changed
2026-05-20 01:27:56.466647 | 
2026-05-20 01:27:56.466726 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:27:56.800925 | controller | Output suppressed because no_log was given
2026-05-20 01:27:56.806348 | 
2026-05-20 01:27:56.806415 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:27:56.845425 | 
2026-05-20 01:27:56.845678 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:27:56.883062 | 
2026-05-20 01:27:56.883226 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:27:56.915153 | 
2026-05-20 01:27:56.915354 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:27:56.947600 | 
2026-05-20 01:27:56.947773 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:27:56.986151 | 
2026-05-20 01:27:56.986326 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-20 01:27:57.226562 | controller | changed
2026-05-20 01:27:57.232705 | 
2026-05-20 01:27:57.232780 | TASK [apache-logs-conf : Define config paths]
2026-05-20 01:27:57.289140 | controller | ok
2026-05-20 01:27:57.298611 | 
2026-05-20 01:27:57.298687 | TASK [apache-logs-conf : Discover configurations]
2026-05-20 01:27:57.538533 | controller | Output suppressed because no_log was given
2026-05-20 01:27:57.543998 | 
2026-05-20 01:27:57.544076 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-20 01:27:57.580353 | 
2026-05-20 01:27:57.580593 | LOOP [apache-logs-conf : Link configurations]
2026-05-20 01:27:57.622755 | 
2026-05-20 01:27:57.622907 | TASK [capture-performance-data : Generate statistics]
2026-05-20 01:27:59.997807 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-20 01:28:00.018110 | controller | Using python 3.12 to install setuptools
2026-05-20 01:28:00.046823 | controller | /usr/bin/python3.12: No module named pip
2026-05-20 01:28:00.057731 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-20 01:28:00.164727 | controller | ERROR
2026-05-20 01:28:00.165104 | controller | {
2026-05-20 01:28:00.165192 | controller |   "delta": "0:00:02.195695",
2026-05-20 01:28:00.165241 | controller |   "end": "2026-05-20 01:28:00.059848",
2026-05-20 01:28:00.165284 | controller |   "msg": "non-zero return code",
2026-05-20 01:28:00.165329 | controller |   "rc": 127,
2026-05-20 01:28:00.165369 | controller |   "start": "2026-05-20 01:27:57.864153"
2026-05-20 01:28:00.165410 | controller | }
2026-05-20 01:28:00.165466 | controller | ERROR: Ignoring Errors
2026-05-20 01:28:00.177096 | 
2026-05-20 01:28:00.177169 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-20 01:28:00.415774 | controller | changed
2026-05-20 01:28:00.424213 | 
2026-05-20 01:28:00.424280 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-20 01:28:04.116850 | controller | Output suppressed because no_log was given
2026-05-20 01:28:04.130465 | 
2026-05-20 01:28:04.130572 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-20 01:28:04.205907 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.214106 | 
2026-05-20 01:28:04.215837 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.216658 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.217427 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.218310 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.219553 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.222507 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.226035 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.229277 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.232436 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.235400 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.238252 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.241064 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.244087 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.247098 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.249892 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.252541 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.255391 | controller | skipping: Conditional result was False
2026-05-20 01:28:04.328090 | 
2026-05-20 01:28:04.328227 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-20 01:28:04.567172 | controller | ok
2026-05-20 01:28:04.574928 | 
2026-05-20 01:28:04.575013 | TASK [devstack-project-conf : Copy configuration files]
2026-05-20 01:28:05.105942 | controller | skipping: Conditional result was False
2026-05-20 01:28:05.119435 | 
2026-05-20 01:28:05.119585 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-20 01:28:05.423109 | controller | /usr/bin/python3: No module named pip
2026-05-20 01:28:06.504420 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-20 01:28:06.661270 | controller | ok: Runtime: 0:00:01.144037
2026-05-20 01:28:06.673348 | 
2026-05-20 01:28:06.673418 | LOOP [stage-output : Register sources]
2026-05-20 01:28:16.097930 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.109424 | 
2026-05-20 01:28:16.109515 | TASK [stage-output : Check sudo]
2026-05-20 01:28:16.651115 | controller | ok: Runtime: 0:00:00.020165
2026-05-20 01:28:16.658482 | 
2026-05-20 01:28:16.658560 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-20 01:28:16.766872 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.767176 | 
2026-05-20 01:28:16.771044 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.774207 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.777204 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.826109 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.828697 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.831943 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.834737 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.837391 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.840259 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.842979 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.845866 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.865471 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.876141 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.876628 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.877460 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.902946 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.903359 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.906161 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.944674 | controller | Output suppressed because no_log was given
2026-05-20 01:28:16.945208 | controller | Output suppressed because no_log was given
2026-05-20 01:28:17.032480 | controller | Output suppressed because no_log was given
2026-05-20 01:28:17.044938 | controller | Output suppressed because no_log was given
2026-05-20 01:28:17.055681 | controller | Output suppressed because no_log was given
2026-05-20 01:28:17.065063 | controller | Output suppressed because no_log was given
2026-05-20 01:28:17.079360 | 
2026-05-20 01:28:17.079437 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-20 01:28:17.143033 | controller | ok
2026-05-20 01:28:17.161946 | 
2026-05-20 01:28:17.162148 | LOOP [stage-output : Ensure target folders exist]
2026-05-20 01:28:17.424461 | controller | changed: "docs"
2026-05-20 01:28:17.619182 | controller | changed: "artifacts"
2026-05-20 01:28:17.844898 | controller | ok: "logs"
2026-05-20 01:28:17.860844 | 
2026-05-20 01:28:17.861179 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-20 01:28:18.179822 | controller | ok: Item: Runtime: 0:00:00.009375
2026-05-20 01:28:18.180195 | controller | changed: All items complete
2026-05-20 01:28:18.180241 | 
2026-05-20 01:28:18.436926 | controller | ok: Item: Runtime: 0:00:00.008818
2026-05-20 01:28:18.692066 | controller | ok: Item: Runtime: 0:00:00.009354
2026-05-20 01:28:18.918083 | controller | ok: Item: Runtime: 0:00:00.010510
2026-05-20 01:28:19.146822 | controller | ok: Item: Runtime: 0:00:00.007926
2026-05-20 01:28:19.379719 | controller | ok: Item: Runtime: 0:00:00.007756
2026-05-20 01:28:19.638688 | controller | ok: Item: Runtime: 0:00:00.008369
2026-05-20 01:28:19.828581 | controller | ok: Item: Runtime: 0:00:00.007052
2026-05-20 01:28:20.073028 | controller | ok: Item: Runtime: 0:00:00.008653
2026-05-20 01:28:20.293783 | controller | ok: Item: Runtime: 0:00:00.007197
2026-05-20 01:28:20.505036 | controller | ok: Item: Runtime: 0:00:00.008165
2026-05-20 01:28:20.730330 | controller | ok: Item: Runtime: 0:00:00.008245
2026-05-20 01:28:20.944825 | controller | ok: Item: Runtime: 0:00:00.008059
2026-05-20 01:28:21.175003 | controller | ok: Item: Runtime: 0:00:00.007913
2026-05-20 01:28:21.395004 | controller | ok: Item: Runtime: 0:00:00.007403
2026-05-20 01:28:21.622244 | controller | ok: Item: Runtime: 0:00:00.007627
2026-05-20 01:28:21.853177 | controller | ok: Item: Runtime: 0:00:00.008243
2026-05-20 01:28:22.087537 | controller | ok: Item: Runtime: 0:00:00.005912
2026-05-20 01:28:22.319398 | controller | ok: Item: Runtime: 0:00:00.008170
2026-05-20 01:28:22.539213 | controller | ok: Item: Runtime: 0:00:00.008497
2026-05-20 01:28:22.574739 | 
2026-05-20 01:28:22.574874 | TASK [stage-output : Make all log files readable]
2026-05-20 01:28:22.842998 | controller | changed
2026-05-20 01:28:22.862655 | 
2026-05-20 01:28:22.862726 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-20 01:28:23.260160 | controller | changed: Renamed files for staging.
2026-05-20 01:28:23.265878 | 
2026-05-20 01:28:23.265945 | TASK [stage-output : Discover log files for compression]
2026-05-20 01:28:23.291686 | controller | skipping: Conditional result was False
2026-05-20 01:28:23.302312 | 
2026-05-20 01:28:23.302412 | LOOP [stage-output : Archive everything from logs]
2026-05-20 01:28:23.354200 | 
2026-05-20 01:28:23.354362 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-20 01:28:24.155318 | controller | changed:
2026-05-20 01:28:24.155511 | controller | created directory /var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/work/logs/controller
2026-05-20 01:28:24.155555 | controller | cd+++++++++ logs/
2026-05-20 01:28:24.155584 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-05-20 01:28:24.155612 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-20 01:28:24.155639 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-20 01:28:24.155665 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-20 01:28:24.155690 | controller | >f+++++++++ logs/devstacklog.txt
2026-05-20 01:28:24.155716 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-05-20 01:28:24.155742 | controller | >f+++++++++ logs/df.txt
2026-05-20 01:28:24.155767 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-20 01:28:24.155792 | controller | >f+++++++++ logs/iptables.txt
2026-05-20 01:28:24.155817 | controller | >f+++++++++ logs/listen53.txt
2026-05-20 01:28:24.155841 | controller | >f+++++++++ logs/local_conf.txt
2026-05-20 01:28:24.155867 | controller | >f+++++++++ logs/mount.txt
2026-05-20 01:28:24.155892 | controller | >f+++++++++ logs/performance.json
2026-05-20 01:28:24.155917 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-20 01:28:24.155943 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-20 01:28:24.155968 | controller | >f+++++++++ logs/services.txt
2026-05-20 01:28:24.155993 | controller | >f+++++++++ logs/sudoers
2026-05-20 01:28:24.156018 | controller | >f+++++++++ logs/syslog.txt
2026-05-20 01:28:24.156043 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-05-20 01:28:24.156067 | controller | cd+++++++++ logs/apache/
2026-05-20 01:28:24.156092 | controller | cd+++++++++ logs/apache_config/
2026-05-20 01:28:24.156117 | controller | cd+++++++++ logs/etc/
2026-05-20 01:28:24.156141 | controller | cd+++++++++ logs/sudoers.d/
2026-05-20 01:28:24.156166 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-20 01:28:24.156191 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-20 01:28:24.156219 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-20 01:28:24.156257 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-20 01:28:24.156283 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-20 01:28:24.168015 | 
2026-05-20 01:28:24.168089 | TASK [Check if a tempest log exits]
2026-05-20 01:28:24.441722 | controller | ok
2026-05-20 01:28:24.450409 | 
2026-05-20 01:28:24.450495 | TASK [Link post-devstack tempest.log]
2026-05-20 01:28:24.475332 | controller | skipping: Conditional result was False
2026-05-20 01:28:24.484172 | 
2026-05-20 01:28:24.484259 | TASK [Capture most recent qemu crash dump, if any]
2026-05-20 01:28:24.727905 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-20 01:28:25.620262 | controller | ERROR
2026-05-20 01:28:25.620420 | controller | {
2026-05-20 01:28:25.620452 | controller |   "delta": "0:00:00.009185",
2026-05-20 01:28:25.620473 | controller |   "end": "2026-05-20 01:28:24.728517",
2026-05-20 01:28:25.620492 | controller |   "msg": "non-zero return code",
2026-05-20 01:28:25.620510 | controller |   "rc": 127,
2026-05-20 01:28:25.620535 | controller |   "start": "2026-05-20 01:28:24.719332"
2026-05-20 01:28:25.620552 | controller | }
2026-05-20 01:28:25.620586 | controller | ERROR: Ignoring Errors
2026-05-20 01:28:25.622090 | 
2026-05-20 01:28:25.622140 | PLAY RECAP
2026-05-20 01:28:25.622183 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-20 01:28:25.622212 | 
2026-05-20 01:28:25.783486 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:28:26.026592 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:26.873488 | 
2026-05-20 01:28:26.873685 | PLAY [all]
2026-05-20 01:28:26.907905 | 
2026-05-20 01:28:26.908080 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 01:28:26.953735 | controller | skipping: Conditional result was False
2026-05-20 01:28:26.979841 | 
2026-05-20 01:28:26.980054 | TASK [fetch-output : Set log path for single node]
2026-05-20 01:28:27.027513 | controller | ok
2026-05-20 01:28:27.047401 | 
2026-05-20 01:28:27.047848 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 01:28:27.471801 | controller -> localhost | ok: "/var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/work/logs"
2026-05-20 01:28:27.736614 | controller -> localhost | changed: "/var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/work/artifacts"
2026-05-20 01:28:27.984503 | controller -> localhost | changed: "/var/lib/zuul/builds/f90d311f64ae4551a03729355695db42/work/docs"
2026-05-20 01:28:27.998695 | 
2026-05-20 01:28:27.998812 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 01:28:28.710152 | controller | changed: .d..t...... ./
2026-05-20 01:28:28.710369 | controller | changed: All items complete
2026-05-20 01:28:28.710410 | 
2026-05-20 01:28:29.194729 | controller | changed: .d..t...... ./
2026-05-20 01:28:29.695205 | controller | changed: .d..t...... ./
2026-05-20 01:28:29.717693 | 
2026-05-20 01:28:29.717833 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 01:28:30.158420 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.008332
2026-05-20 01:28:30.496540 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007444
2026-05-20 01:28:30.523690 | 
2026-05-20 01:28:30.523780 | PLAY [all]
2026-05-20 01:28:30.531511 | 
2026-05-20 01:28:30.531581 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 01:28:31.229216 | controller | changed
2026-05-20 01:28:31.237122 | 
2026-05-20 01:28:31.237218 | PLAY RECAP
2026-05-20 01:28:31.237266 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:28:31.237288 | 
2026-05-20 01:28:31.371612 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:28:31.668990 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 01:28:32.488191 | 
2026-05-20 01:28:32.698421 | PLAY [localhost]
2026-05-20 01:28:32.719838 | 
2026-05-20 01:28:32.720027 | TASK [Generate Zuul manifest]
2026-05-20 01:28:32.743104 | localhost | ok
2026-05-20 01:28:32.776094 | 
2026-05-20 01:28:32.776198 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 01:28:33.185835 | localhost | changed
2026-05-20 01:28:33.200466 | 
2026-05-20 01:28:33.200589 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 01:28:33.240560 | localhost | ok
2026-05-20 01:28:33.250115 | 
2026-05-20 01:28:33.250200 | TASK [Upload logs]
2026-05-20 01:28:33.270981 | localhost | ok
2026-05-20 01:28:33.380166 | 
2026-05-20 01:28:33.715474 | TASK [Set zuul-log-path fact]
2026-05-20 01:28:33.740427 | localhost | ok
2026-05-20 01:28:33.825421 | 
2026-05-20 01:28:33.825543 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:28:33.860325 | localhost | ok
2026-05-20 01:28:33.870190 | 
2026-05-20 01:28:33.870312 | TASK [upload-logs : Create log directories]
2026-05-20 01:28:34.501794 | localhost | changed
2026-05-20 01:28:34.512810 | 
2026-05-20 01:28:34.513073 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 01:28:34.878036 | localhost -> localhost | ok: Runtime: 0:00:00.004147
2026-05-20 01:28:34.882804 | 
2026-05-20 01:28:34.882867 | TASK [upload-logs : Upload logs to log server]
2026-05-20 01:28:35.382014 | localhost | Output suppressed because no_log was given
2026-05-20 01:28:35.390067 | 
2026-05-20 01:28:35.390212 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 01:28:35.437750 | localhost | skipping: Conditional result was False
2026-05-20 01:28:35.446278 | localhost | skipping: Conditional result was False
2026-05-20 01:28:35.461682 | 
2026-05-20 01:28:35.461852 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 01:28:35.536371 | localhost | skipping: Conditional result was False
2026-05-20 01:28:35.541492 | 
2026-05-20 01:28:35.543098 | localhost | skipping: Conditional result was False
2026-05-20 01:28:35.556484 | 
2026-05-20 01:28:35.556617 | LOOP [upload-logs : Upload console log and json output]