2026-05-20 01:16:20.223006 | Job console starting
2026-05-20 01:16:20.243607 | Updating git repos
2026-05-20 01:16:24.008207 | Cloning repos into workspace
2026-05-20 01:16:35.050640 | Restoring repo states
2026-05-20 01:16:37.311601 | Merging changes
2026-05-20 01:16:38.384154 | Checking out repos
2026-05-20 01:16:55.698351 | Preparing playbooks
2026-05-20 01:17:07.954377 | Running Ansible setup
2026-05-20 01:17:14.045103 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:17:15.487228 | 
2026-05-20 01:17:15.487415 | PLAY [localhost]
2026-05-20 01:17:15.510082 | 
2026-05-20 01:17:15.510591 | TASK [Gathering Facts]
2026-05-20 01:17:17.382242 | localhost | ok
2026-05-20 01:17:17.390596 | 
2026-05-20 01:17:17.390675 | TASK [Setup log path fact]
2026-05-20 01:17:17.425693 | localhost | ok
2026-05-20 01:17:17.448916 | 
2026-05-20 01:17:17.449097 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:17:17.485332 | localhost | ok
2026-05-20 01:17:17.494689 | 
2026-05-20 01:17:17.494789 | TASK [emit-job-header : Print job information]
2026-05-20 01:17:17.548721 | # Job Information
2026-05-20 01:17:17.548866 | Ansible Version: 2.16.18
2026-05-20 01:17:17.548898 | Job: magnum-cluster-api-hydrophone-v1.34.7-calico
2026-05-20 01:17:17.548921 | Pipeline: check
2026-05-20 01:17:17.548941 | Executor: 2d72f0692154
2026-05-20 01:17:17.548980 | Triggered by: https://github.com/vexxhost/magnum-cluster-api/pull/1004
2026-05-20 01:17:17.549004 | Event ID: 2fc79390-53e9-11f1-86c4-5c97216a804a
2026-05-20 01:17:17.552461 | 
2026-05-20 01:17:17.552532 | LOOP [emit-job-header : Print node information]
2026-05-20 01:17:17.727851 | localhost | ok:
2026-05-20 01:17:17.727978 | localhost | # Node Information
2026-05-20 01:17:17.728006 | localhost | Inventory Hostname: controller
2026-05-20 01:17:17.728028 | localhost | Hostname: np0000175158
2026-05-20 01:17:17.728048 | localhost | Username: zuul
2026-05-20 01:17:17.728070 | localhost | Distro: Ubuntu 24.04
2026-05-20 01:17:17.728090 | localhost | Provider: yul1
2026-05-20 01:17:17.728109 | localhost | Region: ca-ymq-1
2026-05-20 01:17:17.728128 | localhost | Label: ubuntu-noble-16
2026-05-20 01:17:17.728146 | localhost | Product Name: OpenStack Nova
2026-05-20 01:17:17.728164 | localhost | Interface IP: 199.204.45.38
2026-05-20 01:17:17.746597 | 
2026-05-20 01:17:17.746772 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-20 01:17:19.264340 | localhost -> localhost | changed
2026-05-20 01:17:19.270385 | 
2026-05-20 01:17:19.270465 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-20 01:17:21.954720 | localhost -> localhost | changed
2026-05-20 01:17:21.965633 | 
2026-05-20 01:17:21.966019 | PLAY [all]
2026-05-20 01:17:22.011277 | 
2026-05-20 01:17:22.011462 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-20 01:17:22.512152 | controller -> localhost | ok
2026-05-20 01:17:22.520597 | 
2026-05-20 01:17:22.520697 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-20 01:17:22.554238 | controller | ok
2026-05-20 01:17:22.587773 | controller | included: /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-20 01:17:22.612128 | 
2026-05-20 01:17:22.612483 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-20 01:17:23.764062 | controller -> localhost | Generating public/private rsa key pair.
2026-05-20 01:17:23.765178 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/work/38ebd1f5f1fd44e1a1877660e8b2b86f_id_rsa
2026-05-20 01:17:23.765240 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/work/38ebd1f5f1fd44e1a1877660e8b2b86f_id_rsa.pub
2026-05-20 01:17:23.765282 | controller -> localhost | The key fingerprint is:
2026-05-20 01:17:23.765328 | controller -> localhost | SHA256:ThbEdCJZo0JB9VxHssQarQEyuf0mx36tEnoPAi7+LCw zuul-build-sshkey
2026-05-20 01:17:23.765374 | controller -> localhost | The key's randomart image is:
2026-05-20 01:17:23.765405 | controller -> localhost | +---[RSA 3072]----+
2026-05-20 01:17:23.765453 | controller -> localhost | |   .*++**o=.o    |
2026-05-20 01:17:23.765483 | controller -> localhost | |   ..o.*+*o+     |
2026-05-20 01:17:23.765511 | controller -> localhost | |    .o. +=.      |
2026-05-20 01:17:23.765538 | controller -> localhost | |    ... o.       |
2026-05-20 01:17:23.765577 | controller -> localhost | |    .  oS        |
2026-05-20 01:17:23.765606 | controller -> localhost | |   . ..+*        |
2026-05-20 01:17:23.766530 | controller -> localhost | | .. . .*o. .     |
2026-05-20 01:17:23.766574 | controller -> localhost | |E.oo  ..+.. .    |
2026-05-20 01:17:23.766681 | controller -> localhost | | ..oo  . +o.     |
2026-05-20 01:17:23.766741 | controller -> localhost | +----[SHA256]-----+
2026-05-20 01:17:23.766903 | controller -> localhost | ok: Runtime: 0:00:00.589480
2026-05-20 01:17:23.789544 | 
2026-05-20 01:17:23.789708 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-20 01:17:23.829463 | controller | ok
2026-05-20 01:17:24.240433 | controller | included: /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-20 01:17:24.250796 | 
2026-05-20 01:17:24.250883 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-20 01:17:24.275394 | controller | skipping: Conditional result was False
2026-05-20 01:17:24.287686 | 
2026-05-20 01:17:24.287797 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-20 01:17:25.792520 | controller | changed
2026-05-20 01:17:25.814921 | 
2026-05-20 01:17:25.815075 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-20 01:17:26.074496 | controller | ok
2026-05-20 01:17:26.081473 | 
2026-05-20 01:17:26.081541 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-20 01:17:26.677482 | controller | changed
2026-05-20 01:17:26.687460 | 
2026-05-20 01:17:26.687540 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-20 01:17:27.393527 | controller | changed
2026-05-20 01:17:27.399712 | 
2026-05-20 01:17:27.399777 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-20 01:17:27.428600 | controller | skipping: Conditional result was False
2026-05-20 01:17:27.441229 | 
2026-05-20 01:17:27.441397 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-20 01:17:27.904712 | controller -> localhost | changed
2026-05-20 01:17:27.915422 | 
2026-05-20 01:17:27.915482 | TASK [add-build-sshkey : Add back temp key]
2026-05-20 01:17:28.181134 | controller -> localhost | Identity added: /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/work/38ebd1f5f1fd44e1a1877660e8b2b86f_id_rsa (zuul-build-sshkey)
2026-05-20 01:17:28.181375 | controller -> localhost | ok: Runtime: 0:00:00.012782
2026-05-20 01:17:28.188813 | 
2026-05-20 01:17:28.188893 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-20 01:17:28.472780 | controller | ok
2026-05-20 01:17:28.478650 | 
2026-05-20 01:17:28.478730 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-20 01:17:28.525959 | controller | skipping: Conditional result was False
2026-05-20 01:17:28.544996 | 
2026-05-20 01:17:28.545102 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-20 01:17:28.885226 | controller | ok
2026-05-20 01:17:28.895489 | 
2026-05-20 01:17:28.895555 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-20 01:18:48.030771 | controller | Output suppressed because no_log was given
2026-05-20 01:18:48.061675 | 
2026-05-20 01:18:48.061793 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-20 01:18:48.597362 | controller | ok: "logs"
2026-05-20 01:18:48.628307 | controller | ok: All items complete
2026-05-20 01:18:48.628400 | 
2026-05-20 01:18:48.803000 | controller | ok: "artifacts"
2026-05-20 01:18:48.988775 | controller | ok: "docs"
2026-05-20 01:18:49.005747 | 
2026-05-20 01:18:49.005912 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-20 01:18:49.248324 | controller | changed: "logs"
2026-05-20 01:18:49.454552 | controller | changed: "artifacts"
2026-05-20 01:18:49.655611 | controller | changed: "docs"
2026-05-20 01:18:49.672533 | 
2026-05-20 01:18:49.672658 | PLAY RECAP
2026-05-20 01:18:49.672709 | controller | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-20 01:18:49.672738 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:18:49.672760 | 
2026-05-20 01:18:49.949844 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-20 01:18:50.070510 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:18:51.702441 | 
2026-05-20 01:18:51.702840 | PLAY [all]
2026-05-20 01:18:51.765451 | 
2026-05-20 01:18:51.765772 | TASK [Fix the permissions of the zuul home directory]
2026-05-20 01:18:52.447581 | controller | changed
2026-05-20 01:18:52.460257 | 
2026-05-20 01:18:52.460394 | TASK [Gather minimum local MTU]
2026-05-20 01:18:52.640737 | controller | ok
2026-05-20 01:18:52.652251 | 
2026-05-20 01:18:52.652937 | TASK [Calculate external_bridge_mtu]
2026-05-20 01:18:52.727967 | controller | ok
2026-05-20 01:18:52.742163 | 
2026-05-20 01:18:52.742277 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-05-20 01:18:52.818346 | controller | skipping: Conditional result was False
2026-05-20 01:18:52.831285 | 
2026-05-20 01:18:52.831539 | TASK [configure-swap : Get ephemeral0 device node]
2026-05-20 01:18:53.927269 | controller | ok: Runtime: 0:00:00.010029
2026-05-20 01:18:53.939389 | 
2026-05-20 01:18:53.939518 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-05-20 01:18:54.013183 | controller | skipping: Conditional result was False
2026-05-20 01:18:54.019984 | 
2026-05-20 01:18:54.203218 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-05-20 01:18:54.259488 | controller | skipping: Conditional result was False
2026-05-20 01:18:54.269284 | 
2026-05-20 01:18:54.269470 | TASK [configure-swap : Setup swap file on root device]
2026-05-20 01:18:54.402067 | controller | ok
2026-05-20 01:18:54.422954 | controller | included: /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-05-20 01:18:54.444764 | 
2026-05-20 01:18:54.444907 | TASK [configure-swap : Calculate required swap]
2026-05-20 01:18:54.581211 | controller | ok
2026-05-20 01:18:54.909867 | 
2026-05-20 01:18:54.910225 | TASK [configure-swap : Get root filesystem]
2026-05-20 01:18:55.419904 | controller | ext4
2026-05-20 01:18:55.594185 | controller | ok: Runtime: 0:00:00.010165
2026-05-20 01:18:55.614678 | 
2026-05-20 01:18:55.614804 | TASK [configure-swap : Save root filesystem]
2026-05-20 01:18:55.676567 | controller | ok
2026-05-20 01:18:55.691153 | 
2026-05-20 01:18:55.691497 | TASK [configure-swap : Debug the root_filesystem variable]
2026-05-20 01:18:55.733263 | controller | ok:
2026-05-20 01:18:55.733462 | controller | {
2026-05-20 01:18:55.733507 | controller |   "root_filesystem": "ext4"
2026-05-20 01:18:55.733542 | controller | }
2026-05-20 01:18:55.744077 | 
2026-05-20 01:18:55.744226 | TASK [configure-swap : Create swap backing file]
2026-05-20 01:19:05.414793 | controller | 8192+0 records in
2026-05-20 01:19:05.414992 | controller | 8192+0 records out
2026-05-20 01:19:05.415014 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 9.23665 s, 930 MB/s
2026-05-20 01:19:05.410459 | controller | ok: Runtime: 0:00:09.245781
2026-05-20 01:19:05.423339 | 
2026-05-20 01:19:05.586043 | TASK [configure-swap : Ensure swapfile perms]
2026-05-20 01:19:06.819899 | controller | changed
2026-05-20 01:19:06.867106 | 
2026-05-20 01:19:06.867230 | TASK [configure-swap : Make swapfile]
2026-05-20 01:19:36.553248 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-05-20 01:19:36.553409 | controller | no label, UUID=c992473f-d8bf-449d-beb4-6be384c218f2
2026-05-20 01:19:37.077697 | controller | ok: Runtime: 0:00:29.380918
2026-05-20 01:19:37.083864 | 
2026-05-20 01:19:37.083930 | TASK [configure-swap : Write swap to fstab]
2026-05-20 01:19:37.653214 | controller | changed
2026-05-20 01:19:37.664547 | 
2026-05-20 01:19:37.664683 | TASK [configure-swap : Add all swap]
2026-05-20 01:19:38.218839 | controller | ok: Runtime: 0:00:00.017052
2026-05-20 01:19:38.287865 | 
2026-05-20 01:19:38.287981 | TASK [configure-swap : Debug the swap_required variable]
2026-05-20 01:19:38.328737 | controller | ok:
2026-05-20 01:19:38.328931 | controller | {
2026-05-20 01:19:38.328959 | controller |   "swap_required": "8192"
2026-05-20 01:19:38.329042 | controller | }
2026-05-20 01:19:38.335480 | 
2026-05-20 01:19:38.335545 | TASK [configure-swap : Set swappiness]
2026-05-20 01:19:38.736634 | controller | changed
2026-05-20 01:19:38.745297 | 
2026-05-20 01:19:38.745400 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-05-20 01:19:38.802605 | controller | ok:
2026-05-20 01:19:38.802787 | controller | {
2026-05-20 01:19:38.802816 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-05-20 01:19:38.802840 | controller | }
2026-05-20 01:19:38.820237 | 
2026-05-20 01:19:38.820359 | TASK [setup-stack-user : Create stack group]
2026-05-20 01:19:39.465352 | controller | changed
2026-05-20 01:19:39.470761 | 
2026-05-20 01:19:39.470832 | TASK [setup-stack-user : Create the stack user home folder]
2026-05-20 01:19:39.745410 | controller | changed
2026-05-20 01:19:39.753367 | 
2026-05-20 01:19:39.753480 | TASK [setup-stack-user : Create stack user]
2026-05-20 01:19:41.000657 | controller | changed
2026-05-20 01:19:41.007163 | 
2026-05-20 01:19:41.007238 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-05-20 01:19:41.305635 | controller | changed
2026-05-20 01:19:41.312100 | 
2026-05-20 01:19:41.312172 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-05-20 01:19:42.522217 | controller | changed
2026-05-20 01:19:42.534958 | 
2026-05-20 01:19:42.535111 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-05-20 01:19:42.951455 | controller | changed
2026-05-20 01:19:42.987532 | 
2026-05-20 01:19:42.987651 | TASK [setup-tempest-user : Create tempest group]
2026-05-20 01:19:43.618855 | controller | changed
2026-05-20 01:19:43.626659 | 
2026-05-20 01:19:43.626758 | TASK [setup-tempest-user : Create tempest user]
2026-05-20 01:19:44.508010 | controller | changed
2026-05-20 01:19:44.514748 | 
2026-05-20 01:19:44.514831 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-05-20 01:19:45.372696 | controller | changed
2026-05-20 01:19:45.395909 | 
2026-05-20 01:19:45.396043 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-05-20 01:19:45.888997 | controller | ok: Not all paths examined, check warnings for details
2026-05-20 01:19:45.948049 | 
2026-05-20 01:19:45.948193 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-05-20 01:19:46.464426 | controller | ok: Item: Runtime: 0:00:00.187038
2026-05-20 01:19:46.825013 | controller | ok: Item: Runtime: 0:00:00.130471
2026-05-20 01:19:47.092850 | controller | ok: Item: Runtime: 0:00:00.063715
2026-05-20 01:19:47.591977 | controller | ok: Item: Runtime: 0:00:00.270369
2026-05-20 01:19:47.947976 | controller | ok: Item: Runtime: 0:00:00.152176
2026-05-20 01:19:48.473392 | controller | ok: Item: Runtime: 0:00:00.296820
2026-05-20 01:19:49.193333 | controller | ok: Item: Runtime: 0:00:00.515785
2026-05-20 01:19:49.999098 | controller | ok: Item: Runtime: 0:00:00.578884
2026-05-20 01:19:50.793109 | controller | ok: Item: Runtime: 0:00:00.562317
2026-05-20 01:19:52.119538 | controller | ok: Item: Runtime: 0:00:01.099085
2026-05-20 01:19:52.429523 | controller | ok: Item: Runtime: 0:00:00.105556
2026-05-20 01:19:52.882665 | controller | ok: Item: Runtime: 0:00:00.217742
2026-05-20 01:19:53.433117 | controller | ok: Item: Runtime: 0:00:00.338993
2026-05-20 01:19:56.988134 | controller | ok: Item: Runtime: 0:00:03.321568
2026-05-20 01:19:58.370886 | controller | ok: Item: Runtime: 0:00:01.168322
2026-05-20 01:19:58.886260 | controller | ok: Item: Runtime: 0:00:00.294255
2026-05-20 01:19:58.951122 | 
2026-05-20 01:19:58.951299 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-05-20 01:19:59.175765 | controller | ok: All paths examined
2026-05-20 01:19:59.183389 | 
2026-05-20 01:19:59.183475 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-05-20 01:19:59.446509 | controller | ok: All paths examined
2026-05-20 01:19:59.453711 | 
2026-05-20 01:19:59.453830 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-05-20 01:20:00.051126 | controller | ok: Item: Runtime: 0:00:00.161509
2026-05-20 01:20:00.051452 | controller | changed: All items complete
2026-05-20 01:20:00.051498 | 
2026-05-20 01:20:00.543043 | controller | ok: Item: Runtime: 0:00:00.245527
2026-05-20 01:20:00.640919 | 
2026-05-20 01:20:00.641179 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-05-20 01:20:00.818061 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.822904 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.827755 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.834203 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.839979 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.845786 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.851424 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.857109 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.863530 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.869358 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.873821 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.878638 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.883663 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.890304 | controller | skipping: Conditional result was False
2026-05-20 01:20:00.912128 | controller | skipping: Conditional result was False
2026-05-20 01:20:01.074451 | controller | skipping: Conditional result was False
2026-05-20 01:20:01.216731 | 
2026-05-20 01:20:01.216856 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-05-20 01:20:03.565866 | controller | changed
2026-05-20 01:20:03.622918 | 
2026-05-20 01:20:03.623167 | TASK [setup-devstack-log-dir : Create logs directory]
2026-05-20 01:20:03.925113 | controller | changed
2026-05-20 01:20:03.938490 | 
2026-05-20 01:20:03.938611 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-05-20 01:20:04.255671 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-05-20 01:20:04.521284 | controller | ERROR
2026-05-20 01:20:04.521518 | controller | {
2026-05-20 01:20:04.521575 | controller |   "delta": "0:00:00.016562",
2026-05-20 01:20:04.521612 | controller |   "end": "2026-05-20 01:20:04.256273",
2026-05-20 01:20:04.521636 | controller |   "msg": "non-zero return code",
2026-05-20 01:20:04.521659 | controller |   "rc": 1,
2026-05-20 01:20:04.521681 | controller |   "start": "2026-05-20 01:20:04.239711"
2026-05-20 01:20:04.521714 | controller | }
2026-05-20 01:20:04.521744 | controller | ERROR: Ignoring Errors
2026-05-20 01:20:04.531969 | 
2026-05-20 01:20:04.532089 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-05-20 01:20:04.820910 | controller | ok
2026-05-20 01:20:04.838266 | 
2026-05-20 01:20:04.838390 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-05-20 01:20:05.085075 | controller | /usr/bin/journalctl
2026-05-20 01:20:05.599367 | controller | ok: Runtime: 0:00:00.005549
2026-05-20 01:20:05.606877 | 
2026-05-20 01:20:05.606968 | TASK [start-fresh-logging : Get current date]
2026-05-20 01:20:05.883597 | controller | 2026-05-20 01:20:05
2026-05-20 01:20:06.161545 | controller | ok: Runtime: 0:00:00.009242
2026-05-20 01:20:06.176664 | 
2026-05-20 01:20:06.176810 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-05-20 01:20:06.791921 | controller | changed
2026-05-20 01:20:06.814989 | 
2026-05-20 01:20:06.815208 | TASK [start-fresh-logging : Stop rsyslog]
2026-05-20 01:20:06.855399 | controller | skipping: Conditional result was False
2026-05-20 01:20:06.871186 | 
2026-05-20 01:20:06.871563 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-05-20 01:20:07.425415 | controller | skipping: Conditional result was False
2026-05-20 01:20:07.436162 | 
2026-05-20 01:20:07.436272 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-05-20 01:20:07.979047 | controller | skipping: Conditional result was False
2026-05-20 01:20:08.091313 | 
2026-05-20 01:20:08.091428 | TASK [start-fresh-logging : Recreate syslog file]
2026-05-20 01:20:08.116589 | controller | skipping: Conditional result was False
2026-05-20 01:20:08.122961 | 
2026-05-20 01:20:08.123037 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-05-20 01:20:08.661597 | controller | skipping: Conditional result was False
2026-05-20 01:20:08.672174 | 
2026-05-20 01:20:08.672271 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-05-20 01:20:09.280761 | controller | skipping: Conditional result was False
2026-05-20 01:20:09.380158 | 
2026-05-20 01:20:09.380469 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-05-20 01:20:09.419283 | controller | skipping: Conditional result was False
2026-05-20 01:20:09.440398 | 
2026-05-20 01:20:09.440622 | TASK [start-fresh-logging : Recreate kern.log file]
2026-05-20 01:20:09.466584 | controller | skipping: Conditional result was False
2026-05-20 01:20:09.490236 | 
2026-05-20 01:20:09.490415 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-05-20 01:20:09.537698 | controller | skipping: Conditional result was False
2026-05-20 01:20:09.553646 | 
2026-05-20 01:20:09.554923 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-05-20 01:20:09.613166 | controller | skipping: Conditional result was False
2026-05-20 01:20:09.628459 | 
2026-05-20 01:20:09.628611 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-05-20 01:20:09.655911 | controller | skipping: Conditional result was False
2026-05-20 01:20:10.419773 | 
2026-05-20 01:20:10.419966 | TASK [start-fresh-logging : Start rsyslog]
2026-05-20 01:20:10.447212 | controller | skipping: Conditional result was False
2026-05-20 01:20:10.467507 | 
2026-05-20 01:20:10.467688 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-05-20 01:20:11.010559 | controller | ok
2026-05-20 01:20:11.178540 | 
2026-05-20 01:20:11.178640 | PLAY RECAP
2026-05-20 01:20:11.178685 | controller | ok: 39 changed: 24 unreachable: 0 failed: 0 skipped: 16 rescued: 0 ignored: 1
2026-05-20 01:20:11.178708 | 
2026-05-20 01:20:11.361371 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-05-20 01:20:11.415388 | PRE-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:20:12.623139 | 
2026-05-20 01:20:12.623333 | PLAY [all]
2026-05-20 01:20:12.642277 | 
2026-05-20 01:20:12.642419 | TASK [Install curl]
2026-05-20 01:20:21.235673 | controller | ok
2026-05-20 01:20:21.262722 | 
2026-05-20 01:20:21.262944 | TASK [ensure-rust : Use rustup]
2026-05-20 01:20:21.299624 | controller | ok
2026-05-20 01:20:21.316033 | controller | included: /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/ensure-rust/tasks/rustup.yaml
2026-05-20 01:20:21.324533 | 
2026-05-20 01:20:21.324620 | TASK [ensure-rust : Install Rust]
2026-05-20 01:20:21.979368 | controller | info: downloading installer
2026-05-20 01:20:22.697398 | controller | warn: It looks like you have an existing rustup settings file at:
2026-05-20 01:20:22.698222 | controller | warn: /opt/rust/settings.toml
2026-05-20 01:20:22.698243 | controller | warn: Rustup will install the default toolchain as specified in the settings file,
2026-05-20 01:20:22.698276 | controller | warn: instead of the one inferred from the default host triple.
2026-05-20 01:20:23.655560 | controller | info: profile set to default
2026-05-20 01:20:23.655670 | controller | info: default host triple is x86_64-unknown-linux-gnu
2026-05-20 01:20:23.658890 | controller | info: syncing channel updates for stable-x86_64-unknown-linux-gnu
2026-05-20 01:20:25.532095 | controller | info: latest update on 2026-04-16 for version 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:20:25.532891 | controller | info: downloading 6 components
2026-05-20 01:20:42.169193 | controller | info: default toolchain set to stable-x86_64-unknown-linux-gnu
2026-05-20 01:20:42.169349 | controller |
2026-05-20 01:20:42.186665 | controller |   stable-x86_64-unknown-linux-gnu installed - rustc 1.95.0 (59807616e 2026-04-14)
2026-05-20 01:20:42.186702 | controller |
2026-05-20 01:20:42.186720 | controller |
2026-05-20 01:20:42.186732 | controller | Rust is installed now. Great!
2026-05-20 01:20:42.186744 | controller |
2026-05-20 01:20:42.186780 | controller | To get started you need Cargo's bin directory (/opt/rust/bin) in your PATH
2026-05-20 01:20:42.186792 | controller | environment variable. This has not been done automatically.
2026-05-20 01:20:42.186804 | controller |
2026-05-20 01:20:42.186816 | controller | To configure your current shell, you need to source
2026-05-20 01:20:42.186827 | controller | the corresponding env file under /opt/rust.
2026-05-20 01:20:42.186839 | controller |
2026-05-20 01:20:42.186870 | controller | This is usually done by running one of the following (note the leading DOT):
2026-05-20 01:20:42.186883 | controller | . "/opt/rust/env"            # For sh/bash/zsh/ash/dash/pdksh
2026-05-20 01:20:42.186901 | controller | source "/opt/rust/env.fish"  # For fish
2026-05-20 01:20:42.186914 | controller | source "/opt/rust/env.nu"  # For nushell
2026-05-20 01:20:42.186926 | controller | source "/opt/rust/env.tcsh"  # For tcsh
2026-05-20 01:20:42.186937 | controller | . "/opt/rust/env.ps1"        # For pwsh
2026-05-20 01:20:42.186948 | controller | source "/opt/rust/env.xsh"   # For xonsh
2026-05-20 01:20:42.186963 | controller | warn: no default linker (`cc`) was found in your PATH
2026-05-20 01:20:42.186976 | controller | warn: many Rust crates require a system C toolchain to build
2026-05-20 01:20:42.510538 | controller | ok: Runtime: 0:00:20.455765
2026-05-20 01:20:42.520752 | 
2026-05-20 01:20:42.633519 | TASK [ensure-rust : Install wrapper helper script]
2026-05-20 01:20:43.538117 | controller | changed
2026-05-20 01:20:43.576290 | 
2026-05-20 01:20:43.576432 | TASK [ensure-rust : Run wrapper installation]
2026-05-20 01:20:43.826756 | controller | Install link for cargo
2026-05-20 01:20:43.833855 | controller | Install link for cargo-clippy
2026-05-20 01:20:43.839880 | controller | Install link for cargo-fmt
2026-05-20 01:20:43.845805 | controller | Install link for cargo-miri
2026-05-20 01:20:43.850906 | controller | Install link for clippy-driver
2026-05-20 01:20:43.856010 | controller | Install link for rls
2026-05-20 01:20:43.861820 | controller | Install link for rust-analyzer
2026-05-20 01:20:43.867775 | controller | Install link for rust-gdb
2026-05-20 01:20:43.875113 | controller | Install link for rust-gdbgui
2026-05-20 01:20:43.879535 | controller | Install link for rust-lldb
2026-05-20 01:20:43.884394 | controller | Install link for rustc
2026-05-20 01:20:43.890544 | controller | Install link for rustdoc
2026-05-20 01:20:43.895874 | controller | Install link for rustfmt
2026-05-20 01:20:43.902224 | controller | Install link for rustup
2026-05-20 01:20:44.120851 | controller | ok: Runtime: 0:00:00.100916
2026-05-20 01:20:44.127270 | 
2026-05-20 01:20:44.127352 | TASK [ensure-rust : Install packages]
2026-05-20 01:20:44.157491 | controller | skipping: Conditional result was False
2026-05-20 01:20:44.249053 | 
2026-05-20 01:20:44.249168 | PLAY RECAP
2026-05-20 01:20:44.249213 | controller | ok: 5 changed: 3 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:20:44.249234 | 
2026-05-20 01:20:44.488268 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/pre.yml@main]
2026-05-20 01:20:44.563061 | RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:20:45.968943 | 
2026-05-20 01:20:46.027788 | PLAY [all]
2026-05-20 01:20:46.060309 | 
2026-05-20 01:20:46.060495 | TASK [Install DevStack]
2026-05-20 01:20:46.095241 | controller | ok
2026-05-20 01:20:46.231626 | 
2026-05-20 01:20:46.231923 | TASK [Run devstack on the controller]
2026-05-20 01:20:46.269864 | controller | ok
2026-05-20 01:20:46.401244 | 
2026-05-20 01:20:46.401450 | TASK [run-devstack : Run devstack]
2026-05-20 01:20:47.343557 | controller | + unset GREP_OPTIONS
2026-05-20 01:20:47.343725 | controller | + unset LANG
2026-05-20 01:20:47.343741 | controller | + unset LANGUAGE
2026-05-20 01:20:47.343751 | controller | + LC_ALL=en_US.utf8
2026-05-20 01:20:47.353085 | controller | + export LC_ALL
2026-05-20 01:20:47.355072 | controller | ++ env
2026-05-20 01:20:47.355438 | controller | ++ grep -E '^OS_'
2026-05-20 01:20:47.356066 | controller | ++ cut -d = -f 1
2026-05-20 01:20:47.361757 | controller | + unset
2026-05-20 01:20:47.361779 | controller | + umask 022
2026-05-20 01:20:47.361842 | controller | + PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/snap/bin:/usr/local/bin:/usr/local/sbin:/usr/sbin:/sbin
2026-05-20 01:20:47.364048 | controller | +++ dirname ./stack.sh
2026-05-20 01:20:47.371551 | controller | ++ cd .
2026-05-20 01:20:47.371601 | controller | ++ pwd
2026-05-20 01:20:47.372366 | controller | + TOP_DIR=/opt/stack/devstack
2026-05-20 01:20:47.372384 | controller | + NOUNSET=
2026-05-20 01:20:47.372445 | controller | + [[ -n '' ]]
2026-05-20 01:20:47.373236 | controller | ++ date +%s
2026-05-20 01:20:47.374641 | controller | + DEVSTACK_START_TIME=1779240047
2026-05-20 01:20:47.374685 | controller | + [[ -r /opt/stack/devstack/.stackenv ]]
2026-05-20 01:20:47.374745 | controller | + FILES=/opt/stack/devstack/files
2026-05-20 01:20:47.374792 | controller | + '[' '!' -d /opt/stack/devstack/files ']'
2026-05-20 01:20:47.374840 | controller | + '[' '!' -d /opt/stack/devstack/inc ']'
2026-05-20 01:20:47.374875 | controller | + '[' '!' -d /opt/stack/devstack/lib ']'
2026-05-20 01:20:47.374924 | controller | + [[ '' == \y ]]
2026-05-20 01:20:47.374990 | controller | + [[ 1002 -eq 0 ]]
2026-05-20 01:20:47.375052 | controller | + [[ -n '' ]]
2026-05-20 01:20:47.375095 | controller | + [[ -e /opt/stack/.no-devstack ]]
2026-05-20 01:20:47.375141 | controller | + LAST_SPINNER_PID=
2026-05-20 01:20:47.375157 | controller | + source /opt/stack/devstack/functions
2026-05-20 01:20:47.375326 | controller | ++ [[ -z '' ]]
2026-05-20 01:20:47.375337 | controller | ++ declare -r -g _DEVSTACK_FUNCTIONS=1
2026-05-20 01:20:47.377050 | controller | ++++ dirname /opt/stack/devstack/functions
2026-05-20 01:20:47.378928 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:20:47.378948 | controller | +++ pwd
2026-05-20 01:20:47.379569 | controller | ++ FUNC_DIR=/opt/stack/devstack
2026-05-20 01:20:47.379611 | controller | ++ source /opt/stack/devstack/functions-common
2026-05-20 01:20:47.381483 | controller | ++++ set +o
2026-05-20 01:20:47.381865 | controller | ++++ grep xtrace
2026-05-20 01:20:47.385275 | controller | +++ _XTRACE_FUNCTIONS_COMMON='set -o xtrace'
2026-05-20 01:20:47.385296 | controller | +++ set +o xtrace
2026-05-20 01:20:47.398116 | controller | ++ source /opt/stack/devstack/inc/ini-config
2026-05-20 01:20:47.399642 | controller | ++++ set +o
2026-05-20 01:20:47.400064 | controller | ++++ grep xtrace
2026-05-20 01:20:47.403472 | controller | +++ INC_CONF_TRACE='set -o xtrace'
2026-05-20 01:20:47.403501 | controller | +++ set +o xtrace
2026-05-20 01:20:47.404749 | controller | ++ source /opt/stack/devstack/inc/meta-config
2026-05-20 01:20:47.406564 | controller | ++++ set +o
2026-05-20 01:20:47.407077 | controller | ++++ grep xtrace
2026-05-20 01:20:47.410551 | controller | +++ _XTRACE_INC_META='set -o xtrace'
2026-05-20 01:20:47.410629 | controller | +++ set +o xtrace
2026-05-20 01:20:47.411460 | controller | ++ source /opt/stack/devstack/inc/python
2026-05-20 01:20:47.413316 | controller | ++++ set +o
2026-05-20 01:20:47.413733 | controller | ++++ grep xtrace
2026-05-20 01:20:47.417276 | controller | +++ INC_PY_TRACE='set -o xtrace'
2026-05-20 01:20:47.417346 | controller | +++ set +o xtrace
2026-05-20 01:20:47.419117 | controller | ++ source /opt/stack/devstack/inc/rootwrap
2026-05-20 01:20:47.420867 | controller | ++++ set +o
2026-05-20 01:20:47.421210 | controller | ++++ grep xtrace
2026-05-20 01:20:47.425153 | controller | +++ INC_ROOT_TRACE='set -o xtrace'
2026-05-20 01:20:47.425172 | controller | +++ set +o xtrace
2026-05-20 01:20:47.425558 | controller | ++ source /opt/stack/devstack/inc/async
2026-05-20 01:20:47.426666 | controller | ++++ trueorfalse True DEVSTACK_PARALLEL
2026-05-20 01:20:47.426817 | controller | ++++ local xtrace
2026-05-20 01:20:47.428739 | controller | +++++ set +o
2026-05-20 01:20:47.429249 | controller | +++++ grep xtrace
2026-05-20 01:20:47.435273 | controller | ++++ xtrace='set -o xtrace'
2026-05-20 01:20:47.435391 | controller | ++++ set +o xtrace
2026-05-20 01:20:47.435404 | controller | +++ DEVSTACK_PARALLEL=True
2026-05-20 01:20:47.435413 | controller | +++ _ASYNC_BG_TIME=0
2026-05-20 01:20:47.438192 | controller | +++ set +o
2026-05-20 01:20:47.438645 | controller | +++ grep xtrace
2026-05-20 01:20:47.442623 | controller | ++ _XTRACE_FUNCTIONS='set -o xtrace'
2026-05-20 01:20:47.442681 | controller | ++ set +o xtrace
2026-05-20 01:20:47.445305 | controller | + source /opt/stack/devstack/lib/stack
2026-05-20 01:20:47.445444 | controller | + GetDistro
2026-05-20 01:20:47.445476 | controller | + GetOSVersion
2026-05-20 01:20:47.445488 | controller | + source /etc/os-release
2026-05-20 01:20:47.445526 | controller | ++ PRETTY_NAME='Ubuntu 24.04.4 LTS'
2026-05-20 01:20:47.445536 | controller | ++ NAME=Ubuntu
2026-05-20 01:20:47.445544 | controller | ++ VERSION_ID=24.04
2026-05-20 01:20:47.445557 | controller | ++ VERSION='24.04.4 LTS (Noble Numbat)'
2026-05-20 01:20:47.445565 | controller | ++ VERSION_CODENAME=noble
2026-05-20 01:20:47.445574 | controller | ++ ID=ubuntu
2026-05-20 01:20:47.445583 | controller | ++ ID_LIKE=debian
2026-05-20 01:20:47.445591 | controller | ++ HOME_URL=https://www.ubuntu.com/
2026-05-20 01:20:47.445603 | controller | ++ SUPPORT_URL=https://help.ubuntu.com/
2026-05-20 01:20:47.445612 | controller | ++ BUG_REPORT_URL=https://bugs.launchpad.net/ubuntu/
2026-05-20 01:20:47.445622 | controller | ++ PRIVACY_POLICY_URL=https://www.ubuntu.com/legal/terms-and-policies/privacy-policy
2026-05-20 01:20:47.445631 | controller | ++ UBUNTU_CODENAME=noble
2026-05-20 01:20:47.445639 | controller | ++ LOGO=ubuntu-logo
2026-05-20 01:20:47.445652 | controller | + [[ ubuntu =~ (almalinux|centos|rocky|rhel) ]]
2026-05-20 01:20:47.445664 | controller | + _ensure_lsb_release
2026-05-20 01:20:47.446724 | controller | ++ command -v lsb_release
2026-05-20 01:20:47.447528 | controller | + [[ -x /usr/bin/lsb_release ]]
2026-05-20 01:20:47.447544 | controller | + return
2026-05-20 01:20:47.448638 | controller | ++ lsb_release -r -s
2026-05-20 01:20:47.470748 | controller | + os_RELEASE=24.04
2026-05-20 01:20:47.471864 | controller | ++ lsb_release -c -s
2026-05-20 01:20:47.492329 | controller | + os_CODENAME=noble
2026-05-20 01:20:47.493445 | controller | ++ lsb_release -i -s
2026-05-20 01:20:47.513551 | controller | + os_VENDOR=Ubuntu
2026-05-20 01:20:47.513589 | controller | + [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:20:47.513720 | controller | + os_PACKAGE=deb
2026-05-20 01:20:47.513733 | controller | + typeset -xr os_VENDOR
2026-05-20 01:20:47.513746 | controller | + typeset -xr os_RELEASE
2026-05-20 01:20:47.513758 | controller | + typeset -xr os_PACKAGE
2026-05-20 01:20:47.513770 | controller | + typeset -xr os_CODENAME
2026-05-20 01:20:47.513890 | controller | + [[ Ubuntu =~ (Ubuntu) ]]
2026-05-20 01:20:47.513930 | controller | + DISTRO=noble
2026-05-20 01:20:47.513943 | controller | + typeset -xr DISTRO
2026-05-20 01:20:47.514066 | controller | + rm -f /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:47.516827 | controller | + extract_localrc_section /opt/stack/devstack/local.conf /opt/stack/devstack/localrc /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:47.516915 | controller | + local configfile=/opt/stack/devstack/local.conf
2026-05-20 01:20:47.516925 | controller | + local localrcfile=/opt/stack/devstack/localrc
2026-05-20 01:20:47.516938 | controller | + local localautofile=/opt/stack/devstack/.localrc.auto
2026-05-20 01:20:47.516946 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:47.518275 | controller | ++ get_meta_section_files /opt/stack/devstack/local.conf local
2026-05-20 01:20:47.518404 | controller | ++ local file=/opt/stack/devstack/local.conf
2026-05-20 01:20:47.518418 | controller | ++ local matchgroup=local
2026-05-20 01:20:47.518434 | controller | ++ [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:47.518529 | controller | ++ awk -v matchgroup=local '
2026-05-20 01:20:47.518539 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:20:47.518548 | controller |             gsub("[][]", "", $1);
2026-05-20 01:20:47.518556 | controller |             split($1, a, "|");
2026-05-20 01:20:47.518565 | controller |             if (a[1] == matchgroup)
2026-05-20 01:20:47.518574 | controller |                 print a[2]
2026-05-20 01:20:47.518582 | controller |         }
2026-05-20 01:20:47.518591 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:20:47.521250 | controller | + LRC=localrc
2026-05-20 01:20:47.521279 | controller | + for lfile in $LRC
2026-05-20 01:20:47.521291 | controller | + [[ localrc == \l\o\c\a\l\r\c ]]
2026-05-20 01:20:47.521326 | controller | + [[ -r /opt/stack/devstack/localrc ]]
2026-05-20 01:20:47.521380 | controller | + echo '# Generated file, do not edit'
2026-05-20 01:20:47.521557 | controller | + get_meta_section /opt/stack/devstack/local.conf local localrc
2026-05-20 01:20:47.521656 | controller | + local file=/opt/stack/devstack/local.conf
2026-05-20 01:20:47.521669 | controller | + local matchgroup=local
2026-05-20 01:20:47.521681 | controller | + local configfile=localrc
2026-05-20 01:20:47.521689 | controller | + [[ -r /opt/stack/devstack/local.conf ]]
2026-05-20 01:20:47.521701 | controller | + [[ -z localrc ]]
2026-05-20 01:20:47.521766 | controller | + awk -v matchgroup=local -v configfile=localrc '
2026-05-20 01:20:47.521775 | controller |         BEGIN { group = "" }
2026-05-20 01:20:47.521784 | controller |         /^\[\[.+\|.*\]\]/ {
2026-05-20 01:20:47.521807 | controller |             gsub("[][]", "", $1);
2026-05-20 01:20:47.521816 | controller |             split($1, a, "|");
2026-05-20 01:20:47.521825 | controller |             if (a[1] == matchgroup && a[2] == configfile) {
2026-05-20 01:20:47.521833 | controller |                 group=a[1]
2026-05-20 01:20:47.521841 | controller |             } else {
2026-05-20 01:20:47.521850 | controller |                 group=""
2026-05-20 01:20:47.521858 | controller |             }
2026-05-20 01:20:47.521866 | controller |             next
2026-05-20 01:20:47.521874 | controller |         }
2026-05-20 01:20:47.521882 | controller |         {
2026-05-20 01:20:47.521891 | controller |             if (group != "")
2026-05-20 01:20:47.521899 | controller |                 print $0
2026-05-20 01:20:47.521907 | controller |         }
2026-05-20 01:20:47.521915 | controller |     ' /opt/stack/devstack/local.conf
2026-05-20 01:20:47.525328 | controller | + [[ ! -r /opt/stack/devstack/stackrc ]]
2026-05-20 01:20:47.525360 | controller | + source /opt/stack/devstack/stackrc
2026-05-20 01:20:47.525498 | controller | ++ [[ -z '' ]]
2026-05-20 01:20:47.525512 | controller | ++ declare -r -g _DEVSTACK_STACKRC=1
2026-05-20 01:20:47.527617 | controller | ++++ dirname /opt/stack/devstack/stackrc
2026-05-20 01:20:47.528916 | controller | +++ cd /opt/stack/devstack
2026-05-20 01:20:47.528981 | controller | +++ pwd
2026-05-20 01:20:47.529730 | controller | ++ RC_DIR=/opt/stack/devstack
2026-05-20 01:20:47.529806 | controller | ++ source /opt/stack/devstack/functions
2026-05-20 01:20:47.530032 | controller | +++ [[ -z 1 ]]
2026-05-20 01:20:47.530042 | controller | +++ return 0
2026-05-20 01:20:47.530066 | controller | ++ TARGET_BRANCH=master
2026-05-20 01:20:47.530080 | controller | ++ TRAILING_TARGET_BRANCH=master
2026-05-20 01:20:47.530089 | controller | ++ BRANCHLESS_TARGET_BRANCH=master
2026-05-20 01:20:47.530101 | controller | ++ DEST=/opt/stack
2026-05-20 01:20:47.530113 | controller | ++ DATA_DIR=/opt/stack/data
2026-05-20 01:20:47.530131 | controller | ++ SERVICE_DIR=/opt/stack/status
2026-05-20 01:20:47.530193 | controller | ++ SUBUNIT_OUTPUT=/opt/stack/devstack.subunit
2026-05-20 01:20:47.530241 | controller | ++ [[ 1002 -eq 0 ]]
2026-05-20 01:20:47.531444 | controller | +++ whoami
2026-05-20 01:20:47.673275 | controller | ++ STACK_USER=stack
2026-05-20 01:20:47.673347 | controller | ++ REGION_NAME=RegionOne
2026-05-20 01:20:47.673380 | controller | ++ KEYSTONE_REGION_NAME=RegionOne
2026-05-20 01:20:47.673491 | controller | ++ isset ENABLED_SERVICES
2026-05-20 01:20:47.673524 | controller | ++ [[ -v ENABLED_SERVICES ]]
2026-05-20 01:20:47.673535 | controller | ++ ENABLED_SERVICES=key
2026-05-20 01:20:47.673547 | controller | ++ ENABLED_SERVICES+=,n-api,n-cpu,n-cond,n-sch,n-novnc,n-api-meta
2026-05-20 01:20:47.673559 | controller | ++ ENABLED_SERVICES+=,placement-api,placement-client
2026-05-20 01:20:47.673568 | controller | ++ ENABLED_SERVICES+=,g-api
2026-05-20 01:20:47.673579 | controller | ++ ENABLED_SERVICES+=,c-sch,c-api,c-vol
2026-05-20 01:20:47.673589 | controller | ++ ENABLED_SERVICES+=,ovn-controller,ovn-northd,ovs-vswitchd,ovsdb-server
2026-05-20 01:20:47.673598 | controller | ++ ENABLED_SERVICES+=,q-svc,q-ovn-agent
2026-05-20 01:20:47.673610 | controller | ++ ENABLED_SERVICES+=,horizon
2026-05-20 01:20:47.673618 | controller | ++ ENABLED_SERVICES+=,rabbit,tempest,mysql,etcd3,dstat
2026-05-20 01:20:47.673629 | controller | ++ ENABLE_HTTPD_MOD_WSGI_SERVICES=True
2026-05-20 01:20:47.673638 | controller | ++ NOVA_ENABLED_APIS=osapi_compute,metadata
2026-05-20 01:20:47.673669 | controller | ++ [[ -f /opt/stack/devstack/localrc ]]
2026-05-20 01:20:47.673681 | controller | ++ [[ -f /opt/stack/devstack/.localrc.auto ]]
2026-05-20 01:20:47.673710 | controller | ++ source /opt/stack/devstack/.localrc.auto
2026-05-20 01:20:47.673784 | controller | +++ disable_all_services
2026-05-20 01:20:47.673796 | controller | +++ ENABLED_SERVICES=
2026-05-20 01:20:47.673827 | controller | +++ enable_service c-api
2026-05-20 01:20:47.673869 | controller | +++ local xtrace
2026-05-20 01:20:47.675904 | controller | ++++ set +o
2026-05-20 01:20:47.676405 | controller | ++++ grep xtrace
2026-05-20 01:20:47.680183 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:47.680221 | controller | +++ set +o xtrace
2026-05-20 01:20:47.722338 | controller | +++ enable_service c-bak
2026-05-20 01:20:47.722372 | controller | +++ local xtrace
2026-05-20 01:20:47.725216 | controller | ++++ set +o
2026-05-20 01:20:47.725246 | controller | ++++ grep xtrace
2026-05-20 01:20:47.729548 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:47.729573 | controller | +++ set +o xtrace
2026-05-20 01:20:47.775312 | controller | +++ enable_service c-sch
2026-05-20 01:20:47.775356 | controller | +++ local xtrace
2026-05-20 01:20:47.777285 | controller | ++++ set +o
2026-05-20 01:20:47.777468 | controller | ++++ grep xtrace
2026-05-20 01:20:47.781683 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:47.781710 | controller | +++ set +o xtrace
2026-05-20 01:20:47.820600 | controller | +++ enable_service c-vol
2026-05-20 01:20:47.820635 | controller | +++ local xtrace
2026-05-20 01:20:47.822254 | controller | ++++ set +o
2026-05-20 01:20:47.822826 | controller | ++++ grep xtrace
2026-05-20 01:20:47.826305 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:47.826344 | controller | +++ set +o xtrace
2026-05-20 01:20:47.868338 | controller | +++ disable_service dstat
2026-05-20 01:20:47.868371 | controller | +++ local xtrace
2026-05-20 01:20:47.870321 | controller | ++++ set +o
2026-05-20 01:20:47.870385 | controller | ++++ grep xtrace
2026-05-20 01:20:47.873455 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:47.873486 | controller | +++ set +o xtrace
2026-05-20 01:20:47.904173 | controller | +++ enable_service etcd3
2026-05-20 01:20:47.904218 | controller | +++ local xtrace
2026-05-20 01:20:47.906130 | controller | ++++ set +o
2026-05-20 01:20:47.906594 | controller | ++++ grep xtrace
2026-05-20 01:20:47.910480 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:47.910518 | controller | +++ set +o xtrace
2026-05-20 01:20:47.952705 | controller | +++ enable_service file_tracker
2026-05-20 01:20:47.952733 | controller | +++ local xtrace
2026-05-20 01:20:47.954516 | controller | ++++ set +o
2026-05-20 01:20:47.954915 | controller | ++++ grep xtrace
2026-05-20 01:20:47.959079 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:47.959103 | controller | +++ set +o xtrace
2026-05-20 01:20:48.003290 | controller | +++ enable_service g-api
2026-05-20 01:20:48.003328 | controller | +++ local xtrace
2026-05-20 01:20:48.005224 | controller | ++++ set +o
2026-05-20 01:20:48.005683 | controller | ++++ grep xtrace
2026-05-20 01:20:48.009362 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.009387 | controller | +++ set +o xtrace
2026-05-20 01:20:48.052532 | controller | +++ disable_service horizon
2026-05-20 01:20:48.052572 | controller | +++ local xtrace
2026-05-20 01:20:48.054268 | controller | ++++ set +o
2026-05-20 01:20:48.054490 | controller | ++++ grep xtrace
2026-05-20 01:20:48.057773 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.057794 | controller | +++ set +o xtrace
2026-05-20 01:20:48.086106 | controller | +++ enable_service key
2026-05-20 01:20:48.086179 | controller | +++ local xtrace
2026-05-20 01:20:48.087906 | controller | ++++ set +o
2026-05-20 01:20:48.088426 | controller | ++++ grep xtrace
2026-05-20 01:20:48.091868 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.091912 | controller | +++ set +o xtrace
2026-05-20 01:20:48.136693 | controller | +++ enable_service memory_tracker
2026-05-20 01:20:48.136767 | controller | +++ local xtrace
2026-05-20 01:20:48.138644 | controller | ++++ set +o
2026-05-20 01:20:48.139002 | controller | ++++ grep xtrace
2026-05-20 01:20:48.141850 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.141885 | controller | +++ set +o xtrace
2026-05-20 01:20:48.186918 | controller | +++ enable_service mysql
2026-05-20 01:20:48.186956 | controller | +++ local xtrace
2026-05-20 01:20:48.188695 | controller | ++++ set +o
2026-05-20 01:20:48.189048 | controller | ++++ grep xtrace
2026-05-20 01:20:48.192334 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.192353 | controller | +++ set +o xtrace
2026-05-20 01:20:48.235231 | controller | +++ enable_service n-api
2026-05-20 01:20:48.235263 | controller | +++ local xtrace
2026-05-20 01:20:48.237019 | controller | ++++ set +o
2026-05-20 01:20:48.237372 | controller | ++++ grep xtrace
2026-05-20 01:20:48.240703 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.240723 | controller | +++ set +o xtrace
2026-05-20 01:20:48.286306 | controller | +++ enable_service n-api-meta
2026-05-20 01:20:48.286352 | controller | +++ local xtrace
2026-05-20 01:20:48.286359 | controller | ++++ set +o
2026-05-20 01:20:48.286365 | controller | ++++ grep xtrace
2026-05-20 01:20:48.289139 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.289185 | controller | +++ set +o xtrace
2026-05-20 01:20:48.328944 | controller | +++ enable_service n-cond
2026-05-20 01:20:48.329030 | controller | +++ local xtrace
2026-05-20 01:20:48.331319 | controller | ++++ set +o
2026-05-20 01:20:48.331687 | controller | ++++ grep xtrace
2026-05-20 01:20:48.335593 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.335615 | controller | +++ set +o xtrace
2026-05-20 01:20:48.381509 | controller | +++ enable_service n-cpu
2026-05-20 01:20:48.381535 | controller | +++ local xtrace
2026-05-20 01:20:48.384025 | controller | ++++ set +o
2026-05-20 01:20:48.384041 | controller | ++++ grep xtrace
2026-05-20 01:20:48.388601 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.388678 | controller | +++ set +o xtrace
2026-05-20 01:20:48.433800 | controller | +++ enable_service n-novnc
2026-05-20 01:20:48.433846 | controller | +++ local xtrace
2026-05-20 01:20:48.435638 | controller | ++++ set +o
2026-05-20 01:20:48.435982 | controller | ++++ grep xtrace
2026-05-20 01:20:48.439333 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.439363 | controller | +++ set +o xtrace
2026-05-20 01:20:48.482962 | controller | +++ enable_service n-sch
2026-05-20 01:20:48.482998 | controller | +++ local xtrace
2026-05-20 01:20:48.484287 | controller | ++++ set +o
2026-05-20 01:20:48.484693 | controller | ++++ grep xtrace
2026-05-20 01:20:48.487415 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.487449 | controller | +++ set +o xtrace
2026-05-20 01:20:48.531476 | controller | +++ enable_service o-api
2026-05-20 01:20:48.531510 | controller | +++ local xtrace
2026-05-20 01:20:48.533464 | controller | ++++ set +o
2026-05-20 01:20:48.533717 | controller | ++++ grep xtrace
2026-05-20 01:20:48.537279 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.537310 | controller | +++ set +o xtrace
2026-05-20 01:20:48.576887 | controller | +++ enable_service o-da
2026-05-20 01:20:48.576920 | controller | +++ local xtrace
2026-05-20 01:20:48.578329 | controller | ++++ set +o
2026-05-20 01:20:48.578930 | controller | ++++ grep xtrace
2026-05-20 01:20:48.582394 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.582487 | controller | +++ set +o xtrace
2026-05-20 01:20:48.625968 | controller | +++ enable_service o-hk
2026-05-20 01:20:48.625991 | controller | +++ local xtrace
2026-05-20 01:20:48.627642 | controller | ++++ set +o
2026-05-20 01:20:48.628117 | controller | ++++ grep xtrace
2026-05-20 01:20:48.631541 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.631571 | controller | +++ set +o xtrace
2026-05-20 01:20:48.674360 | controller | +++ enable_service octavia
2026-05-20 01:20:48.674394 | controller | +++ local xtrace
2026-05-20 01:20:48.676355 | controller | ++++ set +o
2026-05-20 01:20:48.676434 | controller | ++++ grep xtrace
2026-05-20 01:20:48.679812 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.679833 | controller | +++ set +o xtrace
2026-05-20 01:20:48.724389 | controller | +++ enable_service openstack-cli-server
2026-05-20 01:20:48.724411 | controller | +++ local xtrace
2026-05-20 01:20:48.726361 | controller | ++++ set +o
2026-05-20 01:20:48.726875 | controller | ++++ grep xtrace
2026-05-20 01:20:48.730937 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.730964 | controller | +++ set +o xtrace
2026-05-20 01:20:48.773476 | controller | +++ enable_service ovn-controller
2026-05-20 01:20:48.773500 | controller | +++ local xtrace
2026-05-20 01:20:48.775387 | controller | ++++ set +o
2026-05-20 01:20:48.775773 | controller | ++++ grep xtrace
2026-05-20 01:20:48.779134 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.779216 | controller | +++ set +o xtrace
2026-05-20 01:20:48.820485 | controller | +++ enable_service ovn-northd
2026-05-20 01:20:48.820508 | controller | +++ local xtrace
2026-05-20 01:20:48.822691 | controller | ++++ grep xtrace
2026-05-20 01:20:48.823325 | controller | ++++ set +o
2026-05-20 01:20:48.825479 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.825507 | controller | +++ set +o xtrace
2026-05-20 01:20:48.868420 | controller | +++ enable_service ovs-vswitchd
2026-05-20 01:20:48.868441 | controller | +++ local xtrace
2026-05-20 01:20:48.870387 | controller | ++++ set +o
2026-05-20 01:20:48.870757 | controller | ++++ grep xtrace
2026-05-20 01:20:48.874401 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.874419 | controller | +++ set +o xtrace
2026-05-20 01:20:48.918695 | controller | +++ enable_service ovsdb-server
2026-05-20 01:20:48.918726 | controller | +++ local xtrace
2026-05-20 01:20:48.920211 | controller | ++++ set +o
2026-05-20 01:20:48.920462 | controller | ++++ grep xtrace
2026-05-20 01:20:48.923621 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.923664 | controller | +++ set +o xtrace
2026-05-20 01:20:48.966976 | controller | +++ enable_service placement-api
2026-05-20 01:20:48.967010 | controller | +++ local xtrace
2026-05-20 01:20:48.968883 | controller | ++++ set +o
2026-05-20 01:20:48.969515 | controller | ++++ grep xtrace
2026-05-20 01:20:48.973727 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:48.973759 | controller | +++ set +o xtrace
2026-05-20 01:20:49.015422 | controller | +++ enable_service q-ovn-agent
2026-05-20 01:20:49.015453 | controller | +++ local xtrace
2026-05-20 01:20:49.017099 | controller | ++++ set +o
2026-05-20 01:20:49.017476 | controller | ++++ grep xtrace
2026-05-20 01:20:49.020986 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.021004 | controller | +++ set +o xtrace
2026-05-20 01:20:49.065413 | controller | +++ enable_service q-svc
2026-05-20 01:20:49.065444 | controller | +++ local xtrace
2026-05-20 01:20:49.067344 | controller | ++++ set +o
2026-05-20 01:20:49.067570 | controller | ++++ grep xtrace
2026-05-20 01:20:49.070723 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.070750 | controller | +++ set +o xtrace
2026-05-20 01:20:49.115217 | controller | +++ enable_service rabbit
2026-05-20 01:20:49.115287 | controller | +++ local xtrace
2026-05-20 01:20:49.117071 | controller | ++++ set +o
2026-05-20 01:20:49.117515 | controller | ++++ grep xtrace
2026-05-20 01:20:49.121494 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.121520 | controller | +++ set +o xtrace
2026-05-20 01:20:49.165930 | controller | +++ disable_service s-account
2026-05-20 01:20:49.165998 | controller | +++ local xtrace
2026-05-20 01:20:49.168033 | controller | ++++ set +o
2026-05-20 01:20:49.168050 | controller | ++++ grep xtrace
2026-05-20 01:20:49.171690 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.171744 | controller | +++ set +o xtrace
2026-05-20 01:20:49.202814 | controller | +++ disable_service s-container
2026-05-20 01:20:49.202875 | controller | +++ local xtrace
2026-05-20 01:20:49.204533 | controller | ++++ set +o
2026-05-20 01:20:49.204991 | controller | ++++ grep xtrace
2026-05-20 01:20:49.208369 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.208409 | controller | +++ set +o xtrace
2026-05-20 01:20:49.240550 | controller | +++ disable_service s-object
2026-05-20 01:20:49.240608 | controller | +++ local xtrace
2026-05-20 01:20:49.242302 | controller | ++++ set +o
2026-05-20 01:20:49.242845 | controller | ++++ grep xtrace
2026-05-20 01:20:49.246255 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.246284 | controller | +++ set +o xtrace
2026-05-20 01:20:49.276710 | controller | +++ disable_service s-proxy
2026-05-20 01:20:49.276772 | controller | +++ local xtrace
2026-05-20 01:20:49.278553 | controller | ++++ set +o
2026-05-20 01:20:49.278730 | controller | ++++ grep xtrace
2026-05-20 01:20:49.281232 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.281256 | controller | +++ set +o xtrace
2026-05-20 01:20:49.308623 | controller | +++ disable_service tempest
2026-05-20 01:20:49.308682 | controller | +++ local xtrace
2026-05-20 01:20:49.310397 | controller | ++++ set +o
2026-05-20 01:20:49.310769 | controller | ++++ grep xtrace
2026-05-20 01:20:49.314275 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.314295 | controller | +++ set +o xtrace
2026-05-20 01:20:49.345009 | controller | +++ enable_service tls-proxy
2026-05-20 01:20:49.345069 | controller | +++ local xtrace
2026-05-20 01:20:49.346819 | controller | ++++ set +o
2026-05-20 01:20:49.347256 | controller | ++++ grep xtrace
2026-05-20 01:20:49.350918 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.350940 | controller | +++ set +o xtrace
2026-05-20 01:20:49.392772 | controller | +++ ADMIN_PASSWORD=secretadmin
2026-05-20 01:20:49.392848 | controller | +++ DATABASE_PASSWORD=secretdatabase
2026-05-20 01:20:49.392856 | controller | +++ DEBUG_LIBVIRT_COREDUMPS=True
2026-05-20 01:20:49.392869 | controller | +++ DISABLE_AMP_IMAGE_BUILD=True
2026-05-20 01:20:49.392874 | controller | +++ ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:20:49.392879 | controller | +++ ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:20:49.392884 | controller | +++ ENABLE_ZSWAP=True
2026-05-20 01:20:49.392890 | controller | +++ ERROR_ON_CLONE=True
2026-05-20 01:20:49.392896 | controller | +++ FIXED_RANGE=10.1.0.0/20
2026-05-20 01:20:49.392901 | controller | +++ FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:20:49.392906 | controller | +++ GIT_BASE=https://github.com
2026-05-20 01:20:49.392911 | controller | +++ HOST_IP=199.204.45.38
2026-05-20 01:20:49.392916 | controller | +++ IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:20:49.392921 | controller | +++ LIBVIRT_TYPE=qemu
2026-05-20 01:20:49.392926 | controller | +++ LOGFILE=/opt/stack/logs/devstacklog.txt
2026-05-20 01:20:49.392932 | controller | +++ LOG_COLOR=False
2026-05-20 01:20:49.392938 | controller | +++ MAGNUM_GUEST_IMAGE_URL=https://github.com/vexxhost/capo-image-elements/releases/latest/download/ubuntu-22.04-v1.34.7.qcow2
2026-05-20 01:20:49.392944 | controller | +++ MANILA_DEFAULT_SHARE_TYPE_EXTRA_SPECS='snapshot_support=True create_share_from_snapshot_support=True'
2026-05-20 01:20:49.392950 | controller | +++ MANILA_ENABLED_BACKENDS=generic
2026-05-20 01:20:49.392955 | controller | +++ MANILA_USE_SERVICE_INSTANCE_PASSWORD=True
2026-05-20 01:20:49.392964 | controller | +++ NETWORK_GATEWAY=10.1.0.1
2026-05-20 01:20:49.392969 | controller | +++ NOVA_LIBVIRT_TB_CACHE_SIZE=128
2026-05-20 01:20:49.392976 | controller | +++ NOVA_VNC_ENABLED=True
2026-05-20 01:20:49.392981 | controller | +++ OCTAVIA_NODE=api
2026-05-20 01:20:49.392986 | controller | +++ OVN_DBS_LOG_LEVEL=dbg
2026-05-20 01:20:49.392990 | controller | +++ PUBLIC_BRIDGE_MTU=1430
2026-05-20 01:20:49.392995 | controller | +++ PUBLIC_NETWORK_GATEWAY=172.24.5.1
2026-05-20 01:20:49.393000 | controller | +++ RABBIT_PASSWORD=secretrabbit
2026-05-20 01:20:49.393005 | controller | +++ SERVICE_HOST=199.204.45.38
2026-05-20 01:20:49.393010 | controller | +++ SERVICE_PASSWORD=secretservice
2026-05-20 01:20:49.393015 | controller | +++ SWIFT_HASH=1234123412341234
2026-05-20 01:20:49.393022 | controller | +++ SWIFT_REPLICAS=1
2026-05-20 01:20:49.393027 | controller | +++ SWIFT_START_ALL_SERVICES=False
2026-05-20 01:20:49.393032 | controller | +++ VERBOSE=True
2026-05-20 01:20:49.393079 | controller | +++ VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:20:49.393086 | controller | +++ LIBS_FROM_GIT=novnc,barbican,cinder,devstack,glance,keystone,magnum,manila,neutron,nova,octavia,os-test-images,ovn-octavia-provider,placement,python-magnumclient,requirements,swift,magnum-cluster-api
2026-05-20 01:20:49.393095 | controller | +++ enable_plugin barbican https://github.com/openstack/barbican
2026-05-20 01:20:49.393101 | controller | +++ local name=barbican
2026-05-20 01:20:49.393105 | controller | +++ local url=https://github.com/openstack/barbican
2026-05-20 01:20:49.393113 | controller | +++ local branch=master
2026-05-20 01:20:49.393118 | controller | +++ is_plugin_enabled barbican
2026-05-20 01:20:49.393193 | controller | +++ local name=barbican
2026-05-20 01:20:49.393207 | controller | +++ [[ ,, =~ ,barbican, ]]
2026-05-20 01:20:49.393293 | controller | +++ return 1
2026-05-20 01:20:49.393305 | controller | +++ DEVSTACK_PLUGINS+=,barbican
2026-05-20 01:20:49.393313 | controller | +++ GITREPO[$name]=https://github.com/openstack/barbican
2026-05-20 01:20:49.393320 | controller | +++ GITDIR[$name]=/opt/stack/barbican
2026-05-20 01:20:49.393346 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:49.393379 | controller | +++ enable_plugin magnum https://review.opendev.org/openstack/magnum
2026-05-20 01:20:49.393389 | controller | +++ local name=magnum
2026-05-20 01:20:49.393396 | controller | +++ local url=https://review.opendev.org/openstack/magnum
2026-05-20 01:20:49.393420 | controller | +++ local branch=master
2026-05-20 01:20:49.393428 | controller | +++ is_plugin_enabled magnum
2026-05-20 01:20:49.393436 | controller | +++ local name=magnum
2026-05-20 01:20:49.393463 | controller | +++ [[ ,,barbican, =~ ,magnum, ]]
2026-05-20 01:20:49.393485 | controller | +++ return 1
2026-05-20 01:20:49.393494 | controller | +++ DEVSTACK_PLUGINS+=,magnum
2026-05-20 01:20:49.393501 | controller | +++ GITREPO[$name]=https://review.opendev.org/openstack/magnum
2026-05-20 01:20:49.393524 | controller | +++ GITDIR[$name]=/opt/stack/magnum
2026-05-20 01:20:49.393532 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:49.393570 | controller | +++ enable_plugin octavia https://github.com/openstack/octavia
2026-05-20 01:20:49.393578 | controller | +++ local name=octavia
2026-05-20 01:20:49.393586 | controller | +++ local url=https://github.com/openstack/octavia
2026-05-20 01:20:49.393593 | controller | +++ local branch=master
2026-05-20 01:20:49.393617 | controller | +++ is_plugin_enabled octavia
2026-05-20 01:20:49.393625 | controller | +++ local name=octavia
2026-05-20 01:20:49.393632 | controller | +++ [[ ,,barbican,magnum, =~ ,octavia, ]]
2026-05-20 01:20:49.393663 | controller | +++ return 1
2026-05-20 01:20:49.393671 | controller | +++ DEVSTACK_PLUGINS+=,octavia
2026-05-20 01:20:49.393678 | controller | +++ GITREPO[$name]=https://github.com/openstack/octavia
2026-05-20 01:20:49.393685 | controller | +++ GITDIR[$name]=/opt/stack/octavia
2026-05-20 01:20:49.393712 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:49.393740 | controller | +++ enable_plugin manila https://github.com/openstack/manila
2026-05-20 01:20:49.393747 | controller | +++ local name=manila
2026-05-20 01:20:49.393754 | controller | +++ local url=https://github.com/openstack/manila
2026-05-20 01:20:49.393777 | controller | +++ local branch=master
2026-05-20 01:20:49.393785 | controller | +++ is_plugin_enabled manila
2026-05-20 01:20:49.393791 | controller | +++ local name=manila
2026-05-20 01:20:49.393815 | controller | +++ [[ ,,barbican,magnum,octavia, =~ ,manila, ]]
2026-05-20 01:20:49.393843 | controller | +++ return 1
2026-05-20 01:20:49.393851 | controller | +++ DEVSTACK_PLUGINS+=,manila
2026-05-20 01:20:49.393858 | controller | +++ GITREPO[$name]=https://github.com/openstack/manila
2026-05-20 01:20:49.393867 | controller | +++ GITDIR[$name]=/opt/stack/manila
2026-05-20 01:20:49.393875 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:49.393913 | controller | +++ enable_plugin magnum-cluster-api https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:49.393922 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:20:49.393929 | controller | +++ local url=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:49.393951 | controller | +++ local branch=master
2026-05-20 01:20:49.393959 | controller | +++ is_plugin_enabled magnum-cluster-api
2026-05-20 01:20:49.393967 | controller | +++ local name=magnum-cluster-api
2026-05-20 01:20:49.393993 | controller | +++ [[ ,,barbican,magnum,octavia,manila, =~ ,magnum-cluster-api, ]]
2026-05-20 01:20:49.394047 | controller | +++ return 1
2026-05-20 01:20:49.394056 | controller | +++ DEVSTACK_PLUGINS+=,magnum-cluster-api
2026-05-20 01:20:49.394063 | controller | +++ GITREPO[$name]=https://github.com/vexxhost/magnum-cluster-api
2026-05-20 01:20:49.394078 | controller | +++ GITDIR[$name]=/opt/stack/magnum-cluster-api
2026-05-20 01:20:49.394085 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:49.394126 | controller | +++ enable_plugin ovn-octavia-provider https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:49.394135 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:20:49.394184 | controller | +++ local url=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:49.394190 | controller | +++ local branch=master
2026-05-20 01:20:49.394198 | controller | +++ is_plugin_enabled ovn-octavia-provider
2026-05-20 01:20:49.394204 | controller | +++ local name=ovn-octavia-provider
2026-05-20 01:20:49.394233 | controller | +++ [[ ,,barbican,magnum,octavia,manila,magnum-cluster-api, =~ ,ovn-octavia-provider, ]]
2026-05-20 01:20:49.394261 | controller | +++ return 1
2026-05-20 01:20:49.394269 | controller | +++ DEVSTACK_PLUGINS+=,ovn-octavia-provider
2026-05-20 01:20:49.394277 | controller | +++ GITREPO[$name]=https://github.com/openstack/ovn-octavia-provider
2026-05-20 01:20:49.394301 | controller | +++ GITDIR[$name]=/opt/stack/ovn-octavia-provider
2026-05-20 01:20:49.394307 | controller | +++ GITBRANCH[$name]=master
2026-05-20 01:20:49.394412 | controller | ++ CELLSV2_SETUP=superconductor
2026-05-20 01:20:49.394419 | controller | ++ HORIZON_APACHE_ROOT=/dashboard
2026-05-20 01:20:49.395296 | controller | +++ trueorfalse False USER_UNITS
2026-05-20 01:20:49.395425 | controller | +++ local xtrace
2026-05-20 01:20:49.397221 | controller | ++++ set +o
2026-05-20 01:20:49.397640 | controller | ++++ grep xtrace
2026-05-20 01:20:49.401449 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.401469 | controller | +++ set +o xtrace
2026-05-20 01:20:49.402462 | controller | ++ USER_UNITS=False
2026-05-20 01:20:49.402530 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:20:49.402539 | controller | ++ SYSTEMD_DIR=/etc/systemd/system
2026-05-20 01:20:49.402544 | controller | ++ SYSTEMCTL='sudo systemctl'
2026-05-20 01:20:49.402552 | controller | ++ [[ -r /opt/stack/devstack/.localrc.password ]]
2026-05-20 01:20:49.403616 | controller | +++ _get_python_version python3
2026-05-20 01:20:49.403765 | controller | +++ local interp=python3
2026-05-20 01:20:49.403775 | controller | +++ local version
2026-05-20 01:20:49.405067 | controller | ++++ which python3
2026-05-20 01:20:49.409143 | controller | +++ [[ -x /usr/bin/python3 ]]
2026-05-20 01:20:49.410350 | controller | ++++ python3 -c 'import sys; print("%s.%s" % sys.version_info[0:2])'
2026-05-20 01:20:49.429661 | controller | +++ version=3.12
2026-05-20 01:20:49.429732 | controller | +++ echo 3.12
2026-05-20 01:20:49.430524 | controller | ++ _DEFAULT_PYTHON3_VERSION=3.12
2026-05-20 01:20:49.430619 | controller | ++ export PYTHON3_VERSION=3.12
2026-05-20 01:20:49.430630 | controller | ++ PYTHON3_VERSION=3.12
2026-05-20 01:20:49.430643 | controller | ++ export 'VIRTUALENV_CMD=python3.12 -m venv'
2026-05-20 01:20:49.430652 | controller | ++ VIRTUALENV_CMD='python3.12 -m venv'
2026-05-20 01:20:49.430702 | controller | ++ '[' -t 1 ']'
2026-05-20 01:20:49.430712 | controller | ++ _LOG_COLOR_DEFAULT=False
2026-05-20 01:20:49.432151 | controller | +++ trueorfalse False LOG_COLOR
2026-05-20 01:20:49.432471 | controller | +++ local xtrace
2026-05-20 01:20:49.434923 | controller | ++++ set +o
2026-05-20 01:20:49.435273 | controller | ++++ grep xtrace
2026-05-20 01:20:49.438142 | controller | +++ xtrace='set -o xtrace'
2026-05-20 01:20:49.438240 | controller | +++ set +o xtrace
2026-05-20 01:20:49.439460 | controller | ++ LOG_COLOR=False
2026-05-20 01:20:49.439601 | controller | ++ [[ False == \T\r\u\e ]]
2026-05-20 01:20:49.439612 | controller | ++ export 'PS4=+ $(short_source):   '
2026-05-20 01:20:49.439621 | controller | ++ PS4='+ $(short_source):   '
2026-05-20 01:20:49.444524 | controller | +++ stackrc:source:167                       :   trueorfalse False ENFORCE_SCOPE
2026-05-20 01:20:49.448734 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:49.455017 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:49.455932 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:49.464117 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:49.468486 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:49.473694 | controller | ++ stackrc:source:167                       :   ENFORCE_SCOPE=False
2026-05-20 01:20:49.478566 | controller | ++ stackrc:source:174                       :   DEVSTACK_VENV=/opt/stack/data/venv
2026-05-20 01:20:49.482809 | controller | ++ stackrc:source:180                       :   [[ Ubuntu =~ (CentOSStream|Rocky) ]]
2026-05-20 01:20:49.488640 | controller | +++ stackrc:source:183                       :   trueorfalse True GLOBAL_VENV
2026-05-20 01:20:49.492174 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:49.497705 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:49.498831 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:49.506556 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:49.510604 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:49.515909 | controller | ++ stackrc:source:183                       :   GLOBAL_VENV=True
2026-05-20 01:20:49.521679 | controller | +++ stackrc:source:190                       :   trueorfalse False USE_VENV
2026-05-20 01:20:49.526173 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:49.532636 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:49.532812 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:49.540903 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:49.544554 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:49.548879 | controller | ++ stackrc:source:190                       :   USE_VENV=False
2026-05-20 01:20:49.553178 | controller | ++ stackrc:source:195                       :   ADDITIONAL_VENV_PACKAGES=
2026-05-20 01:20:49.558923 | controller | +++ stackrc:source:199                       :   trueorfalse False DATABASE_QUERY_LOGGING
2026-05-20 01:20:49.563172 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:49.568959 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:49.569843 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:49.576674 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:49.580881 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:49.585897 | controller | ++ stackrc:source:199                       :   DATABASE_QUERY_LOGGING=False
2026-05-20 01:20:49.591044 | controller | +++ stackrc:source:203                       :   trueorfalse True MYSQL_GATHER_PERFORMANCE
2026-05-20 01:20:49.595163 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:49.601051 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:49.601491 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:49.610103 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:49.614334 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:49.619646 | controller | ++ stackrc:source:203                       :   MYSQL_GATHER_PERFORMANCE=True
2026-05-20 01:20:49.625273 | controller | +++ stackrc:source:208                       :   trueorfalse True MYSQL_REDUCE_MEMORY
2026-05-20 01:20:49.629596 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:49.636246 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:49.636727 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:49.644117 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:49.647362 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:49.652148 | controller | ++ stackrc:source:208                       :   MYSQL_REDUCE_MEMORY=True
2026-05-20 01:20:49.656287 | controller | ++ stackrc:source:219                       :   GIT_TIMEOUT=0
2026-05-20 01:20:49.660242 | controller | ++ stackrc:source:225                       :   WSGI_MODE=uwsgi
2026-05-20 01:20:49.663911 | controller | ++ stackrc:source:226                       :   [[ uwsgi != \u\w\s\g\i ]]
2026-05-20 01:20:49.668541 | controller | ++ stackrc:source:234                       :   GIT_BASE=https://github.com
2026-05-20 01:20:49.672966 | controller | ++ stackrc:source:237                       :   REQUIREMENTS_DIR=/opt/stack/requirements
2026-05-20 01:20:49.676731 | controller | ++ stackrc:source:255                       :   DEVSTACK_SERIES=2026.2
2026-05-20 01:20:49.680869 | controller | ++ stackrc:source:264                       :   CINDER_REPO=https://github.com/openstack/cinder.git
2026-05-20 01:20:49.685267 | controller | ++ stackrc:source:265                       :   CINDER_BRANCH=master
2026-05-20 01:20:49.689212 | controller | ++ stackrc:source:268                       :   GLANCE_REPO=https://github.com/openstack/glance.git
2026-05-20 01:20:49.693228 | controller | ++ stackrc:source:269                       :   GLANCE_BRANCH=master
2026-05-20 01:20:49.697107 | controller | ++ stackrc:source:272                       :   HORIZON_REPO=https://github.com/openstack/horizon.git
2026-05-20 01:20:49.700993 | controller | ++ stackrc:source:273                       :   HORIZON_BRANCH=master
2026-05-20 01:20:49.705363 | controller | ++ stackrc:source:276                       :   KEYSTONE_REPO=https://github.com/openstack/keystone.git
2026-05-20 01:20:49.709058 | controller | ++ stackrc:source:277                       :   KEYSTONE_BRANCH=master
2026-05-20 01:20:49.713388 | controller | ++ stackrc:source:280                       :   NEUTRON_REPO=https://github.com/openstack/neutron.git
2026-05-20 01:20:49.717843 | controller | ++ stackrc:source:281                       :   NEUTRON_BRANCH=master
2026-05-20 01:20:49.721997 | controller | ++ stackrc:source:284                       :   NOVA_REPO=https://github.com/openstack/nova.git
2026-05-20 01:20:49.726261 | controller | ++ stackrc:source:285                       :   NOVA_BRANCH=master
2026-05-20 01:20:49.730159 | controller | ++ stackrc:source:288                       :   SWIFT_REPO=https://github.com/openstack/swift.git
2026-05-20 01:20:49.734012 | controller | ++ stackrc:source:289                       :   SWIFT_BRANCH=master
2026-05-20 01:20:49.738579 | controller | ++ stackrc:source:292                       :   PLACEMENT_REPO=https://github.com/openstack/placement.git
2026-05-20 01:20:49.742880 | controller | ++ stackrc:source:293                       :   PLACEMENT_BRANCH=master
2026-05-20 01:20:49.746978 | controller | ++ stackrc:source:302                       :   REQUIREMENTS_REPO=https://github.com/openstack/requirements.git
2026-05-20 01:20:49.751082 | controller | ++ stackrc:source:303                       :   REQUIREMENTS_BRANCH=master
2026-05-20 01:20:49.755090 | controller | ++ stackrc:source:306                       :   TEMPEST_REPO=https://github.com/openstack/tempest.git
2026-05-20 01:20:49.758957 | controller | ++ stackrc:source:307                       :   TEMPEST_BRANCH=master
2026-05-20 01:20:49.763159 | controller | ++ stackrc:source:308                       :   TEMPEST_VENV_UPPER_CONSTRAINTS=master
2026-05-20 01:20:49.767599 | controller | ++ stackrc:source:310                       :   OSTESTIMAGES_REPO=https://github.com/openstack/os-test-images.git
2026-05-20 01:20:49.771893 | controller | ++ stackrc:source:311                       :   OSTESTIMAGES_BRANCH=master
2026-05-20 01:20:49.775837 | controller | ++ stackrc:source:312                       :   OSTESTIMAGES_DIR=/opt/stack/os-test-images
2026-05-20 01:20:49.780159 | controller | ++ stackrc:source:322                       :   GITREPO["python-cinderclient"]=https://github.com/openstack/python-cinderclient.git
2026-05-20 01:20:49.784114 | controller | ++ stackrc:source:323                       :   GITBRANCH["python-cinderclient"]=master
2026-05-20 01:20:49.788501 | controller | ++ stackrc:source:326                       :   GITREPO["python-brick-cinderclient-ext"]=https://github.com/openstack/python-brick-cinderclient-ext.git
2026-05-20 01:20:49.792624 | controller | ++ stackrc:source:327                       :   GITBRANCH["python-brick-cinderclient-ext"]=master
2026-05-20 01:20:49.796688 | controller | ++ stackrc:source:330                       :   GITREPO["python-barbicanclient"]=https://github.com/openstack/python-barbicanclient.git
2026-05-20 01:20:49.800504 | controller | ++ stackrc:source:331                       :   GITBRANCH["python-barbicanclient"]=master
2026-05-20 01:20:49.805027 | controller | ++ stackrc:source:332                       :   GITDIR["python-barbicanclient"]=/opt/stack/python-barbicanclient
2026-05-20 01:20:49.809284 | controller | ++ stackrc:source:335                       :   GITREPO["python-glanceclient"]=https://github.com/openstack/python-glanceclient.git
2026-05-20 01:20:49.813077 | controller | ++ stackrc:source:336                       :   GITBRANCH["python-glanceclient"]=master
2026-05-20 01:20:49.817013 | controller | ++ stackrc:source:339                       :   GITREPO["python-ironicclient"]=https://github.com/openstack/python-ironicclient.git
2026-05-20 01:20:49.820988 | controller | ++ stackrc:source:340                       :   GITBRANCH["python-ironicclient"]=master
2026-05-20 01:20:49.825020 | controller | ++ stackrc:source:342                       :   GITDIR["python-ironicclient"]=/opt/stack/python-ironicclient
2026-05-20 01:20:49.828939 | controller | ++ stackrc:source:345                       :   GITREPO["keystoneauth"]=https://github.com/openstack/keystoneauth.git
2026-05-20 01:20:49.833768 | controller | ++ stackrc:source:346                       :   GITBRANCH["keystoneauth"]=master
2026-05-20 01:20:49.837390 | controller | ++ stackrc:source:349                       :   GITREPO["python-keystoneclient"]=https://github.com/openstack/python-keystoneclient.git
2026-05-20 01:20:49.841240 | controller | ++ stackrc:source:350                       :   GITBRANCH["python-keystoneclient"]=master
2026-05-20 01:20:49.845464 | controller | ++ stackrc:source:353                       :   GITREPO["python-neutronclient"]=https://github.com/openstack/python-neutronclient.git
2026-05-20 01:20:49.849067 | controller | ++ stackrc:source:354                       :   GITBRANCH["python-neutronclient"]=master
2026-05-20 01:20:49.852984 | controller | ++ stackrc:source:357                       :   GITREPO["python-novaclient"]=https://github.com/openstack/python-novaclient.git
2026-05-20 01:20:49.856949 | controller | ++ stackrc:source:358                       :   GITBRANCH["python-novaclient"]=master
2026-05-20 01:20:49.860844 | controller | ++ stackrc:source:361                       :   GITREPO["python-swiftclient"]=https://github.com/openstack/python-swiftclient.git
2026-05-20 01:20:49.864957 | controller | ++ stackrc:source:362                       :   GITBRANCH["python-swiftclient"]=master
2026-05-20 01:20:49.868829 | controller | ++ stackrc:source:365                       :   GITREPO["python-openstackclient"]=https://github.com/openstack/python-openstackclient.git
2026-05-20 01:20:49.872681 | controller | ++ stackrc:source:366                       :   GITBRANCH["python-openstackclient"]=master
2026-05-20 01:20:49.876969 | controller | ++ stackrc:source:368                       :   GITDIR["python-openstackclient"]=/opt/stack/python-openstackclient
2026-05-20 01:20:49.881179 | controller | ++ stackrc:source:371                       :   GITREPO["osc-placement"]=https://github.com/openstack/osc-placement.git
2026-05-20 01:20:49.884990 | controller | ++ stackrc:source:372                       :   GITBRANCH["osc-placement"]=master
2026-05-20 01:20:49.889181 | controller | ++ stackrc:source:383                       :   GITREPO["castellan"]=https://github.com/openstack/castellan.git
2026-05-20 01:20:49.893704 | controller | ++ stackrc:source:384                       :   GITBRANCH["castellan"]=master
2026-05-20 01:20:49.897582 | controller | ++ stackrc:source:387                       :   GITREPO["cliff"]=https://github.com/openstack/cliff.git
2026-05-20 01:20:49.901076 | controller | ++ stackrc:source:388                       :   GITBRANCH["cliff"]=master
2026-05-20 01:20:49.905163 | controller | ++ stackrc:source:391                       :   GITREPO["futurist"]=https://github.com/openstack/futurist.git
2026-05-20 01:20:49.908151 | controller | ++ stackrc:source:392                       :   GITBRANCH["futurist"]=master
2026-05-20 01:20:49.911011 | controller | ++ stackrc:source:395                       :   GITREPO["debtcollector"]=https://github.com/openstack/debtcollector.git
2026-05-20 01:20:49.915237 | controller | ++ stackrc:source:396                       :   GITBRANCH["debtcollector"]=master
2026-05-20 01:20:49.919171 | controller | ++ stackrc:source:399                       :   GITREPO["etcd3gw"]=https://github.com/openstack/etcd3gw.git
2026-05-20 01:20:49.923324 | controller | ++ stackrc:source:400                       :   GITBRANCH["etcd3gw"]=master
2026-05-20 01:20:49.927456 | controller | ++ stackrc:source:403                       :   GITREPO["automaton"]=https://github.com/openstack/automaton.git
2026-05-20 01:20:49.931478 | controller | ++ stackrc:source:404                       :   GITBRANCH["automaton"]=master
2026-05-20 01:20:49.936453 | controller | ++ stackrc:source:407                       :   GITREPO["oslo.cache"]=https://github.com/openstack/oslo.cache.git
2026-05-20 01:20:49.940648 | controller | ++ stackrc:source:408                       :   GITBRANCH["oslo.cache"]=master
2026-05-20 01:20:49.944233 | controller | ++ stackrc:source:411                       :   GITREPO["oslo.concurrency"]=https://github.com/openstack/oslo.concurrency.git
2026-05-20 01:20:49.947621 | controller | ++ stackrc:source:412                       :   GITBRANCH["oslo.concurrency"]=master
2026-05-20 01:20:49.951534 | controller | ++ stackrc:source:415                       :   GITREPO["oslo.config"]=https://github.com/openstack/oslo.config.git
2026-05-20 01:20:49.954954 | controller | ++ stackrc:source:416                       :   GITBRANCH["oslo.config"]=master
2026-05-20 01:20:49.958513 | controller | ++ stackrc:source:419                       :   GITREPO["oslo.context"]=https://github.com/openstack/oslo.context.git
2026-05-20 01:20:49.962025 | controller | ++ stackrc:source:420                       :   GITBRANCH["oslo.context"]=master
2026-05-20 01:20:49.966354 | controller | ++ stackrc:source:423                       :   GITREPO["oslo.db"]=https://github.com/openstack/oslo.db.git
2026-05-20 01:20:49.970264 | controller | ++ stackrc:source:424                       :   GITBRANCH["oslo.db"]=master
2026-05-20 01:20:49.973899 | controller | ++ stackrc:source:427                       :   GITREPO["oslo.i18n"]=https://github.com/openstack/oslo.i18n.git
2026-05-20 01:20:49.977597 | controller | ++ stackrc:source:428                       :   GITBRANCH["oslo.i18n"]=master
2026-05-20 01:20:49.981728 | controller | ++ stackrc:source:431                       :   GITREPO["oslo.limit"]=https://github.com/openstack/oslo.limit.git
2026-05-20 01:20:49.985603 | controller | ++ stackrc:source:432                       :   GITBRANCH["oslo.limit"]=master
2026-05-20 01:20:49.989716 | controller | ++ stackrc:source:435                       :   GITREPO["oslo.log"]=https://github.com/openstack/oslo.log.git
2026-05-20 01:20:49.993965 | controller | ++ stackrc:source:436                       :   GITBRANCH["oslo.log"]=master
2026-05-20 01:20:49.998045 | controller | ++ stackrc:source:439                       :   GITREPO["oslo.messaging"]=https://github.com/openstack/oslo.messaging.git
2026-05-20 01:20:50.001979 | controller | ++ stackrc:source:440                       :   GITBRANCH["oslo.messaging"]=master
2026-05-20 01:20:50.006165 | controller | ++ stackrc:source:443                       :   GITREPO["oslo.middleware"]=https://github.com/openstack/oslo.middleware.git
2026-05-20 01:20:50.010316 | controller | ++ stackrc:source:444                       :   GITBRANCH["oslo.middleware"]=master
2026-05-20 01:20:50.013931 | controller | ++ stackrc:source:447                       :   GITREPO["oslo.policy"]=https://github.com/openstack/oslo.policy.git
2026-05-20 01:20:50.017746 | controller | ++ stackrc:source:448                       :   GITBRANCH["oslo.policy"]=master
2026-05-20 01:20:50.021547 | controller | ++ stackrc:source:451                       :   GITREPO["oslo.privsep"]=https://github.com/openstack/oslo.privsep.git
2026-05-20 01:20:50.025684 | controller | ++ stackrc:source:452                       :   GITBRANCH["oslo.privsep"]=master
2026-05-20 01:20:50.030015 | controller | ++ stackrc:source:455                       :   GITREPO["oslo.reports"]=https://github.com/openstack/oslo.reports.git
2026-05-20 01:20:50.034095 | controller | ++ stackrc:source:456                       :   GITBRANCH["oslo.reports"]=master
2026-05-20 01:20:50.037946 | controller | ++ stackrc:source:459                       :   GITREPO["oslo.rootwrap"]=https://github.com/openstack/oslo.rootwrap.git
2026-05-20 01:20:50.041837 | controller | ++ stackrc:source:460                       :   GITBRANCH["oslo.rootwrap"]=master
2026-05-20 01:20:50.046416 | controller | ++ stackrc:source:463                       :   GITREPO["oslo.serialization"]=https://github.com/openstack/oslo.serialization.git
2026-05-20 01:20:50.050276 | controller | ++ stackrc:source:464                       :   GITBRANCH["oslo.serialization"]=master
2026-05-20 01:20:50.053971 | controller | ++ stackrc:source:467                       :   GITREPO["oslo.service"]=https://github.com/openstack/oslo.service.git
2026-05-20 01:20:50.058161 | controller | ++ stackrc:source:468                       :   GITBRANCH["oslo.service"]=master
2026-05-20 01:20:50.061658 | controller | ++ stackrc:source:471                       :   GITREPO["oslo.utils"]=https://github.com/openstack/oslo.utils.git
2026-05-20 01:20:50.065355 | controller | ++ stackrc:source:472                       :   GITBRANCH["oslo.utils"]=master
2026-05-20 01:20:50.069205 | controller | ++ stackrc:source:475                       :   GITREPO["oslo.versionedobjects"]=https://github.com/openstack/oslo.versionedobjects.git
2026-05-20 01:20:50.073067 | controller | ++ stackrc:source:476                       :   GITBRANCH["oslo.versionedobjects"]=master
2026-05-20 01:20:50.076919 | controller | ++ stackrc:source:479                       :   GITREPO["oslo.vmware"]=https://github.com/openstack/oslo.vmware.git
2026-05-20 01:20:50.081641 | controller | ++ stackrc:source:480                       :   GITBRANCH["oslo.vmware"]=master
2026-05-20 01:20:50.085822 | controller | ++ stackrc:source:483                       :   GITREPO["osprofiler"]=https://github.com/openstack/osprofiler.git
2026-05-20 01:20:50.089682 | controller | ++ stackrc:source:484                       :   GITBRANCH["osprofiler"]=master
2026-05-20 01:20:50.093582 | controller | ++ stackrc:source:487                       :   GITREPO["pycadf"]=https://github.com/openstack/pycadf.git
2026-05-20 01:20:50.097527 | controller | ++ stackrc:source:488                       :   GITBRANCH["pycadf"]=master
2026-05-20 01:20:50.101657 | controller | ++ stackrc:source:491                       :   GITREPO["stevedore"]=https://github.com/openstack/stevedore.git
2026-05-20 01:20:50.105467 | controller | ++ stackrc:source:492                       :   GITBRANCH["stevedore"]=master
2026-05-20 01:20:50.108839 | controller | ++ stackrc:source:495                       :   GITREPO["taskflow"]=https://github.com/openstack/taskflow.git
2026-05-20 01:20:50.112726 | controller | ++ stackrc:source:496                       :   GITBRANCH["taskflow"]=master
2026-05-20 01:20:50.116107 | controller | ++ stackrc:source:499                       :   GITREPO["tooz"]=https://github.com/openstack/tooz.git
2026-05-20 01:20:50.120334 | controller | ++ stackrc:source:500                       :   GITBRANCH["tooz"]=master
2026-05-20 01:20:50.124524 | controller | ++ stackrc:source:503                       :   GITREPO["pbr"]=https://github.com/openstack/pbr.git
2026-05-20 01:20:50.128318 | controller | ++ stackrc:source:504                       :   GITBRANCH["pbr"]=master
2026-05-20 01:20:50.132161 | controller | ++ stackrc:source:514                       :   GITREPO["cursive"]=https://github.com/openstack/cursive.git
2026-05-20 01:20:50.136175 | controller | ++ stackrc:source:515                       :   GITBRANCH["cursive"]=master
2026-05-20 01:20:50.140006 | controller | ++ stackrc:source:518                       :   GITREPO["glance_store"]=https://github.com/openstack/glance_store.git
2026-05-20 01:20:50.144032 | controller | ++ stackrc:source:519                       :   GITBRANCH["glance_store"]=master
2026-05-20 01:20:50.147905 | controller | ++ stackrc:source:522                       :   GITREPO["keystonemiddleware"]=https://github.com/openstack/keystonemiddleware.git
2026-05-20 01:20:50.151637 | controller | ++ stackrc:source:523                       :   GITBRANCH["keystonemiddleware"]=master
2026-05-20 01:20:50.155647 | controller | ++ stackrc:source:526                       :   GITREPO["ceilometermiddleware"]=https://github.com/openstack/ceilometermiddleware.git
2026-05-20 01:20:50.159383 | controller | ++ stackrc:source:527                       :   GITBRANCH["ceilometermiddleware"]=master
2026-05-20 01:20:50.163245 | controller | ++ stackrc:source:528                       :   GITDIR["ceilometermiddleware"]=/opt/stack/ceilometermiddleware
2026-05-20 01:20:50.167177 | controller | ++ stackrc:source:531                       :   GITREPO["openstacksdk"]=https://github.com/openstack/openstacksdk.git
2026-05-20 01:20:50.171234 | controller | ++ stackrc:source:532                       :   GITBRANCH["openstacksdk"]=master
2026-05-20 01:20:50.175394 | controller | ++ stackrc:source:535                       :   GITREPO["os-brick"]=https://github.com/openstack/os-brick.git
2026-05-20 01:20:50.178897 | controller | ++ stackrc:source:536                       :   GITBRANCH["os-brick"]=master
2026-05-20 01:20:50.182604 | controller | ++ stackrc:source:539                       :   GITREPO["os-client-config"]=https://github.com/openstack/os-client-config.git
2026-05-20 01:20:50.186824 | controller | ++ stackrc:source:540                       :   GITBRANCH["os-client-config"]=master
2026-05-20 01:20:50.190773 | controller | ++ stackrc:source:541                       :   GITDIR["os-client-config"]=/opt/stack/os-client-config
2026-05-20 01:20:50.194742 | controller | ++ stackrc:source:544                       :   GITREPO["os-vif"]=https://github.com/openstack/os-vif.git
2026-05-20 01:20:50.198752 | controller | ++ stackrc:source:545                       :   GITBRANCH["os-vif"]=master
2026-05-20 01:20:50.202794 | controller | ++ stackrc:source:548                       :   GITREPO["osc-lib"]=https://github.com/openstack/osc-lib.git
2026-05-20 01:20:50.206657 | controller | ++ stackrc:source:549                       :   GITBRANCH["osc-lib"]=master
2026-05-20 01:20:50.210545 | controller | ++ stackrc:source:552                       :   GITREPO["ironic-lib"]=https://github.com/openstack/ironic-lib.git
2026-05-20 01:20:50.213932 | controller | ++ stackrc:source:553                       :   GITBRANCH["ironic-lib"]=master
2026-05-20 01:20:50.217810 | controller | ++ stackrc:source:555                       :   GITDIR["ironic-lib"]=/opt/stack/ironic-lib
2026-05-20 01:20:50.221221 | controller | ++ stackrc:source:558                       :   GITREPO["diskimage-builder"]=https://github.com/openstack/diskimage-builder.git
2026-05-20 01:20:50.224884 | controller | ++ stackrc:source:559                       :   GITBRANCH["diskimage-builder"]=master
2026-05-20 01:20:50.228829 | controller | ++ stackrc:source:560                       :   GITDIR["diskimage-builder"]=/opt/stack/diskimage-builder
2026-05-20 01:20:50.232681 | controller | ++ stackrc:source:563                       :   GITREPO["neutron-lib"]=https://github.com/openstack/neutron-lib.git
2026-05-20 01:20:50.236350 | controller | ++ stackrc:source:564                       :   GITBRANCH["neutron-lib"]=master
2026-05-20 01:20:50.240084 | controller | ++ stackrc:source:565                       :   GITDIR["neutron-lib"]=/opt/stack/neutron-lib
2026-05-20 01:20:50.244147 | controller | ++ stackrc:source:568                       :   GITREPO["os-resource-classes"]=https://github.com/openstack/os-resource-classes.git
2026-05-20 01:20:50.247615 | controller | ++ stackrc:source:569                       :   GITBRANCH["os-resource-classes"]=master
2026-05-20 01:20:50.252053 | controller | ++ stackrc:source:572                       :   GITREPO["os-traits"]=https://github.com/openstack/os-traits.git
2026-05-20 01:20:50.256147 | controller | ++ stackrc:source:573                       :   GITBRANCH["os-traits"]=master
2026-05-20 01:20:50.260144 | controller | ++ stackrc:source:576                       :   GITREPO["ovsdbapp"]=https://github.com/openstack/ovsdbapp.git
2026-05-20 01:20:50.263934 | controller | ++ stackrc:source:577                       :   GITBRANCH["ovsdbapp"]=master
2026-05-20 01:20:50.267632 | controller | ++ stackrc:source:578                       :   GITDIR["ovsdbapp"]=/opt/stack/ovsdbapp
2026-05-20 01:20:50.270424 | controller | ++ stackrc:source:581                       :   GITREPO["os-ken"]=https://github.com/openstack/os-ken.git
2026-05-20 01:20:50.273667 | controller | ++ stackrc:source:582                       :   GITBRANCH["os-ken"]=master
2026-05-20 01:20:50.277392 | controller | ++ stackrc:source:583                       :   GITDIR["os-ken"]=/opt/stack/os-ken
2026-05-20 01:20:50.281586 | controller | ++ stackrc:source:595                       :   IRONIC_PYTHON_AGENT_REPO=https://github.com/openstack/ironic-python-agent.git
2026-05-20 01:20:50.285363 | controller | ++ stackrc:source:596                       :   IRONIC_PYTHON_AGENT_BRANCH=master
2026-05-20 01:20:50.288834 | controller | ++ stackrc:source:599                       :   NOVNC_REPO=https://github.com/novnc/novnc.git
2026-05-20 01:20:50.291939 | controller | ++ stackrc:source:600                       :   NOVNC_BRANCH=v1.3.0
2026-05-20 01:20:50.295990 | controller | ++ stackrc:source:603                       :   SPICE_REPO=http://anongit.freedesktop.org/git/spice/spice-html5.git
2026-05-20 01:20:50.300169 | controller | ++ stackrc:source:604                       :   SPICE_BRANCH=master
2026-05-20 01:20:50.305480 | controller | +++ stackrc:source:610                       :   trueorfalse False ENABLE_VOLUME_MULTIATTACH
2026-05-20 01:20:50.309740 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:50.316375 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:50.316705 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:50.323622 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:50.326779 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:50.331257 | controller | ++ stackrc:source:610                       :   ENABLE_VOLUME_MULTIATTACH=False
2026-05-20 01:20:50.335596 | controller | ++ stackrc:source:615                       :   DEFAULT_VIRT_DRIVER=libvirt
2026-05-20 01:20:50.339714 | controller | ++ stackrc:source:616                       :   VIRT_DRIVER=libvirt
2026-05-20 01:20:50.343839 | controller | ++ stackrc:source:617                       :   case "$VIRT_DRIVER" in
2026-05-20 01:20:50.347601 | controller | ++ stackrc:source:619                       :   LIBVIRT_TYPE=qemu
2026-05-20 01:20:50.351471 | controller | ++ stackrc:source:620                       :   LIBVIRT_CPU_MODE=custom
2026-05-20 01:20:50.355359 | controller | ++ stackrc:source:621                       :   LIBVIRT_CPU_MODEL=Nehalem
2026-05-20 01:20:50.359384 | controller | ++ stackrc:source:623                       :   [[ -z Ubuntu ]]
2026-05-20 01:20:50.363196 | controller | ++ stackrc:source:627                       :   [[ Ubuntu =~ (Debian|Ubuntu) ]]
2026-05-20 01:20:50.366879 | controller | ++ stackrc:source:628                       :   LIBVIRT_GROUP=libvirt
2026-05-20 01:20:50.370627 | controller | ++ stackrc:source:667                       :   CIRROS_VERSION=0.6.3
2026-05-20 01:20:50.376027 | controller | +++ stackrc:source:668                       :   uname -m
2026-05-20 01:20:50.382448 | controller | ++ stackrc:source:668                       :   CIRROS_ARCH=x86_64
2026-05-20 01:20:50.387801 | controller | +++ stackrc:source:673                       :   trueorfalse True DOWNLOAD_DEFAULT_IMAGES
2026-05-20 01:20:50.392150 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:50.399778 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:50.400583 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:50.405031 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:50.406913 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:50.410145 | controller | ++ stackrc:source:673                       :   DOWNLOAD_DEFAULT_IMAGES=True
2026-05-20 01:20:50.413207 | controller | ++ stackrc:source:674                       :   [[ True == \T\r\u\e ]]
2026-05-20 01:20:50.416427 | controller | ++ stackrc:source:675                       :   [[ -n '' ]]
2026-05-20 01:20:50.419022 | controller | ++ stackrc:source:678                       :   case "$VIRT_DRIVER" in
2026-05-20 01:20:50.421392 | controller | ++ stackrc:source:680                       :   case "$LIBVIRT_TYPE" in
2026-05-20 01:20:50.424233 | controller | ++ stackrc:source:686                       :   DEFAULT_IMAGE_NAME=cirros-0.6.3-x86_64-disk
2026-05-20 01:20:50.426302 | controller | ++ stackrc:source:687                       :   DEFAULT_IMAGE_FILE_NAME=cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:50.428286 | controller | ++ stackrc:source:688                       :   IMAGE_URLS+=https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:50.431012 | controller | ++ stackrc:source:701                       :   DOWNLOAD_DEFAULT_IMAGES=False
2026-05-20 01:20:50.435335 | controller | ++ stackrc:source:710                       :   EXTRA_CACHE_URLS=
2026-05-20 01:20:50.439320 | controller | ++ stackrc:source:713                       :   ETCD_VERSION=v3.5.21
2026-05-20 01:20:50.443404 | controller | ++ stackrc:source:714                       :   ETCD_SHA256_AMD64=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:20:50.446386 | controller | ++ stackrc:source:715                       :   ETCD_SHA256_ARM64=95bf6918623a097c0385b96f139d90248614485e781ec9bee4768dbb6c79c53f
2026-05-20 01:20:50.451513 | controller | ++ stackrc:source:716                       :   ETCD_SHA256_PPC64=6fb6ecb3d1b331eb177dc610a8efad3aceb1f836d6aeb439ba0bfac5d5c2a38c
2026-05-20 01:20:50.455469 | controller | ++ stackrc:source:717                       :   ETCD_SHA256_S390X=a211a83961ba8a7e94f7d6343ad769e699db21a715ba4f3b68cf31ea28f9c951
2026-05-20 01:20:50.459483 | controller | ++ stackrc:source:719                       :   is_arch x86_64
2026-05-20 01:20:50.463954 | controller | +++ functions-common:is_arch:519             :   uname -m
2026-05-20 01:20:50.470020 | controller | ++ functions-common:is_arch:519             :   [[ x86_64 == \x\8\6\_\6\4 ]]
2026-05-20 01:20:50.474061 | controller | ++ stackrc:source:720                       :   ETCD_ARCH=amd64
2026-05-20 01:20:50.477525 | controller | ++ stackrc:source:721                       :   ETCD_SHA256=adddda4b06718e68671ffabff2f8cee48488ba61ad82900e639d108f2148501c
2026-05-20 01:20:50.481371 | controller | ++ stackrc:source:734                       :   ETCD_PORT=2379
2026-05-20 01:20:50.485899 | controller | ++ stackrc:source:735                       :   ETCD_PEER_PORT=2380
2026-05-20 01:20:50.490245 | controller | ++ stackrc:source:736                       :   ETCD_DOWNLOAD_URL=https://github.com/etcd-io/etcd/releases/download
2026-05-20 01:20:50.493860 | controller | ++ stackrc:source:737                       :   ETCD_NAME=etcd-v3.5.21-linux-amd64
2026-05-20 01:20:50.497792 | controller | ++ stackrc:source:738                       :   ETCD_DOWNLOAD_FILE=etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:50.502390 | controller | ++ stackrc:source:739                       :   ETCD_DOWNLOAD_LOCATION=https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:50.506286 | controller | ++ stackrc:source:741                       :   EXTRA_CACHE_URLS+=,https://github.com/etcd-io/etcd/releases/download/v3.5.21/etcd-v3.5.21-linux-amd64.tar.gz
2026-05-20 01:20:50.510449 | controller | ++ stackrc:source:744                       :   CACHE_BACKEND=dogpile.cache.memcached
2026-05-20 01:20:50.514139 | controller | ++ stackrc:source:745                       :   MEMCACHE_SERVERS=localhost:11211
2026-05-20 01:20:50.517330 | controller | ++ stackrc:source:748                       :   for image_url in ${IMAGE_URLS//,/ }
2026-05-20 01:20:50.522344 | controller | +++ stackrc:source:749                       :   wc -l
2026-05-20 01:20:50.523389 | controller | +++ stackrc:source:749                       :   echo https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:50.523402 | controller | +++ stackrc:source:749                       :   grep -o -F https://github.com/cirros-dev/cirros/releases/download/0.6.3/cirros-0.6.3-x86_64-disk.img
2026-05-20 01:20:50.530739 | controller | ++ stackrc:source:749                       :   '[' 1 -gt 1 ']'
2026-05-20 01:20:50.535165 | controller | ++ stackrc:source:755                       :   VOLUME_BACKING_FILE_SIZE=30G
2026-05-20 01:20:50.538903 | controller | ++ stackrc:source:758                       :   VOLUME_NAME_PREFIX=volume-
2026-05-20 01:20:50.543833 | controller | ++ stackrc:source:759                       :   INSTANCE_NAME_PREFIX=instance-
2026-05-20 01:20:50.547684 | controller | ++ stackrc:source:762                       :   S3_SERVICE_PORT=3333
2026-05-20 01:20:50.551756 | controller | ++ stackrc:source:765                       :   PRIVATE_NETWORK_NAME=private
2026-05-20 01:20:50.555172 | controller | ++ stackrc:source:766                       :   PUBLIC_NETWORK_NAME=public
2026-05-20 01:20:50.558353 | controller | ++ stackrc:source:768                       :   PUBLIC_INTERFACE=
2026-05-20 01:20:50.562088 | controller | ++ stackrc:source:771                       :   SERVICE_PROTOCOL=http
2026-05-20 01:20:50.568630 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:20:50.578066 | controller | +++ stackrc:source:777                       :   nproc
2026-05-20 01:20:50.583699 | controller | ++ stackrc:source:777                       :   API_WORKERS=4
2026-05-20 01:20:50.587086 | controller | ++ stackrc:source:780                       :   SERVICE_TIMEOUT=60
2026-05-20 01:20:50.590552 | controller | ++ stackrc:source:783                       :   NOVA_READY_TIMEOUT=60
2026-05-20 01:20:50.594208 | controller | ++ stackrc:source:786                       :   SERVICE_GRACEFUL_SHUTDOWN_TIMEOUT=5
2026-05-20 01:20:50.596553 | controller | ++ stackrc:source:789                       :   WORKER_TIMEOUT=80
2026-05-20 01:20:50.601088 | controller | +++ stackrc:source:797                       :   trueorfalse False OFFLINE
2026-05-20 01:20:50.604624 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:50.608975 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:50.610018 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:50.614843 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:50.618069 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:50.621945 | controller | ++ stackrc:source:797                       :   OFFLINE=False
2026-05-20 01:20:50.626894 | controller | +++ stackrc:source:802                       :   trueorfalse False ERROR_ON_CLONE
2026-05-20 01:20:50.631036 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:50.637741 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:50.638085 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:50.645549 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:50.648893 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:50.653762 | controller | ++ stackrc:source:802                       :   ERROR_ON_CLONE=True
2026-05-20 01:20:50.657124 | controller | +++ stackrc:source:805                       :   trueorfalse True ENABLE_DEBUG_LOG_LEVEL
2026-05-20 01:20:50.660062 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:50.665272 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:50.666750 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:50.673718 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:50.676930 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:50.682374 | controller | ++ stackrc:source:805                       :   ENABLE_DEBUG_LOG_LEVEL=True
2026-05-20 01:20:50.685949 | controller | ++ stackrc:source:811                       :   FLOATING_RANGE=172.24.5.0/24
2026-05-20 01:20:50.689292 | controller | ++ stackrc:source:812                       :   IPV4_ADDRS_SAFE_TO_USE=10.1.0.0/20
2026-05-20 01:20:50.693089 | controller | ++ stackrc:source:813                       :   FIXED_RANGE=10.1.0.0/20
2026-05-20 01:20:50.696248 | controller | ++ stackrc:source:814                       :   HOST_IP_IFACE=
2026-05-20 01:20:50.699688 | controller | ++ stackrc:source:815                       :   HOST_IP=199.204.45.38
2026-05-20 01:20:50.703949 | controller | ++ stackrc:source:816                       :   HOST_IPV6=
2026-05-20 01:20:50.709439 | controller | +++ stackrc:source:818                       :   get_default_host_ip 10.1.0.0/20 172.24.5.0/24 '' 199.204.45.38 inet
2026-05-20 01:20:50.713149 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=10.1.0.0/20
2026-05-20 01:20:50.717085 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=172.24.5.0/24
2026-05-20 01:20:50.720850 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:20:50.724724 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=199.204.45.38
2026-05-20 01:20:50.728383 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet
2026-05-20 01:20:50.732631 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z 199.204.45.38 -o 199.204.45.38 == dhcp ']'
2026-05-20 01:20:50.736438 | controller | +++ functions-common:get_default_host_ip:785 :   echo 199.204.45.38
2026-05-20 01:20:50.741603 | controller | ++ stackrc:source:818                       :   HOST_IP=199.204.45.38
2026-05-20 01:20:50.745867 | controller | ++ stackrc:source:819                       :   '[' 199.204.45.38 == '' ']'
2026-05-20 01:20:50.750565 | controller | +++ stackrc:source:823                       :   get_default_host_ip '' '' '' '' inet6
2026-05-20 01:20:50.754749 | controller | +++ functions-common:get_default_host_ip:754 :   local fixed_range=
2026-05-20 01:20:50.758795 | controller | +++ functions-common:get_default_host_ip:755 :   local floating_range=
2026-05-20 01:20:50.763434 | controller | +++ functions-common:get_default_host_ip:756 :   local host_ip_iface=
2026-05-20 01:20:50.767285 | controller | +++ functions-common:get_default_host_ip:757 :   local host_ip=
2026-05-20 01:20:50.771271 | controller | +++ functions-common:get_default_host_ip:758 :   local af=inet6
2026-05-20 01:20:50.775417 | controller | +++ functions-common:get_default_host_ip:761 :   '[' -z '' -o '' == dhcp ']'
2026-05-20 01:20:50.779366 | controller | +++ functions-common:get_default_host_ip:762 :   host_ip=
2026-05-20 01:20:50.786464 | controller | ++++ functions-common:get_default_host_ip:764 :   ip -f inet6 route list match default table all
2026-05-20 01:20:50.786828 | controller | ++++ functions-common:get_default_host_ip:764 :   grep via
2026-05-20 01:20:50.787183 | controller | ++++ functions-common:get_default_host_ip:764 :   head -1
2026-05-20 01:20:50.787400 | controller | ++++ functions-common:get_default_host_ip:764 :   awk '/default/ {print $5}'
2026-05-20 01:20:50.795281 | controller | +++ functions-common:get_default_host_ip:764 :   host_ip_iface=
2026-05-20 01:20:50.799704 | controller | +++ functions-common:get_default_host_ip:765 :   local host_ips
2026-05-20 01:20:50.806680 | controller | ++++ functions-common:get_default_host_ip:766 :   sed /temporary/d
2026-05-20 01:20:50.806715 | controller | ++++ functions-common:get_default_host_ip:766 :   LC_ALL=C
2026-05-20 01:20:50.806859 | controller | ++++ functions-common:get_default_host_ip:766 :   awk '/inet6/ {split($2,parts,"/");  print parts[1]}'
2026-05-20 01:20:50.810859 | controller | ++++ functions-common:get_default_host_ip:766 :   ip -f inet6 addr show
2026-05-20 01:20:50.819928 | controller | +++ functions-common:get_default_host_ip:766 :   host_ips='::1
2026-05-20 01:20:50.819954 | controller | 2604:e100:1:0:f816:3eff:fee5:1df1
2026-05-20 01:20:50.819963 | controller | fe80::f816:3eff:fee5:1df1'
2026-05-20 01:20:50.823827 | controller | +++ functions-common:get_default_host_ip:767 :   local ip
2026-05-20 01:20:50.827863 | controller | +++ functions-common:get_default_host_ip:768 :   for ip in $host_ips
2026-05-20 01:20:50.832086 | controller | +++ functions-common:get_default_host_ip:775 :   [[ inet6 == \i\n\e\t\6 ]]
2026-05-20 01:20:50.835514 | controller | +++ functions-common:get_default_host_ip:776 :   host_ip=::1
2026-05-20 01:20:50.839054 | controller | +++ functions-common:get_default_host_ip:777 :   break
2026-05-20 01:20:50.842880 | controller | +++ functions-common:get_default_host_ip:785 :   echo ::1
2026-05-20 01:20:50.847728 | controller | ++ stackrc:source:823                       :   HOST_IPV6=::1
2026-05-20 01:20:50.853127 | controller | +++ stackrc:source:826                       :   trueorfalse True NEUTRON_PORT_SECURITY
2026-05-20 01:20:50.857084 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:50.862413 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:50.863573 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:50.872099 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:50.876297 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:50.880426 | controller | ++ stackrc:source:826                       :   NEUTRON_PORT_SECURITY=True
2026-05-20 01:20:50.884826 | controller | ++ stackrc:source:831                       :   SERVICE_IP_VERSION=4
2026-05-20 01:20:50.889269 | controller | ++ stackrc:source:837                       :   [[ 4 != \4 ]]
2026-05-20 01:20:50.893486 | controller | ++ stackrc:source:841                       :   [[ 4 == 4 ]]
2026-05-20 01:20:50.897450 | controller | ++ stackrc:source:842                       :   DEF_SERVICE_HOST=199.204.45.38
2026-05-20 01:20:50.900981 | controller | ++ stackrc:source:843                       :   DEF_SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:20:50.904918 | controller | ++ stackrc:source:844                       :   DEF_SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:20:50.908684 | controller | ++ stackrc:source:847                       :   [[ 4 == 6 ]]
2026-05-20 01:20:50.912659 | controller | ++ stackrc:source:858                       :   SERVICE_LISTEN_ADDRESS=0.0.0.0
2026-05-20 01:20:50.916660 | controller | ++ stackrc:source:862                       :   SERVICE_HOST=199.204.45.38
2026-05-20 01:20:50.920352 | controller | ++ stackrc:source:864                       :   SERVICE_LOCAL_HOST=127.0.0.1
2026-05-20 01:20:50.924421 | controller | ++ stackrc:source:868                       :   TUNNEL_IP_VERSION=4
2026-05-20 01:20:50.928844 | controller | ++ stackrc:source:871                       :   [[ 4 != \4 ]]
2026-05-20 01:20:50.932630 | controller | ++ stackrc:source:875                       :   [[ 4 == 4 ]]
2026-05-20 01:20:50.936351 | controller | ++ stackrc:source:876                       :   DEF_TUNNEL_ENDPOINT_IP=199.204.45.38
2026-05-20 01:20:50.940328 | controller | ++ stackrc:source:879                       :   [[ 4 == 6 ]]
2026-05-20 01:20:50.944149 | controller | ++ stackrc:source:890                       :   TUNNEL_ENDPOINT_IP=199.204.45.38
2026-05-20 01:20:50.949490 | controller | +++ stackrc:source:893                       :   trueorfalse False SYSLOG
2026-05-20 01:20:50.953637 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:50.959851 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:50.959890 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:50.967028 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:50.970588 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:50.975545 | controller | ++ stackrc:source:893                       :   SYSLOG=False
2026-05-20 01:20:50.979355 | controller | ++ stackrc:source:894                       :   SYSLOG_HOST=199.204.45.38
2026-05-20 01:20:50.983047 | controller | ++ stackrc:source:895                       :   SYSLOG_PORT=516
2026-05-20 01:20:50.986867 | controller | ++ stackrc:source:899                       :   GIT_DEPTH=0
2026-05-20 01:20:50.992518 | controller | +++ stackrc:source:903                       :   trueorfalse True RECREATE_KEYSTONE_DB
2026-05-20 01:20:50.996944 | controller | +++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:51.003329 | controller | ++++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:51.004315 | controller | ++++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:51.012192 | controller | +++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:51.016047 | controller | +++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:51.021259 | controller | ++ stackrc:source:903                       :   RECREATE_KEYSTONE_DB=True
2026-05-20 01:20:51.025616 | controller | ++ stackrc:source:915                       :   [[ -z '' ]]
2026-05-20 01:20:51.029517 | controller | ++ stackrc:source:916                       :   default_logdir=/opt/stack/logs
2026-05-20 01:20:51.033413 | controller | ++ stackrc:source:917                       :   [[ -z /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:20:51.036919 | controller | ++ stackrc:source:922                       :   LOGDIR=/opt/stack/logs
2026-05-20 01:20:51.040607 | controller | ++ stackrc:source:923                       :   logfile=devstacklog.txt
2026-05-20 01:20:51.044459 | controller | ++ stackrc:source:924                       :   [[ -z /opt/stack/logs ]]
2026-05-20 01:20:51.047929 | controller | ++ stackrc:source:924                       :   [[ /opt/stack/logs == \d\e\v\s\t\a\c\k\l\o\g\.\t\x\t ]]
2026-05-20 01:20:51.051798 | controller | ++ stackrc:source:929                       :   unset default_logdir logfile
2026-05-20 01:20:51.055433 | controller | ++ stackrc:source:935                       :   ULIMIT_NOFILE=2048
2026-05-20 01:20:51.059639 | controller | + ./stack.sh:main:228                      :   write_devstack_version
2026-05-20 01:20:51.065369 | controller | + functions:write_devstack_version:876     :   cat -
2026-05-20 01:20:51.065782 | controller | + functions:write_devstack_version:876     :   sudo tee /etc/devstack-version
2026-05-20 01:20:51.070662 | controller | ++ functions:write_devstack_version:876     :   git log '--format=%H %s %ci' -1
2026-05-20 01:20:51.118591 | controller | + ./stack.sh:main:232                      :   SUPPORTED_DISTROS='trixie|bookworm|noble|rhel9|rhel10'
2026-05-20 01:20:51.122966 | controller | + ./stack.sh:main:234                      :   [[ ! noble =~ trixie|bookworm|noble|rhel9|rhel10 ]]
2026-05-20 01:20:51.127231 | controller | + ./stack.sh:main:245                      :   export_proxy_variables
2026-05-20 01:20:51.131090 | controller | + functions-common:export_proxy_variables:2232 :   isset http_proxy
2026-05-20 01:20:51.135539 | controller | + functions-common:isset:266               :   [[ -v http_proxy ]]
2026-05-20 01:20:51.139756 | controller | + functions-common:export_proxy_variables:2235 :   isset https_proxy
2026-05-20 01:20:51.143676 | controller | + functions-common:isset:266               :   [[ -v https_proxy ]]
2026-05-20 01:20:51.147532 | controller | + functions-common:export_proxy_variables:2238 :   isset no_proxy
2026-05-20 01:20:51.151943 | controller | + functions-common:isset:266               :   [[ -v no_proxy ]]
2026-05-20 01:20:51.156182 | controller | + ./stack.sh:main:250                      :   disable_negated_services
2026-05-20 01:20:51.159787 | controller | + functions-common:disable_negated_services:1937 :   local xtrace
2026-05-20 01:20:51.165595 | controller | ++ functions-common:disable_negated_services:1938 :   grep xtrace
2026-05-20 01:20:51.166101 | controller | ++ functions-common:disable_negated_services:1938 :   set +o
2026-05-20 01:20:51.173757 | controller | + functions-common:disable_negated_services:1938 :   xtrace='set -o xtrace'
2026-05-20 01:20:51.177446 | controller | + functions-common:disable_negated_services:1939 :   set +o xtrace
2026-05-20 01:20:51.199953 | controller | + ./stack.sh:main:257                      :   is_package_installed sudo
2026-05-20 01:20:51.204380 | controller | + functions-common:is_package_installed:1441 :   [[ -z sudo ]]
2026-05-20 01:20:51.208677 | controller | + functions-common:is_package_installed:1445 :   [[ -z deb ]]
2026-05-20 01:20:51.212785 | controller | + functions-common:is_package_installed:1449 :   [[ deb = \d\e\b ]]
2026-05-20 01:20:51.217443 | controller | + functions-common:is_package_installed:1450 :   dpkg -s sudo
2026-05-20 01:20:51.236027 | controller | + ./stack.sh:main:260                      :   sudo grep -q '^#includedir.*/etc/sudoers.d' /etc/sudoers
2026-05-20 01:20:51.257810 | controller | + ./stack.sh:main:261                      :   echo '#includedir /etc/sudoers.d'
2026-05-20 01:20:51.258258 | controller | + ./stack.sh:main:261                      :   sudo tee -a /etc/sudoers
2026-05-20 01:20:51.269780 | controller | #includedir /etc/sudoers.d
2026-05-20 01:20:51.275968 | controller | + ./stack.sh:main:264                      :   [[ -n '' ]]
2026-05-20 01:20:51.281557 | controller | ++ ./stack.sh:main:273                      :   mktemp
2026-05-20 01:20:51.289266 | controller | + ./stack.sh:main:273                      :   TEMPFILE=/tmp/tmp.TzkWOk45nj
2026-05-20 01:20:51.293403 | controller | + ./stack.sh:main:274                      :   echo 'stack ALL=(root) NOPASSWD:ALL'
2026-05-20 01:20:51.297670 | controller | + ./stack.sh:main:277                      :   echo 'Defaults:stack secure_path=/sbin:/usr/sbin:/usr/bin:/bin:/usr/local/sbin:/usr/local/bin'
2026-05-20 01:20:51.301893 | controller | + ./stack.sh:main:278                      :   echo 'Defaults:stack !requiretty'
2026-05-20 01:20:51.305407 | controller | + ./stack.sh:main:279                      :   chmod 0440 /tmp/tmp.TzkWOk45nj
2026-05-20 01:20:51.312492 | controller | + ./stack.sh:main:280                      :   sudo chown root:root /tmp/tmp.TzkWOk45nj
2026-05-20 01:20:51.334395 | controller | + ./stack.sh:main:281                      :   sudo mv /tmp/tmp.TzkWOk45nj /etc/sudoers.d/50_stack_sh
2026-05-20 01:20:51.356306 | controller | + ./stack.sh:main:287                      :   is_ubuntu
2026-05-20 01:20:51.360542 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:20:51.365405 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:20:51.370684 | controller | + ./stack.sh:main:288                      :   sudo tee /etc/apt/apt.conf.d/80retry
2026-05-20 01:20:51.370940 | controller | + ./stack.sh:main:288                      :   echo 'APT::Acquire::Retries "20";'
2026-05-20 01:20:51.390799 | controller | + ./stack.sh:main:326                      :   DEST=/opt/stack
2026-05-20 01:20:51.395089 | controller | + ./stack.sh:main:331                      :   [[ ! -d /opt/stack ]]
2026-05-20 01:20:51.399170 | controller | + ./stack.sh:main:338                      :   [[ -n /opt/stack/logs ]]
2026-05-20 01:20:51.403063 | controller | + ./stack.sh:main:339                      :   sudo mkdir -p /opt/stack/logs
2026-05-20 01:20:51.423829 | controller | + ./stack.sh:main:340                      :   safe_chown -R stack /opt/stack/logs
2026-05-20 01:20:51.428234 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/logs
2026-05-20 01:20:51.432428 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:51.438669 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:51.439215 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:51.446974 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:51.450803 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:51.459016 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/logs
2026-05-20 01:20:51.482898 | controller | + ./stack.sh:main:341                      :   safe_chmod 0755 /opt/stack/logs
2026-05-20 01:20:51.486829 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/logs
2026-05-20 01:20:51.490687 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:51.497032 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:51.497657 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:51.504994 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:51.508952 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:51.516915 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/logs
2026-05-20 01:20:51.537635 | controller | + ./stack.sh:main:345                      :   DATA_DIR=/opt/stack/data
2026-05-20 01:20:51.542181 | controller | + ./stack.sh:main:346                      :   [[ ! -d /opt/stack/data ]]
2026-05-20 01:20:51.546788 | controller | + ./stack.sh:main:347                      :   sudo mkdir -p /opt/stack/data
2026-05-20 01:20:51.567879 | controller | + ./stack.sh:main:348                      :   safe_chown -R stack /opt/stack/data
2026-05-20 01:20:51.572814 | controller | + functions-common:safe_chown:2315         :   _safe_permission_operation chown -R stack /opt/stack/data
2026-05-20 01:20:51.576243 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:51.582800 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:51.582920 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:51.590347 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:51.595051 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:51.603523 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chown -R stack /opt/stack/data
2026-05-20 01:20:51.624066 | controller | + ./stack.sh:main:349                      :   safe_chmod 0755 /opt/stack/data
2026-05-20 01:20:51.628344 | controller | + functions-common:safe_chmod:2309         :   _safe_permission_operation chmod 0755 /opt/stack/data
2026-05-20 01:20:51.632280 | controller | + functions-common:_safe_permission_operation:2140 :   local xtrace
2026-05-20 01:20:51.637885 | controller | ++ functions-common:_safe_permission_operation:2141 :   grep xtrace
2026-05-20 01:20:51.638694 | controller | ++ functions-common:_safe_permission_operation:2141 :   set +o
2026-05-20 01:20:51.645266 | controller | + functions-common:_safe_permission_operation:2141 :   xtrace='set -o xtrace'
2026-05-20 01:20:51.649195 | controller | + functions-common:_safe_permission_operation:2142 :   set +o xtrace
2026-05-20 01:20:51.656912 | controller | + functions-common:_safe_permission_operation:2163 :   sudo chmod 0755 /opt/stack/data
2026-05-20 01:20:51.676320 | controller | + ./stack.sh:main:353                      :   async_init
2026-05-20 01:20:51.681156 | controller | + inc/async:async_init:217                 :   local async_dir=/opt/stack/async
2026-05-20 01:20:51.685157 | controller | + inc/async:async_init:220                 :   rm -Rf /opt/stack/async
2026-05-20 01:20:51.692071 | controller | + inc/async:async_init:223                 :   mkdir -p /opt/stack/async
2026-05-20 01:20:51.700896 | controller | ++ ./stack.sh:main:358                      :   hostname -s
2026-05-20 01:20:51.706768 | controller | + ./stack.sh:main:358                      :   LOCAL_HOSTNAME=np0000175158
2026-05-20 01:20:51.710845 | controller | + ./stack.sh:main:359                      :   grep -Fqwe np0000175158 /etc/hosts
2026-05-20 01:20:51.719921 | controller | ++ ./stack.sh:main:366                      :   trueorfalse False SKIP_EPEL_INSTALL
2026-05-20 01:20:51.724444 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:20:51.731330 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:20:51.732312 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:20:51.740477 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:20:51.745242 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:20:51.750568 | controller | + ./stack.sh:main:366                      :   SKIP_EPEL_INSTALL=False
2026-05-20 01:20:51.754656 | controller | + ./stack.sh:main:368                      :   [[ noble == \r\h\e\l\9 ]]
2026-05-20 01:20:51.758716 | controller | + ./stack.sh:main:382                      :   [[ noble == \r\h\e\l\1\0 ]]
2026-05-20 01:20:51.762954 | controller | + ./stack.sh:main:387                      :   [[ noble == \o\p\e\n\E\u\l\e\r\-\2\2\.\0\3 ]]
2026-05-20 01:20:51.767451 | controller | + ./stack.sh:main:404                      :   install_python
2026-05-20 01:20:51.771767 | controller | + inc/python:install_python:482            :   install_python3
2026-05-20 01:20:51.775814 | controller | + inc/python:install_python3:488           :   is_ubuntu
2026-05-20 01:20:51.779685 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:20:51.783679 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:20:51.787961 | controller | + inc/python:install_python3:489           :   apt_get install python3.12 python3.12-dev
2026-05-20 01:20:51.791759 | controller | + functions-common:apt_get:1188            :   local xtrace result
2026-05-20 01:20:51.797598 | controller | ++ functions-common:apt_get:1189            :   set +o
2026-05-20 01:20:51.798381 | controller | ++ functions-common:apt_get:1189            :   grep xtrace
2026-05-20 01:20:51.805992 | controller | + functions-common:apt_get:1189            :   xtrace='set -o xtrace'
2026-05-20 01:20:51.809881 | controller | + functions-common:apt_get:1190            :   set +o xtrace
2026-05-20 01:20:51.824148 | controller | + functions-common:apt_get:1201            :   sudo DEBIAN_FRONTEND=noninteractive http_proxy= https_proxy= no_proxy= apt-get --option Dpkg::Options::=--force-confold --assume-yes install python3.12 python3.12-dev
2026-05-20 01:20:51.872100 | controller | Reading package lists...
2026-05-20 01:20:52.109704 | controller | Building dependency tree...
2026-05-20 01:20:52.109906 | controller | Reading state information...
2026-05-20 01:20:52.333990 | controller | python3.12 is already the newest version (3.12.3-1ubuntu0.13).
2026-05-20 01:20:52.334027 | controller | python3.12 set to manually installed.
2026-05-20 01:20:52.334037 | controller | The following additional packages will be installed:
2026-05-20 01:20:52.334052 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:20:52.334828 | controller |   libpython3.12t64 linux-libc-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:20:52.335874 | controller | Suggested packages:
2026-05-20 01:20:52.335907 | controller |   glibc-doc manpages-dev
2026-05-20 01:20:52.335917 | controller | Recommended packages:
2026-05-20 01:20:52.335926 | controller |   manpages manpages-dev libc-devtools
2026-05-20 01:20:52.380241 | controller | The following NEW packages will be installed:
2026-05-20 01:20:52.380858 | controller |   libc-dev-bin libc6-dev libcrypt-dev libexpat1-dev libpython3.12-dev
2026-05-20 01:20:52.381461 | controller |   libpython3.12t64 linux-libc-dev python3.12-dev rpcsvc-proto zlib1g-dev
2026-05-20 01:20:52.794081 | controller | 0 upgraded, 10 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:20:52.794173 | controller | Need to get 13.4 MB of archives.
2026-05-20 01:20:52.794192 | controller | After this operation, 63.4 MB of additional disk space will be used.
2026-05-20 01:20:52.794204 | 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:20:52.976942 | 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:20:59.799530 | 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:00.257212 | 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:00.513451 | 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:06.819706 | 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:07.134387 | 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:10.987693 | 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:11.870511 | 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:17.831765 | 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:18.616586 | controller | debconf: delaying package configuration, since apt-utils is not installed
2026-05-20 01:21:18.670048 | controller | Fetched 13.4 MB in 26s (516 kB/s)
2026-05-20 01:21:18.966174 | controller | Selecting previously unselected package libc-dev-bin.
2026-05-20 01:21:19.285519 | 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:19.287045 | controller | Preparing to unpack .../0-libc-dev-bin_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:19.507305 | controller | Unpacking libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:20.088444 | controller | Selecting previously unselected package linux-libc-dev:amd64.
2026-05-20 01:21:20.094763 | controller | Preparing to unpack .../1-linux-libc-dev_6.8.0-117.117_amd64.deb ...
2026-05-20 01:21:20.158394 | controller | Unpacking linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:21.867401 | controller | Selecting previously unselected package libcrypt-dev:amd64.
2026-05-20 01:21:21.873953 | controller | Preparing to unpack .../2-libcrypt-dev_1%3a4.4.36-4build1_amd64.deb ...
2026-05-20 01:21:21.966344 | controller | Unpacking libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:22.109338 | controller | Selecting previously unselected package rpcsvc-proto.
2026-05-20 01:21:22.115869 | controller | Preparing to unpack .../3-rpcsvc-proto_1.4.2-0ubuntu7_amd64.deb ...
2026-05-20 01:21:22.121380 | controller | Unpacking rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:22.196437 | controller | Selecting previously unselected package libc6-dev:amd64.
2026-05-20 01:21:22.203412 | controller | Preparing to unpack .../4-libc6-dev_2.39-0ubuntu8.7_amd64.deb ...
2026-05-20 01:21:22.209103 | controller | Unpacking libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:22.766509 | controller | Selecting previously unselected package libexpat1-dev:amd64.
2026-05-20 01:21:22.773090 | controller | Preparing to unpack .../5-libexpat1-dev_2.6.1-2ubuntu0.4_amd64.deb ...
2026-05-20 01:21:22.778969 | controller | Unpacking libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:22.859407 | controller | Selecting previously unselected package libpython3.12t64:amd64.
2026-05-20 01:21:22.865283 | controller | Preparing to unpack .../6-libpython3.12t64_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:22.876162 | controller | Unpacking libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:23.019299 | controller | Selecting previously unselected package zlib1g-dev:amd64.
2026-05-20 01:21:23.026196 | controller | Preparing to unpack .../7-zlib1g-dev_1%3a1.3.dfsg-3.1ubuntu2.1_amd64.deb ...
2026-05-20 01:21:23.031632 | controller | Unpacking zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:23.108288 | controller | Selecting previously unselected package libpython3.12-dev:amd64.
2026-05-20 01:21:23.114560 | controller | Preparing to unpack .../8-libpython3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:23.120458 | controller | Unpacking libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:23.417935 | controller | Selecting previously unselected package python3.12-dev.
2026-05-20 01:21:23.425501 | controller | Preparing to unpack .../9-python3.12-dev_3.12.3-1ubuntu0.13_amd64.deb ...
2026-05-20 01:21:23.435417 | controller | Unpacking python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:23.512758 | controller | Setting up linux-libc-dev:amd64 (6.8.0-117.117) ...
2026-05-20 01:21:23.531026 | controller | Setting up libpython3.12t64:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:23.548282 | controller | Setting up rpcsvc-proto (1.4.2-0ubuntu7) ...
2026-05-20 01:21:23.567084 | controller | Setting up libcrypt-dev:amd64 (1:4.4.36-4build1) ...
2026-05-20 01:21:23.594985 | controller | Setting up libc-dev-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:23.610147 | controller | Setting up libc6-dev:amd64 (2.39-0ubuntu8.7) ...
2026-05-20 01:21:23.629388 | controller | Setting up libexpat1-dev:amd64 (2.6.1-2ubuntu0.4) ...
2026-05-20 01:21:23.650842 | controller | Setting up zlib1g-dev:amd64 (1:1.3.dfsg-3.1ubuntu2.1) ...
2026-05-20 01:21:23.666431 | controller | Setting up libpython3.12-dev:amd64 (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:23.684665 | controller | Setting up python3.12-dev (3.12.3-1ubuntu0.13) ...
2026-05-20 01:21:23.704248 | controller | Processing triggers for libc-bin (2.39-0ubuntu8.7) ...
2026-05-20 01:21:23.997890 | controller | + functions-common:apt_get:1205            :   result=0
2026-05-20 01:21:24.001193 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:21:24.004658 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:21:24.008742 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:21:24.013272 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:21:24.016743 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:21:24.020965 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:21:24.025483 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:21:24.029306 | controller | + functions-common:time_stop:2421          :   start_time=1779240051819
2026-05-20 01:21:24.033895 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240051819 ]]
2026-05-20 01:21:24.039160 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:21:24.044659 | controller | + functions-common:time_stop:2426          :   end_time=1779240084040
2026-05-20 01:21:24.049264 | controller | + functions-common:time_stop:2427          :   elapsed_time=32221
2026-05-20 01:21:24.053261 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:21:24.057793 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:21:24.061632 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=32221
2026-05-20 01:21:24.065264 | controller | + functions-common:apt_get:1209            :   return 0
2026-05-20 01:21:24.071518 | controller | ++ inc/python:install_python:483            :   which python3.12
2026-05-20 01:21:24.079319 | controller | + inc/python:install_python:483            :   export PYTHON=/usr/bin/python3.12
2026-05-20 01:21:24.083993 | controller | + inc/python:install_python:483            :   PYTHON=/usr/bin/python3.12
2026-05-20 01:21:24.089527 | controller | ++ ./stack.sh:main:411                      :   trueorfalse True VERBOSE
2026-05-20 01:21:24.094228 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:24.100769 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:24.101623 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:24.109353 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:24.113702 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:24.119525 | controller | + ./stack.sh:main:411                      :   VERBOSE=True
2026-05-20 01:21:24.125288 | controller | ++ ./stack.sh:main:412                      :   trueorfalse False VERBOSE
2026-05-20 01:21:24.130053 | controller | ++ functions-common:trueorfalse:224         :   local xtrace
2026-05-20 01:21:24.137375 | controller | +++ functions-common:trueorfalse:225         :   set +o
2026-05-20 01:21:24.137516 | controller | +++ functions-common:trueorfalse:225         :   grep xtrace
2026-05-20 01:21:24.145225 | controller | ++ functions-common:trueorfalse:225         :   xtrace='set -o xtrace'
2026-05-20 01:21:24.149873 | controller | ++ functions-common:trueorfalse:226         :   set +o xtrace
2026-05-20 01:21:24.155821 | controller | + ./stack.sh:main:412                      :   VERBOSE_NO_TIMESTAMP=True
2026-05-20 01:21:24.160464 | controller | + ./stack.sh:main:458                      :   TIMESTAMP_FORMAT=%F-%H%M%S
2026-05-20 01:21:24.164885 | controller | + ./stack.sh:main:459                      :   LOGDAYS=7
2026-05-20 01:21:24.170014 | controller | ++ ./stack.sh:main:460                      :   date +%F-%H%M%S
2026-05-20 01:21:24.176015 | controller | + ./stack.sh:main:460                      :   CURRENT_LOG_TIME=2026-05-20-012124
2026-05-20 01:21:24.180789 | controller | + ./stack.sh:main:462                      :   [[ -n /opt/stack/logs/devstacklog.txt ]]
2026-05-20 01:21:24.185101 | controller | + ./stack.sh:main:465                      :   LOGFILE_DIR=/opt/stack/logs
2026-05-20 01:21:24.189242 | controller | + ./stack.sh:main:466                      :   LOGFILE_NAME=devstacklog.txt
2026-05-20 01:21:24.193504 | controller | + ./stack.sh:main:467                      :   mkdir -p /opt/stack/logs
2026-05-20 01:21:24.201064 | controller | + ./stack.sh:main:468                      :   find /opt/stack/logs -maxdepth 1 -name 'devstacklog.txt.*' -mtime +7 -exec rm '{}' ';'
2026-05-20 01:21:24.210045 | controller | + ./stack.sh:main:469                      :   LOGFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012124
2026-05-20 01:21:24.213800 | controller | + ./stack.sh:main:470                      :   SUMFILE=/opt/stack/logs/devstacklog.txt.2026-05-20-012124.summary.2026-05-20-012124
2026-05-20 01:21:24.218010 | controller | + ./stack.sh:main:476                      :   exec
2026-05-20 01:21:24.222031 | controller | + ./stack.sh:main:477                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:24.225794 | controller | + ./stack.sh:main:478                      :   _of_args=-v
2026-05-20 01:21:24.229970 | controller | + ./stack.sh:main:479                      :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:24.234308 | controller | + ./stack.sh:main:480                      :   _of_args='-v --no-timestamp'
2026-05-20 01:21:24.238234 | controller | + ./stack.sh:main:483                      :   exec
2026-05-20 01:21:24.243482 | 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-012124
2026-05-20 01:21:24.274027 | controller | + ./stack.sh:main:485                      :   exec
2026-05-20 01:21:24.274117 | controller | + ./stack.sh:main:493                      :   echo_summary 'stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012124'
2026-05-20 01:21:24.274166 | controller | ++ ./stack.sh:main:485                      :   /usr/bin/python3.12 /opt/stack/devstack/tools/outfilter.py -o /opt/stack/logs/devstacklog.txt.2026-05-20-012124.summary.2026-05-20-012124
2026-05-20 01:21:24.274176 | controller | + ./stack.sh:echo_summary:438              :   [[ -t 3 ]]
2026-05-20 01:21:24.274185 | controller | + ./stack.sh:echo_summary:444              :   echo -e stack.sh log /opt/stack/logs/devstacklog.txt.2026-05-20-012124
2026-05-20 01:21:24.274194 | controller | + ./stack.sh:main:495                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012124 /opt/stack/logs/devstacklog.txt
2026-05-20 01:21:24.274203 | controller | + ./stack.sh:main:496                      :   ln -sf /opt/stack/logs/devstacklog.txt.2026-05-20-012124.summary.2026-05-20-012124 /opt/stack/logs/devstacklog.txt.summary
2026-05-20 01:21:24.274213 | controller | + ./stack.sh:main:511                      :   check_path_perm_sanity /opt/stack
2026-05-20 01:21:24.277219 | controller | + functions:check_path_perm_sanity:615     :   local real_path
2026-05-20 01:21:24.282800 | controller | ++ functions:check_path_perm_sanity:616     :   readlink -f /opt/stack
2026-05-20 01:21:24.289754 | controller | + functions:check_path_perm_sanity:616     :   real_path=/opt/stack
2026-05-20 01:21:24.293932 | controller | + functions:check_path_perm_sanity:617     :   local rebuilt_path=
2026-05-20 01:21:24.300870 | controller | ++ functions:check_path_perm_sanity:618     :   echo /opt/stack
2026-05-20 01:21:24.301028 | controller | ++ functions:check_path_perm_sanity:618     :   tr / ' '
2026-05-20 01:21:24.308307 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:24.312050 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt
2026-05-20 01:21:24.317691 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt
2026-05-20 01:21:24.325275 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:24.329673 | controller | + functions:check_path_perm_sanity:618     :   for i in $(echo ${real_path} | tr "/" " ")
2026-05-20 01:21:24.333738 | controller | + functions:check_path_perm_sanity:619     :   rebuilt_path=/opt/stack
2026-05-20 01:21:24.339168 | controller | ++ functions:check_path_perm_sanity:621     :   stat -c %a /opt/stack
2026-05-20 01:21:24.346962 | controller | + functions:check_path_perm_sanity:621     :   [[ 755 = 700 ]]
2026-05-20 01:21:24.351333 | controller | + ./stack.sh:main:517                      :   trap exit_trap EXIT
2026-05-20 01:21:24.356412 | controller | + ./stack.sh:main:558                      :   trap err_trap ERR
2026-05-20 01:21:24.360685 | controller | + ./stack.sh:main:571                      :   set -o errexit
2026-05-20 01:21:24.364737 | controller | + ./stack.sh:main:574                      :   uname -a
2026-05-20 01:21:24.366892 | controller | Linux np0000175158 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:24.371779 | controller | + ./stack.sh:main:577                      :   SSL_BUNDLE_FILE=/opt/stack/data/ca-bundle.pem
2026-05-20 01:21:24.375849 | controller | + ./stack.sh:main:578                      :   rm -f /opt/stack/data/ca-bundle.pem
2026-05-20 01:21:24.383190 | controller | + ./stack.sh:main:581                      :   source /opt/stack/devstack/lib/database
2026-05-20 01:21:24.450107 | controller | + ./stack.sh:main:582                      :   source /opt/stack/devstack/lib/rpc_backend
2026-05-20 01:21:24.482612 | controller | + ./stack.sh:main:585                      :   source /opt/stack/devstack/lib/host
2026-05-20 01:21:24.488313 | controller | +++ lib/host:source:12                       :   trueorfalse True ENABLE_KSM
2026-05-20 01:21:24.517545 | controller | ++ lib/host:source:12                       :   ENABLE_KSM=True
2026-05-20 01:21:24.523156 | controller | +++ lib/host:source:13                       :   trueorfalse True ENABLE_KSMTUNED
2026-05-20 01:21:24.551722 | controller | ++ lib/host:source:13                       :   ENABLE_KSMTUNED=True
2026-05-20 01:21:24.557567 | controller | +++ lib/host:source:34                       :   trueorfalse False ENABLE_ZSWAP
2026-05-20 01:21:24.586838 | controller | ++ lib/host:source:34                       :   ENABLE_ZSWAP=True
2026-05-20 01:21:24.590845 | controller | ++ lib/host:source:37                       :   ZSWAP_COMPRESSOR=lz4
2026-05-20 01:21:24.594495 | controller | ++ lib/host:source:38                       :   ZSWAP_ZPOOL=zsmalloc
2026-05-20 01:21:24.600807 | controller | +++ lib/host:source:53                       :   trueorfalse False ENABLE_SYSCTL_MEM_TUNING
2026-05-20 01:21:24.630534 | controller | ++ lib/host:source:53                       :   ENABLE_SYSCTL_MEM_TUNING=True
2026-05-20 01:21:24.636345 | controller | +++ lib/host:source:75                       :   trueorfalse False ENABLE_SYSCTL_NET_TUNING
2026-05-20 01:21:24.664920 | controller | ++ lib/host:source:75                       :   ENABLE_SYSCTL_NET_TUNING=True
2026-05-20 01:21:24.669621 | controller | + ./stack.sh:main:588                      :   tune_host
2026-05-20 01:21:24.673974 | controller | + lib/host:tune_host:96                    :   configure_host_mem
2026-05-20 01:21:24.678347 | controller | + lib/host:configure_host_mem:70           :   configure_zswap
2026-05-20 01:21:24.682517 | controller | + lib/host:configure_zswap:40              :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:24.686394 | controller | + lib/host:configure_zswap:43              :   is_ubuntu
2026-05-20 01:21:24.690378 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:24.693900 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:24.699186 | controller | + lib/host:configure_zswap:44              :   echo lz4
2026-05-20 01:21:24.699303 | controller | + lib/host:configure_zswap:44              :   sudo tee /sys/module/zswap/parameters/compressor
2026-05-20 01:21:24.710719 | controller | lz4
2026-05-20 01:21:24.717669 | controller | + lib/host:configure_zswap:45              :   echo zsmalloc
2026-05-20 01:21:24.718791 | controller | + lib/host:configure_zswap:45              :   sudo tee /sys/module/zswap/parameters/zpool
2026-05-20 01:21:24.733336 | controller | zsmalloc
2026-05-20 01:21:24.739661 | controller | + lib/host:configure_zswap:47              :   echo 1
2026-05-20 01:21:24.739781 | controller | + lib/host:configure_zswap:47              :   sudo tee /sys/module/zswap/parameters/enabled
2026-05-20 01:21:24.751213 | controller | 1
2026-05-20 01:21:24.780807 | controller | + lib/host:configure_zswap:49              :   sudo grep -R . /sys/module/zswap/parameters
2026-05-20 01:21:24.797463 | controller | /sys/module/zswap/parameters/same_filled_pages_enabled:Y
2026-05-20 01:21:24.797573 | controller | /sys/module/zswap/parameters/enabled:Y
2026-05-20 01:21:24.797593 | controller | /sys/module/zswap/parameters/shrinker_enabled:Y
2026-05-20 01:21:24.797602 | controller | /sys/module/zswap/parameters/max_pool_percent:20
2026-05-20 01:21:24.797612 | controller | /sys/module/zswap/parameters/compressor:lz4
2026-05-20 01:21:24.797620 | controller | /sys/module/zswap/parameters/non_same_filled_pages_enabled:Y
2026-05-20 01:21:24.797629 | controller | /sys/module/zswap/parameters/zpool:zsmalloc
2026-05-20 01:21:24.797638 | controller | /sys/module/zswap/parameters/exclusive_loads:N
2026-05-20 01:21:24.797646 | controller | /sys/module/zswap/parameters/accept_threshold_percent:90
2026-05-20 01:21:24.803702 | controller | + lib/host:configure_host_mem:71           :   configure_ksm
2026-05-20 01:21:24.808556 | controller | + lib/host:configure_ksm:15                :   [[ True == \T\r\u\e ]]
2026-05-20 01:21:24.813094 | controller | + lib/host:configure_ksm:16                :   install_package ksmtuned
2026-05-20 01:21:24.816575 | controller | + functions-common:install_package:1432    :   update_package_repo
2026-05-20 01:21:24.819813 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:21:24.823211 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=False
2026-05-20 01:21:24.826144 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=False
2026-05-20 01:21:24.829460 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:24.831850 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:21:24.835699 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:21:24.839636 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:21:24.842355 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:21:24.845854 | controller | + functions-common:apt_get_update:1160     :   [[ False == \T\r\u\e ]]
2026-05-20 01:21:24.849129 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:21:24.851916 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:21:24.856577 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:21:24.863188 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:21:24.867280 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:21:24.871516 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:21:24.876373 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:21:24.879789 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:21:24.884183 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:21:24.890082 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240084885
2026-05-20 01:21:24.893278 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:21:24.897468 | 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:24.901381 | 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:25.293571 | controller | Hit:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:21:25.317693 | controller | Hit:2 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:21:25.355723 | controller | Hit:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:21:34.984012 | controller | Ign:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:21:48.469056 | controller | Ign:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:21:56.376532 | controller | Ign:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:10.906633 | controller | Err:4 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:22:10.906720 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:11.951230 | controller | Reading package lists...
2026-05-20 01:22:11.980688 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:22:11.980713 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:22:11.990815 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:22:11.995747 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:22:12.000458 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:22:12.005069 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:22:12.009551 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:22:12.013861 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:22:12.017842 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:22:12.021832 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:22:12.025869 | controller | + functions-common:time_stop:2421          :   start_time=1779240084885
2026-05-20 01:22:12.030543 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240084885 ]]
2026-05-20 01:22:12.036222 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:22:12.042953 | controller | + functions-common:time_stop:2426          :   end_time=1779240132037
2026-05-20 01:22:12.046737 | controller | + functions-common:time_stop:2427          :   elapsed_time=47152
2026-05-20 01:22:12.050457 | controller | + functions-common:time_stop:2428          :   total=0
2026-05-20 01:22:12.054624 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:22:12.058463 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=47152
2026-05-20 01:22:12.063291 | controller | + functions-common:install_package:1433    :   real_install_package ksmtuned
2026-05-20 01:22:12.066860 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:22:12.070495 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:22:12.074367 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:22:12.078689 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:22:12.112907 | 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:22:12.155424 | controller | Reading package lists...
2026-05-20 01:22:12.433864 | controller | Building dependency tree...
2026-05-20 01:22:12.433994 | controller | Reading state information...
2026-05-20 01:22:12.710622 | controller | Recommended packages:
2026-05-20 01:22:12.710673 | controller |   qemu-kvm
2026-05-20 01:22:12.748374 | controller | The following NEW packages will be installed:
2026-05-20 01:22:12.750577 | controller |   ksmtuned
2026-05-20 01:22:27.670930 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:22:27.670983 | controller | Need to get 7,444 B of archives.
2026-05-20 01:22:27.670994 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:22:27.671004 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:22:43.700657 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:22:55.712504 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:23:14.479485 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:23:14.479549 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:23:14.495706 | 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:23:14.495778 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:23:14.504306 | controller | + functions-common:apt_get:1205            :   result=100
2026-05-20 01:23:14.508961 | controller | + functions-common:apt_get:1208            :   time_stop apt-get
2026-05-20 01:23:14.512427 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:23:14.517773 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:23:14.521912 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:23:14.527056 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:23:14.530936 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:23:14.535820 | controller | + functions-common:time_stop:2420          :   name=apt-get
2026-05-20 01:23:14.540141 | controller | + functions-common:time_stop:2421          :   start_time=1779240132108
2026-05-20 01:23:14.543299 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240132108 ]]
2026-05-20 01:23:14.549408 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:23:14.556480 | controller | + functions-common:time_stop:2426          :   end_time=1779240194551
2026-05-20 01:23:14.560354 | controller | + functions-common:time_stop:2427          :   elapsed_time=62443
2026-05-20 01:23:14.564536 | controller | + functions-common:time_stop:2428          :   total=32221
2026-05-20 01:23:14.569310 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:23:14.573451 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=94664
2026-05-20 01:23:14.577038 | controller | + functions-common:apt_get:1209            :   return 100
2026-05-20 01:23:14.581199 | controller | + functions-common:install_package:1434    :   RETRY_UPDATE=True
2026-05-20 01:23:14.585425 | controller | + functions-common:install_package:1434    :   update_package_repo
2026-05-20 01:23:14.589466 | controller | + functions-common:update_package_repo:1406 :   NO_UPDATE_REPOS=False
2026-05-20 01:23:14.594213 | controller | + functions-common:update_package_repo:1407 :   REPOS_UPDATED=True
2026-05-20 01:23:14.598428 | controller | + functions-common:update_package_repo:1408 :   RETRY_UPDATE=True
2026-05-20 01:23:14.602267 | controller | + functions-common:update_package_repo:1410 :   [[ False = \T\r\u\e ]]
2026-05-20 01:23:14.605866 | controller | + functions-common:update_package_repo:1414 :   is_ubuntu
2026-05-20 01:23:14.610323 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:23:14.613784 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:23:14.618317 | controller | + functions-common:update_package_repo:1415 :   apt_get_update
2026-05-20 01:23:14.621896 | controller | + functions-common:apt_get_update:1160     :   [[ True == \T\r\u\e ]]
2026-05-20 01:23:14.626104 | controller | + functions-common:apt_get_update:1160     :   [[ True != \T\r\u\e ]]
2026-05-20 01:23:14.630151 | controller | + functions-common:apt_get_update:1165     :   [[ False = \T\r\u\e ]]
2026-05-20 01:23:14.634424 | controller | + functions-common:apt_get_update:1167     :   local sudo=sudo
2026-05-20 01:23:14.639920 | controller | ++ functions-common:apt_get_update:1168     :   id -u
2026-05-20 01:23:14.647477 | controller | + functions-common:apt_get_update:1168     :   [[ 1002 = \0 ]]
2026-05-20 01:23:14.651462 | controller | + functions-common:apt_get_update:1171     :   time_start apt-get-update
2026-05-20 01:23:14.654438 | controller | + functions-common:time_start:2400         :   local name=apt-get-update
2026-05-20 01:23:14.658382 | controller | + functions-common:time_start:2401         :   local start_time=
2026-05-20 01:23:14.662438 | controller | + functions-common:time_start:2402         :   [[ -n '' ]]
2026-05-20 01:23:14.667361 | controller | ++ functions-common:time_start:2405         :   date +%s%3N
2026-05-20 01:23:14.673966 | controller | + functions-common:time_start:2405         :   _TIME_START[$name]=1779240194669
2026-05-20 01:23:14.678530 | controller | + functions-common:apt_get_update:1173     :   local 'proxies=http_proxy= https_proxy= no_proxy= '
2026-05-20 01:23:14.681755 | controller | + functions-common:apt_get_update:1174     :   local 'update_cmd=sudo http_proxy= https_proxy= no_proxy=  apt-get update'
2026-05-20 01:23:14.685408 | 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:23:28.734757 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:23:29.806687 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:23:40.839567 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:23:42.657069 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:23:52.680545 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:23:52.852631 | controller | Ign:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:24:02.693951 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:24:06.870847 | controller | Err:2 http://security.ubuntu.com/ubuntu noble-security InRelease
2026-05-20 01:24:06.870895 | controller |   Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:24:12.705036 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:24:22.224823 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:24:32.246541 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:24:42.269065 | controller | Ign:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:24:52.291195 | controller | Ign:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:07.308914 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-05-20 01:25:07.308957 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:17.322397 | controller | Err:3 http://nova.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease
2026-05-20 01:25:17.322440 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:27.336176 | controller | Err:4 http://nova.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease
2026-05-20 01:25:27.336219 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:25:28.356619 | controller | Reading package lists...
2026-05-20 01:25:28.382792 | 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:25:28.382832 | 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:25:28.382846 | 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:25:28.382859 | controller | W: Failed to fetch http://security.ubuntu.com/ubuntu/dists/noble-security/InRelease  Temporary failure resolving 'security.ubuntu.com'
2026-05-20 01:25:28.382872 | controller | W: Some index files failed to download. They have been ignored, or old ones used instead.
2026-05-20 01:25:28.391699 | controller | + functions-common:apt_get_update:1179     :   REPOS_UPDATED=True
2026-05-20 01:25:28.395546 | controller | + functions-common:apt_get_update:1181     :   time_stop apt-get-update
2026-05-20 01:25:28.400485 | controller | + functions-common:time_stop:2414          :   local name
2026-05-20 01:25:28.404785 | controller | + functions-common:time_stop:2415          :   local end_time
2026-05-20 01:25:28.408946 | controller | + functions-common:time_stop:2416          :   local elapsed_time
2026-05-20 01:25:28.413932 | controller | + functions-common:time_stop:2417          :   local total
2026-05-20 01:25:28.418044 | controller | + functions-common:time_stop:2418          :   local start_time
2026-05-20 01:25:28.421912 | controller | + functions-common:time_stop:2420          :   name=apt-get-update
2026-05-20 01:25:28.426394 | controller | + functions-common:time_stop:2421          :   start_time=1779240194669
2026-05-20 01:25:28.430284 | controller | + functions-common:time_stop:2423          :   [[ -z 1779240194669 ]]
2026-05-20 01:25:28.435412 | controller | ++ functions-common:time_stop:2426          :   date +%s%3N
2026-05-20 01:25:28.442172 | controller | + functions-common:time_stop:2426          :   end_time=1779240328437
2026-05-20 01:25:28.447429 | controller | + functions-common:time_stop:2427          :   elapsed_time=133768
2026-05-20 01:25:28.451617 | controller | + functions-common:time_stop:2428          :   total=47152
2026-05-20 01:25:28.455404 | controller | + functions-common:time_stop:2430          :   _TIME_START[$name]=
2026-05-20 01:25:28.459832 | controller | + functions-common:time_stop:2431          :   _TIME_TOTAL[$name]=180920
2026-05-20 01:25:28.463514 | controller | + functions-common:install_package:1434    :   real_install_package ksmtuned
2026-05-20 01:25:28.466483 | controller | + functions-common:real_install_package:1420 :   is_ubuntu
2026-05-20 01:25:28.471010 | controller | + functions-common:is_ubuntu:546           :   [[ -z deb ]]
2026-05-20 01:25:28.475141 | controller | + functions-common:is_ubuntu:549           :   '[' deb = deb ']'
2026-05-20 01:25:28.478298 | controller | + functions-common:real_install_package:1421 :   apt_get install ksmtuned
2026-05-20 01:25:28.513905 | 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:25:28.556345 | controller | Reading package lists...
2026-05-20 01:25:28.827508 | controller | Building dependency tree...
2026-05-20 01:25:28.827544 | controller | Reading state information...
2026-05-20 01:25:29.085641 | controller | Recommended packages:
2026-05-20 01:25:29.085686 | controller |   qemu-kvm
2026-05-20 01:25:29.117275 | controller | The following NEW packages will be installed:
2026-05-20 01:25:29.121324 | controller |   ksmtuned
2026-05-20 01:25:44.170769 | controller | 0 upgraded, 1 newly installed, 0 to remove and 1 not upgraded.
2026-05-20 01:25:44.170862 | controller | Need to get 7,444 B of archives.
2026-05-20 01:25:44.170874 | controller | After this operation, 44.0 kB of additional disk space will be used.
2026-05-20 01:25:44.170883 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:25:55.187889 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:26:07.205999 | controller | Ign:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:26:21.217601 | controller | Err:1 http://nova.clouds.archive.ubuntu.com/ubuntu noble/universe amd64 ksmtuned amd64 4.20150326
2026-05-20 01:26:21.217690 | controller |   Temporary failure resolving 'nova.clouds.archive.ubuntu.com'
2026-05-20 01:26:21.225872 | 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:26:21.225915 | controller | E: Unable to fetch some archives, maybe run apt-get update or try with --fix-missing?
2026-05-20 01:26:21.237342 | controller | + functions-common:apt_get:1               :   exit_trap
2026-05-20 01:26:21.242502 | controller | + ./stack.sh:exit_trap:519                 :   local r=100
2026-05-20 01:26:21.249610 | controller | ++ ./stack.sh:exit_trap:520                 :   jobs -p
2026-05-20 01:26:21.255304 | controller | + ./stack.sh:exit_trap:520                 :   jobs=
2026-05-20 01:26:21.258465 | controller | + ./stack.sh:exit_trap:523                 :   [[ -n '' ]]
2026-05-20 01:26:21.264050 | controller | + ./stack.sh:exit_trap:529                 :   '[' -f '' ']'
2026-05-20 01:26:21.268695 | controller | + ./stack.sh:exit_trap:534                 :   kill_spinner
2026-05-20 01:26:21.272245 | controller | + ./stack.sh:kill_spinner:429              :   '[' '!' -z '' ']'
2026-05-20 01:26:21.276186 | controller | + ./stack.sh:exit_trap:536                 :   [[ 100 -ne 0 ]]
2026-05-20 01:26:21.280568 | controller | + ./stack.sh:exit_trap:537                 :   echo 'Error on exit'
2026-05-20 01:26:21.280624 | controller | Error on exit
2026-05-20 01:26:21.285449 | controller | + ./stack.sh:exit_trap:539                 :   type -p generate-subunit
2026-05-20 01:26:21.290554 | controller | + ./stack.sh:exit_trap:542                 :   [[ -z /opt/stack/logs ]]
2026-05-20 01:26:21.294230 | controller | + ./stack.sh:exit_trap:545                 :   /usr/bin/python3.12 /opt/stack/devstack/tools/worlddump.py -d /opt/stack/logs
2026-05-20 01:26:21.828443 | controller | + ./stack.sh:exit_trap:554                 :   exit 100
2026-05-20 01:26:21.832209 | controller | *** FINISHED ***
2026-05-20 01:26:21.945629 | controller | ERROR
2026-05-20 01:26:21.945824 | controller | {
2026-05-20 01:26:21.945879 | controller |   "delta": "0:05:34.512711",
2026-05-20 01:26:21.945921 | controller |   "end": "2026-05-20 01:26:21.844478",
2026-05-20 01:26:21.945948 | controller |   "msg": "non-zero return code",
2026-05-20 01:26:21.945974 | controller |   "rc": 100,
2026-05-20 01:26:21.946006 | controller |   "start": "2026-05-20 01:20:47.331767"
2026-05-20 01:26:21.946036 | controller | }
failure
2026-05-20 01:26:21.960036 | 
2026-05-20 01:26:21.960123 | PLAY RECAP
2026-05-20 01:26:21.960169 | controller | ok: 0 changed: 0 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-05-20 01:26:21.960192 | 
2026-05-20 01:26:22.197142 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/run.yml@main]
2026-05-20 01:26:22.202177 | POST-RUN START: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:26:22.862710 | 
2026-05-20 01:26:22.862824 | PLAY [all]
2026-05-20 01:26:22.874919 | 
2026-05-20 01:26:22.875024 | LOOP [Copy individual Hydrophone result files]
2026-05-20 01:26:23.516234 | controller | ERROR: Item: e2e.log
2026-05-20 01:26:23.516542 | controller | {
2026-05-20 01:26:23.516597 | controller |   "ansible_loop_var": "item",
2026-05-20 01:26:23.516637 | controller |   "item": "e2e.log",
2026-05-20 01:26:23.516717 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/e2e.log not found"
2026-05-20 01:26:23.516752 | controller | }
2026-05-20 01:26:23.783311 | controller | ERROR: Item: junit_01.xml
2026-05-20 01:26:23.783440 | controller | {
2026-05-20 01:26:23.783473 | controller |   "ansible_loop_var": "item",
2026-05-20 01:26:23.783495 | controller |   "item": "junit_01.xml",
2026-05-20 01:26:23.783514 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results/junit_01.xml not found"
2026-05-20 01:26:23.783532 | controller | }
2026-05-20 01:26:23.797384 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:23.804442 | 
2026-05-20 01:26:23.804528 | TASK [Copy Hydrophone results tarball to output folder]
2026-05-20 01:26:24.102583 | controller | ERROR
2026-05-20 01:26:24.102810 | controller | {
2026-05-20 01:26:24.102840 | controller |   "msg": "Source src/github.com/vexxhost/magnum-cluster-api/hydrophone-results.tar.gz not found"
2026-05-20 01:26:24.102861 | controller | }
2026-05-20 01:26:24.102885 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:24.113912 | 
2026-05-20 01:26:24.113982 | TASK [Return built artifacts to Zuul]
2026-05-20 01:26:24.147030 | controller | ok
2026-05-20 01:26:24.154272 | 
2026-05-20 01:26:24.154356 | TASK [Get the Docker volume ID]
2026-05-20 01:26:24.692545 | controller | ERROR
2026-05-20 01:26:24.692808 | controller | {
2026-05-20 01:26:24.692850 | controller |   "msg": "[Errno 2] No such file or directory: b'docker'",
2026-05-20 01:26:24.692881 | controller |   "rc": 2
2026-05-20 01:26:24.692908 | controller | }
2026-05-20 01:26:24.692968 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:24.700157 | 
2026-05-20 01:26:24.700247 | TASK [Copy all of the pod logs]
2026-05-20 01:26:24.722341 | controller | ERROR
2026-05-20 01:26:24.722538 | controller | {
2026-05-20 01:26:24.722577 | 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/38ebd1f5f1fd44e1a1877660e8b2b86f/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:26:24.722610 | controller | }
2026-05-20 01:26:24.722643 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:24.723751 | 
2026-05-20 01:26:24.723810 | PLAY RECAP
2026-05-20 01:26:24.723865 | controller | ok: 5 changed: 0 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4
2026-05-20 01:26:24.723894 | 
2026-05-20 01:26:24.848891 | POST-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/magnum-cluster-api/zuul.d/playbooks/hydrophone/post.yml@main]
2026-05-20 01:26:24.858210 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:26:25.486372 | 
2026-05-20 01:26:25.486604 | PLAY [all]
2026-05-20 01:26:25.505197 | 
2026-05-20 01:26:25.505533 | TASK [export-devstack-journal : Ensure /home/zuul/logs exists]
2026-05-20 01:26:26.100637 | controller | changed
2026-05-20 01:26:26.108801 | 
2026-05-20 01:26:26.108880 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-05-20 01:26:27.148756 | controller | ok: Runtime: 0:00:00.459198
2026-05-20 01:26:27.156858 | 
2026-05-20 01:26:27.156997 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-05-20 01:26:27.700689 | controller | ok: Runtime: 0:00:00.021821
2026-05-20 01:26:27.711684 | 
2026-05-20 01:26:27.711807 | TASK [export-devstack-journal : Export journal]
2026-05-20 01:26:28.303766 | controller | ok: Runtime: 0:00:00.024540
2026-05-20 01:26:28.313357 | 
2026-05-20 01:26:28.313475 | TASK [export-devstack-journal : Save journal README]
2026-05-20 01:26:29.187370 | controller | changed
2026-05-20 01:26:29.201474 | 
2026-05-20 01:26:29.201553 | TASK [apache-logs-conf : Ensure /home/zuul/apache exists]
2026-05-20 01:26:29.447991 | controller | changed
2026-05-20 01:26:29.455684 | 
2026-05-20 01:26:29.455823 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:26:29.785810 | controller | Output suppressed because no_log was given
2026-05-20 01:26:29.790974 | 
2026-05-20 01:26:29.791041 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:26:29.823566 | 
2026-05-20 01:26:29.823717 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:26:29.849342 | 
2026-05-20 01:26:29.849505 | TASK [apache-logs-conf : Find logs]
2026-05-20 01:26:29.884703 | 
2026-05-20 01:26:29.884819 | LOOP [apache-logs-conf : Dereference files]
2026-05-20 01:26:29.922243 | 
2026-05-20 01:26:29.922495 | LOOP [apache-logs-conf : Create hard links]
2026-05-20 01:26:29.960016 | 
2026-05-20 01:26:29.960200 | TASK [apache-logs-conf : Ensure /home/zuul/apache_config apache_config exists]
2026-05-20 01:26:30.200405 | controller | changed
2026-05-20 01:26:30.208473 | 
2026-05-20 01:26:30.208551 | TASK [apache-logs-conf : Define config paths]
2026-05-20 01:26:30.243432 | controller | ok
2026-05-20 01:26:30.248594 | 
2026-05-20 01:26:30.248658 | TASK [apache-logs-conf : Discover configurations]
2026-05-20 01:26:30.484034 | controller | Output suppressed because no_log was given
2026-05-20 01:26:30.490165 | 
2026-05-20 01:26:30.490237 | LOOP [apache-logs-conf : Dereference configurations]
2026-05-20 01:26:30.517161 | 
2026-05-20 01:26:30.517340 | LOOP [apache-logs-conf : Link configurations]
2026-05-20 01:26:30.550342 | 
2026-05-20 01:26:30.550494 | TASK [capture-performance-data : Generate statistics]
2026-05-20 01:26:33.003514 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-05-20 01:26:33.022505 | controller | Using python 3.12 to install setuptools
2026-05-20 01:26:33.056154 | controller | /usr/bin/python3.12: No module named pip
2026-05-20 01:26:33.066504 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-05-20 01:26:33.137426 | controller | ERROR
2026-05-20 01:26:33.137573 | controller | {
2026-05-20 01:26:33.137607 | controller |   "delta": "0:00:02.289157",
2026-05-20 01:26:33.137628 | controller |   "end": "2026-05-20 01:26:33.068570",
2026-05-20 01:26:33.137647 | controller |   "msg": "non-zero return code",
2026-05-20 01:26:33.137669 | controller |   "rc": 127,
2026-05-20 01:26:33.137689 | controller |   "start": "2026-05-20 01:26:30.779413"
2026-05-20 01:26:33.137707 | controller | }
2026-05-20 01:26:33.137732 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:33.148700 | 
2026-05-20 01:26:33.148769 | TASK [devstack-project-conf : Ensure /home/zuul/etc exists]
2026-05-20 01:26:33.395139 | controller | changed
2026-05-20 01:26:33.404489 | 
2026-05-20 01:26:33.404563 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-05-20 01:26:37.206839 | controller | Output suppressed because no_log was given
2026-05-20 01:26:37.220148 | 
2026-05-20 01:26:37.220220 | LOOP [devstack-project-conf : Copy configuration files]
2026-05-20 01:26:37.289290 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.289615 | 
2026-05-20 01:26:37.291370 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.293595 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.296310 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.299898 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.302725 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.305510 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.308460 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.311204 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.313966 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.316684 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.319574 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.322495 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.325331 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.328122 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.330774 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.333430 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.336123 | controller | skipping: Conditional result was False
2026-05-20 01:26:37.416711 | 
2026-05-20 01:26:37.416875 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-05-20 01:26:37.695007 | controller | ok
2026-05-20 01:26:37.703509 | 
2026-05-20 01:26:37.703616 | TASK [devstack-project-conf : Copy configuration files]
2026-05-20 01:26:38.239359 | controller | skipping: Conditional result was False
2026-05-20 01:26:38.258057 | 
2026-05-20 01:26:38.258145 | TASK [capture-system-logs : Stage various logs and reports]
2026-05-20 01:26:38.560445 | controller | /usr/bin/python3: No module named pip
2026-05-20 01:26:39.731369 | controller | grep: /home/zuul/apache/*.log: No such file or directory
2026-05-20 01:26:39.801750 | controller | ok: Runtime: 0:00:01.241393
2026-05-20 01:26:39.813340 | 
2026-05-20 01:26:39.813418 | LOOP [stage-output : Register sources]
2026-05-20 01:26:49.369938 | controller | Output suppressed because no_log was given
2026-05-20 01:26:49.381949 | 
2026-05-20 01:26:49.382010 | TASK [stage-output : Check sudo]
2026-05-20 01:26:49.917643 | controller | ok: Runtime: 0:00:00.022479
2026-05-20 01:26:49.925526 | 
2026-05-20 01:26:49.925775 | LOOP [stage-output : Set source and destination for files and folders]
2026-05-20 01:26:50.022361 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.022960 | 
2026-05-20 01:26:50.024735 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.025838 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.028211 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.071208 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.071836 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.072194 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.072580 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.072928 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.074560 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.077102 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.079570 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.099975 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.107678 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.108273 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.109770 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.136397 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.136846 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.137435 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.172748 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.173312 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.256417 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.265767 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.277948 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.289854 | controller | Output suppressed because no_log was given
2026-05-20 01:26:50.305404 | 
2026-05-20 01:26:50.305526 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-05-20 01:26:50.382955 | controller | ok
2026-05-20 01:26:50.390351 | 
2026-05-20 01:26:50.390420 | LOOP [stage-output : Ensure target folders exist]
2026-05-20 01:26:50.656682 | controller | changed: "docs"
2026-05-20 01:26:50.877696 | controller | changed: "artifacts"
2026-05-20 01:26:51.118785 | controller | ok: "logs"
2026-05-20 01:26:51.141094 | 
2026-05-20 01:26:51.141272 | LOOP [stage-output : Copy files and folders to staging folder]
2026-05-20 01:26:51.425731 | controller | ok: Item: Runtime: 0:00:00.010095
2026-05-20 01:26:51.426188 | controller | changed: All items complete
2026-05-20 01:26:51.426241 | 
2026-05-20 01:26:51.652240 | controller | ok: Item: Runtime: 0:00:00.010124
2026-05-20 01:26:51.878531 | controller | ok: Item: Runtime: 0:00:00.009282
2026-05-20 01:26:52.111164 | controller | ok: Item: Runtime: 0:00:00.008452
2026-05-20 01:26:52.333307 | controller | ok: Item: Runtime: 0:00:00.008394
2026-05-20 01:26:52.562688 | controller | ok: Item: Runtime: 0:00:00.008960
2026-05-20 01:26:52.857019 | controller | ok: Item: Runtime: 0:00:00.007907
2026-05-20 01:26:53.040518 | controller | ok: Item: Runtime: 0:00:00.008638
2026-05-20 01:26:53.278291 | controller | ok: Item: Runtime: 0:00:00.008730
2026-05-20 01:26:54.000779 | controller | ok: Item: Runtime: 0:00:00.009574
2026-05-20 01:26:54.010773 | controller | ok: Item: Runtime: 0:00:00.007657
2026-05-20 01:26:54.029688 | controller | ok: Item: Runtime: 0:00:00.009931
2026-05-20 01:26:54.213007 | controller | ok: Item: Runtime: 0:00:00.009150
2026-05-20 01:26:54.465410 | controller | ok: Item: Runtime: 0:00:00.009113
2026-05-20 01:26:54.700635 | controller | ok: Item: Runtime: 0:00:00.008446
2026-05-20 01:26:54.939885 | controller | ok: Item: Runtime: 0:00:00.007523
2026-05-20 01:26:55.180601 | controller | ok: Item: Runtime: 0:00:00.013405
2026-05-20 01:26:55.404928 | controller | ok: Item: Runtime: 0:00:00.007855
2026-05-20 01:26:55.634579 | controller | ok: Item: Runtime: 0:00:00.007767
2026-05-20 01:26:55.871653 | controller | ok: Item: Runtime: 0:00:00.009079
2026-05-20 01:26:55.887812 | 
2026-05-20 01:26:55.887874 | TASK [stage-output : Make all log files readable]
2026-05-20 01:26:56.137109 | controller | changed
2026-05-20 01:26:56.145602 | 
2026-05-20 01:26:56.145685 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-05-20 01:26:56.655483 | controller | changed: Renamed files for staging.
2026-05-20 01:26:56.662331 | 
2026-05-20 01:26:56.662395 | TASK [stage-output : Discover log files for compression]
2026-05-20 01:26:56.685892 | controller | skipping: Conditional result was False
2026-05-20 01:26:56.698211 | 
2026-05-20 01:26:56.698286 | LOOP [stage-output : Archive everything from logs]
2026-05-20 01:26:56.742249 | 
2026-05-20 01:26:56.742381 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-05-20 01:26:57.408276 | controller | changed:
2026-05-20 01:26:57.408523 | controller | created directory /var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/work/logs/controller
2026-05-20 01:26:57.408568 | controller | cd+++++++++ logs/
2026-05-20 01:26:57.408598 | controller | >f+++++++++ logs/_.localrc_auto.txt
2026-05-20 01:26:57.408625 | controller | >f+++++++++ logs/deprecations_log.txt
2026-05-20 01:26:57.408651 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-05-20 01:26:57.408679 | controller | >f+++++++++ logs/devstack.journal.gz
2026-05-20 01:26:57.408711 | controller | >f+++++++++ logs/devstacklog.txt
2026-05-20 01:26:57.408742 | controller | >f+++++++++ logs/devstacklog.txt.summary
2026-05-20 01:26:57.408773 | controller | >f+++++++++ logs/df.txt
2026-05-20 01:26:57.408804 | controller | >f+++++++++ logs/dpkg-l.txt
2026-05-20 01:26:57.408835 | controller | >f+++++++++ logs/iptables.txt
2026-05-20 01:26:57.408866 | controller | >f+++++++++ logs/listen53.txt
2026-05-20 01:26:57.408893 | controller | >f+++++++++ logs/local_conf.txt
2026-05-20 01:26:57.408918 | controller | >f+++++++++ logs/mount.txt
2026-05-20 01:26:57.408943 | controller | >f+++++++++ logs/performance.json
2026-05-20 01:26:57.408995 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-05-20 01:26:57.409023 | controller | >f+++++++++ logs/resolv_conf.txt
2026-05-20 01:26:57.409048 | controller | >f+++++++++ logs/services.txt
2026-05-20 01:26:57.409073 | controller | >f+++++++++ logs/sudoers
2026-05-20 01:26:57.409098 | controller | >f+++++++++ logs/syslog.txt
2026-05-20 01:26:57.409122 | controller | >f+++++++++ logs/worlddump-latest.txt
2026-05-20 01:26:57.409147 | controller | cd+++++++++ logs/apache/
2026-05-20 01:26:57.409171 | controller | cd+++++++++ logs/apache_config/
2026-05-20 01:26:57.409195 | controller | cd+++++++++ logs/etc/
2026-05-20 01:26:57.409219 | controller | cd+++++++++ logs/sudoers.d/
2026-05-20 01:26:57.409243 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-05-20 01:26:57.409268 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-05-20 01:26:57.409294 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-05-20 01:26:57.409319 | controller | >f+++++++++ logs/sudoers.d/README
2026-05-20 01:26:57.409344 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-05-20 01:26:57.422343 | 
2026-05-20 01:26:57.422408 | TASK [Check if a tempest log exits]
2026-05-20 01:26:57.681145 | controller | ok
2026-05-20 01:26:57.690073 | 
2026-05-20 01:26:57.690144 | TASK [Link post-devstack tempest.log]
2026-05-20 01:26:57.715309 | controller | skipping: Conditional result was False
2026-05-20 01:26:57.724683 | 
2026-05-20 01:26:57.724793 | TASK [Capture most recent qemu crash dump, if any]
2026-05-20 01:26:57.967599 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-05-20 01:26:58.267910 | controller | ERROR
2026-05-20 01:26:58.268054 | controller | {
2026-05-20 01:26:58.268093 | controller |   "delta": "0:00:00.008027",
2026-05-20 01:26:58.268121 | controller |   "end": "2026-05-20 01:26:57.968295",
2026-05-20 01:26:58.268147 | controller |   "msg": "non-zero return code",
2026-05-20 01:26:58.268172 | controller |   "rc": 127,
2026-05-20 01:26:58.268197 | controller |   "start": "2026-05-20 01:26:57.960268"
2026-05-20 01:26:58.268221 | controller | }
2026-05-20 01:26:58.268252 | controller | ERROR: Ignoring Errors
2026-05-20 01:26:58.269976 | 
2026-05-20 01:26:58.270036 | PLAY RECAP
2026-05-20 01:26:58.270095 | controller | ok: 26 changed: 17 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 2
2026-05-20 01:26:58.270124 | 
2026-05-20 01:26:58.392173 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-05-20 01:26:58.402048 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:26:58.998941 | 
2026-05-20 01:26:58.999126 | PLAY [all]
2026-05-20 01:26:59.011841 | 
2026-05-20 01:26:59.011936 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-20 01:26:59.056658 | controller | skipping: Conditional result was False
2026-05-20 01:26:59.068572 | 
2026-05-20 01:26:59.068685 | TASK [fetch-output : Set log path for single node]
2026-05-20 01:26:59.111640 | controller | ok
2026-05-20 01:26:59.118583 | 
2026-05-20 01:26:59.118735 | LOOP [fetch-output : Ensure local output dirs]
2026-05-20 01:26:59.472367 | controller -> localhost | ok: "/var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/work/logs"
2026-05-20 01:26:59.666818 | controller -> localhost | changed: "/var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/work/artifacts"
2026-05-20 01:26:59.863207 | controller -> localhost | changed: "/var/lib/zuul/builds/38ebd1f5f1fd44e1a1877660e8b2b86f/work/docs"
2026-05-20 01:26:59.885553 | 
2026-05-20 01:26:59.885671 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-20 01:27:00.520323 | controller | changed: .d..t...... ./
2026-05-20 01:27:00.520582 | controller | changed: All items complete
2026-05-20 01:27:00.520630 | 
2026-05-20 01:27:01.007101 | controller | changed: .d..t...... ./
2026-05-20 01:27:01.487906 | controller | changed: .d..t...... ./
2026-05-20 01:27:01.500298 | 
2026-05-20 01:27:01.500366 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-20 01:27:02.003829 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007237
2026-05-20 01:27:02.223831 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.005944
2026-05-20 01:27:02.246828 | 
2026-05-20 01:27:02.246954 | PLAY [all]
2026-05-20 01:27:02.253920 | 
2026-05-20 01:27:02.253990 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-20 01:27:02.940617 | controller | changed
2026-05-20 01:27:02.947070 | 
2026-05-20 01:27:02.947121 | PLAY RECAP
2026-05-20 01:27:02.947168 | controller | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-20 01:27:02.947191 | 
2026-05-20 01:27:03.060603 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-20 01:27:03.070803 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-20 01:27:03.654308 | 
2026-05-20 01:27:03.654421 | PLAY [localhost]
2026-05-20 01:27:03.663805 | 
2026-05-20 01:27:03.663895 | TASK [Generate Zuul manifest]
2026-05-20 01:27:03.686096 | localhost | ok
2026-05-20 01:27:03.702334 | 
2026-05-20 01:27:03.702416 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-20 01:27:04.107660 | localhost | changed
2026-05-20 01:27:04.119424 | 
2026-05-20 01:27:04.119493 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-20 01:27:04.151086 | localhost | ok
2026-05-20 01:27:04.161333 | 
2026-05-20 01:27:04.161427 | TASK [Upload logs]
2026-05-20 01:27:04.183182 | localhost | ok
2026-05-20 01:27:04.311740 | 
2026-05-20 01:27:04.311866 | TASK [Set zuul-log-path fact]
2026-05-20 01:27:04.344836 | localhost | ok
2026-05-20 01:27:04.361576 | 
2026-05-20 01:27:04.361694 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-20 01:27:04.395288 | localhost | ok
2026-05-20 01:27:04.408103 | 
2026-05-20 01:27:04.408202 | TASK [upload-logs : Create log directories]
2026-05-20 01:27:04.805817 | localhost | changed
2026-05-20 01:27:04.811950 | 
2026-05-20 01:27:04.812031 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-20 01:27:05.199216 | localhost -> localhost | ok: Runtime: 0:00:00.004110
2026-05-20 01:27:05.204392 | 
2026-05-20 01:27:05.204456 | TASK [upload-logs : Upload logs to log server]
2026-05-20 01:27:05.640560 | localhost | Output suppressed because no_log was given
2026-05-20 01:27:05.645829 | 
2026-05-20 01:27:05.645895 | LOOP [upload-logs : Compress console log and json output]
2026-05-20 01:27:05.689645 | localhost | skipping: Conditional result was False
2026-05-20 01:27:05.701006 | localhost | skipping: Conditional result was False
2026-05-20 01:27:05.715716 | 
2026-05-20 01:27:05.715820 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-20 01:27:05.760440 | localhost | skipping: Conditional result was False
2026-05-20 01:27:05.760888 | 
2026-05-20 01:27:05.765054 | localhost | skipping: Conditional result was False
2026-05-20 01:27:05.778995 | 
2026-05-20 01:27:05.779114 | LOOP [upload-logs : Upload console log and json output]