2026-08-04 11:55:47.562077 | Job console starting 2026-08-04 11:55:47.574973 | Updating git repos 2026-08-04 11:55:48.131387 | Cloning repos into workspace 2026-08-04 11:55:58.638633 | Restoring repo states 2026-08-04 11:55:58.715447 | Merging changes 2026-08-04 11:56:06.221544 | Checking out repos 2026-08-04 11:56:08.652128 | Preparing playbooks 2026-08-04 11:56:15.778171 | Running Ansible setup 2026-08-04 11:56:22.305875 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/base/pre.yaml@master] 2026-08-04 11:56:23.054013 | 2026-08-04 11:56:23.054160 | PLAY [localhost] 2026-08-04 11:56:23.063063 | 2026-08-04 11:56:23.063145 | TASK [Gathering Facts] 2026-08-04 11:56:24.923812 | localhost | ok 2026-08-04 11:56:24.931456 | 2026-08-04 11:56:24.931589 | TASK [Setup log path fact] 2026-08-04 11:56:24.948767 | localhost | ok 2026-08-04 11:56:24.960018 | 2026-08-04 11:56:24.960140 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-04 11:56:24.987482 | localhost | ok 2026-08-04 11:56:24.993474 | 2026-08-04 11:56:24.993581 | TASK [emit-job-header : Print job information] 2026-08-04 11:56:25.031715 | # Job Information 2026-08-04 11:56:25.031885 | Ansible Version: 2.16.19 2026-08-04 11:56:25.031917 | Job: cinder-storpool-tempest 2026-08-04 11:56:25.031938 | Pipeline: check 2026-08-04 11:56:25.031956 | Executor: zuul-executor-0.zuul-executor.openstack-ci.svc.cluster.local 2026-08-04 11:56:25.031974 | Triggered by: https://review.opendev.org/c/openstack/cinder/+/926263 2026-08-04 11:56:25.031995 | Log URL (when completed): https://logs-openstack-ci.storpool.net/be6/OpenStack/be6c2967e25a4268a9b24c57981828be/ 2026-08-04 11:56:25.032013 | Event ID: 73a9ee8c5b114f71a0e32a04c63fed85 2026-08-04 11:56:25.034843 | 2026-08-04 11:56:25.034909 | LOOP [emit-job-header : Print node information] 2026-08-04 11:56:25.163082 | localhost | ok: 2026-08-04 11:56:25.163287 | localhost | # Node Information 2026-08-04 11:56:25.163315 | localhost | Inventory Hostname: controller 2026-08-04 11:56:25.163336 | localhost | Hostname: np0000006434 2026-08-04 11:56:25.163354 | localhost | Username: ubuntu 2026-08-04 11:56:25.163373 | localhost | Distro: Ubuntu 24.04 2026-08-04 11:56:25.163391 | localhost | Provider: openstack-testing 2026-08-04 11:56:25.163408 | localhost | Region: RegionOne 2026-08-04 11:56:25.163423 | localhost | Label: os-ubuntu2404-4xlarge 2026-08-04 11:56:25.163439 | localhost | Product Name: OpenStack Nova 2026-08-04 11:56:25.163455 | localhost | Interface IP: 10.4.3.192 2026-08-04 11:56:25.210243 | localhost | ok: 2026-08-04 11:56:25.210353 | localhost | # Node Information 2026-08-04 11:56:25.210378 | localhost | Inventory Hostname: lab-sp-a1 2026-08-04 11:56:25.210397 | localhost | Hostname: np0000006436 2026-08-04 11:56:25.210415 | localhost | Username: ubuntu 2026-08-04 11:56:25.210434 | localhost | Distro: Ubuntu 24.04 2026-08-04 11:56:25.210451 | localhost | Provider: openstack-testing 2026-08-04 11:56:25.210467 | localhost | Region: RegionOne 2026-08-04 11:56:25.210484 | localhost | Label: os-ubuntu2404-2xlarge 2026-08-04 11:56:25.210502 | localhost | Product Name: OpenStack Nova 2026-08-04 11:56:25.210517 | localhost | Interface IP: 10.4.3.235 2026-08-04 11:56:25.259367 | localhost | ok: 2026-08-04 11:56:25.259473 | localhost | # Node Information 2026-08-04 11:56:25.259497 | localhost | Inventory Hostname: undercloud-client 2026-08-04 11:56:25.259516 | localhost | Hostname: np0000006435 2026-08-04 11:56:25.259554 | localhost | Username: ubuntu 2026-08-04 11:56:25.259573 | localhost | Distro: Ubuntu 24.04 2026-08-04 11:56:25.259589 | localhost | Provider: openstack-testing 2026-08-04 11:56:25.259605 | localhost | Region: RegionOne 2026-08-04 11:56:25.259621 | localhost | Label: os-ubuntu2404-small 2026-08-04 11:56:25.259638 | localhost | Product Name: OpenStack Nova 2026-08-04 11:56:25.259653 | localhost | Interface IP: 10.4.3.41 2026-08-04 11:56:25.269316 | 2026-08-04 11:56:25.269444 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-08-04 11:56:25.637595 | localhost -> localhost | changed 2026-08-04 11:56:25.644323 | 2026-08-04 11:56:25.644473 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-08-04 11:56:26.556737 | localhost -> localhost | changed 2026-08-04 11:56:26.562732 | 2026-08-04 11:56:26.562787 | PLAY [all] 2026-08-04 11:56:26.570498 | 2026-08-04 11:56:26.570584 | TASK [include_role : start-zuul-console] 2026-08-04 11:56:26.591777 | controller | ok 2026-08-04 11:56:26.610340 | lab-sp-a1 | ok 2026-08-04 11:56:26.645065 | undercloud-client | ok 2026-08-04 11:56:26.666885 | 2026-08-04 11:56:26.666990 | TASK [start-zuul-console : Start zuul_console daemon.] 2026-08-04 11:56:26.982067 | controller | ok 2026-08-04 11:56:26.988200 | lab-sp-a1 | ok 2026-08-04 11:56:27.014170 | undercloud-client | ok 2026-08-04 11:56:27.032406 | 2026-08-04 11:56:27.032516 | TASK [include_role : validate-host] 2026-08-04 11:56:27.058358 | controller | ok 2026-08-04 11:56:27.076361 | lab-sp-a1 | ok 2026-08-04 11:56:27.091186 | undercloud-client | ok 2026-08-04 11:56:27.121671 | 2026-08-04 11:56:27.121782 | TASK [validate-host : Define zuul_info_dir fact] 2026-08-04 11:56:27.159141 | controller | ok 2026-08-04 11:56:27.165398 | lab-sp-a1 | ok 2026-08-04 11:56:27.170042 | undercloud-client | ok 2026-08-04 11:56:27.174894 | 2026-08-04 11:56:27.174969 | TASK [validate-host : Ensure Zuul Ansible directory exists] 2026-08-04 11:56:27.402358 | controller -> localhost | ok 2026-08-04 11:56:27.409511 | 2026-08-04 11:56:27.409647 | TASK [validate-host : Collect information about the host] 2026-08-04 11:56:28.179693 | undercloud-client | ok 2026-08-04 11:56:28.218776 | controller | ok 2026-08-04 11:56:28.234919 | lab-sp-a1 | ok 2026-08-04 11:56:28.245118 | 2026-08-04 11:56:28.245195 | TASK [validate-host : Sanitize hostname] 2026-08-04 11:56:28.296746 | controller | ok 2026-08-04 11:56:28.300853 | lab-sp-a1 | ok 2026-08-04 11:56:28.314777 | undercloud-client | ok 2026-08-04 11:56:28.319322 | 2026-08-04 11:56:28.319410 | TASK [validate-host : Write out all ansible variables/facts known for each host] 2026-08-04 11:56:28.740637 | lab-sp-a1 -> localhost | changed 2026-08-04 11:56:28.752029 | controller -> localhost | changed 2026-08-04 11:56:28.763125 | undercloud-client -> localhost | changed 2026-08-04 11:56:28.768514 | 2026-08-04 11:56:28.768622 | TASK [validate-host : Collect information about zuul worker] 2026-08-04 11:56:29.162705 | lab-sp-a1 | ok 2026-08-04 11:56:29.169920 | undercloud-client | ok 2026-08-04 11:56:29.173788 | controller | ok 2026-08-04 11:56:29.178602 | 2026-08-04 11:56:29.178715 | TASK [validate-host : Write out all zuul information for each host] 2026-08-04 11:56:29.649793 | controller -> localhost | changed 2026-08-04 11:56:29.651399 | lab-sp-a1 -> localhost | changed 2026-08-04 11:56:29.652106 | undercloud-client -> localhost | changed 2026-08-04 11:56:29.668683 | 2026-08-04 11:56:29.668796 | TASK [include_role : prepare-workspace] 2026-08-04 11:56:29.704652 | controller | ok 2026-08-04 11:56:29.711061 | lab-sp-a1 | ok 2026-08-04 11:56:29.714879 | undercloud-client | ok 2026-08-04 11:56:29.749903 | 2026-08-04 11:56:29.750013 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-08-04 11:56:30.007714 | controller | ok 2026-08-04 11:56:30.083242 | undercloud-client | ok 2026-08-04 11:56:30.119087 | lab-sp-a1 | ok 2026-08-04 11:56:30.165257 | 2026-08-04 11:56:30.165387 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-08-04 11:57:19.259228 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 11:57:27.520691 | controller | Output suppressed because no_log was given 2026-08-04 11:57:30.157944 | undercloud-client | Output suppressed because no_log was given 2026-08-04 11:57:30.177090 | 2026-08-04 11:57:30.177221 | TASK [include_role : add-build-sshkey] 2026-08-04 11:57:30.212595 | controller | ok 2026-08-04 11:57:30.230300 | lab-sp-a1 | ok 2026-08-04 11:57:30.244959 | undercloud-client | ok 2026-08-04 11:57:30.286677 | 2026-08-04 11:57:30.286787 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-08-04 11:57:30.504361 | controller -> localhost | ok 2026-08-04 11:57:30.510033 | 2026-08-04 11:57:30.510118 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-08-04 11:57:30.546495 | controller | ok 2026-08-04 11:57:30.550334 | lab-sp-a1 | ok 2026-08-04 11:57:30.563665 | undercloud-client | ok 2026-08-04 11:57:30.593032 | controller | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-04 11:57:30.593176 | lab-sp-a1 | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-04 11:57:30.593200 | undercloud-client | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-08-04 11:57:30.601808 | 2026-08-04 11:57:30.601978 | TASK [add-build-sshkey : Create Temp SSH key] 2026-08-04 11:57:31.304030 | controller -> localhost | Generating public/private rsa key pair. 2026-08-04 11:57:31.304291 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/be6c2967e25a4268a9b24c57981828be_id_rsa 2026-08-04 11:57:31.304337 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/be6c2967e25a4268a9b24c57981828be_id_rsa.pub 2026-08-04 11:57:31.304366 | controller -> localhost | The key fingerprint is: 2026-08-04 11:57:31.304398 | controller -> localhost | SHA256:1mq8sd7jEYe07DJSGyCZunFO9mYoj7O2wjDmiqVEGPg zuul-build-sshkey 2026-08-04 11:57:31.304428 | controller -> localhost | The key's randomart image is: 2026-08-04 11:57:31.304456 | controller -> localhost | +---[RSA 3072]----+ 2026-08-04 11:57:31.304484 | controller -> localhost | | | 2026-08-04 11:57:31.304520 | controller -> localhost | |. o | 2026-08-04 11:57:31.304577 | controller -> localhost | |o + . . | 2026-08-04 11:57:31.304605 | controller -> localhost | |.o . . . + o | 2026-08-04 11:57:31.304631 | controller -> localhost | |..E + S * . | 2026-08-04 11:57:31.304655 | controller -> localhost | |+. B o + = o | 2026-08-04 11:57:31.304678 | controller -> localhost | |=o+ o = O o | 2026-08-04 11:57:31.304701 | controller -> localhost | |+=o+ o o B.. | 2026-08-04 11:57:31.304723 | controller -> localhost | |=o++. .+.o. | 2026-08-04 11:57:31.304746 | controller -> localhost | +----[SHA256]-----+ 2026-08-04 11:57:31.304798 | controller -> localhost | ok: Runtime: 0:00:00.304681 2026-08-04 11:57:31.309369 | 2026-08-04 11:57:31.309441 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-08-04 11:57:31.336379 | controller | ok 2026-08-04 11:57:31.353864 | lab-sp-a1 | ok 2026-08-04 11:57:31.368957 | undercloud-client | ok 2026-08-04 11:57:31.389220 | controller | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-04 11:57:31.389344 | lab-sp-a1 | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-04 11:57:31.389379 | undercloud-client | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-08-04 11:57:31.406296 | 2026-08-04 11:57:31.406427 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-08-04 11:57:31.437267 | controller | skipping: Conditional result was False 2026-08-04 11:57:31.439688 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:57:31.450165 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:57:31.459968 | 2026-08-04 11:57:31.460128 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-08-04 11:57:32.478176 | lab-sp-a1 | changed 2026-08-04 11:57:32.502940 | controller | changed 2026-08-04 11:57:32.527310 | undercloud-client | changed 2026-08-04 11:57:32.531646 | 2026-08-04 11:57:32.531727 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-08-04 11:57:32.736069 | controller | ok 2026-08-04 11:57:32.750704 | lab-sp-a1 | ok 2026-08-04 11:57:32.765102 | undercloud-client | ok 2026-08-04 11:57:32.769560 | 2026-08-04 11:57:32.769629 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-08-04 11:57:33.242903 | controller | changed 2026-08-04 11:57:33.268830 | lab-sp-a1 | changed 2026-08-04 11:57:33.282822 | undercloud-client | changed 2026-08-04 11:57:33.287106 | 2026-08-04 11:57:33.287175 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-08-04 11:57:33.883884 | undercloud-client | changed 2026-08-04 11:57:33.888082 | lab-sp-a1 | changed 2026-08-04 11:57:33.922576 | controller | changed 2026-08-04 11:57:33.927178 | 2026-08-04 11:57:33.927257 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-08-04 11:57:33.957847 | controller | skipping: Conditional result was False 2026-08-04 11:57:33.960123 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:57:33.970465 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:57:33.975619 | 2026-08-04 11:57:33.975698 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-08-04 11:57:34.282372 | controller -> localhost | changed 2026-08-04 11:57:34.299778 | 2026-08-04 11:57:34.299929 | TASK [add-build-sshkey : Add back temp key] 2026-08-04 11:57:34.573169 | controller -> localhost | Identity added: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/be6c2967e25a4268a9b24c57981828be_id_rsa (zuul-build-sshkey) 2026-08-04 11:57:34.573457 | controller -> localhost | ok: Runtime: 0:00:00.011355 2026-08-04 11:57:34.577937 | 2026-08-04 11:57:34.578014 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-08-04 11:57:34.886511 | controller | ok 2026-08-04 11:57:34.897146 | undercloud-client | ok 2026-08-04 11:57:34.901665 | lab-sp-a1 | ok 2026-08-04 11:57:34.907670 | 2026-08-04 11:57:34.907837 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-08-04 11:57:34.943355 | controller | skipping: Conditional result was False 2026-08-04 11:57:34.945873 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:57:34.956761 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:57:34.976916 | 2026-08-04 11:57:34.977104 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-08-04 11:57:35.186934 | controller | ok: "logs" 2026-08-04 11:57:35.187493 | controller | ok: All items complete 2026-08-04 11:57:35.187551 | 2026-08-04 11:57:35.200330 | lab-sp-a1 | ok: "logs" 2026-08-04 11:57:35.211282 | undercloud-client | ok: "logs" 2026-08-04 11:57:35.363204 | controller | ok: "artifacts" 2026-08-04 11:57:35.383146 | lab-sp-a1 | ok: "artifacts" 2026-08-04 11:57:35.388430 | undercloud-client | ok: "artifacts" 2026-08-04 11:57:35.538882 | controller | ok: "docs" 2026-08-04 11:57:35.567101 | lab-sp-a1 | ok: "docs" 2026-08-04 11:57:35.578354 | undercloud-client | ok: "docs" 2026-08-04 11:57:35.578491 | undercloud-client | ok: All items complete 2026-08-04 11:57:35.578516 | 2026-08-04 11:57:35.585968 | 2026-08-04 11:57:35.586109 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-08-04 11:57:35.793596 | controller | changed: "logs" 2026-08-04 11:57:35.820740 | lab-sp-a1 | changed: "logs" 2026-08-04 11:57:35.855911 | undercloud-client | changed: "logs" 2026-08-04 11:57:35.976978 | controller | changed: "artifacts" 2026-08-04 11:57:36.017654 | lab-sp-a1 | changed: "artifacts" 2026-08-04 11:57:36.035394 | undercloud-client | changed: "artifacts" 2026-08-04 11:57:36.167727 | controller | changed: "docs" 2026-08-04 11:57:36.220798 | undercloud-client | changed: "docs" 2026-08-04 11:57:36.220914 | undercloud-client | changed: All items complete 2026-08-04 11:57:36.220939 | 2026-08-04 11:57:36.240113 | lab-sp-a1 | changed: "docs" 2026-08-04 11:57:36.264341 | 2026-08-04 11:57:36.264455 | PLAY RECAP 2026-08-04 11:57:36.264513 | controller | ok: 23 changed: 10 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-04 11:57:36.264600 | lab-sp-a1 | ok: 18 changed: 7 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-04 11:57:36.264632 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:57:36.264655 | undercloud-client | ok: 18 changed: 7 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-08-04 11:57:36.264673 | 2026-08-04 11:57:36.412561 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/base/pre.yaml@master] 2026-08-04 11:57:36.421643 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master] 2026-08-04 11:57:37.177869 | 2026-08-04 11:57:37.178015 | PLAY [all] 2026-08-04 11:57:37.198620 | 2026-08-04 11:57:37.198746 | TASK [Fix the permissions of the zuul home directory] 2026-08-04 11:57:37.861130 | undercloud-client | changed 2026-08-04 11:57:37.866831 | controller | changed 2026-08-04 11:57:37.871420 | lab-sp-a1 | changed 2026-08-04 11:57:37.877157 | 2026-08-04 11:57:37.877255 | TASK [Gather minimum local MTU] 2026-08-04 11:57:38.001653 | controller | ok 2026-08-04 11:57:38.016706 | lab-sp-a1 | ok 2026-08-04 11:57:38.021859 | undercloud-client | ok 2026-08-04 11:57:38.062674 | 2026-08-04 11:57:38.062795 | TASK [Calculate external_bridge_mtu] 2026-08-04 11:57:38.119184 | controller | ok 2026-08-04 11:57:38.124069 | lab-sp-a1 | ok 2026-08-04 11:57:38.150225 | undercloud-client | ok 2026-08-04 11:57:38.155553 | 2026-08-04 11:57:38.155667 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists] 2026-08-04 11:57:38.185169 | controller | skipping: Conditional result was False 2026-08-04 11:57:38.186965 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:57:38.207680 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:57:38.215941 | 2026-08-04 11:57:38.216076 | TASK [configure-swap : Get ephemeral0 device node] 2026-08-04 11:57:38.808695 | lab-sp-a1 | ok: Runtime: 0:00:00.005806 2026-08-04 11:57:38.823075 | undercloud-client | ok: Runtime: 0:00:00.008664 2026-08-04 11:57:38.831504 | controller | ok: Runtime: 0:00:00.006861 2026-08-04 11:57:38.836857 | 2026-08-04 11:57:38.836941 | TASK [configure-swap : Set ephemeral device if LABEL exists] 2026-08-04 11:57:38.866549 | controller | skipping: Conditional result was False 2026-08-04 11:57:38.877746 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:57:38.888394 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:57:38.894747 | 2026-08-04 11:57:38.894837 | TASK [configure-swap : Setup swap on ephemeral storage] 2026-08-04 11:57:38.923948 | controller | skipping: Conditional result was False 2026-08-04 11:57:38.935168 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:57:38.945594 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:57:38.951375 | 2026-08-04 11:57:38.951455 | TASK [configure-swap : Setup swap file on root device] 2026-08-04 11:57:39.010149 | controller | ok 2026-08-04 11:57:39.016837 | lab-sp-a1 | ok 2026-08-04 11:57:39.031199 | undercloud-client | ok 2026-08-04 11:57:39.050074 | controller | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml 2026-08-04 11:57:39.050168 | lab-sp-a1 | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml 2026-08-04 11:57:39.050192 | undercloud-client | included: /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml 2026-08-04 11:57:39.057632 | 2026-08-04 11:57:39.057714 | TASK [configure-swap : Calculate required swap] 2026-08-04 11:57:39.113316 | controller | ok 2026-08-04 11:57:39.128316 | lab-sp-a1 | ok 2026-08-04 11:57:39.132288 | undercloud-client | ok 2026-08-04 11:57:39.138039 | 2026-08-04 11:57:39.138137 | TASK [configure-swap : Get root filesystem] 2026-08-04 11:57:39.449394 | controller | ext4 2026-08-04 11:57:39.467515 | lab-sp-a1 | ext4 2026-08-04 11:57:39.473737 | undercloud-client | ext4 2026-08-04 11:57:39.774218 | controller | ok: Runtime: 0:00:00.005609 2026-08-04 11:57:39.782831 | lab-sp-a1 | ok: Runtime: 0:00:00.005926 2026-08-04 11:57:39.791831 | undercloud-client | ok: Runtime: 0:00:00.006924 2026-08-04 11:57:39.797032 | 2026-08-04 11:57:39.797115 | TASK [configure-swap : Save root filesystem] 2026-08-04 11:57:39.833400 | controller | ok 2026-08-04 11:57:39.838690 | lab-sp-a1 | ok 2026-08-04 11:57:39.842608 | undercloud-client | ok 2026-08-04 11:57:39.847735 | 2026-08-04 11:57:39.847804 | TASK [configure-swap : Debug the root_filesystem variable] 2026-08-04 11:57:39.889375 | controller | ok: 2026-08-04 11:57:39.889650 | controller | { 2026-08-04 11:57:39.889679 | controller | "root_filesystem": "ext4" 2026-08-04 11:57:39.889698 | controller | } 2026-08-04 11:57:39.894814 | lab-sp-a1 | ok: 2026-08-04 11:57:39.894888 | lab-sp-a1 | { 2026-08-04 11:57:39.894911 | lab-sp-a1 | "root_filesystem": "ext4" 2026-08-04 11:57:39.894929 | lab-sp-a1 | } 2026-08-04 11:57:39.899219 | undercloud-client | ok: 2026-08-04 11:57:39.899275 | undercloud-client | { 2026-08-04 11:57:39.899297 | undercloud-client | "root_filesystem": "ext4" 2026-08-04 11:57:39.899315 | undercloud-client | } 2026-08-04 11:57:39.904222 | 2026-08-04 11:57:39.904299 | TASK [configure-swap : Create swap backing file] 2026-08-04 11:57:47.140654 | controller | 8192+0 records in 2026-08-04 11:57:47.140726 | controller | 8192+0 records out 2026-08-04 11:57:47.140737 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 6.96494 s, 1.2 GB/s 2026-08-04 11:57:48.304952 | lab-sp-a1 | 8192+0 records in 2026-08-04 11:57:48.305121 | lab-sp-a1 | 8192+0 records out 2026-08-04 11:57:48.305134 | lab-sp-a1 | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 8.09901 s, 1.1 GB/s 2026-08-04 11:57:52.397381 | undercloud-client | 8192+0 records in 2026-08-04 11:57:52.397432 | undercloud-client | 8192+0 records out 2026-08-04 11:57:52.397476 | undercloud-client | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 12.1806 s, 705 MB/s 2026-08-04 11:57:52.512084 | controller | ok: Runtime: 0:00:06.970677 2026-08-04 11:57:52.521575 | lab-sp-a1 | ok: Runtime: 0:00:08.103978 2026-08-04 11:57:52.577252 | undercloud-client | ok: Runtime: 0:00:12.185684 2026-08-04 11:57:52.582708 | 2026-08-04 11:57:52.582801 | TASK [configure-swap : Ensure swapfile perms] 2026-08-04 11:57:52.864083 | lab-sp-a1 | changed 2026-08-04 11:57:52.875683 | undercloud-client | changed 2026-08-04 11:57:52.881793 | controller | changed 2026-08-04 11:57:52.888362 | 2026-08-04 11:57:52.888512 | TASK [configure-swap : Make swapfile] 2026-08-04 11:57:53.255654 | lab-sp-a1 | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes) 2026-08-04 11:57:53.255728 | lab-sp-a1 | no label, UUID=a7dc9390-f6a5-43cf-8eab-205e114ac704 2026-08-04 11:57:53.304285 | undercloud-client | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes) 2026-08-04 11:57:53.304646 | undercloud-client | no label, UUID=8a912112-6463-4480-b37d-151bf4163f41 2026-08-04 11:57:53.493569 | lab-sp-a1 | ok: Runtime: 0:00:00.011059 2026-08-04 11:57:53.507608 | undercloud-client | ok: Runtime: 0:00:00.044698 2026-08-04 11:57:54.066541 | controller | ok: Runtime: 0:00:00.808946 2026-08-04 11:57:54.071616 | 2026-08-04 11:57:54.071690 | TASK [configure-swap : Write swap to fstab] 2026-08-04 11:57:54.026562 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes) 2026-08-04 11:57:54.026666 | controller | no label, UUID=e3274006-2bfd-429f-b54f-8c08bafb49be 2026-08-04 11:57:54.471679 | controller | changed 2026-08-04 11:57:54.478448 | undercloud-client | changed 2026-08-04 11:57:54.483491 | lab-sp-a1 | changed 2026-08-04 11:57:54.490077 | 2026-08-04 11:57:54.490210 | TASK [configure-swap : Add all swap] 2026-08-04 11:57:55.121235 | controller | ok: Runtime: 0:00:00.007265 2026-08-04 11:57:55.129471 | lab-sp-a1 | ok: Runtime: 0:00:00.007164 2026-08-04 11:57:55.137361 | undercloud-client | ok: Runtime: 0:00:00.011390 2026-08-04 11:57:55.143208 | 2026-08-04 11:57:55.143282 | TASK [configure-swap : Debug the swap_required variable] 2026-08-04 11:57:55.183149 | controller | ok: 2026-08-04 11:57:55.183417 | controller | { 2026-08-04 11:57:55.183443 | controller | "swap_required": "8192" 2026-08-04 11:57:55.183461 | controller | } 2026-08-04 11:57:55.187901 | lab-sp-a1 | ok: 2026-08-04 11:57:55.187959 | lab-sp-a1 | { 2026-08-04 11:57:55.187986 | lab-sp-a1 | "swap_required": "8192" 2026-08-04 11:57:55.188012 | lab-sp-a1 | } 2026-08-04 11:57:55.193837 | undercloud-client | ok: 2026-08-04 11:57:55.193896 | undercloud-client | { 2026-08-04 11:57:55.193929 | undercloud-client | "swap_required": "8192" 2026-08-04 11:57:55.193953 | undercloud-client | } 2026-08-04 11:57:55.198799 | 2026-08-04 11:57:55.198887 | TASK [configure-swap : Set swappiness] 2026-08-04 11:57:55.549672 | controller | changed 2026-08-04 11:57:55.559808 | lab-sp-a1 | changed 2026-08-04 11:57:55.567277 | undercloud-client | changed 2026-08-04 11:57:55.574833 | 2026-08-04 11:57:55.575000 | TASK [configure-swap : Debug the ephemeral_device variable] 2026-08-04 11:57:55.616974 | controller | ok: 2026-08-04 11:57:55.617250 | controller | { 2026-08-04 11:57:55.617278 | controller | "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined" 2026-08-04 11:57:55.617297 | controller | } 2026-08-04 11:57:55.622026 | lab-sp-a1 | ok: 2026-08-04 11:57:55.622087 | lab-sp-a1 | { 2026-08-04 11:57:55.622110 | lab-sp-a1 | "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined" 2026-08-04 11:57:55.622128 | lab-sp-a1 | } 2026-08-04 11:57:55.626112 | undercloud-client | ok: 2026-08-04 11:57:55.626162 | undercloud-client | { 2026-08-04 11:57:55.626184 | undercloud-client | "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined" 2026-08-04 11:57:55.626202 | undercloud-client | } 2026-08-04 11:57:55.643978 | 2026-08-04 11:57:55.644104 | TASK [setup-stack-user : Create stack group] 2026-08-04 11:57:56.067009 | controller | changed 2026-08-04 11:57:56.073036 | lab-sp-a1 | changed 2026-08-04 11:57:56.080199 | undercloud-client | changed 2026-08-04 11:57:56.086188 | 2026-08-04 11:57:56.086337 | TASK [setup-stack-user : Create the stack user home folder] 2026-08-04 11:57:56.316593 | controller | changed 2026-08-04 11:57:56.358317 | lab-sp-a1 | changed 2026-08-04 11:57:56.406219 | undercloud-client | changed 2026-08-04 11:57:56.412474 | 2026-08-04 11:57:56.412650 | TASK [setup-stack-user : Create stack user] 2026-08-04 11:57:56.934019 | controller | changed 2026-08-04 11:57:56.941023 | lab-sp-a1 | changed 2026-08-04 11:57:56.958898 | undercloud-client | changed 2026-08-04 11:57:56.964697 | 2026-08-04 11:57:56.964839 | TASK [setup-stack-user : Set stack user home directory permissions and ownership] 2026-08-04 11:57:57.188627 | controller | changed 2026-08-04 11:57:57.208243 | lab-sp-a1 | changed 2026-08-04 11:57:57.212821 | undercloud-client | changed 2026-08-04 11:57:57.218712 | 2026-08-04 11:57:57.218788 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d] 2026-08-04 11:57:58.328264 | controller | changed 2026-08-04 11:57:58.346271 | lab-sp-a1 | changed 2026-08-04 11:57:58.374157 | undercloud-client | changed 2026-08-04 11:57:58.382701 | 2026-08-04 11:57:58.382831 | TASK [setup-stack-user : Create .cache folder within BASE] 2026-08-04 11:57:58.707069 | lab-sp-a1 | changed 2026-08-04 11:57:58.743181 | controller | changed 2026-08-04 11:57:58.758756 | undercloud-client | changed 2026-08-04 11:57:58.777828 | 2026-08-04 11:57:58.777958 | TASK [setup-tempest-user : Create tempest group] 2026-08-04 11:57:59.093114 | controller | changed 2026-08-04 11:57:59.098294 | undercloud-client | changed 2026-08-04 11:57:59.102563 | lab-sp-a1 | changed 2026-08-04 11:57:59.107940 | 2026-08-04 11:57:59.108013 | TASK [setup-tempest-user : Create tempest user] 2026-08-04 11:57:59.425008 | controller | changed 2026-08-04 11:57:59.430203 | lab-sp-a1 | changed 2026-08-04 11:57:59.519692 | undercloud-client | changed 2026-08-04 11:57:59.527465 | 2026-08-04 11:57:59.527642 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d] 2026-08-04 11:58:00.267832 | undercloud-client | changed 2026-08-04 11:58:00.295957 | controller | changed 2026-08-04 11:58:00.322287 | lab-sp-a1 | changed 2026-08-04 11:58:00.342071 | 2026-08-04 11:58:00.342160 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job] 2026-08-04 11:58:00.708537 | controller | ok: Not all paths examined, check warnings for details 2026-08-04 11:58:00.718939 | lab-sp-a1 | ok: Not all paths examined, check warnings for details 2026-08-04 11:58:00.727307 | undercloud-client | ok: Not all paths examined, check warnings for details 2026-08-04 11:58:00.735252 | 2026-08-04 11:58:00.735322 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory] 2026-08-04 11:58:01.126509 | controller | ok: Item: Runtime: 0:00:00.132930 2026-08-04 11:58:01.136025 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.114195 2026-08-04 11:58:01.197733 | undercloud-client | ok: Item: Runtime: 0:00:00.169241 2026-08-04 11:58:01.849280 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.509360 2026-08-04 11:58:01.866574 | controller | ok: Item: Runtime: 0:00:00.541541 2026-08-04 11:58:02.277484 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.133718 2026-08-04 11:58:02.290662 | controller | ok: Item: Runtime: 0:00:00.136332 2026-08-04 11:58:02.598509 | controller | ok: Item: Runtime: 0:00:00.052395 2026-08-04 11:58:02.608645 | undercloud-client | ok: Item: Runtime: 0:00:01.094182 2026-08-04 11:58:02.619302 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.051865 2026-08-04 11:58:03.033357 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.259136 2026-08-04 11:58:03.043824 | undercloud-client | ok: Item: Runtime: 0:00:00.244831 2026-08-04 11:58:03.054119 | controller | ok: Item: Runtime: 0:00:00.271147 2026-08-04 11:58:03.332338 | undercloud-client | ok: Item: Runtime: 0:00:00.059723 2026-08-04 11:58:03.637503 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.392742 2026-08-04 11:58:03.649285 | controller | ok: Item: Runtime: 0:00:00.399632 2026-08-04 11:58:04.147663 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.314575 2026-08-04 11:58:04.157393 | controller | ok: Item: Runtime: 0:00:00.308395 2026-08-04 11:58:04.167840 | undercloud-client | ok: Item: Runtime: 0:00:00.620632 2026-08-04 11:58:05.218084 | undercloud-client | ok: Item: Runtime: 0:00:00.845727 2026-08-04 11:58:05.334696 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.981742 2026-08-04 11:58:05.355257 | controller | ok: Item: Runtime: 0:00:01.004511 2026-08-04 11:58:06.119979 | undercloud-client | ok: Item: Runtime: 0:00:00.674843 2026-08-04 11:58:06.627874 | lab-sp-a1 | ok: Item: Runtime: 0:00:01.078617 2026-08-04 11:58:06.637651 | controller | ok: Item: Runtime: 0:00:01.066249 2026-08-04 11:58:06.995330 | controller | ok: Item: Runtime: 0:00:00.169872 2026-08-04 11:58:07.004785 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.176355 2026-08-04 11:58:08.596500 | undercloud-client | ok: Item: Runtime: 0:00:02.253424 2026-08-04 11:58:09.544000 | controller | ok: Item: Runtime: 0:00:02.355123 2026-08-04 11:58:09.572177 | lab-sp-a1 | ok: Item: Runtime: 0:00:02.339583 2026-08-04 11:58:09.572317 | lab-sp-a1 | changed: All items complete 2026-08-04 11:58:09.572353 | 2026-08-04 11:58:11.228410 | undercloud-client | ok: Item: Runtime: 0:00:02.422660 2026-08-04 11:58:11.763564 | undercloud-client | ok: Item: Runtime: 0:00:00.331183 2026-08-04 11:58:17.320516 | undercloud-client | ok: Item: Runtime: 0:00:05.327670 2026-08-04 11:58:17.379610 | 2026-08-04 11:58:17.379745 | TASK [setup-devstack-source-dirs : Find top level github projects] 2026-08-04 11:58:17.619806 | controller | ok: Not all paths examined, check warnings for details 2026-08-04 11:58:17.637233 | lab-sp-a1 | ok: Not all paths examined, check warnings for details 2026-08-04 11:58:17.654163 | undercloud-client | ok: Not all paths examined, check warnings for details 2026-08-04 11:58:17.661250 | 2026-08-04 11:58:17.661367 | TASK [setup-devstack-source-dirs : Find actual github repos] 2026-08-04 11:58:17.694583 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.695668 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.716274 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.725011 | 2026-08-04 11:58:17.725176 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory] 2026-08-04 11:58:17.790975 | 2026-08-04 11:58:17.791261 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory] 2026-08-04 11:58:17.828791 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.830310 | 2026-08-04 11:58:17.838604 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.839970 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.844777 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.848244 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.850176 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.853898 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.855652 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.859870 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.860972 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.864763 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.865384 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.866316 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.869027 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.869982 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.885470 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.886205 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.886822 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.887400 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.888002 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.888650 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.889280 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.889947 | controller | skipping: Conditional result was False 2026-08-04 11:58:17.890654 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.893190 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.902110 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.902243 | 2026-08-04 11:58:17.902942 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.903701 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.904295 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.904907 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.905502 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:17.906863 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.907462 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:17.927701 | 2026-08-04 11:58:17.927829 | TASK [setup-devstack-source-dirs : Set ownership of repos] 2026-08-04 11:58:19.768269 | controller | changed 2026-08-04 11:58:19.827823 | lab-sp-a1 | changed 2026-08-04 11:58:20.118464 | undercloud-client | changed 2026-08-04 11:58:20.141492 | 2026-08-04 11:58:20.141667 | TASK [setup-devstack-log-dir : Create logs directory] 2026-08-04 11:58:20.397581 | controller | changed 2026-08-04 11:58:20.403173 | lab-sp-a1 | changed 2026-08-04 11:58:20.429305 | undercloud-client | changed 2026-08-04 11:58:20.454328 | 2026-08-04 11:58:20.454564 | TASK [setup-devstack-cache : Copy cached devstack files] 2026-08-04 11:58:20.736057 | controller | find: ‘/opt/cache/files’: No such file or directory 2026-08-04 11:58:20.783541 | lab-sp-a1 | find: ‘/opt/cache/files’: No such file or directory 2026-08-04 11:58:20.770956 | undercloud-client | find: ‘/opt/cache/files’: No such file or directory 2026-08-04 11:58:21.048232 | controller | ERROR 2026-08-04 11:58:21.048419 | controller | { 2026-08-04 11:58:21.048448 | controller | "delta": "0:00:00.004322", 2026-08-04 11:58:21.048468 | controller | "end": "2026-08-04 11:58:20.736381", 2026-08-04 11:58:21.048485 | controller | "msg": "non-zero return code", 2026-08-04 11:58:21.048501 | controller | "rc": 1, 2026-08-04 11:58:21.048519 | controller | "start": "2026-08-04 11:58:20.732059" 2026-08-04 11:58:21.048573 | controller | } 2026-08-04 11:58:21.048599 | controller | ERROR: Ignoring Errors 2026-08-04 11:58:21.107420 | undercloud-client | ERROR 2026-08-04 11:58:21.107578 | undercloud-client | { 2026-08-04 11:58:21.107620 | undercloud-client | "delta": "0:00:00.004844", 2026-08-04 11:58:21.107642 | undercloud-client | "end": "2026-08-04 11:58:20.771195", 2026-08-04 11:58:21.107659 | undercloud-client | "msg": "non-zero return code", 2026-08-04 11:58:21.107676 | undercloud-client | "rc": 1, 2026-08-04 11:58:21.107692 | undercloud-client | "start": "2026-08-04 11:58:20.766351" 2026-08-04 11:58:21.107708 | undercloud-client | } 2026-08-04 11:58:21.107729 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 11:58:21.116670 | lab-sp-a1 | ERROR 2026-08-04 11:58:21.116791 | lab-sp-a1 | { 2026-08-04 11:58:21.116818 | lab-sp-a1 | "delta": "0:00:00.004700", 2026-08-04 11:58:21.116836 | lab-sp-a1 | "end": "2026-08-04 11:58:20.783879", 2026-08-04 11:58:21.116853 | lab-sp-a1 | "msg": "non-zero return code", 2026-08-04 11:58:21.116870 | lab-sp-a1 | "rc": 1, 2026-08-04 11:58:21.116887 | lab-sp-a1 | "start": "2026-08-04 11:58:20.779179" 2026-08-04 11:58:21.116903 | lab-sp-a1 | } 2026-08-04 11:58:21.116923 | lab-sp-a1 | ERROR: Ignoring Errors 2026-08-04 11:58:21.122036 | 2026-08-04 11:58:21.122153 | TASK [setup-devstack-cache : Set ownership of cached files] 2026-08-04 11:58:21.346369 | controller | ok 2026-08-04 11:58:21.443969 | lab-sp-a1 | ok 2026-08-04 11:58:21.448861 | undercloud-client | ok 2026-08-04 11:58:21.468890 | 2026-08-04 11:58:21.469027 | TASK [start-fresh-logging : Check for /bin/journalctl file] 2026-08-04 11:58:21.743349 | controller | /usr/bin/journalctl 2026-08-04 11:58:21.737332 | lab-sp-a1 | /usr/bin/journalctl 2026-08-04 11:58:21.770706 | undercloud-client | /usr/bin/journalctl 2026-08-04 11:58:22.048870 | controller | ok: Runtime: 0:00:00.003957 2026-08-04 11:58:22.057912 | lab-sp-a1 | ok: Runtime: 0:00:00.004501 2026-08-04 11:58:22.067735 | undercloud-client | ok: Runtime: 0:00:00.004736 2026-08-04 11:58:22.073131 | 2026-08-04 11:58:22.073227 | TASK [start-fresh-logging : Get current date] 2026-08-04 11:58:22.359528 | controller | 2026-08-04 11:58:22 2026-08-04 11:58:22.351264 | lab-sp-a1 | 2026-08-04 11:58:22 2026-08-04 11:58:22.452081 | undercloud-client | 2026-08-04 11:58:22 2026-08-04 11:58:22.661759 | controller | ok: Runtime: 0:00:00.004171 2026-08-04 11:58:22.674319 | lab-sp-a1 | ok: Runtime: 0:00:00.003707 2026-08-04 11:58:22.687944 | undercloud-client | ok: Runtime: 0:00:00.004570 2026-08-04 11:58:22.695849 | 2026-08-04 11:58:22.696014 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt] 2026-08-04 11:58:23.208307 | controller | changed 2026-08-04 11:58:23.234856 | lab-sp-a1 | changed 2026-08-04 11:58:23.332353 | undercloud-client | changed 2026-08-04 11:58:23.338181 | 2026-08-04 11:58:23.338301 | TASK [start-fresh-logging : Stop rsyslog] 2026-08-04 11:58:23.357215 | controller | skipping: Conditional result was False 2026-08-04 11:58:23.371444 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:23.382774 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:23.389686 | 2026-08-04 11:58:23.389834 | TASK [start-fresh-logging : Save syslog file prior to devstack run] 2026-08-04 11:58:23.966458 | controller | skipping: Conditional result was False 2026-08-04 11:58:23.967219 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:23.967392 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:23.973510 | 2026-08-04 11:58:23.973634 | TASK [start-fresh-logging : Save kern.log file prior to devstack run] 2026-08-04 11:58:24.550239 | controller | skipping: Conditional result was False 2026-08-04 11:58:24.550934 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:24.551108 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:24.557415 | 2026-08-04 11:58:24.557574 | TASK [start-fresh-logging : Recreate syslog file] 2026-08-04 11:58:24.577838 | controller | skipping: Conditional result was False 2026-08-04 11:58:24.591692 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:24.602823 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:24.609030 | 2026-08-04 11:58:24.609127 | TASK [start-fresh-logging : Recreate syslog file owner and group] 2026-08-04 11:58:25.234711 | controller | skipping: Conditional result was False 2026-08-04 11:58:25.245630 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:25.256633 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:25.262624 | 2026-08-04 11:58:25.262701 | TASK [start-fresh-logging : Recreate syslog file permissions] 2026-08-04 11:58:25.839181 | controller | skipping: Conditional result was False 2026-08-04 11:58:25.851809 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:25.863441 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:25.870399 | 2026-08-04 11:58:25.870553 | TASK [start-fresh-logging : Add read permissions to all on syslog file] 2026-08-04 11:58:25.903263 | controller | skipping: Conditional result was False 2026-08-04 11:58:25.905631 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:25.916079 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:25.923230 | 2026-08-04 11:58:25.923371 | TASK [start-fresh-logging : Recreate kern.log file] 2026-08-04 11:58:25.944769 | controller | skipping: Conditional result was False 2026-08-04 11:58:25.959734 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:25.971278 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:25.978005 | 2026-08-04 11:58:25.978159 | TASK [start-fresh-logging : Recreate kern.log file owner and group] 2026-08-04 11:58:26.564796 | controller | skipping: Conditional result was False 2026-08-04 11:58:26.580860 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:26.592517 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:26.602369 | 2026-08-04 11:58:26.602612 | TASK [start-fresh-logging : Recreate kern.log file permissions] 2026-08-04 11:58:27.240203 | controller | skipping: Conditional result was False 2026-08-04 11:58:27.252485 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:27.273929 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:27.281738 | 2026-08-04 11:58:27.281884 | TASK [start-fresh-logging : Add read permissions to all on kern.log file] 2026-08-04 11:58:27.302312 | controller | skipping: Conditional result was False 2026-08-04 11:58:27.317039 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:27.328574 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:27.338202 | 2026-08-04 11:58:27.338360 | TASK [start-fresh-logging : Start rsyslog] 2026-08-04 11:58:27.359840 | controller | skipping: Conditional result was False 2026-08-04 11:58:27.374349 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 11:58:27.385785 | undercloud-client | skipping: Conditional result was False 2026-08-04 11:58:27.410892 | 2026-08-04 11:58:27.411073 | TASK [write-devstack-local-conf : Write a job-specific local_conf file] 2026-08-04 11:58:28.065847 | undercloud-client | ok 2026-08-04 11:58:28.074507 | lab-sp-a1 | ok 2026-08-04 11:58:28.085785 | controller | ok 2026-08-04 11:58:28.106430 | 2026-08-04 11:58:28.106561 | PLAY RECAP 2026-08-04 11:58:28.106611 | controller | ok: 37 changed: 23 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 1 2026-08-04 11:58:28.106638 | lab-sp-a1 | ok: 37 changed: 23 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 1 2026-08-04 11:58:28.106660 | undercloud-client | ok: 37 changed: 23 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 1 2026-08-04 11:58:28.106676 | 2026-08-04 11:58:28.306347 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master] 2026-08-04 11:58:28.324181 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/01-disable-unattended-upgrades.yaml@master] 2026-08-04 11:58:29.201680 | 2026-08-04 11:58:29.201868 | PLAY [all] 2026-08-04 11:58:29.222668 | 2026-08-04 11:58:29.222857 | LOOP [Disable apt periodic updates] 2026-08-04 11:58:30.051585 | undercloud-client | changed: line added 2026-08-04 11:58:30.057475 | controller | changed: line added 2026-08-04 11:58:30.077118 | lab-sp-a1 | changed: line added 2026-08-04 11:58:30.238770 | undercloud-client | changed: line added 2026-08-04 11:58:30.244634 | controller | changed: line added 2026-08-04 11:58:30.274837 | lab-sp-a1 | changed: line added 2026-08-04 11:58:30.274952 | lab-sp-a1 | changed: All items complete 2026-08-04 11:58:30.274977 | 2026-08-04 11:58:30.288334 | 2026-08-04 11:58:30.288497 | LOOP [Stop and disable units related to automatic, unattended upgrades] 2026-08-04 11:58:31.440274 | lab-sp-a1 | Synchronizing state of unattended-upgrades.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 2026-08-04 11:58:31.440560 | lab-sp-a1 | Executing: /usr/lib/systemd/systemd-sysv-install disable unattended-upgrades 2026-08-04 11:58:31.440594 | lab-sp-a1 | Removed "/etc/systemd/system/multi-user.target.wants/unattended-upgrades.service". 2026-08-04 11:58:31.440644 | lab-sp-a1 | ok: Item: unattended-upgrades Runtime: 0:00:00.748681 2026-08-04 11:58:31.463199 | controller | Synchronizing state of unattended-upgrades.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 2026-08-04 11:58:31.463331 | controller | Executing: /usr/lib/systemd/systemd-sysv-install disable unattended-upgrades 2026-08-04 11:58:31.463365 | controller | Removed "/etc/systemd/system/multi-user.target.wants/unattended-upgrades.service". 2026-08-04 11:58:31.463405 | controller | ok: Item: unattended-upgrades Runtime: 0:00:00.771909 2026-08-04 11:58:31.754753 | undercloud-client | Synchronizing state of unattended-upgrades.service with SysV service script with /usr/lib/systemd/systemd-sysv-install. 2026-08-04 11:58:31.754991 | undercloud-client | Executing: /usr/lib/systemd/systemd-sysv-install disable unattended-upgrades 2026-08-04 11:58:31.755023 | undercloud-client | Removed "/etc/systemd/system/multi-user.target.wants/unattended-upgrades.service". 2026-08-04 11:58:31.755055 | undercloud-client | ok: Item: unattended-upgrades Runtime: 0:00:01.064385 2026-08-04 11:58:31.905971 | lab-sp-a1 | Disabling 'apt-daily.service', but its triggering units are still active: 2026-08-04 11:58:31.907100 | lab-sp-a1 | apt-daily.timer 2026-08-04 11:58:31.907174 | lab-sp-a1 | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=, 2026-08-04 11:58:31.907206 | lab-sp-a1 | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for 2026-08-04 11:58:31.907239 | lab-sp-a1 | template units). This means they are not meant to be enabled or disabled using systemctl. 2026-08-04 11:58:31.907258 | lab-sp-a1 | 2026-08-04 11:58:31.907276 | lab-sp-a1 | Possible reasons for having these kinds of units are: 2026-08-04 11:58:31.907295 | lab-sp-a1 | • A unit may be statically enabled by being symlinked from another unit's 2026-08-04 11:58:31.907317 | lab-sp-a1 | .wants/, .requires/, or .upholds/ directory. 2026-08-04 11:58:31.907335 | lab-sp-a1 | • A unit's purpose may be to act as a helper for some other unit which has 2026-08-04 11:58:31.907353 | lab-sp-a1 | a requirement dependency on it. 2026-08-04 11:58:31.907371 | lab-sp-a1 | • A unit may be started when needed via activation (socket, path, timer, 2026-08-04 11:58:31.907388 | lab-sp-a1 | D-Bus, udev, scripted systemctl call, ...). 2026-08-04 11:58:31.907405 | lab-sp-a1 | • In case of template units, the unit is meant to be enabled with some 2026-08-04 11:58:31.907422 | lab-sp-a1 | instance name specified. 2026-08-04 11:58:31.907439 | lab-sp-a1 | Stopping 'apt-daily.service', but its triggering units are still active: 2026-08-04 11:58:31.907455 | lab-sp-a1 | apt-daily.timer 2026-08-04 11:58:31.907486 | lab-sp-a1 | ok: Item: apt-daily.service Runtime: 0:00:00.233252 2026-08-04 11:58:31.979623 | controller | Disabling 'apt-daily.service', but its triggering units are still active: 2026-08-04 11:58:31.979747 | controller | apt-daily.timer 2026-08-04 11:58:31.979774 | controller | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=, 2026-08-04 11:58:31.979793 | controller | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for 2026-08-04 11:58:31.979812 | controller | template units). This means they are not meant to be enabled or disabled using systemctl. 2026-08-04 11:58:31.979832 | controller | 2026-08-04 11:58:31.979850 | controller | Possible reasons for having these kinds of units are: 2026-08-04 11:58:31.979870 | controller | • A unit may be statically enabled by being symlinked from another unit's 2026-08-04 11:58:31.979891 | controller | .wants/, .requires/, or .upholds/ directory. 2026-08-04 11:58:31.979910 | controller | • A unit's purpose may be to act as a helper for some other unit which has 2026-08-04 11:58:31.979929 | controller | a requirement dependency on it. 2026-08-04 11:58:31.979947 | controller | • A unit may be started when needed via activation (socket, path, timer, 2026-08-04 11:58:31.979966 | controller | D-Bus, udev, scripted systemctl call, ...). 2026-08-04 11:58:31.979994 | controller | • In case of template units, the unit is meant to be enabled with some 2026-08-04 11:58:31.980020 | controller | instance name specified. 2026-08-04 11:58:31.980045 | controller | Stopping 'apt-daily.service', but its triggering units are still active: 2026-08-04 11:58:31.980081 | controller | apt-daily.timer 2026-08-04 11:58:31.980112 | controller | ok: Item: apt-daily.service Runtime: 0:00:00.255917 2026-08-04 11:58:32.298130 | undercloud-client | Disabling 'apt-daily.service', but its triggering units are still active: 2026-08-04 11:58:32.298240 | undercloud-client | apt-daily.timer 2026-08-04 11:58:32.298265 | undercloud-client | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=, 2026-08-04 11:58:32.298288 | undercloud-client | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for 2026-08-04 11:58:32.298307 | undercloud-client | template units). This means they are not meant to be enabled or disabled using systemctl. 2026-08-04 11:58:32.298325 | undercloud-client | 2026-08-04 11:58:32.298344 | undercloud-client | Possible reasons for having these kinds of units are: 2026-08-04 11:58:32.298372 | undercloud-client | • A unit may be statically enabled by being symlinked from another unit's 2026-08-04 11:58:32.298436 | undercloud-client | .wants/, .requires/, or .upholds/ directory. 2026-08-04 11:58:32.298461 | undercloud-client | • A unit's purpose may be to act as a helper for some other unit which has 2026-08-04 11:58:32.298490 | undercloud-client | a requirement dependency on it. 2026-08-04 11:58:32.298510 | undercloud-client | • A unit may be started when needed via activation (socket, path, timer, 2026-08-04 11:58:32.298550 | undercloud-client | D-Bus, udev, scripted systemctl call, ...). 2026-08-04 11:58:32.298578 | undercloud-client | • In case of template units, the unit is meant to be enabled with some 2026-08-04 11:58:32.298599 | undercloud-client | instance name specified. 2026-08-04 11:58:32.298616 | undercloud-client | Stopping 'apt-daily.service', but its triggering units are still active: 2026-08-04 11:58:32.298633 | undercloud-client | apt-daily.timer 2026-08-04 11:58:32.298661 | undercloud-client | ok: Item: apt-daily.service Runtime: 0:00:00.331229 2026-08-04 11:58:32.414960 | lab-sp-a1 | Removed "/etc/systemd/system/timers.target.wants/apt-daily.timer". 2026-08-04 11:58:32.415160 | lab-sp-a1 | ok: Item: apt-daily.timer Runtime: 0:00:00.241260 2026-08-04 11:58:32.439230 | controller | Removed "/etc/systemd/system/timers.target.wants/apt-daily.timer". 2026-08-04 11:58:32.439370 | controller | ok: Item: apt-daily.timer Runtime: 0:00:00.252393 2026-08-04 11:58:32.859297 | undercloud-client | Removed "/etc/systemd/system/timers.target.wants/apt-daily.timer". 2026-08-04 11:58:32.859433 | undercloud-client | ok: Item: apt-daily.timer Runtime: 0:00:00.339332 2026-08-04 11:58:32.869378 | lab-sp-a1 | Removed "/etc/systemd/system/timers.target.wants/apt-daily-upgrade.timer". 2026-08-04 11:58:32.869514 | lab-sp-a1 | ok: Item: apt-daily-upgrade.timer Runtime: 0:00:00.244519 2026-08-04 11:58:32.926051 | controller | Removed "/etc/systemd/system/timers.target.wants/apt-daily-upgrade.timer". 2026-08-04 11:58:32.926163 | controller | ok: Item: apt-daily-upgrade.timer Runtime: 0:00:00.264951 2026-08-04 11:58:33.399141 | lab-sp-a1 | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=, 2026-08-04 11:58:33.399262 | lab-sp-a1 | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for 2026-08-04 11:58:33.399285 | lab-sp-a1 | template units). This means they are not meant to be enabled or disabled using systemctl. 2026-08-04 11:58:33.399305 | lab-sp-a1 | 2026-08-04 11:58:33.399323 | lab-sp-a1 | Possible reasons for having these kinds of units are: 2026-08-04 11:58:33.399344 | lab-sp-a1 | • A unit may be statically enabled by being symlinked from another unit's 2026-08-04 11:58:33.399363 | lab-sp-a1 | .wants/, .requires/, or .upholds/ directory. 2026-08-04 11:58:33.399381 | lab-sp-a1 | • A unit's purpose may be to act as a helper for some other unit which has 2026-08-04 11:58:33.399399 | lab-sp-a1 | a requirement dependency on it. 2026-08-04 11:58:33.399416 | lab-sp-a1 | • A unit may be started when needed via activation (socket, path, timer, 2026-08-04 11:58:33.399434 | lab-sp-a1 | D-Bus, udev, scripted systemctl call, ...). 2026-08-04 11:58:33.399454 | lab-sp-a1 | • In case of template units, the unit is meant to be enabled with some 2026-08-04 11:58:33.399474 | lab-sp-a1 | instance name specified. 2026-08-04 11:58:33.399502 | lab-sp-a1 | ok: Item: apt-daily-upgrade.service Runtime: 0:00:00.263151 2026-08-04 11:58:33.423661 | undercloud-client | Removed "/etc/systemd/system/timers.target.wants/apt-daily-upgrade.timer". 2026-08-04 11:58:33.423827 | undercloud-client | ok: Item: apt-daily-upgrade.timer Runtime: 0:00:00.358019 2026-08-04 11:58:33.423864 | undercloud-client | changed: All items complete 2026-08-04 11:58:33.423886 | 2026-08-04 11:58:33.436093 | controller | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=, 2026-08-04 11:58:33.436223 | controller | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for 2026-08-04 11:58:33.436249 | controller | template units). This means they are not meant to be enabled or disabled using systemctl. 2026-08-04 11:58:33.436268 | controller | 2026-08-04 11:58:33.436287 | controller | Possible reasons for having these kinds of units are: 2026-08-04 11:58:33.436317 | controller | • A unit may be statically enabled by being symlinked from another unit's 2026-08-04 11:58:33.436338 | controller | .wants/, .requires/, or .upholds/ directory. 2026-08-04 11:58:33.436357 | controller | • A unit's purpose may be to act as a helper for some other unit which has 2026-08-04 11:58:33.436374 | controller | a requirement dependency on it. 2026-08-04 11:58:33.436392 | controller | • A unit may be started when needed via activation (socket, path, timer, 2026-08-04 11:58:33.436409 | controller | D-Bus, udev, scripted systemctl call, ...). 2026-08-04 11:58:33.436425 | controller | • In case of template units, the unit is meant to be enabled with some 2026-08-04 11:58:33.436442 | controller | instance name specified. 2026-08-04 11:58:33.436472 | controller | ok: Item: apt-daily-upgrade.service Runtime: 0:00:00.279656 2026-08-04 11:58:33.955717 | undercloud-client | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=, 2026-08-04 11:58:33.955865 | undercloud-client | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for 2026-08-04 11:58:33.955900 | undercloud-client | template units). This means they are not meant to be enabled or disabled using systemctl. 2026-08-04 11:58:33.955931 | undercloud-client | 2026-08-04 11:58:33.955958 | undercloud-client | Possible reasons for having these kinds of units are: 2026-08-04 11:58:33.955986 | undercloud-client | • A unit may be statically enabled by being symlinked from another unit's 2026-08-04 11:58:33.956016 | undercloud-client | .wants/, .requires/, or .upholds/ directory. 2026-08-04 11:58:33.956042 | undercloud-client | • A unit's purpose may be to act as a helper for some other unit which has 2026-08-04 11:58:33.956080 | undercloud-client | a requirement dependency on it. 2026-08-04 11:58:33.956108 | undercloud-client | • A unit may be started when needed via activation (socket, path, timer, 2026-08-04 11:58:33.956134 | undercloud-client | D-Bus, udev, scripted systemctl call, ...). 2026-08-04 11:58:33.956159 | undercloud-client | • In case of template units, the unit is meant to be enabled with some 2026-08-04 11:58:33.956185 | undercloud-client | instance name specified. 2026-08-04 11:58:33.956224 | undercloud-client | ok: Item: apt-daily-upgrade.service Runtime: 0:00:00.325263 2026-08-04 11:58:33.971674 | 2026-08-04 11:58:33.971812 | TASK [Wait for the lock to get freed] 2026-08-04 11:58:35.078191 | lab-sp-a1 | ERROR 2026-08-04 11:58:35.078446 | lab-sp-a1 | { 2026-08-04 11:58:35.078477 | lab-sp-a1 | "attempts": 1, 2026-08-04 11:58:35.078498 | lab-sp-a1 | "delta": "0:00:00.027054", 2026-08-04 11:58:35.078516 | lab-sp-a1 | "end": "2026-08-04 11:58:34.302424", 2026-08-04 11:58:35.078555 | lab-sp-a1 | "msg": "non-zero return code", 2026-08-04 11:58:35.078573 | lab-sp-a1 | "rc": 1, 2026-08-04 11:58:35.078589 | lab-sp-a1 | "start": "2026-08-04 11:58:34.275370" 2026-08-04 11:58:35.078604 | lab-sp-a1 | } 2026-08-04 11:58:35.078626 | lab-sp-a1 | ERROR: Ignoring Errors 2026-08-04 11:58:35.087121 | controller | ERROR 2026-08-04 11:58:35.087245 | controller | { 2026-08-04 11:58:35.087272 | controller | "attempts": 1, 2026-08-04 11:58:35.087292 | controller | "delta": "0:00:00.027932", 2026-08-04 11:58:35.087310 | controller | "end": "2026-08-04 11:58:34.309114", 2026-08-04 11:58:35.087328 | controller | "msg": "non-zero return code", 2026-08-04 11:58:35.087345 | controller | "rc": 1, 2026-08-04 11:58:35.087362 | controller | "start": "2026-08-04 11:58:34.281182" 2026-08-04 11:58:35.087379 | controller | } 2026-08-04 11:58:35.087401 | controller | ERROR: Ignoring Errors 2026-08-04 11:58:35.132336 | undercloud-client | ERROR 2026-08-04 11:58:35.132464 | undercloud-client | { 2026-08-04 11:58:35.132491 | undercloud-client | "attempts": 1, 2026-08-04 11:58:35.132510 | undercloud-client | "delta": "0:00:00.021593", 2026-08-04 11:58:35.132546 | undercloud-client | "end": "2026-08-04 11:58:34.331839", 2026-08-04 11:58:35.132580 | undercloud-client | "msg": "non-zero return code", 2026-08-04 11:58:35.132598 | undercloud-client | "rc": 1, 2026-08-04 11:58:35.132614 | undercloud-client | "start": "2026-08-04 11:58:34.310246" 2026-08-04 11:58:35.132632 | undercloud-client | } 2026-08-04 11:58:35.132654 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 11:58:35.133854 | 2026-08-04 11:58:35.133897 | PLAY RECAP 2026-08-04 11:58:35.133938 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-08-04 11:58:35.133965 | lab-sp-a1 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-08-04 11:58:35.133988 | undercloud-client | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1 2026-08-04 11:58:35.134006 | 2026-08-04 11:58:35.240844 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/01-disable-unattended-upgrades.yaml@master] 2026-08-04 11:58:35.250817 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/02-install-linux-modules-extra-for-z3fold.yaml@master] 2026-08-04 11:58:36.052069 | 2026-08-04 11:58:36.052203 | PLAY [all] 2026-08-04 11:58:36.066479 | 2026-08-04 11:58:36.066601 | TASK [Install the package linux-modules-extra for the currently running kernel] 2026-08-04 11:58:37.058268 | undercloud-client | Hit:1 http://lab.clouds.archive.ubuntu.com/ubuntu noble InRelease 2026-08-04 11:58:37.077469 | undercloud-client | Get:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB] 2026-08-04 11:58:37.242540 | lab-sp-a1 | Hit:1 http://lab.clouds.archive.ubuntu.com/ubuntu noble InRelease 2026-08-04 11:58:37.270922 | lab-sp-a1 | Get:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB] 2026-08-04 11:58:37.392805 | lab-sp-a1 | Get:3 http://security.ubuntu.com/ubuntu noble-security InRelease [126 kB] 2026-08-04 11:58:37.442106 | lab-sp-a1 | Get:4 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB] 2026-08-04 11:58:37.514936 | lab-sp-a1 | Get:5 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [1174 kB] 2026-08-04 11:58:37.201467 | undercloud-client | Get:3 http://security.ubuntu.com/ubuntu noble-security InRelease [126 kB] 2026-08-04 11:58:37.245334 | undercloud-client | Get:4 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB] 2026-08-04 11:58:37.391815 | undercloud-client | Get:5 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [1174 kB] 2026-08-04 11:58:37.600483 | undercloud-client | Get:6 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main Translation-en [280 kB] 2026-08-04 11:58:37.645267 | undercloud-client | Get:7 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Components [180 kB] 2026-08-04 11:58:37.648603 | undercloud-client | Get:8 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 c-n-f Metadata [17.7 kB] 2026-08-04 11:58:37.648624 | undercloud-client | Get:9 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1681 kB] 2026-08-04 11:58:37.255800 | controller | Hit:1 http://lab.clouds.archive.ubuntu.com/ubuntu noble InRelease 2026-08-04 11:58:37.285838 | controller | Get:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB] 2026-08-04 11:58:37.405438 | controller | Get:3 http://security.ubuntu.com/ubuntu noble-security InRelease [126 kB] 2026-08-04 11:58:37.454375 | controller | Get:4 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB] 2026-08-04 11:58:37.528609 | controller | Get:5 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [1174 kB] 2026-08-04 11:58:37.696385 | lab-sp-a1 | Get:6 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main Translation-en [280 kB] 2026-08-04 11:58:37.736048 | lab-sp-a1 | Get:7 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Components [180 kB] 2026-08-04 11:58:37.741254 | lab-sp-a1 | Get:8 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 c-n-f Metadata [17.7 kB] 2026-08-04 11:58:37.741379 | lab-sp-a1 | Get:9 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1681 kB] 2026-08-04 11:58:37.830124 | lab-sp-a1 | Get:10 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe Translation-en [334 kB] 2026-08-04 11:58:37.840318 | lab-sp-a1 | Get:11 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Components [388 kB] 2026-08-04 11:58:37.861764 | lab-sp-a1 | Get:12 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 c-n-f Metadata [34.9 kB] 2026-08-04 11:58:37.862285 | lab-sp-a1 | Get:13 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [1398 kB] 2026-08-04 11:58:37.886654 | lab-sp-a1 | Get:14 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted Translation-en [316 kB] 2026-08-04 11:58:37.894791 | lab-sp-a1 | Get:15 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Components [212 B] 2026-08-04 11:58:37.894831 | lab-sp-a1 | Get:16 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 c-n-f Metadata [456 B] 2026-08-04 11:58:37.894838 | lab-sp-a1 | Get:17 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Packages [45.4 kB] 2026-08-04 11:58:37.896010 | lab-sp-a1 | Get:18 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse Translation-en [12.3 kB] 2026-08-04 11:58:37.896052 | lab-sp-a1 | Get:19 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Components [940 B] 2026-08-04 11:58:37.934597 | lab-sp-a1 | Get:20 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 c-n-f Metadata [656 B] 2026-08-04 11:58:37.934883 | lab-sp-a1 | Get:21 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Packages [40.6 kB] 2026-08-04 11:58:37.935262 | lab-sp-a1 | Get:22 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main Translation-en [9172 B] 2026-08-04 11:58:37.941136 | lab-sp-a1 | Get:23 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Components [5772 B] 2026-08-04 11:58:37.941388 | lab-sp-a1 | Get:24 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 c-n-f Metadata [368 B] 2026-08-04 11:58:37.941631 | lab-sp-a1 | Get:25 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Packages [31.0 kB] 2026-08-04 11:58:37.941956 | lab-sp-a1 | Get:26 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe Translation-en [18.6 kB] 2026-08-04 11:58:37.942223 | lab-sp-a1 | Get:27 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Components [12.6 kB] 2026-08-04 11:58:37.977289 | lab-sp-a1 | Get:28 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 c-n-f Metadata [1588 B] 2026-08-04 11:58:37.977321 | lab-sp-a1 | Get:29 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/restricted amd64 Components [212 B] 2026-08-04 11:58:38.001080 | lab-sp-a1 | Get:30 http://security.ubuntu.com/ubuntu noble-security/main amd64 Packages [897 kB] 2026-08-04 11:58:38.015978 | lab-sp-a1 | Get:31 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Packages [748 B] 2026-08-04 11:58:38.016806 | lab-sp-a1 | Get:32 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse Translation-en [340 B] 2026-08-04 11:58:38.017085 | lab-sp-a1 | Get:33 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Components [212 B] 2026-08-04 11:58:37.726392 | undercloud-client | Get:10 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe Translation-en [334 kB] 2026-08-04 11:58:37.732584 | undercloud-client | Get:11 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Components [388 kB] 2026-08-04 11:58:37.742254 | undercloud-client | Get:12 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 c-n-f Metadata [34.9 kB] 2026-08-04 11:58:37.742289 | undercloud-client | Get:13 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [1398 kB] 2026-08-04 11:58:37.768794 | undercloud-client | Get:14 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted Translation-en [316 kB] 2026-08-04 11:58:37.777001 | undercloud-client | Get:15 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Components [212 B] 2026-08-04 11:58:37.777030 | undercloud-client | Get:16 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 c-n-f Metadata [456 B] 2026-08-04 11:58:37.777035 | undercloud-client | Get:17 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Packages [45.4 kB] 2026-08-04 11:58:37.777038 | undercloud-client | Get:18 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse Translation-en [12.3 kB] 2026-08-04 11:58:37.795821 | undercloud-client | Get:19 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Components [940 B] 2026-08-04 11:58:37.818414 | undercloud-client | Get:20 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 c-n-f Metadata [656 B] 2026-08-04 11:58:37.818701 | undercloud-client | Get:21 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Packages [40.6 kB] 2026-08-04 11:58:37.822041 | undercloud-client | Get:22 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main Translation-en [9172 B] 2026-08-04 11:58:37.822522 | undercloud-client | Get:23 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Components [5772 B] 2026-08-04 11:58:37.822538 | undercloud-client | Get:24 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 c-n-f Metadata [368 B] 2026-08-04 11:58:37.843971 | undercloud-client | Get:25 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Packages [31.0 kB] 2026-08-04 11:58:37.846344 | undercloud-client | Get:26 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe Translation-en [18.6 kB] 2026-08-04 11:58:37.847854 | undercloud-client | Get:27 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Components [12.6 kB] 2026-08-04 11:58:37.849383 | undercloud-client | Get:28 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 c-n-f Metadata [1588 B] 2026-08-04 11:58:37.894865 | undercloud-client | Get:29 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/restricted amd64 Components [212 B] 2026-08-04 11:58:37.895091 | undercloud-client | Get:30 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Packages [748 B] 2026-08-04 11:58:37.896655 | undercloud-client | Get:31 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse Translation-en [340 B] 2026-08-04 11:58:37.897165 | undercloud-client | Get:32 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Components [212 B] 2026-08-04 11:58:37.963610 | undercloud-client | Get:33 http://security.ubuntu.com/ubuntu noble-security/main amd64 Packages [897 kB] 2026-08-04 11:58:37.714418 | controller | Get:6 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main Translation-en [280 kB] 2026-08-04 11:58:37.753938 | controller | Get:7 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Components [180 kB] 2026-08-04 11:58:37.758106 | controller | Get:8 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 c-n-f Metadata [17.7 kB] 2026-08-04 11:58:37.758367 | controller | Get:9 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1681 kB] 2026-08-04 11:58:37.871933 | controller | Get:10 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe Translation-en [334 kB] 2026-08-04 11:58:37.878523 | controller | Get:11 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Components [388 kB] 2026-08-04 11:58:37.886864 | controller | Get:12 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 c-n-f Metadata [34.9 kB] 2026-08-04 11:58:37.886898 | controller | Get:13 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [1398 kB] 2026-08-04 11:58:37.922773 | controller | Get:14 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted Translation-en [316 kB] 2026-08-04 11:58:37.926360 | controller | Get:15 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Components [212 B] 2026-08-04 11:58:37.927562 | controller | Get:16 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 c-n-f Metadata [456 B] 2026-08-04 11:58:37.927755 | controller | Get:17 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Packages [45.4 kB] 2026-08-04 11:58:37.927884 | controller | Get:18 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse Translation-en [12.3 kB] 2026-08-04 11:58:37.928002 | controller | Get:19 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Components [940 B] 2026-08-04 11:58:37.951272 | controller | Get:20 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 c-n-f Metadata [656 B] 2026-08-04 11:58:37.952142 | controller | Get:21 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Packages [40.6 kB] 2026-08-04 11:58:37.953129 | controller | Get:22 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main Translation-en [9172 B] 2026-08-04 11:58:37.967749 | controller | Get:23 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Components [5772 B] 2026-08-04 11:58:37.969177 | controller | Get:24 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 c-n-f Metadata [368 B] 2026-08-04 11:58:37.969351 | controller | Get:25 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Packages [31.0 kB] 2026-08-04 11:58:37.969458 | controller | Get:26 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe Translation-en [18.6 kB] 2026-08-04 11:58:37.969557 | controller | Get:27 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Components [12.6 kB] 2026-08-04 11:58:37.992003 | controller | Get:28 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 c-n-f Metadata [1588 B] 2026-08-04 11:58:37.992229 | controller | Get:29 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/restricted amd64 Components [212 B] 2026-08-04 11:58:38.015487 | controller | Get:30 http://security.ubuntu.com/ubuntu noble-security/main amd64 Packages [897 kB] 2026-08-04 11:58:38.031231 | controller | Get:31 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Packages [748 B] 2026-08-04 11:58:38.031774 | controller | Get:32 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse Translation-en [340 B] 2026-08-04 11:58:38.031791 | controller | Get:33 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Components [212 B] 2026-08-04 11:58:38.596491 | undercloud-client | Get:34 http://security.ubuntu.com/ubuntu noble-security/main Translation-en [198 kB] 2026-08-04 11:58:38.623468 | undercloud-client | Get:35 http://security.ubuntu.com/ubuntu noble-security/main amd64 Components [46.3 kB] 2026-08-04 11:58:38.630383 | undercloud-client | Get:36 http://security.ubuntu.com/ubuntu noble-security/main amd64 c-n-f Metadata [11.9 kB] 2026-08-04 11:58:38.632165 | undercloud-client | Get:37 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Packages [1199 kB] 2026-08-04 11:58:38.663431 | lab-sp-a1 | Get:34 http://security.ubuntu.com/ubuntu noble-security/main Translation-en [198 kB] 2026-08-04 11:58:38.685828 | lab-sp-a1 | Get:35 http://security.ubuntu.com/ubuntu noble-security/main amd64 Components [46.3 kB] 2026-08-04 11:58:38.690544 | lab-sp-a1 | Get:36 http://security.ubuntu.com/ubuntu noble-security/main amd64 c-n-f Metadata [11.9 kB] 2026-08-04 11:58:38.692532 | lab-sp-a1 | Get:37 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Packages [1199 kB] 2026-08-04 11:58:38.960131 | lab-sp-a1 | Get:38 http://security.ubuntu.com/ubuntu noble-security/universe Translation-en [239 kB] 2026-08-04 11:58:38.966814 | lab-sp-a1 | Get:39 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Components [76.3 kB] 2026-08-04 11:58:38.980317 | lab-sp-a1 | Get:40 http://security.ubuntu.com/ubuntu noble-security/universe amd64 c-n-f Metadata [24.2 kB] 2026-08-04 11:58:38.980825 | lab-sp-a1 | Get:41 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [1273 kB] 2026-08-04 11:58:38.937511 | undercloud-client | Get:38 http://security.ubuntu.com/ubuntu noble-security/universe Translation-en [239 kB] 2026-08-04 11:58:38.940123 | undercloud-client | Get:39 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Components [76.3 kB] 2026-08-04 11:58:38.942430 | undercloud-client | Get:40 http://security.ubuntu.com/ubuntu noble-security/universe amd64 c-n-f Metadata [24.2 kB] 2026-08-04 11:58:38.943265 | undercloud-client | Get:41 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [1273 kB] 2026-08-04 11:58:39.145300 | undercloud-client | Get:42 http://security.ubuntu.com/ubuntu noble-security/restricted Translation-en [290 kB] 2026-08-04 11:58:39.147439 | undercloud-client | Get:43 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Components [212 B] 2026-08-04 11:58:39.147558 | undercloud-client | Get:44 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 c-n-f Metadata [444 B] 2026-08-04 11:58:39.147959 | undercloud-client | Get:45 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Packages [40.3 kB] 2026-08-04 11:58:39.149837 | undercloud-client | Get:46 http://security.ubuntu.com/ubuntu noble-security/multiverse Translation-en [10.6 kB] 2026-08-04 11:58:38.717744 | controller | Get:34 http://security.ubuntu.com/ubuntu noble-security/main Translation-en [198 kB] 2026-08-04 11:58:38.845545 | controller | Get:35 http://security.ubuntu.com/ubuntu noble-security/main amd64 Components [46.3 kB] 2026-08-04 11:58:38.846011 | controller | Get:36 http://security.ubuntu.com/ubuntu noble-security/main amd64 c-n-f Metadata [11.9 kB] 2026-08-04 11:58:38.846411 | controller | Get:37 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Packages [1199 kB] 2026-08-04 11:58:38.993498 | controller | Get:38 http://security.ubuntu.com/ubuntu noble-security/universe Translation-en [239 kB] 2026-08-04 11:58:39.135244 | controller | Get:39 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Components [76.3 kB] 2026-08-04 11:58:39.136024 | controller | Get:40 http://security.ubuntu.com/ubuntu noble-security/universe amd64 c-n-f Metadata [24.2 kB] 2026-08-04 11:58:39.136320 | controller | Get:41 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [1273 kB] 2026-08-04 11:58:39.134554 | lab-sp-a1 | Get:42 http://security.ubuntu.com/ubuntu noble-security/restricted Translation-en [290 kB] 2026-08-04 11:58:39.214075 | lab-sp-a1 | Get:43 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Components [212 B] 2026-08-04 11:58:39.214112 | lab-sp-a1 | Get:44 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 c-n-f Metadata [444 B] 2026-08-04 11:58:39.214234 | lab-sp-a1 | Get:45 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Packages [40.3 kB] 2026-08-04 11:58:39.214530 | lab-sp-a1 | Get:46 http://security.ubuntu.com/ubuntu noble-security/multiverse Translation-en [10.6 kB] 2026-08-04 11:58:39.214699 | lab-sp-a1 | Get:47 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Components [208 B] 2026-08-04 11:58:39.214874 | lab-sp-a1 | Get:48 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 c-n-f Metadata [468 B] 2026-08-04 11:58:39.208265 | undercloud-client | Get:47 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Components [208 B] 2026-08-04 11:58:39.208306 | undercloud-client | Get:48 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 c-n-f Metadata [468 B] 2026-08-04 11:58:39.350170 | controller | Get:42 http://security.ubuntu.com/ubuntu noble-security/restricted Translation-en [290 kB] 2026-08-04 11:58:39.472639 | controller | Get:43 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Components [212 B] 2026-08-04 11:58:39.472673 | controller | Get:44 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 c-n-f Metadata [444 B] 2026-08-04 11:58:39.472680 | controller | Get:45 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Packages [40.3 kB] 2026-08-04 11:58:39.472974 | controller | Get:46 http://security.ubuntu.com/ubuntu noble-security/multiverse Translation-en [10.6 kB] 2026-08-04 11:58:39.473141 | controller | Get:47 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Components [208 B] 2026-08-04 11:58:39.473417 | controller | Get:48 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 c-n-f Metadata [468 B] 2026-08-04 11:58:44.032498 | lab-sp-a1 | Fetched 10.7 MB in 2s (4947 kB/s) 2026-08-04 11:58:44.573866 | undercloud-client | Fetched 10.7 MB in 2s (4320 kB/s) 2026-08-04 11:58:44.266933 | controller | Fetched 10.7 MB in 2s (4505 kB/s) 2026-08-04 11:58:44.830503 | lab-sp-a1 | Reading package lists... 2026-08-04 11:58:44.869500 | lab-sp-a1 | Reading package lists... 2026-08-04 11:58:45.124009 | controller | Reading package lists... 2026-08-04 11:58:45.162719 | controller | Reading package lists... 2026-08-04 11:58:45.119410 | lab-sp-a1 | Building dependency tree... 2026-08-04 11:58:45.119990 | lab-sp-a1 | Reading state information... 2026-08-04 11:58:45.423744 | lab-sp-a1 | The following additional packages will be installed: 2026-08-04 11:58:45.423803 | lab-sp-a1 | wireless-regdb 2026-08-04 11:58:45.454464 | lab-sp-a1 | The following NEW packages will be installed: 2026-08-04 11:58:45.455105 | lab-sp-a1 | linux-modules-extra-6.8.0-100-generic wireless-regdb 2026-08-04 11:58:45.561720 | lab-sp-a1 | 0 upgraded, 2 newly installed, 0 to remove and 172 not upgraded. 2026-08-04 11:58:45.561780 | lab-sp-a1 | Need to get 113 MB of archives. 2026-08-04 11:58:45.561806 | lab-sp-a1 | After this operation, 112 MB of additional disk space will be used. 2026-08-04 11:58:45.561813 | lab-sp-a1 | Get:1 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 wireless-regdb all 2025.10.07-0ubuntu1~24.04.1 [7542 B] 2026-08-04 11:58:45.645111 | undercloud-client | Reading package lists... 2026-08-04 11:58:45.384418 | controller | Building dependency tree... 2026-08-04 11:58:45.384938 | controller | Reading state information... 2026-08-04 11:58:45.655064 | controller | The following additional packages will be installed: 2026-08-04 11:58:45.655111 | controller | wireless-regdb 2026-08-04 11:58:45.684273 | controller | The following NEW packages will be installed: 2026-08-04 11:58:45.684811 | controller | linux-modules-extra-6.8.0-100-generic wireless-regdb 2026-08-04 11:58:45.601650 | lab-sp-a1 | Get:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 linux-modules-extra-6.8.0-100-generic amd64 6.8.0-100.100 [113 MB] 2026-08-04 11:58:45.686002 | undercloud-client | Reading package lists... 2026-08-04 11:58:45.938047 | undercloud-client | Building dependency tree... 2026-08-04 11:58:45.938689 | undercloud-client | Reading state information... 2026-08-04 11:58:45.786970 | controller | 0 upgraded, 2 newly installed, 0 to remove and 172 not upgraded. 2026-08-04 11:58:45.787012 | controller | Need to get 113 MB of archives. 2026-08-04 11:58:45.787029 | controller | After this operation, 112 MB of additional disk space will be used. 2026-08-04 11:58:45.787036 | controller | Get:1 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 wireless-regdb all 2025.10.07-0ubuntu1~24.04.1 [7542 B] 2026-08-04 11:58:45.828829 | controller | Get:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 linux-modules-extra-6.8.0-100-generic amd64 6.8.0-100.100 [113 MB] 2026-08-04 11:58:46.228752 | undercloud-client | The following additional packages will be installed: 2026-08-04 11:58:46.228849 | undercloud-client | wireless-regdb 2026-08-04 11:58:46.262873 | undercloud-client | The following NEW packages will be installed: 2026-08-04 11:58:46.263601 | undercloud-client | linux-modules-extra-6.8.0-100-generic wireless-regdb 2026-08-04 11:58:46.393087 | undercloud-client | 0 upgraded, 2 newly installed, 0 to remove and 172 not upgraded. 2026-08-04 11:58:46.393134 | undercloud-client | Need to get 113 MB of archives. 2026-08-04 11:58:46.393158 | undercloud-client | After this operation, 112 MB of additional disk space will be used. 2026-08-04 11:58:46.393170 | undercloud-client | Get:1 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 wireless-regdb all 2025.10.07-0ubuntu1~24.04.1 [7542 B] 2026-08-04 11:58:46.445889 | undercloud-client | Get:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 linux-modules-extra-6.8.0-100-generic amd64 6.8.0-100.100 [113 MB] 2026-08-04 11:58:47.638296 | lab-sp-a1 | debconf: unable to initialize frontend: Dialog 2026-08-04 11:58:47.638335 | lab-sp-a1 | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.) 2026-08-04 11:58:47.638342 | lab-sp-a1 | debconf: falling back to frontend: Readline 2026-08-04 11:58:47.648642 | lab-sp-a1 | debconf: unable to initialize frontend: Readline 2026-08-04 11:58:47.648668 | lab-sp-a1 | debconf: (This frontend requires a controlling tty.) 2026-08-04 11:58:47.648674 | lab-sp-a1 | debconf: falling back to frontend: Teletype 2026-08-04 11:58:47.654245 | lab-sp-a1 | dpkg-preconfigure: unable to re-open stdin: 2026-08-04 11:58:47.696821 | lab-sp-a1 | Fetched 113 MB in 2s (58.0 MB/s) 2026-08-04 11:58:47.720597 | lab-sp-a1 | Selecting previously unselected package wireless-regdb. 2026-08-04 11:58:47.891756 | lab-sp-a1 | (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 ... 74915 files and directories currently installed.) 2026-08-04 11:58:47.895229 | lab-sp-a1 | Preparing to unpack .../wireless-regdb_2025.10.07-0ubuntu1~24.04.1_all.deb ... 2026-08-04 11:58:47.898222 | lab-sp-a1 | Unpacking wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ... 2026-08-04 11:58:47.927609 | lab-sp-a1 | Selecting previously unselected package linux-modules-extra-6.8.0-100-generic. 2026-08-04 11:58:47.933441 | lab-sp-a1 | Preparing to unpack .../linux-modules-extra-6.8.0-100-generic_6.8.0-100.100_amd64.deb ... 2026-08-04 11:58:47.935135 | lab-sp-a1 | Unpacking linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:48.501803 | controller | debconf: unable to initialize frontend: Dialog 2026-08-04 11:58:48.501860 | controller | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.) 2026-08-04 11:58:48.501869 | controller | debconf: falling back to frontend: Readline 2026-08-04 11:58:48.511371 | controller | debconf: unable to initialize frontend: Readline 2026-08-04 11:58:48.511416 | controller | debconf: (This frontend requires a controlling tty.) 2026-08-04 11:58:48.511432 | controller | debconf: falling back to frontend: Teletype 2026-08-04 11:58:48.517716 | controller | dpkg-preconfigure: unable to re-open stdin: 2026-08-04 11:58:48.562078 | controller | Fetched 113 MB in 3s (43.7 MB/s) 2026-08-04 11:58:48.585103 | controller | Selecting previously unselected package wireless-regdb. 2026-08-04 11:58:48.684881 | 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 ... 74915 files and directories currently installed.) 2026-08-04 11:58:48.686631 | controller | Preparing to unpack .../wireless-regdb_2025.10.07-0ubuntu1~24.04.1_all.deb ... 2026-08-04 11:58:48.689671 | controller | Unpacking wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ... 2026-08-04 11:58:48.724541 | controller | Selecting previously unselected package linux-modules-extra-6.8.0-100-generic. 2026-08-04 11:58:48.731426 | controller | Preparing to unpack .../linux-modules-extra-6.8.0-100-generic_6.8.0-100.100_amd64.deb ... 2026-08-04 11:58:48.734850 | controller | Unpacking linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:49.392038 | undercloud-client | debconf: unable to initialize frontend: Dialog 2026-08-04 11:58:49.392100 | undercloud-client | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.) 2026-08-04 11:58:49.392130 | undercloud-client | debconf: falling back to frontend: Readline 2026-08-04 11:58:49.402262 | undercloud-client | debconf: unable to initialize frontend: Readline 2026-08-04 11:58:49.402295 | undercloud-client | debconf: (This frontend requires a controlling tty.) 2026-08-04 11:58:49.402304 | undercloud-client | debconf: falling back to frontend: Teletype 2026-08-04 11:58:49.407125 | undercloud-client | dpkg-preconfigure: unable to re-open stdin: 2026-08-04 11:58:49.453110 | undercloud-client | Fetched 113 MB in 3s (39.4 MB/s) 2026-08-04 11:58:49.479295 | undercloud-client | Selecting previously unselected package wireless-regdb. 2026-08-04 11:58:49.578280 | undercloud-client | (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 ... 74915 files and directories currently installed.) 2026-08-04 11:58:49.578643 | undercloud-client | Preparing to unpack .../wireless-regdb_2025.10.07-0ubuntu1~24.04.1_all.deb ... 2026-08-04 11:58:49.581078 | undercloud-client | Unpacking wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ... 2026-08-04 11:58:49.613282 | undercloud-client | Selecting previously unselected package linux-modules-extra-6.8.0-100-generic. 2026-08-04 11:58:49.620034 | undercloud-client | Preparing to unpack .../linux-modules-extra-6.8.0-100-generic_6.8.0-100.100_amd64.deb ... 2026-08-04 11:58:49.621624 | undercloud-client | Unpacking linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:49.595893 | lab-sp-a1 | Setting up wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ... 2026-08-04 11:58:49.600065 | lab-sp-a1 | Setting up linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:50.388370 | controller | Setting up wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ... 2026-08-04 11:58:50.392791 | controller | Setting up linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:51.453435 | undercloud-client | Setting up wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ... 2026-08-04 11:58:51.459777 | undercloud-client | Setting up linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:51.711182 | lab-sp-a1 | Processing triggers for man-db (2.12.0-4build2) ... 2026-08-04 11:58:52.022658 | lab-sp-a1 | Processing triggers for linux-image-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:52.034398 | lab-sp-a1 | /etc/kernel/postinst.d/initramfs-tools: 2026-08-04 11:58:52.034424 | lab-sp-a1 | update-initramfs: Generating /boot/initrd.img-6.8.0-100-generic 2026-08-04 11:58:52.314421 | controller | Processing triggers for man-db (2.12.0-4build2) ... 2026-08-04 11:58:52.576188 | controller | Processing triggers for linux-image-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:52.586536 | controller | /etc/kernel/postinst.d/initramfs-tools: 2026-08-04 11:58:52.586566 | controller | update-initramfs: Generating /boot/initrd.img-6.8.0-100-generic 2026-08-04 11:58:53.509021 | undercloud-client | Processing triggers for man-db (2.12.0-4build2) ... 2026-08-04 11:58:53.762899 | undercloud-client | Processing triggers for linux-image-6.8.0-100-generic (6.8.0-100.100) ... 2026-08-04 11:58:53.771601 | undercloud-client | /etc/kernel/postinst.d/initramfs-tools: 2026-08-04 11:58:53.771626 | undercloud-client | update-initramfs: Generating /boot/initrd.img-6.8.0-100-generic 2026-08-04 11:58:59.990005 | lab-sp-a1 | /etc/kernel/postinst.d/zz-update-grub: 2026-08-04 11:58:59.990040 | lab-sp-a1 | Sourcing file `/etc/default/grub' 2026-08-04 11:58:59.992383 | lab-sp-a1 | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 2026-08-04 11:58:59.994562 | lab-sp-a1 | Generating grub configuration file ... 2026-08-04 11:59:00.118855 | lab-sp-a1 | Found linux image: /boot/vmlinuz-6.8.0-100-generic 2026-08-04 11:59:00.135410 | lab-sp-a1 | Found initrd image: /boot/initrd.img-6.8.0-100-generic 2026-08-04 11:59:00.422409 | lab-sp-a1 | Warning: os-prober will not be executed to detect other bootable partitions. 2026-08-04 11:59:00.422880 | lab-sp-a1 | Systems on them will not be added to the GRUB boot configuration. 2026-08-04 11:59:00.422894 | lab-sp-a1 | Check GRUB_DISABLE_OS_PROBER documentation entry. 2026-08-04 11:59:00.425019 | lab-sp-a1 | Adding boot menu entry for UEFI Firmware Settings ... 2026-08-04 11:59:00.436784 | lab-sp-a1 | done 2026-08-04 11:59:00.379765 | controller | /etc/kernel/postinst.d/zz-update-grub: 2026-08-04 11:59:00.379809 | controller | Sourcing file `/etc/default/grub' 2026-08-04 11:59:00.382307 | controller | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 2026-08-04 11:59:00.384751 | controller | Generating grub configuration file ... 2026-08-04 11:59:00.503919 | controller | Found linux image: /boot/vmlinuz-6.8.0-100-generic 2026-08-04 11:59:00.515176 | controller | Found initrd image: /boot/initrd.img-6.8.0-100-generic 2026-08-04 11:59:00.823507 | lab-sp-a1 | debconf: unable to initialize frontend: Dialog 2026-08-04 11:59:00.823551 | lab-sp-a1 | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.) 2026-08-04 11:59:00.823558 | lab-sp-a1 | debconf: falling back to frontend: Readline 2026-08-04 11:59:00.832696 | lab-sp-a1 | debconf: unable to initialize frontend: Readline 2026-08-04 11:59:00.832726 | lab-sp-a1 | debconf: (This frontend requires a controlling tty.) 2026-08-04 11:59:00.832732 | lab-sp-a1 | debconf: falling back to frontend: Teletype 2026-08-04 11:59:00.986822 | lab-sp-a1 | 2026-08-04 11:59:00.986872 | lab-sp-a1 | Running kernel seems to be up-to-date. 2026-08-04 11:59:00.986879 | lab-sp-a1 | 2026-08-04 11:59:00.986885 | lab-sp-a1 | No services need to be restarted. 2026-08-04 11:59:00.986890 | lab-sp-a1 | 2026-08-04 11:59:00.986894 | lab-sp-a1 | No containers need to be restarted. 2026-08-04 11:59:00.986899 | lab-sp-a1 | 2026-08-04 11:59:00.986903 | lab-sp-a1 | No user sessions are running outdated binaries. 2026-08-04 11:59:00.986908 | lab-sp-a1 | 2026-08-04 11:59:00.986923 | lab-sp-a1 | No VM guests are running outdated hypervisor (qemu) binaries on this host. 2026-08-04 11:59:01.041969 | undercloud-client | /etc/kernel/postinst.d/zz-update-grub: 2026-08-04 11:59:01.042095 | undercloud-client | Sourcing file `/etc/default/grub' 2026-08-04 11:59:01.044767 | undercloud-client | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg' 2026-08-04 11:59:01.047492 | undercloud-client | Generating grub configuration file ... 2026-08-04 11:59:00.805709 | controller | Warning: os-prober will not be executed to detect other bootable partitions. 2026-08-04 11:59:00.805752 | controller | Systems on them will not be added to the GRUB boot configuration. 2026-08-04 11:59:00.805758 | controller | Check GRUB_DISABLE_OS_PROBER documentation entry. 2026-08-04 11:59:00.808121 | controller | Adding boot menu entry for UEFI Firmware Settings ... 2026-08-04 11:59:00.820820 | controller | done 2026-08-04 11:59:01.169058 | undercloud-client | Found linux image: /boot/vmlinuz-6.8.0-100-generic 2026-08-04 11:59:01.178356 | undercloud-client | Found initrd image: /boot/initrd.img-6.8.0-100-generic 2026-08-04 11:59:01.438277 | undercloud-client | Warning: os-prober will not be executed to detect other bootable partitions. 2026-08-04 11:59:01.438612 | undercloud-client | Systems on them will not be added to the GRUB boot configuration. 2026-08-04 11:59:01.438631 | undercloud-client | Check GRUB_DISABLE_OS_PROBER documentation entry. 2026-08-04 11:59:01.440664 | undercloud-client | Adding boot menu entry for UEFI Firmware Settings ... 2026-08-04 11:59:01.449972 | undercloud-client | done 2026-08-04 11:59:01.231665 | controller | debconf: unable to initialize frontend: Dialog 2026-08-04 11:59:01.231708 | controller | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.) 2026-08-04 11:59:01.231714 | controller | debconf: falling back to frontend: Readline 2026-08-04 11:59:01.243319 | controller | debconf: unable to initialize frontend: Readline 2026-08-04 11:59:01.243356 | controller | debconf: (This frontend requires a controlling tty.) 2026-08-04 11:59:01.243365 | controller | debconf: falling back to frontend: Teletype 2026-08-04 11:59:01.406137 | controller | 2026-08-04 11:59:01.406186 | controller | Running kernel seems to be up-to-date. 2026-08-04 11:59:01.406198 | controller | 2026-08-04 11:59:01.406212 | controller | No services need to be restarted. 2026-08-04 11:59:01.406222 | controller | 2026-08-04 11:59:01.406231 | controller | No containers need to be restarted. 2026-08-04 11:59:01.406240 | controller | 2026-08-04 11:59:01.406255 | controller | No user sessions are running outdated binaries. 2026-08-04 11:59:01.406264 | controller | 2026-08-04 11:59:01.406283 | controller | No VM guests are running outdated hypervisor (qemu) binaries on this host. 2026-08-04 11:59:01.913411 | undercloud-client | debconf: unable to initialize frontend: Dialog 2026-08-04 11:59:01.913623 | undercloud-client | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.) 2026-08-04 11:59:01.913641 | undercloud-client | debconf: falling back to frontend: Readline 2026-08-04 11:59:01.921256 | undercloud-client | debconf: unable to initialize frontend: Readline 2026-08-04 11:59:01.921276 | undercloud-client | debconf: (This frontend requires a controlling tty.) 2026-08-04 11:59:01.921282 | undercloud-client | debconf: falling back to frontend: Teletype 2026-08-04 11:59:02.054985 | undercloud-client | 2026-08-04 11:59:02.056230 | undercloud-client | Running kernel seems to be up-to-date. 2026-08-04 11:59:02.056249 | undercloud-client | 2026-08-04 11:59:02.056255 | undercloud-client | No services need to be restarted. 2026-08-04 11:59:02.056260 | undercloud-client | 2026-08-04 11:59:02.056265 | undercloud-client | No containers need to be restarted. 2026-08-04 11:59:02.056269 | undercloud-client | 2026-08-04 11:59:02.056274 | undercloud-client | No user sessions are running outdated binaries. 2026-08-04 11:59:02.056278 | undercloud-client | 2026-08-04 11:59:02.056290 | undercloud-client | No VM guests are running outdated hypervisor (qemu) binaries on this host. 2026-08-04 11:59:03.201900 | lab-sp-a1 | ok: Runtime: 0:00:25.229316 2026-08-04 11:59:03.212703 | controller | ok: Runtime: 0:00:25.671192 2026-08-04 11:59:03.317776 | undercloud-client | ok: Runtime: 0:00:26.492282 2026-08-04 11:59:03.320116 | 2026-08-04 11:59:03.320167 | PLAY RECAP 2026-08-04 11:59:03.320212 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:59:03.320239 | lab-sp-a1 | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:59:03.320262 | undercloud-client | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:59:03.320285 | 2026-08-04 11:59:03.436327 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/02-install-linux-modules-extra-for-z3fold.yaml@master] 2026-08-04 11:59:03.446194 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/03-provide-unobtainable-novnc.yaml@master] 2026-08-04 11:59:04.164729 | 2026-08-04 11:59:04.164860 | PLAY [all] 2026-08-04 11:59:04.179431 | 2026-08-04 11:59:04.179542 | TASK [Copy novnc] 2026-08-04 11:59:04.973492 | controller | changed 2026-08-04 11:59:04.978779 | lab-sp-a1 | changed 2026-08-04 11:59:05.044942 | undercloud-client | changed 2026-08-04 11:59:05.051155 | 2026-08-04 11:59:05.051321 | TASK [Fix novnc permissions] 2026-08-04 11:59:05.421394 | lab-sp-a1 | changed 2026-08-04 11:59:05.427339 | undercloud-client | changed 2026-08-04 11:59:05.472859 | controller | changed 2026-08-04 11:59:05.474347 | 2026-08-04 11:59:05.474395 | PLAY RECAP 2026-08-04 11:59:05.474436 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:59:05.474466 | lab-sp-a1 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:59:05.474493 | undercloud-client | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:59:05.474510 | 2026-08-04 11:59:05.604467 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/03-provide-unobtainable-novnc.yaml@master] 2026-08-04 11:59:05.614502 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/sp-init.yaml@master] 2026-08-04 11:59:06.380988 | 2026-08-04 11:59:06.381209 | PLAY [tempest] 2026-08-04 11:59:06.398666 | 2026-08-04 11:59:06.398843 | TASK [Apply the StorPool patches] 2026-08-04 11:59:07.435797 | controller | ok: Runtime: 0:00:00.007519 2026-08-04 11:59:07.440904 | 2026-08-04 11:59:07.440988 | TASK [Apply the StorPool experimental patches] 2026-08-04 11:59:08.007123 | controller | skipping: Conditional result was False 2026-08-04 11:59:08.013115 | 2026-08-04 11:59:08.013190 | TASK [Apply the StorPool patches to the other place] 2026-08-04 11:59:08.545782 | controller | ok: Runtime: 0:00:00.008150 2026-08-04 11:59:08.550678 | 2026-08-04 11:59:08.550749 | TASK [Apply the StorPool experimental patches to the other place] 2026-08-04 11:59:09.078311 | controller | skipping: Conditional result was False 2026-08-04 11:59:09.079895 | 2026-08-04 11:59:09.079948 | PLAY RECAP 2026-08-04 11:59:09.079994 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0 2026-08-04 11:59:09.080016 | 2026-08-04 11:59:09.218845 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/sp-init.yaml@master] 2026-08-04 11:59:09.229193 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-client/pre.yaml@master] 2026-08-04 11:59:09.980988 | 2026-08-04 11:59:09.981132 | PLAY [undercloud-client] 2026-08-04 11:59:09.995306 | 2026-08-04 11:59:09.995425 | TASK [Ensure OpenStack Credentials Directory Exists] 2026-08-04 11:59:10.672574 | undercloud-client | changed 2026-08-04 11:59:10.682156 | 2026-08-04 11:59:10.682422 | TASK [Provision OpenStack Credentials] 2026-08-04 11:59:11.460919 | undercloud-client | Output suppressed because no_log was given 2026-08-04 11:59:11.466374 | 2026-08-04 11:59:11.466476 | TASK [Install python3-pip] 2026-08-04 11:59:31.091661 | undercloud-client | changed 2026-08-04 11:59:31.104397 | 2026-08-04 11:59:31.104580 | TASK [Install virtualenv] 2026-08-04 11:59:33.234870 | undercloud-client | changed 2026-08-04 11:59:33.241100 | 2026-08-04 11:59:33.241220 | TASK [Create an OpenStack CLI Venv] 2026-08-04 11:59:47.936285 | undercloud-client | changed 2026-08-04 11:59:47.939125 | 2026-08-04 11:59:47.939181 | PLAY RECAP 2026-08-04 11:59:47.939238 | undercloud-client | ok: 5 changed: 5 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 11:59:47.939264 | 2026-08-04 11:59:48.056023 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-client/pre.yaml@master] 2026-08-04 11:59:48.066211 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-storpool/pre.yaml@master] 2026-08-04 11:59:48.893169 | 2026-08-04 11:59:48.893303 | PLAY [undercloud-client] 2026-08-04 11:59:48.906865 | 2026-08-04 11:59:48.907063 | LOOP [Create the StorPool Networks] 2026-08-04 11:59:51.516020 | undercloud-client | +---------------------------+-----------------------------------------------------+ 2026-08-04 11:59:51.516254 | undercloud-client | | Field | Value | 2026-08-04 11:59:51.516311 | undercloud-client | +---------------------------+-----------------------------------------------------+ 2026-08-04 11:59:51.516354 | undercloud-client | | admin_state_up | UP | 2026-08-04 11:59:51.516419 | undercloud-client | | availability_zone_hints | lab | 2026-08-04 11:59:51.516452 | undercloud-client | | availability_zones | | 2026-08-04 11:59:51.516482 | undercloud-client | | created_at | 2026-08-04T11:59:50Z | 2026-08-04 11:59:51.516517 | undercloud-client | | description | | 2026-08-04 11:59:51.516575 | undercloud-client | | dns_domain | | 2026-08-04 11:59:51.516608 | undercloud-client | | id | 761f26ec-4e5c-49ee-bd08-6389e5eec9f3 | 2026-08-04 11:59:51.516638 | undercloud-client | | ipv4_address_scope | None | 2026-08-04 11:59:51.516665 | undercloud-client | | ipv6_address_scope | None | 2026-08-04 11:59:51.516689 | undercloud-client | | is_default | False | 2026-08-04 11:59:51.516713 | undercloud-client | | is_vlan_qinq | None | 2026-08-04 11:59:51.516736 | undercloud-client | | is_vlan_transparent | False | 2026-08-04 11:59:51.516763 | undercloud-client | | l2_adjacency | True | 2026-08-04 11:59:51.516789 | undercloud-client | | mtu | 8942 | 2026-08-04 11:59:51.516817 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp-api | 2026-08-04 11:59:51.516845 | undercloud-client | | port_security_enabled | True | 2026-08-04 11:59:51.516873 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 11:59:51.516901 | undercloud-client | | provider:network_type | None | 2026-08-04 11:59:51.516928 | undercloud-client | | provider:physical_network | None | 2026-08-04 11:59:51.516955 | undercloud-client | | provider:segmentation_id | None | 2026-08-04 11:59:51.516983 | undercloud-client | | pvlan | None | 2026-08-04 11:59:51.517016 | undercloud-client | | qinq | False | 2026-08-04 11:59:51.517045 | undercloud-client | | qos_policy_id | None | 2026-08-04 11:59:51.517084 | undercloud-client | | revision_number | 1 | 2026-08-04 11:59:51.517116 | undercloud-client | | router:external | Internal | 2026-08-04 11:59:51.517143 | undercloud-client | | segments | None | 2026-08-04 11:59:51.517170 | undercloud-client | | shared | False | 2026-08-04 11:59:51.517200 | undercloud-client | | status | ACTIVE | 2026-08-04 11:59:51.517226 | undercloud-client | | subnets | | 2026-08-04 11:59:51.517250 | undercloud-client | | tags | | 2026-08-04 11:59:51.517273 | undercloud-client | | updated_at | 2026-08-04T11:59:50Z | 2026-08-04 11:59:51.517296 | undercloud-client | +---------------------------+-----------------------------------------------------+ 2026-08-04 11:59:51.517344 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp-api Runtime: 0:00:01.879199 2026-08-04 11:59:53.538687 | undercloud-client | +---------------------------+--------------------------------------------------+ 2026-08-04 11:59:53.538792 | undercloud-client | | Field | Value | 2026-08-04 11:59:53.538816 | undercloud-client | +---------------------------+--------------------------------------------------+ 2026-08-04 11:59:53.538836 | undercloud-client | | admin_state_up | UP | 2026-08-04 11:59:53.538853 | undercloud-client | | availability_zone_hints | lab | 2026-08-04 11:59:53.538872 | undercloud-client | | availability_zones | | 2026-08-04 11:59:53.538888 | undercloud-client | | created_at | 2026-08-04T11:59:52Z | 2026-08-04 11:59:53.538905 | undercloud-client | | description | | 2026-08-04 11:59:53.538922 | undercloud-client | | dns_domain | | 2026-08-04 11:59:53.538938 | undercloud-client | | id | d83b3754-ba6b-43e4-b2b1-e09586af1200 | 2026-08-04 11:59:53.538955 | undercloud-client | | ipv4_address_scope | None | 2026-08-04 11:59:53.538971 | undercloud-client | | ipv6_address_scope | None | 2026-08-04 11:59:53.538987 | undercloud-client | | is_default | False | 2026-08-04 11:59:53.539004 | undercloud-client | | is_vlan_qinq | None | 2026-08-04 11:59:53.539019 | undercloud-client | | is_vlan_transparent | False | 2026-08-04 11:59:53.539035 | undercloud-client | | l2_adjacency | True | 2026-08-04 11:59:53.539052 | undercloud-client | | mtu | 8942 | 2026-08-04 11:59:53.539077 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp0 | 2026-08-04 11:59:53.539096 | undercloud-client | | port_security_enabled | True | 2026-08-04 11:59:53.539118 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 11:59:53.539136 | undercloud-client | | provider:network_type | None | 2026-08-04 11:59:53.539153 | undercloud-client | | provider:physical_network | None | 2026-08-04 11:59:53.539170 | undercloud-client | | provider:segmentation_id | None | 2026-08-04 11:59:53.539187 | undercloud-client | | pvlan | None | 2026-08-04 11:59:53.539204 | undercloud-client | | qinq | False | 2026-08-04 11:59:53.539223 | undercloud-client | | qos_policy_id | None | 2026-08-04 11:59:53.539240 | undercloud-client | | revision_number | 1 | 2026-08-04 11:59:53.539257 | undercloud-client | | router:external | Internal | 2026-08-04 11:59:53.539274 | undercloud-client | | segments | None | 2026-08-04 11:59:53.539291 | undercloud-client | | shared | False | 2026-08-04 11:59:53.539307 | undercloud-client | | status | ACTIVE | 2026-08-04 11:59:53.539323 | undercloud-client | | subnets | | 2026-08-04 11:59:53.539339 | undercloud-client | | tags | | 2026-08-04 11:59:53.539356 | undercloud-client | | updated_at | 2026-08-04T11:59:52Z | 2026-08-04 11:59:53.539372 | undercloud-client | +---------------------------+--------------------------------------------------+ 2026-08-04 11:59:53.539400 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp0 Runtime: 0:00:01.884286 2026-08-04 11:59:55.466344 | undercloud-client | +---------------------------+--------------------------------------------------+ 2026-08-04 11:59:55.466478 | undercloud-client | | Field | Value | 2026-08-04 11:59:55.466511 | undercloud-client | +---------------------------+--------------------------------------------------+ 2026-08-04 11:59:55.466561 | undercloud-client | | admin_state_up | UP | 2026-08-04 11:59:55.466587 | undercloud-client | | availability_zone_hints | lab | 2026-08-04 11:59:55.466609 | undercloud-client | | availability_zones | | 2026-08-04 11:59:55.466632 | undercloud-client | | created_at | 2026-08-04T11:59:54Z | 2026-08-04 11:59:55.466653 | undercloud-client | | description | | 2026-08-04 11:59:55.466675 | undercloud-client | | dns_domain | | 2026-08-04 11:59:55.466698 | undercloud-client | | id | 48cc81d8-6664-4375-afe1-c0fb77d472a4 | 2026-08-04 11:59:55.466719 | undercloud-client | | ipv4_address_scope | None | 2026-08-04 11:59:55.466741 | undercloud-client | | ipv6_address_scope | None | 2026-08-04 11:59:55.466763 | undercloud-client | | is_default | False | 2026-08-04 11:59:55.466784 | undercloud-client | | is_vlan_qinq | None | 2026-08-04 11:59:55.466806 | undercloud-client | | is_vlan_transparent | False | 2026-08-04 11:59:55.466835 | undercloud-client | | l2_adjacency | True | 2026-08-04 11:59:55.466860 | undercloud-client | | mtu | 8942 | 2026-08-04 11:59:55.466883 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp1 | 2026-08-04 11:59:55.466905 | undercloud-client | | port_security_enabled | True | 2026-08-04 11:59:55.466931 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 11:59:55.466954 | undercloud-client | | provider:network_type | None | 2026-08-04 11:59:55.466976 | undercloud-client | | provider:physical_network | None | 2026-08-04 11:59:55.466997 | undercloud-client | | provider:segmentation_id | None | 2026-08-04 11:59:55.467019 | undercloud-client | | pvlan | None | 2026-08-04 11:59:55.467041 | undercloud-client | | qinq | False | 2026-08-04 11:59:55.467062 | undercloud-client | | qos_policy_id | None | 2026-08-04 11:59:55.467098 | undercloud-client | | revision_number | 1 | 2026-08-04 11:59:55.467122 | undercloud-client | | router:external | Internal | 2026-08-04 11:59:55.467144 | undercloud-client | | segments | None | 2026-08-04 11:59:55.467165 | undercloud-client | | shared | False | 2026-08-04 11:59:55.467187 | undercloud-client | | status | ACTIVE | 2026-08-04 11:59:55.467208 | undercloud-client | | subnets | | 2026-08-04 11:59:55.467230 | undercloud-client | | tags | | 2026-08-04 11:59:55.467251 | undercloud-client | | updated_at | 2026-08-04T11:59:54Z | 2026-08-04 11:59:55.467273 | undercloud-client | +---------------------------+--------------------------------------------------+ 2026-08-04 11:59:55.467313 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp1 Runtime: 0:00:01.734087 2026-08-04 11:59:55.482653 | 2026-08-04 11:59:55.482789 | LOOP [Create the StorPool Subnets] 2026-08-04 11:59:57.624283 | undercloud-client | +----------------------+----------------------------------------------------+ 2026-08-04 11:59:57.624510 | undercloud-client | | Field | Value | 2026-08-04 11:59:57.624558 | undercloud-client | +----------------------+----------------------------------------------------+ 2026-08-04 11:59:57.624578 | undercloud-client | | allocation_pools | 192.168.10.2-192.168.10.254 | 2026-08-04 11:59:57.624595 | undercloud-client | | cidr | 192.168.10.0/24 | 2026-08-04 11:59:57.624611 | undercloud-client | | created_at | 2026-08-04T11:59:57Z | 2026-08-04 11:59:57.624628 | undercloud-client | | description | | 2026-08-04 11:59:57.624644 | undercloud-client | | dns_nameservers | | 2026-08-04 11:59:57.624660 | undercloud-client | | dns_publish_fixed_ip | False | 2026-08-04 11:59:57.624676 | undercloud-client | | enable_dhcp | True | 2026-08-04 11:59:57.624698 | undercloud-client | | gateway_ip | 192.168.10.1 | 2026-08-04 11:59:57.624723 | undercloud-client | | host_routes | | 2026-08-04 11:59:57.624748 | undercloud-client | | id | ce5ad03d-c1cb-4a8e-b793-3024d2c8ca95 | 2026-08-04 11:59:57.624765 | undercloud-client | | ip_version | 4 | 2026-08-04 11:59:57.624784 | undercloud-client | | ipv6_address_mode | None | 2026-08-04 11:59:57.624800 | undercloud-client | | ipv6_ra_mode | None | 2026-08-04 11:59:57.624816 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-subnet-sp-api | 2026-08-04 11:59:57.624832 | undercloud-client | | network_id | 761f26ec-4e5c-49ee-bd08-6389e5eec9f3 | 2026-08-04 11:59:57.624848 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 11:59:57.624864 | undercloud-client | | revision_number | 0 | 2026-08-04 11:59:57.624879 | undercloud-client | | router:external | False | 2026-08-04 11:59:57.624894 | undercloud-client | | segment_id | None | 2026-08-04 11:59:57.624909 | undercloud-client | | service_types | | 2026-08-04 11:59:57.624924 | undercloud-client | | subnetpool_id | None | 2026-08-04 11:59:57.624939 | undercloud-client | | tags | | 2026-08-04 11:59:57.624954 | undercloud-client | | updated_at | 2026-08-04T11:59:57Z | 2026-08-04 11:59:57.624969 | undercloud-client | +----------------------+----------------------------------------------------+ 2026-08-04 11:59:57.625003 | undercloud-client | ok: Item: Runtime: 0:00:01.857164 2026-08-04 11:59:57.625037 | undercloud-client | changed: All items complete 2026-08-04 11:59:57.625055 | 2026-08-04 11:59:59.759302 | undercloud-client | +----------------------+-------------------------------------------------+ 2026-08-04 11:59:59.759412 | undercloud-client | | Field | Value | 2026-08-04 11:59:59.759437 | undercloud-client | +----------------------+-------------------------------------------------+ 2026-08-04 11:59:59.759456 | undercloud-client | | allocation_pools | 192.168.20.2-192.168.20.254 | 2026-08-04 11:59:59.759474 | undercloud-client | | cidr | 192.168.20.0/24 | 2026-08-04 11:59:59.759491 | undercloud-client | | created_at | 2026-08-04T11:59:59Z | 2026-08-04 11:59:59.759509 | undercloud-client | | description | | 2026-08-04 11:59:59.759655 | undercloud-client | | dns_nameservers | | 2026-08-04 11:59:59.759685 | undercloud-client | | dns_publish_fixed_ip | False | 2026-08-04 11:59:59.759704 | undercloud-client | | enable_dhcp | True | 2026-08-04 11:59:59.759721 | undercloud-client | | gateway_ip | 192.168.20.1 | 2026-08-04 11:59:59.759737 | undercloud-client | | host_routes | | 2026-08-04 11:59:59.759753 | undercloud-client | | id | dcdc067f-2761-4be2-a6a8-51336d070521 | 2026-08-04 11:59:59.759769 | undercloud-client | | ip_version | 4 | 2026-08-04 11:59:59.759790 | undercloud-client | | ipv6_address_mode | None | 2026-08-04 11:59:59.759807 | undercloud-client | | ipv6_ra_mode | None | 2026-08-04 11:59:59.759822 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-subnet-sp0 | 2026-08-04 11:59:59.759838 | undercloud-client | | network_id | d83b3754-ba6b-43e4-b2b1-e09586af1200 | 2026-08-04 11:59:59.759854 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 11:59:59.759869 | undercloud-client | | revision_number | 0 | 2026-08-04 11:59:59.759885 | undercloud-client | | router:external | False | 2026-08-04 11:59:59.759900 | undercloud-client | | segment_id | None | 2026-08-04 11:59:59.759918 | undercloud-client | | service_types | | 2026-08-04 11:59:59.759934 | undercloud-client | | subnetpool_id | None | 2026-08-04 11:59:59.759950 | undercloud-client | | tags | | 2026-08-04 11:59:59.759966 | undercloud-client | | updated_at | 2026-08-04T11:59:59Z | 2026-08-04 11:59:59.759988 | undercloud-client | +----------------------+-------------------------------------------------+ 2026-08-04 11:59:59.760022 | undercloud-client | ok: Item: Runtime: 0:00:01.944261 2026-08-04 12:00:01.920029 | undercloud-client | +----------------------+-------------------------------------------------+ 2026-08-04 12:00:01.920191 | undercloud-client | | Field | Value | 2026-08-04 12:00:01.920229 | undercloud-client | +----------------------+-------------------------------------------------+ 2026-08-04 12:00:01.920257 | undercloud-client | | allocation_pools | 192.168.30.2-192.168.30.254 | 2026-08-04 12:00:01.920285 | undercloud-client | | cidr | 192.168.30.0/24 | 2026-08-04 12:00:01.920312 | undercloud-client | | created_at | 2026-08-04T12:00:01Z | 2026-08-04 12:00:01.920339 | undercloud-client | | description | | 2026-08-04 12:00:01.920366 | undercloud-client | | dns_nameservers | | 2026-08-04 12:00:01.920392 | undercloud-client | | dns_publish_fixed_ip | False | 2026-08-04 12:00:01.920417 | undercloud-client | | enable_dhcp | True | 2026-08-04 12:00:01.920441 | undercloud-client | | gateway_ip | 192.168.30.1 | 2026-08-04 12:00:01.920464 | undercloud-client | | host_routes | | 2026-08-04 12:00:01.920491 | undercloud-client | | id | e5c1cce1-7d37-4a50-8cf8-012b9631514a | 2026-08-04 12:00:01.920518 | undercloud-client | | ip_version | 4 | 2026-08-04 12:00:01.920579 | undercloud-client | | ipv6_address_mode | None | 2026-08-04 12:00:01.920613 | undercloud-client | | ipv6_ra_mode | None | 2026-08-04 12:00:01.920641 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-subnet-sp1 | 2026-08-04 12:00:01.920667 | undercloud-client | | network_id | 48cc81d8-6664-4375-afe1-c0fb77d472a4 | 2026-08-04 12:00:01.920693 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 12:00:01.920732 | undercloud-client | | revision_number | 0 | 2026-08-04 12:00:01.920761 | undercloud-client | | router:external | False | 2026-08-04 12:00:01.920788 | undercloud-client | | segment_id | None | 2026-08-04 12:00:01.920814 | undercloud-client | | service_types | | 2026-08-04 12:00:01.920841 | undercloud-client | | subnetpool_id | None | 2026-08-04 12:00:01.920867 | undercloud-client | | tags | | 2026-08-04 12:00:01.920894 | undercloud-client | | updated_at | 2026-08-04T12:00:01Z | 2026-08-04 12:00:01.920922 | undercloud-client | +----------------------+-------------------------------------------------+ 2026-08-04 12:00:01.920967 | undercloud-client | ok: Item: Runtime: 0:00:01.956574 2026-08-04 12:00:01.935672 | 2026-08-04 12:00:01.935824 | LOOP [Create the StorPool Ports] 2026-08-04 12:00:04.460041 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:04.460320 | undercloud-client | | Field | Value | 2026-08-04 12:00:04.460358 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:04.460388 | undercloud-client | | admin_state_up | UP | 2026-08-04 12:00:04.460412 | undercloud-client | | allowed_address_pairs | | 2026-08-04 12:00:04.460436 | undercloud-client | | binding_host_id | None | 2026-08-04 12:00:04.460458 | undercloud-client | | binding_profile | None | 2026-08-04 12:00:04.460483 | undercloud-client | | binding_vif_details | None | 2026-08-04 12:00:04.460507 | undercloud-client | | binding_vif_type | None | 2026-08-04 12:00:04.460556 | undercloud-client | | binding_vnic_type | normal | 2026-08-04 12:00:04.460583 | undercloud-client | | created_at | 2026-08-04T12:00:03Z | 2026-08-04 12:00:04.460607 | undercloud-client | | data_plane_status | None | 2026-08-04 12:00:04.460631 | undercloud-client | | description | | 2026-08-04 12:00:04.460654 | undercloud-client | | device_id | | 2026-08-04 12:00:04.460677 | undercloud-client | | device_owner | | 2026-08-04 12:00:04.460699 | undercloud-client | | device_profile | None | 2026-08-04 12:00:04.460723 | undercloud-client | | dns_assignment | fqdn='host-192-168-10-5.storpool.local.', hostname='host-192-168-10-5', ip_address='192.168.10.5' | 2026-08-04 12:00:04.460756 | undercloud-client | | dns_domain | | 2026-08-04 12:00:04.460780 | undercloud-client | | dns_name | | 2026-08-04 12:00:04.460802 | undercloud-client | | extra_dhcp_opts | | 2026-08-04 12:00:04.460826 | undercloud-client | | fixed_ips | ip_address='192.168.10.5', subnet_id='ce5ad03d-c1cb-4a8e-b793-3024d2c8ca95' | 2026-08-04 12:00:04.460847 | undercloud-client | | hardware_offload_type | None | 2026-08-04 12:00:04.460869 | undercloud-client | | hints | | 2026-08-04 12:00:04.460891 | undercloud-client | | id | bf1cf2a8-8267-4b20-a6ee-cf364d7b9fa5 | 2026-08-04 12:00:04.460915 | undercloud-client | | ip_allocation | immediate | 2026-08-04 12:00:04.460941 | undercloud-client | | mac_address | fa:16:3e:66:1e:72 | 2026-08-04 12:00:04.460965 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp-api | 2026-08-04 12:00:04.460989 | undercloud-client | | network_id | 761f26ec-4e5c-49ee-bd08-6389e5eec9f3 | 2026-08-04 12:00:04.461010 | undercloud-client | | numa_affinity_policy | None | 2026-08-04 12:00:04.461034 | undercloud-client | | port_security_enabled | False | 2026-08-04 12:00:04.461056 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 12:00:04.461090 | undercloud-client | | propagate_uplink_status | None | 2026-08-04 12:00:04.461116 | undercloud-client | | pvlan_type | None | 2026-08-04 12:00:04.461141 | undercloud-client | | pvlan_community | None | 2026-08-04 12:00:04.461163 | undercloud-client | | resource_request | None | 2026-08-04 12:00:04.461187 | undercloud-client | | revision_number | 1 | 2026-08-04 12:00:04.461211 | undercloud-client | | qos_network_policy_id | None | 2026-08-04 12:00:04.461233 | undercloud-client | | qos_policy_id | None | 2026-08-04 12:00:04.461255 | undercloud-client | | security_group_ids | | 2026-08-04 12:00:04.461277 | undercloud-client | | status | DOWN | 2026-08-04 12:00:04.461302 | undercloud-client | | tags | | 2026-08-04 12:00:04.461326 | undercloud-client | | trunk_details | None | 2026-08-04 12:00:04.461348 | undercloud-client | | trusted | None | 2026-08-04 12:00:04.461372 | undercloud-client | | updated_at | 2026-08-04T12:00:03Z | 2026-08-04 12:00:04.461395 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:04.461447 | undercloud-client | ok: Item: Runtime: 0:00:02.195611 2026-08-04 12:00:06.549289 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:06.549404 | undercloud-client | | Field | Value | 2026-08-04 12:00:06.549427 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:06.549445 | undercloud-client | | admin_state_up | UP | 2026-08-04 12:00:06.549462 | undercloud-client | | allowed_address_pairs | | 2026-08-04 12:00:06.549478 | undercloud-client | | binding_host_id | None | 2026-08-04 12:00:06.549494 | undercloud-client | | binding_profile | None | 2026-08-04 12:00:06.549513 | undercloud-client | | binding_vif_details | None | 2026-08-04 12:00:06.549550 | undercloud-client | | binding_vif_type | None | 2026-08-04 12:00:06.549570 | undercloud-client | | binding_vnic_type | normal | 2026-08-04 12:00:06.549586 | undercloud-client | | created_at | 2026-08-04T12:00:06Z | 2026-08-04 12:00:06.549604 | undercloud-client | | data_plane_status | None | 2026-08-04 12:00:06.549623 | undercloud-client | | description | | 2026-08-04 12:00:06.549638 | undercloud-client | | device_id | | 2026-08-04 12:00:06.549655 | undercloud-client | | device_owner | | 2026-08-04 12:00:06.549673 | undercloud-client | | device_profile | None | 2026-08-04 12:00:06.549696 | undercloud-client | | dns_assignment | fqdn='host-192-168-10-6.storpool.local.', hostname='host-192-168-10-6', ip_address='192.168.10.6' | 2026-08-04 12:00:06.549720 | undercloud-client | | dns_domain | | 2026-08-04 12:00:06.549736 | undercloud-client | | dns_name | | 2026-08-04 12:00:06.549752 | undercloud-client | | extra_dhcp_opts | | 2026-08-04 12:00:06.549768 | undercloud-client | | fixed_ips | ip_address='192.168.10.6', subnet_id='ce5ad03d-c1cb-4a8e-b793-3024d2c8ca95' | 2026-08-04 12:00:06.549784 | undercloud-client | | hardware_offload_type | None | 2026-08-04 12:00:06.549799 | undercloud-client | | hints | | 2026-08-04 12:00:06.549814 | undercloud-client | | id | e43f4a8f-7a96-4ec3-b159-c87bc5e71061 | 2026-08-04 12:00:06.549830 | undercloud-client | | ip_allocation | immediate | 2026-08-04 12:00:06.549845 | undercloud-client | | mac_address | fa:16:3e:a7:2b:99 | 2026-08-04 12:00:06.549861 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp-api | 2026-08-04 12:00:06.549876 | undercloud-client | | network_id | 761f26ec-4e5c-49ee-bd08-6389e5eec9f3 | 2026-08-04 12:00:06.549891 | undercloud-client | | numa_affinity_policy | None | 2026-08-04 12:00:06.549909 | undercloud-client | | port_security_enabled | False | 2026-08-04 12:00:06.549934 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 12:00:06.549957 | undercloud-client | | propagate_uplink_status | None | 2026-08-04 12:00:06.549973 | undercloud-client | | pvlan_type | None | 2026-08-04 12:00:06.549989 | undercloud-client | | pvlan_community | None | 2026-08-04 12:00:06.550005 | undercloud-client | | resource_request | None | 2026-08-04 12:00:06.550020 | undercloud-client | | revision_number | 1 | 2026-08-04 12:00:06.550035 | undercloud-client | | qos_network_policy_id | None | 2026-08-04 12:00:06.550050 | undercloud-client | | qos_policy_id | None | 2026-08-04 12:00:06.550066 | undercloud-client | | security_group_ids | | 2026-08-04 12:00:06.550096 | undercloud-client | | status | DOWN | 2026-08-04 12:00:06.550112 | undercloud-client | | tags | | 2026-08-04 12:00:06.550132 | undercloud-client | | trunk_details | None | 2026-08-04 12:00:06.550148 | undercloud-client | | trusted | None | 2026-08-04 12:00:06.550164 | undercloud-client | | updated_at | 2026-08-04T12:00:06Z | 2026-08-04 12:00:06.550179 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:06.550206 | undercloud-client | ok: Item: Runtime: 0:00:01.904529 2026-08-04 12:00:08.733094 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:08.733232 | undercloud-client | | Field | Value | 2026-08-04 12:00:08.733259 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:08.733277 | undercloud-client | | admin_state_up | UP | 2026-08-04 12:00:08.733294 | undercloud-client | | allowed_address_pairs | | 2026-08-04 12:00:08.733311 | undercloud-client | | binding_host_id | None | 2026-08-04 12:00:08.733326 | undercloud-client | | binding_profile | None | 2026-08-04 12:00:08.733342 | undercloud-client | | binding_vif_details | None | 2026-08-04 12:00:08.733358 | undercloud-client | | binding_vif_type | None | 2026-08-04 12:00:08.733374 | undercloud-client | | binding_vnic_type | normal | 2026-08-04 12:00:08.733397 | undercloud-client | | created_at | 2026-08-04T12:00:08Z | 2026-08-04 12:00:08.733420 | undercloud-client | | data_plane_status | None | 2026-08-04 12:00:08.733443 | undercloud-client | | description | | 2026-08-04 12:00:08.733465 | undercloud-client | | device_id | | 2026-08-04 12:00:08.733482 | undercloud-client | | device_owner | | 2026-08-04 12:00:08.733498 | undercloud-client | | device_profile | None | 2026-08-04 12:00:08.733513 | undercloud-client | | dns_assignment | fqdn='host-192-168-20-5.storpool.local.', hostname='host-192-168-20-5', ip_address='192.168.20.5' | 2026-08-04 12:00:08.733549 | undercloud-client | | dns_domain | | 2026-08-04 12:00:08.733574 | undercloud-client | | dns_name | | 2026-08-04 12:00:08.733590 | undercloud-client | | extra_dhcp_opts | | 2026-08-04 12:00:08.733606 | undercloud-client | | fixed_ips | ip_address='192.168.20.5', subnet_id='dcdc067f-2761-4be2-a6a8-51336d070521' | 2026-08-04 12:00:08.733621 | undercloud-client | | hardware_offload_type | None | 2026-08-04 12:00:08.733637 | undercloud-client | | hints | | 2026-08-04 12:00:08.733652 | undercloud-client | | id | e97adaec-5449-46f5-95f2-680969e3582c | 2026-08-04 12:00:08.733667 | undercloud-client | | ip_allocation | immediate | 2026-08-04 12:00:08.733682 | undercloud-client | | mac_address | fa:16:3e:08:b9:61 | 2026-08-04 12:00:08.733698 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp0 | 2026-08-04 12:00:08.733713 | undercloud-client | | network_id | d83b3754-ba6b-43e4-b2b1-e09586af1200 | 2026-08-04 12:00:08.733728 | undercloud-client | | numa_affinity_policy | None | 2026-08-04 12:00:08.733742 | undercloud-client | | port_security_enabled | False | 2026-08-04 12:00:08.733758 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 12:00:08.733773 | undercloud-client | | propagate_uplink_status | None | 2026-08-04 12:00:08.733788 | undercloud-client | | pvlan_type | None | 2026-08-04 12:00:08.733803 | undercloud-client | | pvlan_community | None | 2026-08-04 12:00:08.733819 | undercloud-client | | resource_request | None | 2026-08-04 12:00:08.733835 | undercloud-client | | revision_number | 1 | 2026-08-04 12:00:08.733851 | undercloud-client | | qos_network_policy_id | None | 2026-08-04 12:00:08.733868 | undercloud-client | | qos_policy_id | None | 2026-08-04 12:00:08.733884 | undercloud-client | | security_group_ids | | 2026-08-04 12:00:08.733901 | undercloud-client | | status | DOWN | 2026-08-04 12:00:08.733917 | undercloud-client | | tags | | 2026-08-04 12:00:08.733937 | undercloud-client | | trunk_details | None | 2026-08-04 12:00:08.733954 | undercloud-client | | trusted | None | 2026-08-04 12:00:08.734024 | undercloud-client | | updated_at | 2026-08-04T12:00:08Z | 2026-08-04 12:00:08.734043 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:08.734083 | undercloud-client | ok: Item: Runtime: 0:00:01.999603 2026-08-04 12:00:10.794603 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:10.794707 | undercloud-client | | Field | Value | 2026-08-04 12:00:10.794731 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:10.794750 | undercloud-client | | admin_state_up | UP | 2026-08-04 12:00:10.794768 | undercloud-client | | allowed_address_pairs | | 2026-08-04 12:00:10.794785 | undercloud-client | | binding_host_id | None | 2026-08-04 12:00:10.794802 | undercloud-client | | binding_profile | None | 2026-08-04 12:00:10.794819 | undercloud-client | | binding_vif_details | None | 2026-08-04 12:00:10.794835 | undercloud-client | | binding_vif_type | None | 2026-08-04 12:00:10.794851 | undercloud-client | | binding_vnic_type | normal | 2026-08-04 12:00:10.794867 | undercloud-client | | created_at | 2026-08-04T12:00:10Z | 2026-08-04 12:00:10.794882 | undercloud-client | | data_plane_status | None | 2026-08-04 12:00:10.794898 | undercloud-client | | description | | 2026-08-04 12:00:10.794913 | undercloud-client | | device_id | | 2026-08-04 12:00:10.794929 | undercloud-client | | device_owner | | 2026-08-04 12:00:10.794946 | undercloud-client | | device_profile | None | 2026-08-04 12:00:10.794961 | undercloud-client | | dns_assignment | fqdn='host-192-168-30-5.storpool.local.', hostname='host-192-168-30-5', ip_address='192.168.30.5' | 2026-08-04 12:00:10.794978 | undercloud-client | | dns_domain | | 2026-08-04 12:00:10.794995 | undercloud-client | | dns_name | | 2026-08-04 12:00:10.795016 | undercloud-client | | extra_dhcp_opts | | 2026-08-04 12:00:10.795034 | undercloud-client | | fixed_ips | ip_address='192.168.30.5', subnet_id='e5c1cce1-7d37-4a50-8cf8-012b9631514a' | 2026-08-04 12:00:10.795050 | undercloud-client | | hardware_offload_type | None | 2026-08-04 12:00:10.795067 | undercloud-client | | hints | | 2026-08-04 12:00:10.795119 | undercloud-client | | id | 8efc915c-8887-435f-9707-49795bedac81 | 2026-08-04 12:00:10.795147 | undercloud-client | | ip_allocation | immediate | 2026-08-04 12:00:10.795171 | undercloud-client | | mac_address | fa:16:3e:4c:13:20 | 2026-08-04 12:00:10.795190 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp1 | 2026-08-04 12:00:10.795206 | undercloud-client | | network_id | 48cc81d8-6664-4375-afe1-c0fb77d472a4 | 2026-08-04 12:00:10.795224 | undercloud-client | | numa_affinity_policy | None | 2026-08-04 12:00:10.795243 | undercloud-client | | port_security_enabled | False | 2026-08-04 12:00:10.795260 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 12:00:10.795277 | undercloud-client | | propagate_uplink_status | None | 2026-08-04 12:00:10.795294 | undercloud-client | | pvlan_type | None | 2026-08-04 12:00:10.795314 | undercloud-client | | pvlan_community | None | 2026-08-04 12:00:10.795330 | undercloud-client | | resource_request | None | 2026-08-04 12:00:10.795346 | undercloud-client | | revision_number | 1 | 2026-08-04 12:00:10.795363 | undercloud-client | | qos_network_policy_id | None | 2026-08-04 12:00:10.795383 | undercloud-client | | qos_policy_id | None | 2026-08-04 12:00:10.795400 | undercloud-client | | security_group_ids | | 2026-08-04 12:00:10.795416 | undercloud-client | | status | DOWN | 2026-08-04 12:00:10.795461 | undercloud-client | | tags | | 2026-08-04 12:00:10.795482 | undercloud-client | | trunk_details | None | 2026-08-04 12:00:10.795505 | undercloud-client | | trusted | None | 2026-08-04 12:00:10.795606 | undercloud-client | | updated_at | 2026-08-04T12:00:10Z | 2026-08-04 12:00:10.795637 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:10.795674 | undercloud-client | ok: Item: Runtime: 0:00:01.876605 2026-08-04 12:00:10.815223 | 2026-08-04 12:00:10.815357 | LOOP [Create the StorPool Ports for the Block Client OpenStack Node] 2026-08-04 12:00:12.863138 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:12.863584 | undercloud-client | | Field | Value | 2026-08-04 12:00:12.863637 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:12.863669 | undercloud-client | | admin_state_up | UP | 2026-08-04 12:00:12.863697 | undercloud-client | | allowed_address_pairs | | 2026-08-04 12:00:12.863723 | undercloud-client | | binding_host_id | None | 2026-08-04 12:00:12.863751 | undercloud-client | | binding_profile | None | 2026-08-04 12:00:12.863778 | undercloud-client | | binding_vif_details | None | 2026-08-04 12:00:12.863804 | undercloud-client | | binding_vif_type | None | 2026-08-04 12:00:12.863831 | undercloud-client | | binding_vnic_type | normal | 2026-08-04 12:00:12.863857 | undercloud-client | | created_at | 2026-08-04T12:00:12Z | 2026-08-04 12:00:12.863884 | undercloud-client | | data_plane_status | None | 2026-08-04 12:00:12.863915 | undercloud-client | | description | | 2026-08-04 12:00:12.863942 | undercloud-client | | device_id | | 2026-08-04 12:00:12.863968 | undercloud-client | | device_owner | | 2026-08-04 12:00:12.863993 | undercloud-client | | device_profile | None | 2026-08-04 12:00:12.864020 | undercloud-client | | dns_assignment | fqdn='host-192-168-20-6.storpool.local.', hostname='host-192-168-20-6', ip_address='192.168.20.6' | 2026-08-04 12:00:12.864046 | undercloud-client | | dns_domain | | 2026-08-04 12:00:12.864096 | undercloud-client | | dns_name | | 2026-08-04 12:00:12.864132 | undercloud-client | | extra_dhcp_opts | | 2026-08-04 12:00:12.864159 | undercloud-client | | fixed_ips | ip_address='192.168.20.6', subnet_id='dcdc067f-2761-4be2-a6a8-51336d070521' | 2026-08-04 12:00:12.864185 | undercloud-client | | hardware_offload_type | None | 2026-08-04 12:00:12.864210 | undercloud-client | | hints | | 2026-08-04 12:00:12.864236 | undercloud-client | | id | c787ebbe-803f-4dcc-9ac9-bb06567ec7a2 | 2026-08-04 12:00:12.864261 | undercloud-client | | ip_allocation | immediate | 2026-08-04 12:00:12.864286 | undercloud-client | | mac_address | fa:16:3e:d9:28:08 | 2026-08-04 12:00:12.864312 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp0 | 2026-08-04 12:00:12.864338 | undercloud-client | | network_id | d83b3754-ba6b-43e4-b2b1-e09586af1200 | 2026-08-04 12:00:12.864365 | undercloud-client | | numa_affinity_policy | None | 2026-08-04 12:00:12.864392 | undercloud-client | | port_security_enabled | False | 2026-08-04 12:00:12.864417 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 12:00:12.864443 | undercloud-client | | propagate_uplink_status | None | 2026-08-04 12:00:12.864468 | undercloud-client | | pvlan_type | None | 2026-08-04 12:00:12.864493 | undercloud-client | | pvlan_community | None | 2026-08-04 12:00:12.864518 | undercloud-client | | resource_request | None | 2026-08-04 12:00:12.864572 | undercloud-client | | revision_number | 1 | 2026-08-04 12:00:12.864601 | undercloud-client | | qos_network_policy_id | None | 2026-08-04 12:00:12.864626 | undercloud-client | | qos_policy_id | None | 2026-08-04 12:00:12.864652 | undercloud-client | | security_group_ids | | 2026-08-04 12:00:12.864678 | undercloud-client | | status | DOWN | 2026-08-04 12:00:12.864704 | undercloud-client | | tags | | 2026-08-04 12:00:12.864730 | undercloud-client | | trunk_details | None | 2026-08-04 12:00:12.864764 | undercloud-client | | trusted | None | 2026-08-04 12:00:12.864790 | undercloud-client | | updated_at | 2026-08-04T12:00:12Z | 2026-08-04 12:00:12.864815 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:12.864885 | undercloud-client | ok: Item: Runtime: 0:00:01.778100 2026-08-04 12:00:12.864935 | undercloud-client | changed: All items complete 2026-08-04 12:00:12.864964 | 2026-08-04 12:00:14.867708 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:14.867884 | undercloud-client | | Field | Value | 2026-08-04 12:00:14.867918 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:14.867941 | undercloud-client | | admin_state_up | UP | 2026-08-04 12:00:14.867961 | undercloud-client | | allowed_address_pairs | | 2026-08-04 12:00:14.867981 | undercloud-client | | binding_host_id | None | 2026-08-04 12:00:14.868001 | undercloud-client | | binding_profile | None | 2026-08-04 12:00:14.868021 | undercloud-client | | binding_vif_details | None | 2026-08-04 12:00:14.868041 | undercloud-client | | binding_vif_type | None | 2026-08-04 12:00:14.868060 | undercloud-client | | binding_vnic_type | normal | 2026-08-04 12:00:14.868105 | undercloud-client | | created_at | 2026-08-04T12:00:14Z | 2026-08-04 12:00:14.868126 | undercloud-client | | data_plane_status | None | 2026-08-04 12:00:14.868146 | undercloud-client | | description | | 2026-08-04 12:00:14.868165 | undercloud-client | | device_id | | 2026-08-04 12:00:14.868183 | undercloud-client | | device_owner | | 2026-08-04 12:00:14.868203 | undercloud-client | | device_profile | None | 2026-08-04 12:00:14.868222 | undercloud-client | | dns_assignment | fqdn='host-192-168-30-6.storpool.local.', hostname='host-192-168-30-6', ip_address='192.168.30.6' | 2026-08-04 12:00:14.868241 | undercloud-client | | dns_domain | | 2026-08-04 12:00:14.868260 | undercloud-client | | dns_name | | 2026-08-04 12:00:14.868355 | undercloud-client | | extra_dhcp_opts | | 2026-08-04 12:00:14.868378 | undercloud-client | | fixed_ips | ip_address='192.168.30.6', subnet_id='e5c1cce1-7d37-4a50-8cf8-012b9631514a' | 2026-08-04 12:00:14.868395 | undercloud-client | | hardware_offload_type | None | 2026-08-04 12:00:14.868410 | undercloud-client | | hints | | 2026-08-04 12:00:14.868426 | undercloud-client | | id | 58031b51-76c8-4d59-a3e9-c60a3ba707bd | 2026-08-04 12:00:14.868441 | undercloud-client | | ip_allocation | immediate | 2026-08-04 12:00:14.868456 | undercloud-client | | mac_address | fa:16:3e:22:6e:68 | 2026-08-04 12:00:14.868472 | undercloud-client | | name | a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp1 | 2026-08-04 12:00:14.868487 | undercloud-client | | network_id | 48cc81d8-6664-4375-afe1-c0fb77d472a4 | 2026-08-04 12:00:14.868502 | undercloud-client | | numa_affinity_policy | None | 2026-08-04 12:00:14.868518 | undercloud-client | | port_security_enabled | False | 2026-08-04 12:00:14.868564 | undercloud-client | | project_id | d6d455de68894255adf7ce9496d72ce9 | 2026-08-04 12:00:14.868581 | undercloud-client | | propagate_uplink_status | None | 2026-08-04 12:00:14.868598 | undercloud-client | | pvlan_type | None | 2026-08-04 12:00:14.868614 | undercloud-client | | pvlan_community | None | 2026-08-04 12:00:14.868630 | undercloud-client | | resource_request | None | 2026-08-04 12:00:14.868646 | undercloud-client | | revision_number | 1 | 2026-08-04 12:00:14.868661 | undercloud-client | | qos_network_policy_id | None | 2026-08-04 12:00:14.868677 | undercloud-client | | qos_policy_id | None | 2026-08-04 12:00:14.868698 | undercloud-client | | security_group_ids | | 2026-08-04 12:00:14.868724 | undercloud-client | | status | DOWN | 2026-08-04 12:00:14.868748 | undercloud-client | | tags | | 2026-08-04 12:00:14.868765 | undercloud-client | | trunk_details | None | 2026-08-04 12:00:14.868782 | undercloud-client | | trusted | None | 2026-08-04 12:00:14.868803 | undercloud-client | | updated_at | 2026-08-04T12:00:14Z | 2026-08-04 12:00:14.868819 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+ 2026-08-04 12:00:14.868847 | undercloud-client | ok: Item: Runtime: 0:00:01.827203 2026-08-04 12:00:14.889491 | 2026-08-04 12:00:14.889678 | LOOP [Attach the StorPool Ports to the StorPool Node] 2026-08-04 12:01:16.605197 | undercloud-client | HttpException: 502: Server Error for url: http://os-controller.lab.storpool.local:8774/v2.1/servers/ba39cd9c-23c7-49b3-a259-88bdf33398cc/os-interface, 502 Bad Gateway: The server returned an invalid or incomplete response. 2026-08-04 12:01:16.605609 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp-api Result: 1 2026-08-04 12:01:28.443303 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp0 Runtime: 0:00:11.662013 2026-08-04 12:01:33.875240 | undercloud-client | HttpException: 500: Server Error for url: http://os-controller.lab.storpool.local:8774/v2.1/servers/ba39cd9c-23c7-49b3-a259-88bdf33398cc/os-interface, Unexpected API Error. Please report this at http://bugs.launchpad.net/nova/ and attach the Nova API log if possible. 2026-08-04 12:01:33.875354 | undercloud-client | 2026-08-04 12:01:33.875387 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp1 Result: 1 failure 2026-08-04 12:01:33.895580 | 2026-08-04 12:01:33.895653 | PLAY RECAP 2026-08-04 12:01:33.895700 | undercloud-client | ok: 4 changed: 4 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-08-04 12:01:33.895727 | 2026-08-04 12:01:34.045255 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-storpool/pre.yaml@master] 2026-08-04 12:01:34.056283 | POST-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-storpool/post.yaml@master] 2026-08-04 12:01:34.804366 | 2026-08-04 12:01:34.804569 | PLAY [undercloud-client] 2026-08-04 12:01:34.832545 | 2026-08-04 12:01:34.832691 | LOOP [Remove the StorPool Ports from the StorPool Node] 2026-08-04 12:01:37.643805 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp-api Runtime: 0:00:01.697733 2026-08-04 12:01:39.475327 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp0 Runtime: 0:00:01.654945 2026-08-04 12:01:41.286377 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp1 Runtime: 0:00:01.624920 2026-08-04 12:01:41.305406 | 2026-08-04 12:01:41.305649 | TASK [Remove the StorPool Ports from the OpenStack Node] 2026-08-04 12:01:43.390387 | undercloud-client | ok: Runtime: 0:00:01.630076 2026-08-04 12:01:43.399408 | 2026-08-04 12:01:43.399557 | LOOP [Remove the StorPool Ports from the Block Client OpenStack Node] 2026-08-04 12:01:45.210061 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp-api Runtime: 0:00:01.559695 2026-08-04 12:01:45.210440 | undercloud-client | changed: All items complete 2026-08-04 12:01:45.210474 | 2026-08-04 12:01:46.980812 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp0 Runtime: 0:00:01.588196 2026-08-04 12:01:48.846825 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp1 Runtime: 0:00:01.629528 2026-08-04 12:01:48.858010 | 2026-08-04 12:01:48.858143 | LOOP [Delete the StorPool Ports] 2026-08-04 12:02:50.421518 | undercloud-client | Failed to delete port with name or ID 'a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp-api': HttpException: 502: Server Error for url: http://os-controller.lab.storpool.local:9696/v2.0/ports/bf1cf2a8-8267-4b20-a6ee-cf364d7b9fa5, 502 Bad Gateway: The server returned an invalid or incomplete response. 2026-08-04 12:02:50.421823 | undercloud-client | 1 of 1 ports failed to delete. 2026-08-04 12:02:50.421886 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp-api Result: 1 2026-08-04 12:02:52.421283 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp-api Runtime: 0:00:01.678249 2026-08-04 12:02:54.329378 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp0 Runtime: 0:00:01.638544 2026-08-04 12:02:56.263116 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-1-sp1 Runtime: 0:00:01.648436 2026-08-04 12:02:56.282924 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 12:02:56.290175 | 2026-08-04 12:02:56.290267 | LOOP [Delete the StorPool Block Ports] 2026-08-04 12:02:58.177135 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp0 Runtime: 0:00:01.621386 2026-08-04 12:02:59.979714 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-port-node-2-sp1 Runtime: 0:00:01.611940 2026-08-04 12:02:59.987599 | 2026-08-04 12:02:59.987745 | LOOP [Delete the StorPool Networks] 2026-08-04 12:03:01.739664 | undercloud-client | Failed to delete network with name or ID 'a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp-api': ConflictException: 409: Client Error for url: http://os-controller.lab.storpool.local:9696/v2.0/networks/761f26ec-4e5c-49ee-bd08-6389e5eec9f3, Unable to complete operation on network 761f26ec-4e5c-49ee-bd08-6389e5eec9f3. There are one or more ports still in use on the network, id for these ports is: bf1cf2a8-8267-4b20-a6ee-cf364d7b9fa5. 2026-08-04 12:03:01.739976 | undercloud-client | 1 of 1 networks failed to delete. 2026-08-04 12:03:01.740045 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp-api Result: 1 2026-08-04 12:03:01.740108 | undercloud-client | changed: All items complete 2026-08-04 12:03:01.740140 | 2026-08-04 12:03:04.109166 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp0 Runtime: 0:00:02.056225 2026-08-04 12:03:06.299561 | undercloud-client | ok: Item: a8b26d36-5196-46f5-9e1b-6af4b8909fbf-network-sp1 Runtime: 0:00:02.006760 2026-08-04 12:03:06.317450 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 12:03:06.324353 | 2026-08-04 12:03:06.324492 | TASK [Remove the StorPool Volume to the StorPool Node] 2026-08-04 12:03:08.199437 | undercloud-client | No Volume found for a8b26d36-5196-46f5-9e1b-6af4b8909fbf-storpool-volume 2026-08-04 12:03:28.835870 | undercloud-client | ERROR 2026-08-04 12:03:28.836111 | undercloud-client | { 2026-08-04 12:03:28.836144 | undercloud-client | "attempts": 3, 2026-08-04 12:03:28.836164 | undercloud-client | "delta": "0:00:01.610302", 2026-08-04 12:03:28.836181 | undercloud-client | "end": "2026-08-04 12:03:28.792569", 2026-08-04 12:03:28.836198 | undercloud-client | "msg": "non-zero return code", 2026-08-04 12:03:28.836214 | undercloud-client | "rc": 1, 2026-08-04 12:03:28.836229 | undercloud-client | "start": "2026-08-04 12:03:27.182267" 2026-08-04 12:03:28.836245 | undercloud-client | } 2026-08-04 12:03:28.836266 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 12:03:28.844205 | 2026-08-04 12:03:28.844379 | TASK [Delete the StorPool Volume] 2026-08-04 12:03:30.378552 | undercloud-client | Failed to delete volume with name or ID 'a8b26d36-5196-46f5-9e1b-6af4b8909fbf-storpool-volume': No Volume found for a8b26d36-5196-46f5-9e1b-6af4b8909fbf-storpool-volume 2026-08-04 12:03:30.379244 | undercloud-client | 1 of 1 volumes failed to delete. 2026-08-04 12:03:50.428487 | undercloud-client | ERROR 2026-08-04 12:03:50.428726 | undercloud-client | { 2026-08-04 12:03:50.428770 | undercloud-client | "attempts": 3, 2026-08-04 12:03:50.428798 | undercloud-client | "delta": "0:00:01.373642", 2026-08-04 12:03:50.428823 | undercloud-client | "end": "2026-08-04 12:03:50.394296", 2026-08-04 12:03:50.428858 | undercloud-client | "msg": "non-zero return code", 2026-08-04 12:03:50.428884 | undercloud-client | "rc": 1, 2026-08-04 12:03:50.428911 | undercloud-client | "start": "2026-08-04 12:03:49.020654" 2026-08-04 12:03:50.428942 | undercloud-client | } 2026-08-04 12:03:50.428978 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 12:03:50.430822 | 2026-08-04 12:03:50.430892 | PLAY RECAP 2026-08-04 12:03:50.430943 | undercloud-client | ok: 8 changed: 8 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 4 2026-08-04 12:03:50.430963 | 2026-08-04 12:03:50.589768 | POST-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-storpool/post.yaml@master] 2026-08-04 12:03:50.600219 | POST-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-client/post.yaml@master] 2026-08-04 12:03:51.455555 | 2026-08-04 12:03:51.455701 | PLAY [undercloud-client] 2026-08-04 12:03:51.470967 | 2026-08-04 12:03:51.471117 | TASK [Deprovision OpenStack Credentials] 2026-08-04 12:03:52.396297 | undercloud-client | changed 2026-08-04 12:03:52.397795 | 2026-08-04 12:03:52.397854 | PLAY RECAP 2026-08-04 12:03:52.397907 | undercloud-client | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-08-04 12:03:52.397958 | 2026-08-04 12:03:52.546545 | POST-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-client/post.yaml@master] 2026-08-04 12:03:52.557744 | POST-RUN START: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master] 2026-08-04 12:03:53.360915 | 2026-08-04 12:03:53.361056 | PLAY [tempest] 2026-08-04 12:03:53.376242 | 2026-08-04 12:03:53.376358 | TASK [fetch-subunit-output : Find stestr or testr executable] 2026-08-04 12:03:54.152042 | controller | changed: non-zero return code 2026-08-04 12:03:54.160976 | 2026-08-04 12:03:54.161193 | TASK [fetch-subunit-output : Get the list of directories with subunit files] 2026-08-04 12:03:54.177023 | controller | skipping: Conditional result was False 2026-08-04 12:03:54.187574 | 2026-08-04 12:03:54.187723 | LOOP [fetch-subunit-output : Find any inflight partial subunit files] 2026-08-04 12:03:54.223803 | 2026-08-04 12:03:54.224056 | LOOP [fetch-subunit-output : Copy any inflight subunit files] 2026-08-04 12:03:54.246966 | 2026-08-04 12:03:54.247181 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream] 2026-08-04 12:03:54.272113 | controller | skipping: Conditional result was False 2026-08-04 12:03:54.278342 | 2026-08-04 12:03:54.278440 | LOOP [fetch-subunit-output : Generate subunit file] 2026-08-04 12:03:54.300471 | 2026-08-04 12:03:54.300697 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory] 2026-08-04 12:03:54.326059 | controller | skipping: Conditional result was False 2026-08-04 12:03:54.333702 | 2026-08-04 12:03:54.333843 | TASK [fetch-subunit-output : Remove the temporary file] 2026-08-04 12:03:54.348793 | controller | skipping: Conditional result was False 2026-08-04 12:03:54.356666 | 2026-08-04 12:03:54.356792 | TASK [fetch-subunit-output : Process and fetch subunit results] 2026-08-04 12:03:54.371981 | controller | skipping: Conditional result was False 2026-08-04 12:03:54.383416 | 2026-08-04 12:03:54.383580 | TASK [process-stackviz : Devstack checks if stackviz archive exists] 2026-08-04 12:03:54.750129 | controller | ok 2026-08-04 12:03:54.792237 | 2026-08-04 12:03:54.792363 | TASK [process-stackviz : debug] 2026-08-04 12:03:54.821978 | Stackviz archive could not be found in /opt/cache/files/stackviz-latest.tar.gz 2026-08-04 12:03:54.827749 | 2026-08-04 12:03:54.827884 | TASK [process-stackviz : Check if subunit data exists] 2026-08-04 12:03:55.049907 | controller | ok 2026-08-04 12:03:55.055672 | 2026-08-04 12:03:55.055754 | TASK [process-stackviz : debug] 2026-08-04 12:03:55.087591 | Subunit file could not be found at /opt/stack/tempest/testrepository.subunit 2026-08-04 12:03:55.095073 | 2026-08-04 12:03:55.095218 | TASK [include_role : ensure-pip] 2026-08-04 12:03:55.110355 | controller | skipping: Conditional result was False 2026-08-04 12:03:55.116679 | 2026-08-04 12:03:55.116788 | TASK [process-stackviz : pip] 2026-08-04 12:03:55.141194 | controller | skipping: Conditional result was False 2026-08-04 12:03:55.148662 | 2026-08-04 12:03:55.148774 | TASK [process-stackviz : Deploy stackviz static html+js] 2026-08-04 12:03:55.689422 | controller | skipping: Conditional result was False 2026-08-04 12:03:55.695962 | 2026-08-04 12:03:55.696106 | TASK [process-stackviz : Check if dstat data exists] 2026-08-04 12:03:55.711135 | controller | skipping: Conditional result was False 2026-08-04 12:03:55.717318 | 2026-08-04 12:03:55.717419 | TASK [process-stackviz : Run stackviz with dstat] 2026-08-04 12:03:56.245390 | controller | skipping: Conditional result was False 2026-08-04 12:03:56.255172 | 2026-08-04 12:03:56.255341 | TASK [process-stackviz : Run stackviz without dstat] 2026-08-04 12:03:56.797389 | controller | skipping: Conditional result was False 2026-08-04 12:03:56.804940 | 2026-08-04 12:03:56.805158 | PLAY RECAP 2026-08-04 12:03:56.805211 | controller | ok: 5 changed: 1 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0 2026-08-04 12:03:56.805233 | 2026-08-04 12:03:56.932250 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master] 2026-08-04 12:03:56.943648 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master] 2026-08-04 12:03:57.721237 | 2026-08-04 12:03:57.721370 | PLAY [all] 2026-08-04 12:03:57.741853 | 2026-08-04 12:03:57.742009 | TASK [export-devstack-journal : Ensure /home/ubuntu/logs exists] 2026-08-04 12:03:58.405875 | undercloud-client | changed 2026-08-04 12:03:58.410935 | controller | changed 2026-08-04 12:03:58.710042 | lab-sp-a1 | changed 2026-08-04 12:03:58.716267 | 2026-08-04 12:03:58.716372 | TASK [export-devstack-journal : Export legacy stack screen log files] 2026-08-04 12:04:00.319703 | lab-sp-a1 | ok: Runtime: 0:00:00.752062 2026-08-04 12:04:00.331180 | controller | ok: Runtime: 0:00:00.753503 2026-08-04 12:04:00.341145 | undercloud-client | ok: Runtime: 0:00:00.786659 2026-08-04 12:04:00.347454 | 2026-08-04 12:04:00.347641 | TASK [export-devstack-journal : Export legacy syslog.txt] 2026-08-04 12:04:01.487598 | controller | ok: Runtime: 0:00:00.011221 2026-08-04 12:04:01.495918 | lab-sp-a1 | ok: Runtime: 0:00:00.011003 2026-08-04 12:04:01.504615 | undercloud-client | ok: Runtime: 0:00:00.012746 2026-08-04 12:04:01.509411 | 2026-08-04 12:04:01.509481 | TASK [export-devstack-journal : Export journal] 2026-08-04 12:04:02.119663 | controller | ok: Runtime: 0:00:00.014067 2026-08-04 12:04:02.129002 | lab-sp-a1 | ok: Runtime: 0:00:00.013274 2026-08-04 12:04:02.137355 | undercloud-client | ok: Runtime: 0:00:00.022233 2026-08-04 12:04:02.143546 | 2026-08-04 12:04:02.143619 | TASK [export-devstack-journal : Save journal README] 2026-08-04 12:04:02.860249 | lab-sp-a1 | changed 2026-08-04 12:04:02.868147 | undercloud-client | changed 2026-08-04 12:04:02.876625 | controller | changed 2026-08-04 12:04:02.906412 | 2026-08-04 12:04:02.906622 | TASK [apache-logs-conf : Ensure /home/ubuntu/apache exists] 2026-08-04 12:04:03.124064 | controller | changed 2026-08-04 12:04:03.152233 | lab-sp-a1 | changed 2026-08-04 12:04:03.159874 | undercloud-client | changed 2026-08-04 12:04:03.167641 | 2026-08-04 12:04:03.167803 | TASK [apache-logs-conf : Find logs] 2026-08-04 12:04:03.547636 | controller | Output suppressed because no_log was given 2026-08-04 12:04:03.552203 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:03.557306 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:03.562425 | 2026-08-04 12:04:03.562504 | LOOP [apache-logs-conf : Dereference files] 2026-08-04 12:04:03.607880 | 2026-08-04 12:04:03.608059 | LOOP [apache-logs-conf : Create hard links] 2026-08-04 12:04:03.656488 | 2026-08-04 12:04:03.656730 | TASK [apache-logs-conf : Find logs] 2026-08-04 12:04:03.714074 | 2026-08-04 12:04:03.714275 | LOOP [apache-logs-conf : Dereference files] 2026-08-04 12:04:03.767815 | 2026-08-04 12:04:03.767993 | LOOP [apache-logs-conf : Create hard links] 2026-08-04 12:04:03.817895 | 2026-08-04 12:04:03.818121 | TASK [apache-logs-conf : Ensure /home/ubuntu/apache_config apache_config exists] 2026-08-04 12:04:04.057718 | lab-sp-a1 | changed 2026-08-04 12:04:04.064724 | controller | changed 2026-08-04 12:04:04.069914 | undercloud-client | changed 2026-08-04 12:04:04.076982 | 2026-08-04 12:04:04.077119 | TASK [apache-logs-conf : Define config paths] 2026-08-04 12:04:04.102910 | controller | ok 2026-08-04 12:04:04.169132 | lab-sp-a1 | ok 2026-08-04 12:04:04.173816 | undercloud-client | ok 2026-08-04 12:04:04.178942 | 2026-08-04 12:04:04.179016 | TASK [apache-logs-conf : Discover configurations] 2026-08-04 12:04:04.398037 | controller | Output suppressed because no_log was given 2026-08-04 12:04:04.426624 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:04.433675 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:04.440934 | 2026-08-04 12:04:04.441043 | LOOP [apache-logs-conf : Dereference configurations] 2026-08-04 12:04:04.497610 | 2026-08-04 12:04:04.497795 | LOOP [apache-logs-conf : Link configurations] 2026-08-04 12:04:04.559053 | 2026-08-04 12:04:04.559255 | TASK [capture-performance-data : Generate statistics] 2026-08-04 12:04:05.086470 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found 2026-08-04 12:04:05.096288 | controller | Using python 3.12 to install setuptools 2026-08-04 12:04:05.067844 | lab-sp-a1 | /opt/stack/devstack//inc/python: line 43: -m: command not found 2026-08-04 12:04:05.077157 | lab-sp-a1 | Using python 3.12 to install setuptools 2026-08-04 12:04:05.105224 | lab-sp-a1 | /usr/bin/python3.12: No module named pip 2026-08-04 12:04:05.099983 | undercloud-client | /opt/stack/devstack//inc/python: line 43: -m: command not found 2026-08-04 12:04:05.108652 | undercloud-client | Using python 3.12 to install setuptools 2026-08-04 12:04:05.516900 | undercloud-client | error: externally-managed-environment 2026-08-04 12:04:05.516926 | undercloud-client | 2026-08-04 12:04:05.516932 | undercloud-client | × This environment is externally managed 2026-08-04 12:04:05.516936 | undercloud-client | ╰─> To install Python packages system-wide, try apt install 2026-08-04 12:04:05.516947 | undercloud-client | python3-xyz, where xyz is the package you are trying to 2026-08-04 12:04:05.516953 | undercloud-client | install. 2026-08-04 12:04:05.516957 | undercloud-client | 2026-08-04 12:04:05.516962 | undercloud-client | If you wish to install a non-Debian-packaged Python package, 2026-08-04 12:04:05.516967 | undercloud-client | create a virtual environment using python3 -m venv path/to/venv. 2026-08-04 12:04:05.516970 | undercloud-client | Then use path/to/venv/bin/python and path/to/venv/bin/pip. Make 2026-08-04 12:04:05.516974 | undercloud-client | sure you have python3-full installed. 2026-08-04 12:04:05.516977 | undercloud-client | 2026-08-04 12:04:05.516981 | undercloud-client | If you wish to install a non-Debian packaged Python application, 2026-08-04 12:04:05.516984 | undercloud-client | it may be easiest to use pipx install xyz, which will manage a 2026-08-04 12:04:05.516987 | undercloud-client | virtual environment for you. Make sure you have pipx installed. 2026-08-04 12:04:05.516991 | undercloud-client | 2026-08-04 12:04:05.516994 | undercloud-client | See /usr/share/doc/python3.12/README.venv for more information. 2026-08-04 12:04:05.516997 | undercloud-client | 2026-08-04 12:04:05.517001 | undercloud-client | note: If you believe this is a mistake, please contact your Python installation or OS distribution provider. You can override this, at the risk of breaking your Python installation or OS, by passing --break-system-packages. 2026-08-04 12:04:05.517010 | undercloud-client | hint: See PEP 668 for the detailed specification. 2026-08-04 12:04:05.591496 | undercloud-client | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory 2026-08-04 12:04:05.126222 | controller | /usr/bin/python3.12: No module named pip 2026-08-04 12:04:05.134335 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory 2026-08-04 12:04:05.113143 | lab-sp-a1 | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory 2026-08-04 12:04:05.643070 | controller | ERROR 2026-08-04 12:04:05.643283 | controller | { 2026-08-04 12:04:05.643318 | controller | "delta": "0:00:00.274752", 2026-08-04 12:04:05.643337 | controller | "end": "2026-08-04 12:04:05.135450", 2026-08-04 12:04:05.643354 | controller | "msg": "non-zero return code", 2026-08-04 12:04:05.643371 | controller | "rc": 127, 2026-08-04 12:04:05.643387 | controller | "start": "2026-08-04 12:04:04.860698" 2026-08-04 12:04:05.643404 | controller | } 2026-08-04 12:04:05.643425 | controller | ERROR: Ignoring Errors 2026-08-04 12:04:05.652704 | lab-sp-a1 | ERROR 2026-08-04 12:04:05.652865 | lab-sp-a1 | { 2026-08-04 12:04:05.652895 | lab-sp-a1 | "delta": "0:00:00.277734", 2026-08-04 12:04:05.652915 | lab-sp-a1 | "end": "2026-08-04 12:04:05.114223", 2026-08-04 12:04:05.652932 | lab-sp-a1 | "msg": "non-zero return code", 2026-08-04 12:04:05.652949 | lab-sp-a1 | "rc": 127, 2026-08-04 12:04:05.652966 | lab-sp-a1 | "start": "2026-08-04 12:04:04.836489" 2026-08-04 12:04:05.652982 | lab-sp-a1 | } 2026-08-04 12:04:05.653005 | lab-sp-a1 | ERROR: Ignoring Errors 2026-08-04 12:04:05.662055 | undercloud-client | ERROR 2026-08-04 12:04:05.662217 | undercloud-client | { 2026-08-04 12:04:05.662250 | undercloud-client | "delta": "0:00:00.713311", 2026-08-04 12:04:05.662268 | undercloud-client | "end": "2026-08-04 12:04:05.592570", 2026-08-04 12:04:05.662285 | undercloud-client | "msg": "non-zero return code", 2026-08-04 12:04:05.662302 | undercloud-client | "rc": 127, 2026-08-04 12:04:05.662318 | undercloud-client | "start": "2026-08-04 12:04:04.879259" 2026-08-04 12:04:05.662335 | undercloud-client | } 2026-08-04 12:04:05.662357 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 12:04:05.684903 | 2026-08-04 12:04:05.685064 | TASK [devstack-project-conf : Ensure /home/ubuntu/etc exists] 2026-08-04 12:04:05.915369 | controller | changed 2026-08-04 12:04:05.924593 | lab-sp-a1 | changed 2026-08-04 12:04:05.931783 | undercloud-client | changed 2026-08-04 12:04:05.940397 | 2026-08-04 12:04:05.940698 | LOOP [devstack-project-conf : Check which projects have a config folder] 2026-08-04 12:04:08.510999 | controller | Output suppressed because no_log was given 2026-08-04 12:04:08.574306 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:08.669207 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:08.675789 | 2026-08-04 12:04:08.675864 | LOOP [devstack-project-conf : Copy configuration files] 2026-08-04 12:04:08.707203 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.707574 | 2026-08-04 12:04:08.709848 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.713429 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.714130 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.716765 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.717490 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.720329 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.721150 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.723692 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.724892 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.725716 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.726652 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.728158 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.728782 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.729760 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.731175 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.731806 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.732420 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.733760 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.734400 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.735716 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.736465 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.737143 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.738859 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.739588 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.740248 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.741631 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.742275 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.742887 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.744159 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.744807 | controller | skipping: Conditional result was False 2026-08-04 12:04:08.745420 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.747804 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.748450 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.763014 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:08.763186 | 2026-08-04 12:04:08.764155 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.765786 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.766456 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.767106 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:08.791306 | 2026-08-04 12:04:08.791428 | TASK [devstack-project-conf : Check if openstack has a config folder] 2026-08-04 12:04:08.999580 | controller | ok 2026-08-04 12:04:09.005578 | lab-sp-a1 | ok 2026-08-04 12:04:09.020693 | undercloud-client | ok 2026-08-04 12:04:09.026600 | 2026-08-04 12:04:09.026719 | TASK [devstack-project-conf : Copy configuration files] 2026-08-04 12:04:09.641088 | controller | skipping: Conditional result was False 2026-08-04 12:04:09.641839 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:09.642022 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:09.664723 | 2026-08-04 12:04:09.664893 | TASK [capture-system-logs : Stage various logs and reports] 2026-08-04 12:04:10.009326 | controller | /usr/bin/python3: No module named pip 2026-08-04 12:04:09.994513 | lab-sp-a1 | /usr/bin/python3: No module named pip 2026-08-04 12:04:11.122969 | controller | grep: /home/ubuntu/apache/*.log: No such file or directory 2026-08-04 12:04:11.076725 | lab-sp-a1 | grep: /home/ubuntu/apache/*.log: No such file or directory 2026-08-04 12:04:11.237262 | lab-sp-a1 | ok: Runtime: 0:00:01.141259 2026-08-04 12:04:11.250289 | controller | ok: Runtime: 0:00:01.179562 2026-08-04 12:04:11.655876 | undercloud-client | ok: Runtime: 0:00:01.592114 2026-08-04 12:04:11.676852 | 2026-08-04 12:04:11.676989 | LOOP [stage-output : Register sources] 2026-08-04 12:04:11.611442 | undercloud-client | grep: /home/ubuntu/apache/*.log: No such file or directory 2026-08-04 12:04:22.237825 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:22.241233 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:22.432998 | controller | Output suppressed because no_log was given 2026-08-04 12:04:22.441275 | 2026-08-04 12:04:22.441398 | TASK [stage-output : Check sudo] 2026-08-04 12:04:23.045599 | controller | ok: Runtime: 0:00:00.086137 2026-08-04 12:04:23.054148 | undercloud-client | ok: Runtime: 0:00:00.061075 2026-08-04 12:04:23.063257 | lab-sp-a1 | ok: Runtime: 0:00:00.065916 2026-08-04 12:04:23.070495 | 2026-08-04 12:04:23.070633 | LOOP [stage-output : Set source and destination for files and folders] 2026-08-04 12:04:23.171089 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.171462 | 2026-08-04 12:04:23.172774 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.175242 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.184902 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.197032 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.197853 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.216024 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.216623 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.221551 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.226662 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.226996 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.232188 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.237183 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.237666 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.242503 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.242861 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.243216 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.243547 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.248087 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.248428 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.248891 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.249228 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.249560 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.249868 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.250185 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.250490 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.250826 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.255564 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.255894 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.256351 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.256683 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.256993 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.257434 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.262298 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.262665 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.267633 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.268024 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.268358 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.268678 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.268993 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.269312 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.269629 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.269934 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.270252 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.271065 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.272747 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.273435 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.275905 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.276244 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.281453 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.281813 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.282146 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.282453 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.282777 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.283086 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.283598 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.284210 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.284519 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.286680 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.287010 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.289169 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.289555 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.291633 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.292071 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.297712 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.298044 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.298507 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.298844 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.299306 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.306858 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.307199 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.316840 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.317515 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.317961 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.331497 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.331976 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.332425 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.332832 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.333195 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.338294 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.338639 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.348116 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.385889 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.386381 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.409088 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.422954 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.427993 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.437285 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.442035 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.442377 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.442714 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.443179 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.494222 | controller | Output suppressed because no_log was given 2026-08-04 12:04:23.497986 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.503358 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.503841 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.504168 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.519255 | lab-sp-a1 | Output suppressed because no_log was given 2026-08-04 12:04:23.527940 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.543511 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.556457 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.556911 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.559583 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.562189 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.574933 | undercloud-client | Output suppressed because no_log was given 2026-08-04 12:04:23.583790 | 2026-08-04 12:04:23.583879 | TASK [stage-output : Build a list of source, dest dictionaries] 2026-08-04 12:04:23.660148 | controller | ok 2026-08-04 12:04:23.666294 | lab-sp-a1 | ok 2026-08-04 12:04:23.670967 | undercloud-client | ok 2026-08-04 12:04:23.676948 | 2026-08-04 12:04:23.677022 | LOOP [stage-output : Ensure target folders exist] 2026-08-04 12:04:23.891159 | controller | changed: "docs" 2026-08-04 12:04:23.913025 | lab-sp-a1 | changed: "docs" 2026-08-04 12:04:23.921549 | undercloud-client | changed: "docs" 2026-08-04 12:04:24.122190 | lab-sp-a1 | changed: "artifacts" 2026-08-04 12:04:24.129518 | undercloud-client | changed: "artifacts" 2026-08-04 12:04:24.134568 | controller | changed: "artifacts" 2026-08-04 12:04:24.313368 | lab-sp-a1 | ok: "logs" 2026-08-04 12:04:24.318812 | undercloud-client | ok: "logs" 2026-08-04 12:04:24.326819 | controller | ok: "logs" 2026-08-04 12:04:24.326888 | controller | changed: All items complete 2026-08-04 12:04:24.326915 | 2026-08-04 12:04:24.341156 | 2026-08-04 12:04:24.341238 | LOOP [stage-output : Copy files and folders to staging folder] 2026-08-04 12:04:24.600011 | controller | ok: Item: Runtime: 0:00:00.005192 2026-08-04 12:04:24.611235 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004914 2026-08-04 12:04:24.619450 | undercloud-client | ok: Item: Runtime: 0:00:00.008920 2026-08-04 12:04:24.787084 | controller | ok: Item: Runtime: 0:00:00.005629 2026-08-04 12:04:24.805900 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004617 2026-08-04 12:04:24.815135 | undercloud-client | ok: Item: Runtime: 0:00:00.004800 2026-08-04 12:04:24.988799 | controller | ok: Item: Runtime: 0:00:00.004396 2026-08-04 12:04:25.009262 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.005447 2026-08-04 12:04:25.018767 | undercloud-client | ok: Item: Runtime: 0:00:00.005521 2026-08-04 12:04:25.240344 | controller | ok: Item: Runtime: 0:00:00.005122 2026-08-04 12:04:25.261824 | undercloud-client | ok: Item: Runtime: 0:00:00.005535 2026-08-04 12:04:25.271862 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004513 2026-08-04 12:04:25.406077 | controller | ok: Item: Runtime: 0:00:00.004400 2026-08-04 12:04:25.475471 | undercloud-client | ok: Item: Runtime: 0:00:00.005029 2026-08-04 12:04:25.485325 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004179 2026-08-04 12:04:25.599920 | controller | ok: Item: Runtime: 0:00:00.004502 2026-08-04 12:04:25.697779 | undercloud-client | ok: Item: Runtime: 0:00:00.005314 2026-08-04 12:04:25.707250 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003971 2026-08-04 12:04:25.801879 | controller | ok: Item: Runtime: 0:00:00.004536 2026-08-04 12:04:25.906627 | undercloud-client | ok: Item: Runtime: 0:00:00.004723 2026-08-04 12:04:25.915553 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004343 2026-08-04 12:04:25.996938 | controller | ok: Item: Runtime: 0:00:00.005757 2026-08-04 12:04:26.107190 | undercloud-client | ok: Item: Runtime: 0:00:00.004591 2026-08-04 12:04:26.120963 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004252 2026-08-04 12:04:26.200405 | controller | ok: Item: Runtime: 0:00:00.004472 2026-08-04 12:04:26.308735 | undercloud-client | ok: Item: Runtime: 0:00:00.004711 2026-08-04 12:04:26.319691 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003887 2026-08-04 12:04:26.420371 | controller | ok: Item: Runtime: 0:00:00.004548 2026-08-04 12:04:26.518072 | undercloud-client | ok: Item: Runtime: 0:00:00.004699 2026-08-04 12:04:26.528327 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004243 2026-08-04 12:04:26.623282 | controller | ok: Item: Runtime: 0:00:00.004497 2026-08-04 12:04:26.720072 | undercloud-client | ok: Item: Runtime: 0:00:00.004905 2026-08-04 12:04:26.734016 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004085 2026-08-04 12:04:26.835757 | controller | ok: Item: Runtime: 0:00:00.004662 2026-08-04 12:04:26.927307 | undercloud-client | ok: Item: Runtime: 0:00:00.005171 2026-08-04 12:04:26.936905 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004502 2026-08-04 12:04:27.047163 | controller | ok: Item: Runtime: 0:00:00.004246 2026-08-04 12:04:27.126575 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003947 2026-08-04 12:04:27.136670 | undercloud-client | ok: Item: Runtime: 0:00:00.004657 2026-08-04 12:04:27.256406 | controller | ok: Item: Runtime: 0:00:00.004182 2026-08-04 12:04:27.386391 | undercloud-client | ok: Item: Runtime: 0:00:00.004941 2026-08-04 12:04:27.396222 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003976 2026-08-04 12:04:27.458285 | controller | ok: Item: Runtime: 0:00:00.004563 2026-08-04 12:04:27.529366 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003981 2026-08-04 12:04:27.539374 | undercloud-client | ok: Item: Runtime: 0:00:00.004716 2026-08-04 12:04:27.682262 | controller | ok: Item: Runtime: 0:00:00.004646 2026-08-04 12:04:27.747319 | undercloud-client | ok: Item: Runtime: 0:00:00.004859 2026-08-04 12:04:27.757808 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004109 2026-08-04 12:04:27.973848 | controller | ok: Item: Runtime: 0:00:00.005034 2026-08-04 12:04:27.982478 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004649 2026-08-04 12:04:27.991799 | undercloud-client | ok: Item: Runtime: 0:00:00.004924 2026-08-04 12:04:28.026485 | 2026-08-04 12:04:28.026644 | TASK [stage-output : Make all log files readable] 2026-08-04 12:04:28.318914 | undercloud-client | changed 2026-08-04 12:04:28.334562 | controller | changed 2026-08-04 12:04:28.339532 | lab-sp-a1 | changed 2026-08-04 12:04:28.345321 | 2026-08-04 12:04:28.345407 | TASK [stage-output : Rename log files that match extensions_to_txt] 2026-08-04 12:04:28.729202 | lab-sp-a1 | changed: Renamed files for staging. 2026-08-04 12:04:28.734164 | controller | changed: Renamed files for staging. 2026-08-04 12:04:28.738389 | undercloud-client | changed: Renamed files for staging. 2026-08-04 12:04:28.743581 | 2026-08-04 12:04:28.743686 | TASK [stage-output : Discover log files for compression] 2026-08-04 12:04:28.776946 | controller | skipping: Conditional result was False 2026-08-04 12:04:28.778085 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:28.788479 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:28.794817 | 2026-08-04 12:04:28.794899 | LOOP [stage-output : Archive everything from logs] 2026-08-04 12:04:28.860582 | 2026-08-04 12:04:28.860794 | TASK [fetch-devstack-log-dir : Collect devstack logs] 2026-08-04 12:04:29.653582 | controller | changed: 2026-08-04 12:04:29.653847 | controller | created directory /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/logs/controller 2026-08-04 12:04:29.653877 | controller | cd+++++++++ logs/ 2026-08-04 12:04:29.653897 | controller | >f+++++++++ logs/deprecations_log.txt 2026-08-04 12:04:29.653916 | controller | >f+++++++++ logs/devstack.journal.README.txt 2026-08-04 12:04:29.653934 | controller | >f+++++++++ logs/devstack.journal.gz 2026-08-04 12:04:29.653952 | controller | >f+++++++++ logs/df.txt 2026-08-04 12:04:29.653973 | controller | >f+++++++++ logs/dpkg-l.txt 2026-08-04 12:04:29.653992 | controller | >f+++++++++ logs/iptables.txt 2026-08-04 12:04:29.654010 | controller | >f+++++++++ logs/listen53.txt 2026-08-04 12:04:29.654027 | controller | >f+++++++++ logs/local_conf.txt 2026-08-04 12:04:29.654044 | controller | >f+++++++++ logs/mount.txt 2026-08-04 12:04:29.654062 | controller | >f+++++++++ logs/performance.json 2026-08-04 12:04:29.654079 | controller | >f+++++++++ logs/pip3-freeze.txt 2026-08-04 12:04:29.654095 | controller | >f+++++++++ logs/resolv_conf.txt 2026-08-04 12:04:29.654124 | controller | >f+++++++++ logs/services.txt 2026-08-04 12:04:29.654141 | controller | >f+++++++++ logs/sudoers 2026-08-04 12:04:29.654158 | controller | >f+++++++++ logs/syslog.txt 2026-08-04 12:04:29.654174 | controller | cd+++++++++ logs/apache/ 2026-08-04 12:04:29.654190 | controller | cd+++++++++ logs/apache_config/ 2026-08-04 12:04:29.654207 | controller | cd+++++++++ logs/etc/ 2026-08-04 12:04:29.654223 | controller | cd+++++++++ logs/lvm/ 2026-08-04 12:04:29.654240 | controller | >f+++++++++ logs/lvm/lvm_conf.txt 2026-08-04 12:04:29.654256 | controller | >f+++++++++ logs/lvm/lvmlocal_conf.txt 2026-08-04 12:04:29.654274 | controller | cd+++++++++ logs/lvm/profile/ 2026-08-04 12:04:29.654296 | controller | >f+++++++++ logs/lvm/profile/cache-mq.profile 2026-08-04 12:04:29.654313 | controller | >f+++++++++ logs/lvm/profile/cache-smq.profile 2026-08-04 12:04:29.654330 | controller | >f+++++++++ logs/lvm/profile/command_profile_template.profile 2026-08-04 12:04:29.654406 | controller | >f+++++++++ logs/lvm/profile/lvmdbusd.profile 2026-08-04 12:04:29.654427 | controller | >f+++++++++ logs/lvm/profile/metadata_profile_template.profile 2026-08-04 12:04:29.654444 | controller | >f+++++++++ logs/lvm/profile/thin-generic.profile 2026-08-04 12:04:29.654461 | controller | >f+++++++++ logs/lvm/profile/thin-performance.profile 2026-08-04 12:04:29.654477 | controller | >f+++++++++ logs/lvm/profile/vdo-small.profile 2026-08-04 12:04:29.654494 | controller | cd+++++++++ logs/sudoers.d/ 2026-08-04 12:04:29.654511 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh 2026-08-04 12:04:29.654546 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh 2026-08-04 12:04:29.654565 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users 2026-08-04 12:04:29.654582 | controller | >f+++++++++ logs/sudoers.d/README 2026-08-04 12:04:29.654599 | controller | >f+++++++++ logs/sudoers.d/zuul 2026-08-04 12:04:29.660543 | lab-sp-a1 | changed: 2026-08-04 12:04:29.660609 | lab-sp-a1 | created directory /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/logs/lab-sp-a1 2026-08-04 12:04:29.660633 | lab-sp-a1 | cd+++++++++ logs/ 2026-08-04 12:04:29.660653 | lab-sp-a1 | >f+++++++++ logs/deprecations_log.txt 2026-08-04 12:04:29.660671 | lab-sp-a1 | >f+++++++++ logs/devstack.journal.README.txt 2026-08-04 12:04:29.660690 | lab-sp-a1 | >f+++++++++ logs/devstack.journal.gz 2026-08-04 12:04:29.660716 | lab-sp-a1 | >f+++++++++ logs/df.txt 2026-08-04 12:04:29.660735 | lab-sp-a1 | >f+++++++++ logs/dpkg-l.txt 2026-08-04 12:04:29.660752 | lab-sp-a1 | >f+++++++++ logs/iptables.txt 2026-08-04 12:04:29.660768 | lab-sp-a1 | >f+++++++++ logs/listen53.txt 2026-08-04 12:04:29.660785 | lab-sp-a1 | >f+++++++++ logs/local_conf.txt 2026-08-04 12:04:29.660802 | lab-sp-a1 | >f+++++++++ logs/mount.txt 2026-08-04 12:04:29.660819 | lab-sp-a1 | >f+++++++++ logs/performance.json 2026-08-04 12:04:29.660835 | lab-sp-a1 | >f+++++++++ logs/pip3-freeze.txt 2026-08-04 12:04:29.660851 | lab-sp-a1 | >f+++++++++ logs/resolv_conf.txt 2026-08-04 12:04:29.660868 | lab-sp-a1 | >f+++++++++ logs/services.txt 2026-08-04 12:04:29.660884 | lab-sp-a1 | >f+++++++++ logs/sudoers 2026-08-04 12:04:29.660901 | lab-sp-a1 | >f+++++++++ logs/syslog.txt 2026-08-04 12:04:29.660917 | lab-sp-a1 | cd+++++++++ logs/apache/ 2026-08-04 12:04:29.660934 | lab-sp-a1 | cd+++++++++ logs/apache_config/ 2026-08-04 12:04:29.660951 | lab-sp-a1 | cd+++++++++ logs/etc/ 2026-08-04 12:04:29.660967 | lab-sp-a1 | cd+++++++++ logs/lvm/ 2026-08-04 12:04:29.660989 | lab-sp-a1 | >f+++++++++ logs/lvm/lvm_conf.txt 2026-08-04 12:04:29.661006 | lab-sp-a1 | >f+++++++++ logs/lvm/lvmlocal_conf.txt 2026-08-04 12:04:29.661026 | lab-sp-a1 | cd+++++++++ logs/lvm/profile/ 2026-08-04 12:04:29.661059 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/cache-mq.profile 2026-08-04 12:04:29.661083 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/cache-smq.profile 2026-08-04 12:04:29.661773 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/command_profile_template.profile 2026-08-04 12:04:29.661814 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/lvmdbusd.profile 2026-08-04 12:04:29.661835 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/metadata_profile_template.profile 2026-08-04 12:04:29.661853 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/thin-generic.profile 2026-08-04 12:04:29.661871 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/thin-performance.profile 2026-08-04 12:04:29.661888 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/vdo-small.profile 2026-08-04 12:04:29.661906 | lab-sp-a1 | cd+++++++++ logs/sudoers.d/ 2026-08-04 12:04:29.661923 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/50_stack_sh 2026-08-04 12:04:29.661941 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/51_tempest_sh 2026-08-04 12:04:29.661958 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/90-cloud-init-users 2026-08-04 12:04:29.661977 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/README 2026-08-04 12:04:29.661997 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/zuul 2026-08-04 12:04:29.667333 | undercloud-client | changed: 2026-08-04 12:04:29.667400 | undercloud-client | created directory /var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/logs/undercloud-client 2026-08-04 12:04:29.667428 | undercloud-client | cd+++++++++ logs/ 2026-08-04 12:04:29.667447 | undercloud-client | >f+++++++++ logs/deprecations_log.txt 2026-08-04 12:04:29.667466 | undercloud-client | >f+++++++++ logs/devstack.journal.README.txt 2026-08-04 12:04:29.667484 | undercloud-client | >f+++++++++ logs/devstack.journal.gz 2026-08-04 12:04:29.667502 | undercloud-client | >f+++++++++ logs/df.txt 2026-08-04 12:04:29.667519 | undercloud-client | >f+++++++++ logs/dpkg-l.txt 2026-08-04 12:04:29.667560 | undercloud-client | >f+++++++++ logs/iptables.txt 2026-08-04 12:04:29.667578 | undercloud-client | >f+++++++++ logs/listen53.txt 2026-08-04 12:04:29.667594 | undercloud-client | >f+++++++++ logs/local_conf.txt 2026-08-04 12:04:29.667611 | undercloud-client | >f+++++++++ logs/mount.txt 2026-08-04 12:04:29.667628 | undercloud-client | >f+++++++++ logs/performance.json 2026-08-04 12:04:29.667645 | undercloud-client | >f+++++++++ logs/pip3-freeze.txt 2026-08-04 12:04:29.667661 | undercloud-client | >f+++++++++ logs/resolv_conf.txt 2026-08-04 12:04:29.667678 | undercloud-client | >f+++++++++ logs/services.txt 2026-08-04 12:04:29.667698 | undercloud-client | >f+++++++++ logs/sudoers 2026-08-04 12:04:29.667723 | undercloud-client | >f+++++++++ logs/syslog.txt 2026-08-04 12:04:29.667740 | undercloud-client | cd+++++++++ logs/apache/ 2026-08-04 12:04:29.667757 | undercloud-client | cd+++++++++ logs/apache_config/ 2026-08-04 12:04:29.667773 | undercloud-client | cd+++++++++ logs/etc/ 2026-08-04 12:04:29.667790 | undercloud-client | cd+++++++++ logs/lvm/ 2026-08-04 12:04:29.667806 | undercloud-client | >f+++++++++ logs/lvm/lvm_conf.txt 2026-08-04 12:04:29.667822 | undercloud-client | >f+++++++++ logs/lvm/lvmlocal_conf.txt 2026-08-04 12:04:29.667839 | undercloud-client | cd+++++++++ logs/lvm/profile/ 2026-08-04 12:04:29.667858 | undercloud-client | >f+++++++++ logs/lvm/profile/cache-mq.profile 2026-08-04 12:04:29.667875 | undercloud-client | >f+++++++++ logs/lvm/profile/cache-smq.profile 2026-08-04 12:04:29.667893 | undercloud-client | >f+++++++++ logs/lvm/profile/command_profile_template.profile 2026-08-04 12:04:29.667911 | undercloud-client | >f+++++++++ logs/lvm/profile/lvmdbusd.profile 2026-08-04 12:04:29.667927 | undercloud-client | >f+++++++++ logs/lvm/profile/metadata_profile_template.profile 2026-08-04 12:04:29.667944 | undercloud-client | >f+++++++++ logs/lvm/profile/thin-generic.profile 2026-08-04 12:04:29.667967 | undercloud-client | >f+++++++++ logs/lvm/profile/thin-performance.profile 2026-08-04 12:04:29.667984 | undercloud-client | >f+++++++++ logs/lvm/profile/vdo-small.profile 2026-08-04 12:04:29.668000 | undercloud-client | cd+++++++++ logs/sudoers.d/ 2026-08-04 12:04:29.668017 | undercloud-client | >f+++++++++ logs/sudoers.d/50_stack_sh 2026-08-04 12:04:29.668036 | undercloud-client | >f+++++++++ logs/sudoers.d/51_tempest_sh 2026-08-04 12:04:29.668055 | undercloud-client | >f+++++++++ logs/sudoers.d/90-cloud-init-users 2026-08-04 12:04:29.668071 | undercloud-client | >f+++++++++ logs/sudoers.d/README 2026-08-04 12:04:29.668088 | undercloud-client | >f+++++++++ logs/sudoers.d/zuul 2026-08-04 12:04:29.767278 | 2026-08-04 12:04:29.767399 | TASK [Check if a tempest log exits] 2026-08-04 12:04:30.002223 | controller | ok 2026-08-04 12:04:30.029867 | lab-sp-a1 | ok 2026-08-04 12:04:30.044637 | undercloud-client | ok 2026-08-04 12:04:30.049573 | 2026-08-04 12:04:30.049634 | TASK [Link post-devstack tempest.log] 2026-08-04 12:04:30.068392 | controller | skipping: Conditional result was False 2026-08-04 12:04:30.082369 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:30.093508 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:30.100884 | 2026-08-04 12:04:30.101079 | TASK [Capture most recent qemu crash dump, if any] 2026-08-04 12:04:30.412790 | undercloud-client | /bin/bash: line 1: coredumpctl: command not found 2026-08-04 12:04:30.381873 | controller | /bin/bash: line 1: coredumpctl: command not found 2026-08-04 12:04:30.378303 | lab-sp-a1 | /bin/bash: line 1: coredumpctl: command not found 2026-08-04 12:04:30.685259 | controller | ERROR 2026-08-04 12:04:30.685478 | controller | { 2026-08-04 12:04:30.685506 | controller | "delta": "0:00:00.004831", 2026-08-04 12:04:30.685552 | controller | "end": "2026-08-04 12:04:30.382258", 2026-08-04 12:04:30.685574 | controller | "msg": "non-zero return code", 2026-08-04 12:04:30.685591 | controller | "rc": 127, 2026-08-04 12:04:30.685608 | controller | "start": "2026-08-04 12:04:30.377427" 2026-08-04 12:04:30.685625 | controller | } 2026-08-04 12:04:30.685647 | controller | ERROR: Ignoring Errors 2026-08-04 12:04:30.697353 | lab-sp-a1 | ERROR 2026-08-04 12:04:30.697561 | lab-sp-a1 | { 2026-08-04 12:04:30.697592 | lab-sp-a1 | "delta": "0:00:00.004677", 2026-08-04 12:04:30.697611 | lab-sp-a1 | "end": "2026-08-04 12:04:30.378684", 2026-08-04 12:04:30.697629 | lab-sp-a1 | "msg": "non-zero return code", 2026-08-04 12:04:30.697646 | lab-sp-a1 | "rc": 127, 2026-08-04 12:04:30.697663 | lab-sp-a1 | "start": "2026-08-04 12:04:30.374007" 2026-08-04 12:04:30.697680 | lab-sp-a1 | } 2026-08-04 12:04:30.697703 | lab-sp-a1 | ERROR: Ignoring Errors 2026-08-04 12:04:30.707331 | undercloud-client | ERROR 2026-08-04 12:04:30.707501 | undercloud-client | { 2026-08-04 12:04:30.707551 | undercloud-client | "delta": "0:00:00.004954", 2026-08-04 12:04:30.707572 | undercloud-client | "end": "2026-08-04 12:04:30.413268", 2026-08-04 12:04:30.707590 | undercloud-client | "msg": "non-zero return code", 2026-08-04 12:04:30.707606 | undercloud-client | "rc": 127, 2026-08-04 12:04:30.707623 | undercloud-client | "start": "2026-08-04 12:04:30.408314" 2026-08-04 12:04:30.707639 | undercloud-client | } 2026-08-04 12:04:30.707662 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 12:04:30.713652 | 2026-08-04 12:04:30.713819 | TASK [Collect Guru Meditation Reports from OpenStack services] 2026-08-04 12:04:31.301178 | controller | ERROR 2026-08-04 12:04:31.301455 | controller | { 2026-08-04 12:04:31.301485 | controller | "delta": "0:00:00.052921", 2026-08-04 12:04:31.301505 | controller | "end": "2026-08-04 12:04:31.063243", 2026-08-04 12:04:31.301545 | controller | "msg": "non-zero return code", 2026-08-04 12:04:31.301568 | controller | "rc": -12, 2026-08-04 12:04:31.301586 | controller | "start": "2026-08-04 12:04:31.010322" 2026-08-04 12:04:31.301604 | controller | } 2026-08-04 12:04:31.301628 | controller | ERROR: Ignoring Errors 2026-08-04 12:04:31.311589 | lab-sp-a1 | ERROR 2026-08-04 12:04:31.311770 | lab-sp-a1 | { 2026-08-04 12:04:31.311799 | lab-sp-a1 | "delta": "0:00:00.048059", 2026-08-04 12:04:31.311818 | lab-sp-a1 | "end": "2026-08-04 12:04:31.048143", 2026-08-04 12:04:31.311836 | lab-sp-a1 | "msg": "non-zero return code", 2026-08-04 12:04:31.311853 | lab-sp-a1 | "rc": -12, 2026-08-04 12:04:31.311869 | lab-sp-a1 | "start": "2026-08-04 12:04:31.000084" 2026-08-04 12:04:31.311885 | lab-sp-a1 | } 2026-08-04 12:04:31.311908 | lab-sp-a1 | ERROR: Ignoring Errors 2026-08-04 12:04:31.388682 | undercloud-client | ERROR 2026-08-04 12:04:31.388874 | undercloud-client | { 2026-08-04 12:04:31.388902 | undercloud-client | "delta": "0:00:00.029710", 2026-08-04 12:04:31.388921 | undercloud-client | "end": "2026-08-04 12:04:31.076153", 2026-08-04 12:04:31.388939 | undercloud-client | "msg": "non-zero return code", 2026-08-04 12:04:31.388956 | undercloud-client | "rc": -12, 2026-08-04 12:04:31.388972 | undercloud-client | "start": "2026-08-04 12:04:31.046443" 2026-08-04 12:04:31.388989 | undercloud-client | } 2026-08-04 12:04:31.389011 | undercloud-client | ERROR: Ignoring Errors 2026-08-04 12:04:31.390645 | 2026-08-04 12:04:31.390762 | PLAY RECAP 2026-08-04 12:04:31.390817 | controller | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3 2026-08-04 12:04:31.390844 | lab-sp-a1 | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3 2026-08-04 12:04:31.390868 | undercloud-client | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3 2026-08-04 12:04:31.390886 | 2026-08-04 12:04:31.639651 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master] 2026-08-04 12:04:31.649608 | POST-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/base/post.yaml@master] 2026-08-04 12:04:32.442514 | 2026-08-04 12:04:32.442692 | PLAY [all] 2026-08-04 12:04:32.456716 | 2026-08-04 12:04:32.456884 | TASK [include_role : fetch-output] 2026-08-04 12:04:32.501300 | controller | ok 2026-08-04 12:04:32.506013 | lab-sp-a1 | ok 2026-08-04 12:04:32.520656 | undercloud-client | ok 2026-08-04 12:04:32.545940 | 2026-08-04 12:04:32.546130 | TASK [fetch-output : Set log path for multiple nodes] 2026-08-04 12:04:32.611152 | controller | ok 2026-08-04 12:04:32.627918 | lab-sp-a1 | ok 2026-08-04 12:04:32.643903 | undercloud-client | ok 2026-08-04 12:04:32.649897 | 2026-08-04 12:04:32.650044 | TASK [fetch-output : Set log path for single node] 2026-08-04 12:04:32.685000 | controller | skipping: Conditional result was False 2026-08-04 12:04:32.696277 | lab-sp-a1 | skipping: Conditional result was False 2026-08-04 12:04:32.706811 | undercloud-client | skipping: Conditional result was False 2026-08-04 12:04:32.713803 | 2026-08-04 12:04:32.713917 | LOOP [fetch-output : Ensure local output dirs] 2026-08-04 12:04:33.113600 | lab-sp-a1 -> localhost | ok: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/logs/lab-sp-a1" 2026-08-04 12:04:33.129140 | controller -> localhost | ok: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/logs/controller" 2026-08-04 12:04:33.143179 | undercloud-client -> localhost | ok: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/logs/undercloud-client" 2026-08-04 12:04:33.352897 | lab-sp-a1 -> localhost | changed: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/artifacts" 2026-08-04 12:04:33.362775 | undercloud-client -> localhost | ok: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/artifacts" 2026-08-04 12:04:33.478329 | controller -> localhost | ok: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/artifacts" 2026-08-04 12:04:33.585583 | undercloud-client -> localhost | changed: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/docs" 2026-08-04 12:04:33.612572 | lab-sp-a1 -> localhost | ok: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/docs" 2026-08-04 12:04:33.612828 | lab-sp-a1 -> localhost | changed: All items complete 2026-08-04 12:04:33.612880 | 2026-08-04 12:04:33.795865 | controller -> localhost | ok: "/var/lib/zuul/builds/be6c2967e25a4268a9b24c57981828be/work/docs" 2026-08-04 12:04:33.806391 | 2026-08-04 12:04:33.806517 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-08-04 12:04:34.814097 | undercloud-client | changed: .d..t...... ./ 2026-08-04 12:04:34.817714 | undercloud-client | ok: All items complete 2026-08-04 12:04:34.817867 | 2026-08-04 12:04:34.836261 | controller | changed: .d..t...... ./ 2026-08-04 12:04:34.874773 | lab-sp-a1 | changed: .d..t...... ./ 2026-08-04 12:04:35.498831 | undercloud-client | changed: .d..t...... ./ 2026-08-04 12:04:35.616389 | lab-sp-a1 | changed: .d..t...... ./ 2026-08-04 12:04:35.671189 | controller | ok 2026-08-04 12:04:36.211331 | undercloud-client | changed: .d..t...... ./ 2026-08-04 12:04:36.367322 | lab-sp-a1 | ok 2026-08-04 12:04:36.402544 | controller | ok 2026-08-04 12:04:36.402682 | controller | changed: All items complete 2026-08-04 12:04:36.402723 | 2026-08-04 12:04:36.426989 | 2026-08-04 12:04:36.427152 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-08-04 12:04:36.895057 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.006239 2026-08-04 12:04:37.126909 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.006938 2026-08-04 12:04:37.150302 | 2026-08-04 12:04:37.150403 | PLAY [localhost] 2026-08-04 12:04:37.158147 | 2026-08-04 12:04:37.158234 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-08-04 12:04:37.532773 | localhost | changed 2026-08-04 12:04:37.546236 | 2026-08-04 12:04:37.546364 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-08-04 12:04:37.575201 | localhost | ok 2026-08-04 12:04:37.587760 | 2026-08-04 12:04:37.587888 | TASK [ara-report : Check that ARA is installed] 2026-08-04 12:04:37.964310 | localhost | ok: Runtime: 0:00:00.005655 2026-08-04 12:04:37.969640 | 2026-08-04 12:04:37.969763 | TASK [ara-report : Warn if ARA is not installed] 2026-08-04 12:04:37.999148 | ARA is not installed, no report will be available. 2026-08-04 12:04:38.003631 | 2026-08-04 12:04:38.003760 | TASK [ara-report : Check that the ARA database exists] 2026-08-04 12:04:38.337626 | localhost | ok 2026-08-04 12:04:38.342386 | 2026-08-04 12:04:38.342538 | TASK [ara-report : Warn if no database is found] 2026-08-04 12:04:38.372243 | No report will be available because the ARA database was not found. 2026-08-04 12:04:38.378007 | 2026-08-04 12:04:38.378143 | TASK [ara-report : Validate role configuration] 2026-08-04 12:04:38.408237 | localhost | ok: All assertions passed 2026-08-04 12:04:38.412760 | 2026-08-04 12:04:38.412961 | TASK [ara-report : Generate ARA HTML output] 2026-08-04 12:04:38.438163 | localhost | skipping: Conditional result was False 2026-08-04 12:04:38.443250 | 2026-08-04 12:04:38.443345 | TASK [ara-report : Compress ARA HTML output] 2026-08-04 12:04:38.458913 | localhost | skipping: Conditional result was False 2026-08-04 12:04:38.464446 | 2026-08-04 12:04:38.464583 | TASK [ara-report : Return ARA report] 2026-08-04 12:04:38.478794 | localhost | skipping: Conditional result was False 2026-08-04 12:04:38.483966 | 2026-08-04 12:04:38.484080 | TASK [ara-report : Create the ARA database report directory] 2026-08-04 12:04:38.499254 | localhost | skipping: Conditional result was False 2026-08-04 12:04:38.504495 | 2026-08-04 12:04:38.504615 | TASK [ara-report : Save the ARA database] 2026-08-04 12:04:38.529473 | localhost | skipping: Conditional result was False 2026-08-04 12:04:38.536802 | 2026-08-04 12:04:38.536968 | TASK [ara-report : Return ARA report] 2026-08-04 12:04:38.553840 | localhost | skipping: Conditional result was False 2026-08-04 12:04:38.563897 | 2026-08-04 12:04:38.564008 | PLAY [localhost] 2026-08-04 12:04:38.570155 | 2026-08-04 12:04:38.570277 | TASK [Set zuul-log-path fact] 2026-08-04 12:04:38.590911 | localhost | ok 2026-08-04 12:04:38.629667 | 2026-08-04 12:04:38.629831 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-08-04 12:04:38.671382 | localhost | ok 2026-08-04 12:04:38.683084 | 2026-08-04 12:04:38.683264 | TASK [upload-logs : Create log directories] 2026-08-04 12:04:39.007189 | localhost | changed 2026-08-04 12:04:39.011869 | 2026-08-04 12:04:39.012011 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-08-04 12:04:39.284340 | localhost -> localhost | ok: Runtime: 0:00:00.006453 2026-08-04 12:04:39.288657 | 2026-08-04 12:04:39.288738 | TASK [upload-logs : Upload logs to log server] 2026-08-04 12:04:40.589327 | localhost | Output suppressed because no_log was given 2026-08-04 12:04:40.593853 | 2026-08-04 12:04:40.593965 | LOOP [upload-logs : Compress console log and json output] 2026-08-04 12:04:40.642010 | localhost | skipping: Conditional result was False 2026-08-04 12:04:40.642402 | localhost | changed: All items complete 2026-08-04 12:04:40.642440 | 2026-08-04 12:04:40.650263 | localhost | skipping: Conditional result was False 2026-08-04 12:04:40.667711 | 2026-08-04 12:04:40.667884 | LOOP [upload-logs : Upload compressed console log and json output] 2026-08-04 12:04:40.724884 | localhost | skipping: Conditional result was False 2026-08-04 12:04:40.725422 | 2026-08-04 12:04:40.732683 | localhost | skipping: Conditional result was False 2026-08-04 12:04:40.741518 | 2026-08-04 12:04:40.741698 | LOOP [upload-logs : Upload console log and json output]