2026-08-04 12:01:21.214204 | Job console starting
2026-08-04 12:01:21.230288 | Updating git repos
2026-08-04 12:01:21.754099 | Cloning repos into workspace
2026-08-04 12:01:32.352275 | Restoring repo states
2026-08-04 12:01:32.436582 | Merging changes
2026-08-04 12:01:39.863708 | Checking out repos
2026-08-04 12:01:42.428623 | Preparing playbooks
2026-08-04 12:01:49.793117 | Running Ansible setup
2026-08-04 12:01:56.271844 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/base/pre.yaml@master]
2026-08-04 12:01:57.022789 | 
2026-08-04 12:01:57.022916 | PLAY [localhost]
2026-08-04 12:01:57.031634 | 
2026-08-04 12:01:57.031722 | TASK [Gathering Facts]
2026-08-04 12:01:57.928231 | localhost | ok
2026-08-04 12:01:57.937411 | 
2026-08-04 12:01:57.937587 | TASK [Setup log path fact]
2026-08-04 12:01:57.957101 | localhost | ok
2026-08-04 12:01:57.972852 | 
2026-08-04 12:01:57.972987 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-04 12:01:58.000830 | localhost | ok
2026-08-04 12:01:58.007232 | 
2026-08-04 12:01:58.007343 | TASK [emit-job-header : Print job information]
2026-08-04 12:01:58.045515 | # Job Information
2026-08-04 12:01:58.045708 | Ansible Version: 2.16.19
2026-08-04 12:01:58.045748 | Job: cinder-storpool-tempest-iscsi-multipath
2026-08-04 12:01:58.045772 | Pipeline: check
2026-08-04 12:01:58.045792 | Executor: zuul-executor-0.zuul-executor.openstack-ci.svc.cluster.local
2026-08-04 12:01:58.045812 | Triggered by: https://review.opendev.org/c/openstack/cinder/+/926263
2026-08-04 12:01:58.045833 | Log URL (when completed): https://logs-openstack-ci.storpool.net/9ca/OpenStack/9ca4d3e380ca4996acabe952b28f2fcc/
2026-08-04 12:01:58.045853 | Event ID: 73a9ee8c5b114f71a0e32a04c63fed85
2026-08-04 12:01:58.048712 | 
2026-08-04 12:01:58.048787 | LOOP [emit-job-header : Print node information]
2026-08-04 12:01:58.194805 | localhost | ok:
2026-08-04 12:01:58.194994 | localhost | # Node Information
2026-08-04 12:01:58.195022 | localhost | Inventory Hostname: controller
2026-08-04 12:01:58.195042 | localhost | Hostname: np0000006437
2026-08-04 12:01:58.195061 | localhost | Username: ubuntu
2026-08-04 12:01:58.195089 | localhost | Distro: Ubuntu 24.04
2026-08-04 12:01:58.195110 | localhost | Provider: openstack-testing
2026-08-04 12:01:58.195128 | localhost | Region: RegionOne
2026-08-04 12:01:58.195145 | localhost | Label: os-ubuntu2404-4xlarge
2026-08-04 12:01:58.195162 | localhost | Product Name: OpenStack Nova
2026-08-04 12:01:58.195179 | localhost | Interface IP: 10.4.3.227
2026-08-04 12:01:58.244228 | localhost | ok:
2026-08-04 12:01:58.244323 | localhost | # Node Information
2026-08-04 12:01:58.244346 | localhost | Inventory Hostname: lab-sp-a1
2026-08-04 12:01:58.244366 | localhost | Hostname: np0000006439
2026-08-04 12:01:58.244384 | localhost | Username: ubuntu
2026-08-04 12:01:58.244401 | localhost | Distro: Ubuntu 24.04
2026-08-04 12:01:58.244417 | localhost | Provider: openstack-testing
2026-08-04 12:01:58.244434 | localhost | Region: RegionOne
2026-08-04 12:01:58.244450 | localhost | Label: os-ubuntu2404-2xlarge
2026-08-04 12:01:58.244467 | localhost | Product Name: OpenStack Nova
2026-08-04 12:01:58.244484 | localhost | Interface IP: 10.4.3.154
2026-08-04 12:01:58.293052 | localhost | ok:
2026-08-04 12:01:58.293162 | localhost | # Node Information
2026-08-04 12:01:58.293188 | localhost | Inventory Hostname: undercloud-client
2026-08-04 12:01:58.293207 | localhost | Hostname: np0000006438
2026-08-04 12:01:58.293226 | localhost | Username: ubuntu
2026-08-04 12:01:58.293243 | localhost | Distro: Ubuntu 24.04
2026-08-04 12:01:58.293260 | localhost | Provider: openstack-testing
2026-08-04 12:01:58.293276 | localhost | Region: RegionOne
2026-08-04 12:01:58.293293 | localhost | Label: os-ubuntu2404-small
2026-08-04 12:01:58.293309 | localhost | Product Name: OpenStack Nova
2026-08-04 12:01:58.293325 | localhost | Interface IP: 10.4.3.218
2026-08-04 12:01:58.302844 | 
2026-08-04 12:01:58.302973 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-04 12:01:58.683078 | localhost -> localhost | changed
2026-08-04 12:01:58.688136 | 
2026-08-04 12:01:58.688243 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-04 12:01:59.533124 | localhost -> localhost | changed
2026-08-04 12:01:59.539170 | 
2026-08-04 12:01:59.539242 | PLAY [all]
2026-08-04 12:01:59.546384 | 
2026-08-04 12:01:59.546449 | TASK [include_role : start-zuul-console]
2026-08-04 12:01:59.566579 | controller | ok
2026-08-04 12:01:59.613587 | lab-sp-a1 | ok
2026-08-04 12:01:59.618496 | undercloud-client | ok
2026-08-04 12:01:59.640111 | 
2026-08-04 12:01:59.640179 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-08-04 12:01:59.952567 | lab-sp-a1 | ok
2026-08-04 12:01:59.960328 | controller | ok
2026-08-04 12:01:59.975868 | undercloud-client | ok
2026-08-04 12:01:59.995041 | 
2026-08-04 12:01:59.995166 | TASK [include_role : validate-host]
2026-08-04 12:02:00.028792 | controller | ok
2026-08-04 12:02:00.034134 | lab-sp-a1 | ok
2026-08-04 12:02:00.038994 | undercloud-client | ok
2026-08-04 12:02:00.067503 | 
2026-08-04 12:02:00.067605 | TASK [validate-host : Define zuul_info_dir fact]
2026-08-04 12:02:00.104970 | controller | ok
2026-08-04 12:02:00.109768 | lab-sp-a1 | ok
2026-08-04 12:02:00.125209 | undercloud-client | ok
2026-08-04 12:02:00.130080 | 
2026-08-04 12:02:00.130159 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-08-04 12:02:00.346664 | controller -> localhost | ok
2026-08-04 12:02:00.353017 | 
2026-08-04 12:02:00.353127 | TASK [validate-host : Collect information about the host]
2026-08-04 12:02:01.128814 | undercloud-client | ok
2026-08-04 12:02:01.141426 | controller | ok
2026-08-04 12:02:02.129791 | lab-sp-a1 | ok
2026-08-04 12:02:02.140489 | 
2026-08-04 12:02:02.140587 | TASK [validate-host : Sanitize hostname]
2026-08-04 12:02:02.192028 | controller | ok
2026-08-04 12:02:02.196713 | lab-sp-a1 | ok
2026-08-04 12:02:02.210706 | undercloud-client | ok
2026-08-04 12:02:02.215470 | 
2026-08-04 12:02:02.215566 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-08-04 12:02:02.634116 | controller -> localhost | changed
2026-08-04 12:02:02.665985 | lab-sp-a1 -> localhost | changed
2026-08-04 12:02:02.676925 | undercloud-client -> localhost | changed
2026-08-04 12:02:02.683890 | 
2026-08-04 12:02:02.683978 | TASK [validate-host : Collect information about zuul worker]
2026-08-04 12:02:03.173294 | lab-sp-a1 | ok
2026-08-04 12:02:03.177617 | controller | ok
2026-08-04 12:02:03.182296 | undercloud-client | ok
2026-08-04 12:02:03.186712 | 
2026-08-04 12:02:03.186817 | TASK [validate-host : Write out all zuul information for each host]
2026-08-04 12:02:03.578818 | controller -> localhost | changed
2026-08-04 12:02:03.612073 | lab-sp-a1 -> localhost | changed
2026-08-04 12:02:03.623193 | undercloud-client -> localhost | changed
2026-08-04 12:02:03.644667 | 
2026-08-04 12:02:03.644822 | TASK [include_role : prepare-workspace]
2026-08-04 12:02:03.672962 | controller | ok
2026-08-04 12:02:03.689106 | lab-sp-a1 | ok
2026-08-04 12:02:03.704063 | undercloud-client | ok
2026-08-04 12:02:03.741774 | 
2026-08-04 12:02:03.741895 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-08-04 12:02:03.967929 | controller | ok
2026-08-04 12:02:03.972228 | lab-sp-a1 | ok
2026-08-04 12:02:04.077332 | undercloud-client | ok
2026-08-04 12:02:04.082649 | 
2026-08-04 12:02:04.082737 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-08-04 12:02:56.700240 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:03:03.682259 | controller | Output suppressed because no_log was given
2026-08-04 12:03:04.789976 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:03:04.809508 | 
2026-08-04 12:03:04.809646 | TASK [include_role : add-build-sshkey]
2026-08-04 12:03:04.844473 | controller | ok
2026-08-04 12:03:04.858176 | lab-sp-a1 | ok
2026-08-04 12:03:04.872414 | undercloud-client | ok
2026-08-04 12:03:04.917850 | 
2026-08-04 12:03:04.917982 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-04 12:03:05.129882 | controller -> localhost | ok
2026-08-04 12:03:05.137003 | 
2026-08-04 12:03:05.137113 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-04 12:03:05.172305 | controller | ok
2026-08-04 12:03:05.176270 | lab-sp-a1 | ok
2026-08-04 12:03:05.180042 | undercloud-client | ok
2026-08-04 12:03:05.201816 | controller | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/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 12:03:05.201929 | lab-sp-a1 | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/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 12:03:05.201955 | undercloud-client | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/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 12:03:05.208621 | 
2026-08-04 12:03:05.208704 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-04 12:03:06.174702 | controller -> localhost | Generating public/private rsa key pair.
2026-08-04 12:03:06.175017 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/9ca4d3e380ca4996acabe952b28f2fcc_id_rsa
2026-08-04 12:03:06.175075 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/9ca4d3e380ca4996acabe952b28f2fcc_id_rsa.pub
2026-08-04 12:03:06.175130 | controller -> localhost | The key fingerprint is:
2026-08-04 12:03:06.175168 | controller -> localhost | SHA256:i3xn+9bKCMVDsgsq4LsdQrcsmMmeatEwINFQSnc9wwc zuul-build-sshkey
2026-08-04 12:03:06.175207 | controller -> localhost | The key's randomart image is:
2026-08-04 12:03:06.175243 | controller -> localhost | +---[RSA 3072]----+
2026-08-04 12:03:06.175276 | controller -> localhost | |o*.. .oE.        |
2026-08-04 12:03:06.175320 | controller -> localhost | |+.o .  = .       |
2026-08-04 12:03:06.175356 | controller -> localhost | |+       = .      |
2026-08-04 12:03:06.175389 | controller -> localhost | | o       =       |
2026-08-04 12:03:06.175422 | controller -> localhost | |..+.  . S +      |
2026-08-04 12:03:06.175455 | controller -> localhost | |==o..o o + .     |
2026-08-04 12:03:06.175486 | controller -> localhost | |+=o+. o = o  .   |
2026-08-04 12:03:06.175517 | controller -> localhost | |..*..  . + +. .  |
2026-08-04 12:03:06.175576 | controller -> localhost | |+=..      oo+.   |
2026-08-04 12:03:06.175610 | controller -> localhost | +----[SHA256]-----+
2026-08-04 12:03:06.175683 | controller -> localhost | ok: Runtime: 0:00:00.579483
2026-08-04 12:03:06.182130 | 
2026-08-04 12:03:06.182219 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-04 12:03:06.223378 | controller | ok
2026-08-04 12:03:06.227774 | lab-sp-a1 | ok
2026-08-04 12:03:06.232020 | undercloud-client | ok
2026-08-04 12:03:06.250074 | controller | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-04 12:03:06.250409 | lab-sp-a1 | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-04 12:03:06.250452 | undercloud-client | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/trusted/project_1/gerrit-openstack-ci.sof-k8s.storpool.net/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-04 12:03:06.315097 | 
2026-08-04 12:03:06.315220 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-04 12:03:06.347002 | controller | skipping: Conditional result was False
2026-08-04 12:03:06.349888 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:06.360473 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:06.367023 | 
2026-08-04 12:03:06.367166 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-04 12:03:07.372073 | controller | changed
2026-08-04 12:03:07.386793 | lab-sp-a1 | changed
2026-08-04 12:03:07.441784 | undercloud-client | changed
2026-08-04 12:03:07.446666 | 
2026-08-04 12:03:07.446744 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-04 12:03:07.646938 | controller | ok
2026-08-04 12:03:07.661940 | lab-sp-a1 | ok
2026-08-04 12:03:07.676035 | undercloud-client | ok
2026-08-04 12:03:07.680293 | 
2026-08-04 12:03:07.680358 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-04 12:03:08.176584 | controller | changed
2026-08-04 12:03:08.184024 | lab-sp-a1 | changed
2026-08-04 12:03:08.209036 | undercloud-client | changed
2026-08-04 12:03:08.213810 | 
2026-08-04 12:03:08.213936 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-04 12:03:08.697680 | controller | changed
2026-08-04 12:03:08.701902 | undercloud-client | changed
2026-08-04 12:03:08.705753 | lab-sp-a1 | changed
2026-08-04 12:03:08.710814 | 
2026-08-04 12:03:08.710929 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-04 12:03:08.742938 | controller | skipping: Conditional result was False
2026-08-04 12:03:08.754356 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:08.754679 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:08.760444 | 
2026-08-04 12:03:08.760584 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-04 12:03:09.070648 | controller -> localhost | changed
2026-08-04 12:03:09.088437 | 
2026-08-04 12:03:09.088580 | TASK [add-build-sshkey : Add back temp key]
2026-08-04 12:03:09.351370 | controller -> localhost | Identity added: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/9ca4d3e380ca4996acabe952b28f2fcc_id_rsa (zuul-build-sshkey)
2026-08-04 12:03:09.351676 | controller -> localhost | ok: Runtime: 0:00:00.010107
2026-08-04 12:03:09.359992 | 
2026-08-04 12:03:09.360138 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-04 12:03:09.654560 | controller | ok
2026-08-04 12:03:09.661992 | lab-sp-a1 | ok
2026-08-04 12:03:09.667419 | undercloud-client | ok
2026-08-04 12:03:09.673927 | 
2026-08-04 12:03:09.674047 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-04 12:03:09.710146 | controller | skipping: Conditional result was False
2026-08-04 12:03:09.721595 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:09.732149 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:09.751063 | 
2026-08-04 12:03:09.751197 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-04 12:03:09.968873 | lab-sp-a1 | ok: "logs"
2026-08-04 12:03:09.969162 | lab-sp-a1 | ok: All items complete
2026-08-04 12:03:09.969191 | 
2026-08-04 12:03:09.983766 | controller | ok: "logs"
2026-08-04 12:03:09.988080 | undercloud-client | ok: "logs"
2026-08-04 12:03:10.141879 | lab-sp-a1 | ok: "artifacts"
2026-08-04 12:03:10.158604 | controller | ok: "artifacts"
2026-08-04 12:03:10.169228 | undercloud-client | ok: "artifacts"
2026-08-04 12:03:10.321226 | lab-sp-a1 | ok: "docs"
2026-08-04 12:03:10.338889 | controller | ok: "docs"
2026-08-04 12:03:10.355011 | undercloud-client | ok: "docs"
2026-08-04 12:03:10.355170 | undercloud-client | ok: All items complete
2026-08-04 12:03:10.355202 | 
2026-08-04 12:03:10.368311 | 
2026-08-04 12:03:10.368424 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-04 12:03:10.589788 | controller | changed: "logs"
2026-08-04 12:03:10.596207 | lab-sp-a1 | changed: "logs"
2026-08-04 12:03:10.609360 | undercloud-client | changed: "logs"
2026-08-04 12:03:10.783500 | lab-sp-a1 | changed: "artifacts"
2026-08-04 12:03:10.787978 | controller | changed: "artifacts"
2026-08-04 12:03:10.813673 | undercloud-client | changed: "artifacts"
2026-08-04 12:03:10.973116 | lab-sp-a1 | changed: "docs"
2026-08-04 12:03:10.979642 | controller | changed: "docs"
2026-08-04 12:03:10.999459 | undercloud-client | changed: "docs"
2026-08-04 12:03:10.999619 | undercloud-client | changed: All items complete
2026-08-04 12:03:10.999664 | 
2026-08-04 12:03:11.027207 | 
2026-08-04 12:03:11.027332 | PLAY RECAP
2026-08-04 12:03:11.027393 | controller | ok: 23 changed: 10 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-04 12:03:11.027429 | lab-sp-a1 | ok: 18 changed: 7 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-04 12:03:11.027458 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:03:11.027487 | undercloud-client | ok: 18 changed: 7 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-08-04 12:03:11.027510 | 
2026-08-04 12:03:11.260265 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/base/pre.yaml@master]
2026-08-04 12:03:11.273504 | PRE-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-04 12:03:12.096833 | 
2026-08-04 12:03:12.096987 | PLAY [all]
2026-08-04 12:03:12.118144 | 
2026-08-04 12:03:12.118302 | TASK [Fix the permissions of the zuul home directory]
2026-08-04 12:03:12.835246 | undercloud-client | changed
2026-08-04 12:03:12.842592 | lab-sp-a1 | changed
2026-08-04 12:03:12.850422 | controller | changed
2026-08-04 12:03:12.858510 | 
2026-08-04 12:03:12.858893 | TASK [Gather minimum local MTU]
2026-08-04 12:03:12.994145 | lab-sp-a1 | ok
2026-08-04 12:03:13.049387 | controller | ok
2026-08-04 12:03:13.054503 | undercloud-client | ok
2026-08-04 12:03:13.059954 | 
2026-08-04 12:03:13.060075 | TASK [Calculate external_bridge_mtu]
2026-08-04 12:03:13.115552 | controller | ok
2026-08-04 12:03:13.132978 | lab-sp-a1 | ok
2026-08-04 12:03:13.150193 | undercloud-client | ok
2026-08-04 12:03:13.158345 | 
2026-08-04 12:03:13.158545 | TASK [configure-swap : Set ephemeral device if /dev/xvde exists]
2026-08-04 12:03:13.192734 | controller | skipping: Conditional result was False
2026-08-04 12:03:13.203989 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:13.214599 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:13.222706 | 
2026-08-04 12:03:13.222838 | TASK [configure-swap : Get ephemeral0 device node]
2026-08-04 12:03:13.810393 | controller | ok: Runtime: 0:00:00.005622
2026-08-04 12:03:13.821662 | lab-sp-a1 | ok: Runtime: 0:00:00.005527
2026-08-04 12:03:13.831827 | undercloud-client | ok: Runtime: 0:00:00.006757
2026-08-04 12:03:13.838472 | 
2026-08-04 12:03:13.838643 | TASK [configure-swap : Set ephemeral device if LABEL exists]
2026-08-04 12:03:13.873470 | controller | skipping: Conditional result was False
2026-08-04 12:03:13.885197 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:13.895981 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:13.904952 | 
2026-08-04 12:03:13.905147 | TASK [configure-swap : Setup swap on ephemeral storage]
2026-08-04 12:03:13.946549 | controller | skipping: Conditional result was False
2026-08-04 12:03:13.947890 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:13.968750 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:13.976118 | 
2026-08-04 12:03:13.976291 | TASK [configure-swap : Setup swap file on root device]
2026-08-04 12:03:14.039504 | controller | ok
2026-08-04 12:03:14.048321 | lab-sp-a1 | ok
2026-08-04 12:03:14.075765 | undercloud-client | ok
2026-08-04 12:03:14.102042 | controller | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-08-04 12:03:14.102182 | lab-sp-a1 | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-08-04 12:03:14.102207 | undercloud-client | included: /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/untrusted/project_1/opendev.org/openstack/openstack-zuul-jobs/roles/configure-swap/tasks/root.yaml
2026-08-04 12:03:14.110354 | 
2026-08-04 12:03:14.110519 | TASK [configure-swap : Calculate required swap]
2026-08-04 12:03:14.157224 | controller | ok
2026-08-04 12:03:14.182783 | lab-sp-a1 | ok
2026-08-04 12:03:14.188388 | undercloud-client | ok
2026-08-04 12:03:14.246566 | 
2026-08-04 12:03:14.246702 | TASK [configure-swap : Get root filesystem]
2026-08-04 12:03:14.531773 | lab-sp-a1 | ext4
2026-08-04 12:03:14.514516 | controller | ext4
2026-08-04 12:03:14.547720 | undercloud-client | ext4
2026-08-04 12:03:14.845621 | controller | ok: Runtime: 0:00:00.005825
2026-08-04 12:03:14.855387 | lab-sp-a1 | ok: Runtime: 0:00:00.006458
2026-08-04 12:03:14.863844 | undercloud-client | ok: Runtime: 0:00:00.007588
2026-08-04 12:03:14.869628 | 
2026-08-04 12:03:14.869756 | TASK [configure-swap : Save root filesystem]
2026-08-04 12:03:14.909113 | controller | ok
2026-08-04 12:03:14.914513 | lab-sp-a1 | ok
2026-08-04 12:03:14.918791 | undercloud-client | ok
2026-08-04 12:03:14.925296 | 
2026-08-04 12:03:14.925424 | TASK [configure-swap : Debug the root_filesystem variable]
2026-08-04 12:03:14.967292 | controller | ok:
2026-08-04 12:03:14.967589 | controller | {
2026-08-04 12:03:14.967617 | controller |   "root_filesystem": "ext4"
2026-08-04 12:03:14.967636 | controller | }
2026-08-04 12:03:14.972825 | lab-sp-a1 | ok:
2026-08-04 12:03:14.972918 | lab-sp-a1 | {
2026-08-04 12:03:14.972941 | lab-sp-a1 |   "root_filesystem": "ext4"
2026-08-04 12:03:14.972959 | lab-sp-a1 | }
2026-08-04 12:03:14.978377 | undercloud-client | ok:
2026-08-04 12:03:14.978465 | undercloud-client | {
2026-08-04 12:03:14.978488 | undercloud-client |   "root_filesystem": "ext4"
2026-08-04 12:03:14.978506 | undercloud-client | }
2026-08-04 12:03:14.983779 | 
2026-08-04 12:03:14.983887 | TASK [configure-swap : Create swap backing file]
2026-08-04 12:03:22.284126 | controller | 8192+0 records in
2026-08-04 12:03:22.284243 | controller | 8192+0 records out
2026-08-04 12:03:22.284265 | controller | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 6.97657 s, 1.2 GB/s
2026-08-04 12:03:23.300584 | lab-sp-a1 | 8192+0 records in
2026-08-04 12:03:23.300665 | lab-sp-a1 | 8192+0 records out
2026-08-04 12:03:23.300681 | lab-sp-a1 | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 7.99417 s, 1.1 GB/s
2026-08-04 12:03:27.425152 | undercloud-client | 8192+0 records in
2026-08-04 12:03:27.425208 | undercloud-client | 8192+0 records out
2026-08-04 12:03:27.425596 | undercloud-client | 8589934592 bytes (8.6 GB, 8.0 GiB) copied, 12.1165 s, 709 MB/s
2026-08-04 12:03:27.651970 | controller | ok: Runtime: 0:00:06.984941
2026-08-04 12:03:27.664656 | lab-sp-a1 | ok: Runtime: 0:00:08.000851
2026-08-04 12:03:27.676801 | undercloud-client | ok: Runtime: 0:00:12.122512
2026-08-04 12:03:27.685515 | 
2026-08-04 12:03:27.685675 | TASK [configure-swap : Ensure swapfile perms]
2026-08-04 12:03:27.932960 | lab-sp-a1 | changed
2026-08-04 12:03:27.939051 | controller | changed
2026-08-04 12:03:27.945267 | undercloud-client | changed
2026-08-04 12:03:27.951072 | 
2026-08-04 12:03:27.951230 | TASK [configure-swap : Make swapfile]
2026-08-04 12:03:28.276493 | lab-sp-a1 | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-08-04 12:03:28.276576 | lab-sp-a1 | no label, UUID=d9c065c8-bbe5-4a35-a53f-e19d5f47980d
2026-08-04 12:03:28.315119 | undercloud-client | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-08-04 12:03:28.315884 | undercloud-client | no label, UUID=e9e466e1-c699-4779-a905-fa0d93344571
2026-08-04 12:03:28.567700 | lab-sp-a1 | ok: Runtime: 0:00:00.035599
2026-08-04 12:03:28.580881 | undercloud-client | ok: Runtime: 0:00:00.043371
2026-08-04 12:03:29.468844 | controller | ok: Runtime: 0:00:01.198980
2026-08-04 12:03:29.477802 | 
2026-08-04 12:03:29.477944 | TASK [configure-swap : Write swap to fstab]
2026-08-04 12:03:29.424149 | controller | Setting up swapspace version 1, size = 8 GiB (8589930496 bytes)
2026-08-04 12:03:29.424240 | controller | no label, UUID=d41b8221-5534-4474-8936-452057edcbc4
2026-08-04 12:03:29.881809 | lab-sp-a1 | changed
2026-08-04 12:03:29.886613 | undercloud-client | changed
2026-08-04 12:03:29.892462 | controller | changed
2026-08-04 12:03:29.898232 | 
2026-08-04 12:03:29.898386 | TASK [configure-swap : Add all swap]
2026-08-04 12:03:30.544062 | controller | ok: Runtime: 0:00:00.007771
2026-08-04 12:03:30.560338 | lab-sp-a1 | ok: Runtime: 0:00:00.006877
2026-08-04 12:03:30.573146 | undercloud-client | ok: Runtime: 0:00:00.009499
2026-08-04 12:03:30.581133 | 
2026-08-04 12:03:30.581272 | TASK [configure-swap : Debug the swap_required variable]
2026-08-04 12:03:30.613234 | controller | ok:
2026-08-04 12:03:30.613512 | controller | {
2026-08-04 12:03:30.613561 | controller |   "swap_required": "8192"
2026-08-04 12:03:30.613580 | controller | }
2026-08-04 12:03:30.634429 | lab-sp-a1 | ok:
2026-08-04 12:03:30.634793 | lab-sp-a1 | {
2026-08-04 12:03:30.634838 | lab-sp-a1 |   "swap_required": "8192"
2026-08-04 12:03:30.634867 | lab-sp-a1 | }
2026-08-04 12:03:30.653791 | undercloud-client | ok:
2026-08-04 12:03:30.653971 | undercloud-client | {
2026-08-04 12:03:30.654008 | undercloud-client |   "swap_required": "8192"
2026-08-04 12:03:30.654043 | undercloud-client | }
2026-08-04 12:03:30.664473 | 
2026-08-04 12:03:30.664668 | TASK [configure-swap : Set swappiness]
2026-08-04 12:03:30.985402 | lab-sp-a1 | changed
2026-08-04 12:03:30.990127 | controller | changed
2026-08-04 12:03:31.006195 | undercloud-client | changed
2026-08-04 12:03:31.011346 | 
2026-08-04 12:03:31.011434 | TASK [configure-swap : Debug the ephemeral_device variable]
2026-08-04 12:03:31.060245 | controller | ok:
2026-08-04 12:03:31.060434 | controller | {
2026-08-04 12:03:31.060460 | controller |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-08-04 12:03:31.060479 | controller | }
2026-08-04 12:03:31.066136 | lab-sp-a1 | ok:
2026-08-04 12:03:31.066201 | lab-sp-a1 | {
2026-08-04 12:03:31.066225 | lab-sp-a1 |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-08-04 12:03:31.066243 | lab-sp-a1 | }
2026-08-04 12:03:31.081000 | undercloud-client | ok:
2026-08-04 12:03:31.081118 | undercloud-client | {
2026-08-04 12:03:31.081141 | undercloud-client |   "ephemeral_device": "VARIABLE IS NOT DEFINED!: 'ephemeral_device' is undefined. 'ephemeral_device' is undefined"
2026-08-04 12:03:31.081160 | undercloud-client | }
2026-08-04 12:03:31.100441 | 
2026-08-04 12:03:31.100631 | TASK [setup-stack-user : Create stack group]
2026-08-04 12:03:31.515780 | undercloud-client | changed
2026-08-04 12:03:31.522738 | lab-sp-a1 | changed
2026-08-04 12:03:31.540619 | controller | changed
2026-08-04 12:03:31.548124 | 
2026-08-04 12:03:31.548280 | TASK [setup-stack-user : Create the stack user home folder]
2026-08-04 12:03:31.760203 | controller | changed
2026-08-04 12:03:31.779048 | lab-sp-a1 | changed
2026-08-04 12:03:31.794347 | undercloud-client | changed
2026-08-04 12:03:31.799729 | 
2026-08-04 12:03:31.799810 | TASK [setup-stack-user : Create stack user]
2026-08-04 12:03:32.288569 | lab-sp-a1 | changed
2026-08-04 12:03:32.293314 | undercloud-client | changed
2026-08-04 12:03:32.297894 | controller | changed
2026-08-04 12:03:32.303173 | 
2026-08-04 12:03:32.303261 | TASK [setup-stack-user : Set stack user home directory permissions and ownership]
2026-08-04 12:03:32.544227 | controller | changed
2026-08-04 12:03:32.549569 | lab-sp-a1 | changed
2026-08-04 12:03:32.554591 | undercloud-client | changed
2026-08-04 12:03:32.561015 | 
2026-08-04 12:03:32.561159 | TASK [setup-stack-user : Copy 50_stack_sh file to /etc/sudoers.d]
2026-08-04 12:03:33.612667 | controller | changed
2026-08-04 12:03:33.619078 | undercloud-client | changed
2026-08-04 12:03:33.624033 | lab-sp-a1 | changed
2026-08-04 12:03:33.630243 | 
2026-08-04 12:03:33.630364 | TASK [setup-stack-user : Create .cache folder within BASE]
2026-08-04 12:03:33.845793 | controller | changed
2026-08-04 12:03:33.864108 | lab-sp-a1 | changed
2026-08-04 12:03:33.880399 | undercloud-client | changed
2026-08-04 12:03:33.898443 | 
2026-08-04 12:03:33.898575 | TASK [setup-tempest-user : Create tempest group]
2026-08-04 12:03:34.155674 | undercloud-client | changed
2026-08-04 12:03:34.201576 | controller | changed
2026-08-04 12:03:34.269054 | lab-sp-a1 | changed
2026-08-04 12:03:34.277308 | 
2026-08-04 12:03:34.277461 | TASK [setup-tempest-user : Create tempest user]
2026-08-04 12:03:34.657070 | controller | changed
2026-08-04 12:03:34.665470 | lab-sp-a1 | changed
2026-08-04 12:03:34.673467 | undercloud-client | changed
2026-08-04 12:03:34.681770 | 
2026-08-04 12:03:34.681950 | TASK [setup-tempest-user : Copy 51_tempest_sh to /etc/sudoers.d]
2026-08-04 12:03:35.386139 | lab-sp-a1 | changed
2026-08-04 12:03:35.397720 | controller | changed
2026-08-04 12:03:35.424893 | undercloud-client | changed
2026-08-04 12:03:35.444999 | 
2026-08-04 12:03:35.445155 | TASK [setup-devstack-source-dirs : Find all OpenStack source repos used by this job]
2026-08-04 12:03:35.820607 | controller | ok: Not all paths examined, check warnings for details
2026-08-04 12:03:35.842573 | lab-sp-a1 | ok: Not all paths examined, check warnings for details
2026-08-04 12:03:35.851330 | undercloud-client | ok: Not all paths examined, check warnings for details
2026-08-04 12:03:35.859790 | 
2026-08-04 12:03:35.859915 | LOOP [setup-devstack-source-dirs : Copy Zuul repos into devstack working directory]
2026-08-04 12:03:36.213995 | controller | ok: Item: Runtime: 0:00:00.109388
2026-08-04 12:03:36.249691 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.115818
2026-08-04 12:03:36.335954 | undercloud-client | ok: Item: Runtime: 0:00:00.164461
2026-08-04 12:03:36.891445 | controller | ok: Item: Runtime: 0:00:00.478160
2026-08-04 12:03:36.920979 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.472137
2026-08-04 12:03:37.282987 | controller | ok: Item: Runtime: 0:00:00.126871
2026-08-04 12:03:37.321577 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.126935
2026-08-04 12:03:37.463273 | controller | ok: Item: Runtime: 0:00:00.051385
2026-08-04 12:03:37.609189 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.051288
2026-08-04 12:03:37.696172 | undercloud-client | ok: Item: Runtime: 0:00:01.148974
2026-08-04 12:03:37.904994 | controller | ok: Item: Runtime: 0:00:00.253612
2026-08-04 12:03:38.072639 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.257314
2026-08-04 12:03:38.145899 | undercloud-client | ok: Item: Runtime: 0:00:00.245322
2026-08-04 12:03:38.392856 | undercloud-client | ok: Item: Runtime: 0:00:00.040494
2026-08-04 12:03:38.504743 | controller | ok: Item: Runtime: 0:00:00.411866
2026-08-04 12:03:38.663582 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.402543
2026-08-04 12:03:39.024851 | controller | ok: Item: Runtime: 0:00:00.327329
2026-08-04 12:03:39.111695 | undercloud-client | ok: Item: Runtime: 0:00:00.527473
2026-08-04 12:03:39.179981 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.314091
2026-08-04 12:03:40.115464 | undercloud-client | ok: Item: Runtime: 0:00:00.804334
2026-08-04 12:03:40.236768 | controller | ok: Item: Runtime: 0:00:01.006882
2026-08-04 12:03:40.385644 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.995887
2026-08-04 12:03:40.948673 | undercloud-client | ok: Item: Runtime: 0:00:00.630655
2026-08-04 12:03:41.401270 | controller | ok: Item: Runtime: 0:00:00.968661
2026-08-04 12:03:41.578492 | lab-sp-a1 | ok: Item: Runtime: 0:00:01.012759
2026-08-04 12:03:41.774373 | controller | ok: Item: Runtime: 0:00:00.166668
2026-08-04 12:03:41.957654 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.156048
2026-08-04 12:03:43.405010 | undercloud-client | ok: Item: Runtime: 0:00:02.256834
2026-08-04 12:03:44.217839 | controller | ok: Item: Runtime: 0:00:02.232263
2026-08-04 12:03:44.461712 | lab-sp-a1 | ok: Item: Runtime: 0:00:02.309208
2026-08-04 12:03:44.461830 | lab-sp-a1 | changed: All items complete
2026-08-04 12:03:44.461856 | 
2026-08-04 12:03:45.945400 | undercloud-client | ok: Item: Runtime: 0:00:02.327721
2026-08-04 12:03:46.508038 | undercloud-client | ok: Item: Runtime: 0:00:00.309700
2026-08-04 12:03:51.786271 | undercloud-client | ok: Item: Runtime: 0:00:05.080090
2026-08-04 12:03:51.812234 | 
2026-08-04 12:03:51.812389 | TASK [setup-devstack-source-dirs : Find top level github projects]
2026-08-04 12:03:52.007838 | controller | ok: Not all paths examined, check warnings for details
2026-08-04 12:03:52.026023 | lab-sp-a1 | ok: Not all paths examined, check warnings for details
2026-08-04 12:03:52.052321 | undercloud-client | ok: Not all paths examined, check warnings for details
2026-08-04 12:03:52.059613 | 
2026-08-04 12:03:52.059754 | TASK [setup-devstack-source-dirs : Find actual github repos]
2026-08-04 12:03:52.092796 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.095345 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.105727 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.113224 | 
2026-08-04 12:03:52.113363 | LOOP [setup-devstack-source-dirs : Copy github repos into devstack working directory]
2026-08-04 12:03:52.162588 | 
2026-08-04 12:03:52.162771 | LOOP [setup-devstack-source-dirs : Setup refspec for repos into devstack working directory]
2026-08-04 12:03:52.193784 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.194085 | 
2026-08-04 12:03:52.196592 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.200768 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.204776 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.207154 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.208757 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.211203 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.212911 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.215280 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.216926 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.218379 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.219276 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.220927 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.222897 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.223633 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.225136 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.227290 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.228143 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.229119 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.231321 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.232518 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.233144 | controller | skipping: Conditional result was False
2026-08-04 12:03:52.238350 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.239197 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.239893 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.240773 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.246637 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.246697 | 
2026-08-04 12:03:52.247381 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.248572 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.249186 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:52.252886 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.262382 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.262488 | 
2026-08-04 12:03:52.263225 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:52.282400 | 
2026-08-04 12:03:52.282542 | TASK [setup-devstack-source-dirs : Set ownership of repos]
2026-08-04 12:03:54.111738 | lab-sp-a1 | changed
2026-08-04 12:03:54.117338 | controller | changed
2026-08-04 12:03:54.378264 | undercloud-client | changed
2026-08-04 12:03:54.401356 | 
2026-08-04 12:03:54.401519 | TASK [setup-devstack-log-dir : Create logs directory]
2026-08-04 12:03:54.622356 | controller | changed
2026-08-04 12:03:54.638997 | lab-sp-a1 | changed
2026-08-04 12:03:54.654804 | undercloud-client | changed
2026-08-04 12:03:54.677387 | 
2026-08-04 12:03:54.677545 | TASK [setup-devstack-cache : Copy cached devstack files]
2026-08-04 12:03:55.030747 | lab-sp-a1 | find: ‘/opt/cache/files’: No such file or directory
2026-08-04 12:03:55.008625 | controller | find: ‘/opt/cache/files’: No such file or directory
2026-08-04 12:03:55.041435 | undercloud-client | find: ‘/opt/cache/files’: No such file or directory
2026-08-04 12:03:55.314254 | controller | ERROR
2026-08-04 12:03:55.314439 | controller | {
2026-08-04 12:03:55.314468 | controller |   "delta": "0:00:00.005306",
2026-08-04 12:03:55.314488 | controller |   "end": "2026-08-04 12:03:55.009018",
2026-08-04 12:03:55.314505 | controller |   "msg": "non-zero return code",
2026-08-04 12:03:55.314543 | controller |   "rc": 1,
2026-08-04 12:03:55.314590 | controller |   "start": "2026-08-04 12:03:55.003712"
2026-08-04 12:03:55.314611 | controller | }
2026-08-04 12:03:55.314634 | controller | ERROR: Ignoring Errors
2026-08-04 12:03:55.324402 | lab-sp-a1 | ERROR
2026-08-04 12:03:55.324499 | lab-sp-a1 | {
2026-08-04 12:03:55.324573 | lab-sp-a1 |   "delta": "0:00:00.005304",
2026-08-04 12:03:55.324606 | lab-sp-a1 |   "end": "2026-08-04 12:03:55.031145",
2026-08-04 12:03:55.324624 | lab-sp-a1 |   "msg": "non-zero return code",
2026-08-04 12:03:55.324652 | lab-sp-a1 |   "rc": 1,
2026-08-04 12:03:55.324670 | lab-sp-a1 |   "start": "2026-08-04 12:03:55.025841"
2026-08-04 12:03:55.324686 | lab-sp-a1 | }
2026-08-04 12:03:55.324707 | lab-sp-a1 | ERROR: Ignoring Errors
2026-08-04 12:03:55.333477 | undercloud-client | ERROR
2026-08-04 12:03:55.333576 | undercloud-client | {
2026-08-04 12:03:55.333607 | undercloud-client |   "delta": "0:00:00.004860",
2026-08-04 12:03:55.333626 | undercloud-client |   "end": "2026-08-04 12:03:55.041836",
2026-08-04 12:03:55.333646 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:03:55.333663 | undercloud-client |   "rc": 1,
2026-08-04 12:03:55.333679 | undercloud-client |   "start": "2026-08-04 12:03:55.036976"
2026-08-04 12:03:55.333695 | undercloud-client | }
2026-08-04 12:03:55.333720 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:03:55.339290 | 
2026-08-04 12:03:55.339375 | TASK [setup-devstack-cache : Set ownership of cached files]
2026-08-04 12:03:55.573505 | controller | ok
2026-08-04 12:03:55.579682 | lab-sp-a1 | ok
2026-08-04 12:03:55.584482 | undercloud-client | ok
2026-08-04 12:03:55.607390 | 
2026-08-04 12:03:55.607516 | TASK [start-fresh-logging : Check for /bin/journalctl file]
2026-08-04 12:03:55.882705 | lab-sp-a1 | /usr/bin/journalctl
2026-08-04 12:03:55.871621 | controller | /usr/bin/journalctl
2026-08-04 12:03:55.911967 | undercloud-client | /usr/bin/journalctl
2026-08-04 12:03:56.197560 | controller | ok: Runtime: 0:00:00.004165
2026-08-04 12:03:56.211328 | lab-sp-a1 | ok: Runtime: 0:00:00.003753
2026-08-04 12:03:56.222977 | undercloud-client | ok: Runtime: 0:00:00.004409
2026-08-04 12:03:56.229491 | 
2026-08-04 12:03:56.229656 | TASK [start-fresh-logging : Get current date]
2026-08-04 12:03:56.590838 | lab-sp-a1 | 2026-08-04 12:03:56
2026-08-04 12:03:56.572654 | controller | 2026-08-04 12:03:56
2026-08-04 12:03:56.593818 | undercloud-client | 2026-08-04 12:03:56
2026-08-04 12:03:56.873181 | controller | ok: Runtime: 0:00:00.003719
2026-08-04 12:03:56.882667 | lab-sp-a1 | ok: Runtime: 0:00:00.003353
2026-08-04 12:03:56.891740 | undercloud-client | ok: Runtime: 0:00:00.004275
2026-08-04 12:03:56.898727 | 
2026-08-04 12:03:56.898866 | TASK [start-fresh-logging : Copy current date to log-start-timestamp.txt]
2026-08-04 12:03:57.372477 | controller | changed
2026-08-04 12:03:57.418910 | undercloud-client | changed
2026-08-04 12:03:57.425286 | lab-sp-a1 | changed
2026-08-04 12:03:57.431730 | 
2026-08-04 12:03:57.431855 | TASK [start-fresh-logging : Stop rsyslog]
2026-08-04 12:03:57.451317 | controller | skipping: Conditional result was False
2026-08-04 12:03:57.464668 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:57.475879 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:57.482055 | 
2026-08-04 12:03:57.482185 | TASK [start-fresh-logging : Save syslog file prior to devstack run]
2026-08-04 12:03:58.063868 | controller | skipping: Conditional result was False
2026-08-04 12:03:58.074486 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:58.085558 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:58.091496 | 
2026-08-04 12:03:58.091583 | TASK [start-fresh-logging : Save kern.log file prior to devstack run]
2026-08-04 12:03:58.659356 | controller | skipping: Conditional result was False
2026-08-04 12:03:58.671429 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:58.682580 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:58.688765 | 
2026-08-04 12:03:58.688834 | TASK [start-fresh-logging : Recreate syslog file]
2026-08-04 12:03:58.754482 | controller | skipping: Conditional result was False
2026-08-04 12:03:58.773008 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:58.784261 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:58.790910 | 
2026-08-04 12:03:58.791037 | TASK [start-fresh-logging : Recreate syslog file owner and group]
2026-08-04 12:03:59.366448 | controller | skipping: Conditional result was False
2026-08-04 12:03:59.367186 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:59.367360 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:59.373617 | 
2026-08-04 12:03:59.373719 | TASK [start-fresh-logging : Recreate syslog file permissions]
2026-08-04 12:03:59.949487 | controller | skipping: Conditional result was False
2026-08-04 12:03:59.964864 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:03:59.977239 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:03:59.986734 | 
2026-08-04 12:03:59.986916 | TASK [start-fresh-logging : Add read permissions to all on syslog file]
2026-08-04 12:04:00.009955 | controller | skipping: Conditional result was False
2026-08-04 12:04:00.028082 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:04:00.039700 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:04:00.052229 | 
2026-08-04 12:04:00.052401 | TASK [start-fresh-logging : Recreate kern.log file]
2026-08-04 12:04:00.076277 | controller | skipping: Conditional result was False
2026-08-04 12:04:00.093657 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:04:00.105277 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:04:00.114478 | 
2026-08-04 12:04:00.114673 | TASK [start-fresh-logging : Recreate kern.log file owner and group]
2026-08-04 12:04:00.756232 | controller | skipping: Conditional result was False
2026-08-04 12:04:00.756852 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:04:00.757057 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:04:00.763084 | 
2026-08-04 12:04:00.763177 | TASK [start-fresh-logging : Recreate kern.log file permissions]
2026-08-04 12:04:01.337132 | controller | skipping: Conditional result was False
2026-08-04 12:04:01.348180 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:04:01.359389 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:04:01.366080 | 
2026-08-04 12:04:01.366218 | TASK [start-fresh-logging : Add read permissions to all on kern.log file]
2026-08-04 12:04:01.384973 | controller | skipping: Conditional result was False
2026-08-04 12:04:01.400311 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:04:01.411656 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:04:01.419870 | 
2026-08-04 12:04:01.419997 | TASK [start-fresh-logging : Start rsyslog]
2026-08-04 12:04:01.449669 | controller | skipping: Conditional result was False
2026-08-04 12:04:01.460796 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:04:01.461007 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:04:01.485966 | 
2026-08-04 12:04:01.486094 | TASK [write-devstack-local-conf : Write a job-specific local_conf file]
2026-08-04 12:04:01.922996 | controller | ok
2026-08-04 12:04:01.933993 | lab-sp-a1 | ok
2026-08-04 12:04:01.941396 | undercloud-client | ok
2026-08-04 12:04:01.959505 | 
2026-08-04 12:04:01.959620 | PLAY RECAP
2026-08-04 12:04:01.959681 | controller | ok: 37 changed: 23 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 1
2026-08-04 12:04:01.959723 | lab-sp-a1 | ok: 37 changed: 23 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 1
2026-08-04 12:04:01.959760 | undercloud-client | ok: 37 changed: 23 unreachable: 0 failed: 0 skipped: 18 rescued: 0 ignored: 1
2026-08-04 12:04:01.959790 | 
2026-08-04 12:04:02.119767 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/pre.yaml@master]
2026-08-04 12:04:02.129835 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/01-disable-unattended-upgrades.yaml@master]
2026-08-04 12:04:02.972094 | 
2026-08-04 12:04:02.972250 | PLAY [all]
2026-08-04 12:04:02.988962 | 
2026-08-04 12:04:02.989142 | LOOP [Disable apt periodic updates]
2026-08-04 12:04:03.718434 | controller | changed: line added
2026-08-04 12:04:03.839816 | undercloud-client | changed: line added
2026-08-04 12:04:03.902862 | controller | changed: line added
2026-08-04 12:04:03.940272 | lab-sp-a1 | changed: line added
2026-08-04 12:04:03.940429 | lab-sp-a1 | changed: All items complete
2026-08-04 12:04:03.940457 | 
2026-08-04 12:04:04.052255 | undercloud-client | changed: line added
2026-08-04 12:04:04.117015 | lab-sp-a1 | changed: line added
2026-08-04 12:04:04.171337 | 
2026-08-04 12:04:04.171483 | LOOP [Stop and disable units related to automatic, unattended upgrades]
2026-08-04 12:04:05.305281 | controller | Synchronizing state of unattended-upgrades.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
2026-08-04 12:04:05.305587 | controller | Executing: /usr/lib/systemd/systemd-sysv-install disable unattended-upgrades
2026-08-04 12:04:05.305628 | controller | Removed "/etc/systemd/system/multi-user.target.wants/unattended-upgrades.service".
2026-08-04 12:04:05.305685 | controller | ok: Item: unattended-upgrades Runtime: 0:00:00.780693
2026-08-04 12:04:05.305738 | controller | changed: All items complete
2026-08-04 12:04:05.305770 | 
2026-08-04 12:04:05.316370 | lab-sp-a1 | Synchronizing state of unattended-upgrades.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
2026-08-04 12:04:05.316519 | lab-sp-a1 | Executing: /usr/lib/systemd/systemd-sysv-install disable unattended-upgrades
2026-08-04 12:04:05.316565 | lab-sp-a1 | Removed "/etc/systemd/system/multi-user.target.wants/unattended-upgrades.service".
2026-08-04 12:04:05.316596 | lab-sp-a1 | ok: Item: unattended-upgrades Runtime: 0:00:00.783991
2026-08-04 12:04:05.653839 | undercloud-client | Synchronizing state of unattended-upgrades.service with SysV service script with /usr/lib/systemd/systemd-sysv-install.
2026-08-04 12:04:05.653942 | undercloud-client | Executing: /usr/lib/systemd/systemd-sysv-install disable unattended-upgrades
2026-08-04 12:04:05.653971 | undercloud-client | Removed "/etc/systemd/system/multi-user.target.wants/unattended-upgrades.service".
2026-08-04 12:04:05.654003 | undercloud-client | ok: Item: unattended-upgrades Runtime: 0:00:01.120190
2026-08-04 12:04:05.756272 | controller | Disabling 'apt-daily.service', but its triggering units are still active:
2026-08-04 12:04:05.756487 | controller | apt-daily.timer
2026-08-04 12:04:05.756519 | controller | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=,
2026-08-04 12:04:05.756566 | controller | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for
2026-08-04 12:04:05.756587 | controller | template units). This means they are not meant to be enabled or disabled using systemctl.
2026-08-04 12:04:05.756606 | controller |
2026-08-04 12:04:05.756626 | controller | Possible reasons for having these kinds of units are:
2026-08-04 12:04:05.756646 | controller | • A unit may be statically enabled by being symlinked from another unit's
2026-08-04 12:04:05.756665 | controller |   .wants/, .requires/, or .upholds/ directory.
2026-08-04 12:04:05.756683 | controller | • A unit's purpose may be to act as a helper for some other unit which has
2026-08-04 12:04:05.756701 | controller |   a requirement dependency on it.
2026-08-04 12:04:05.756719 | controller | • A unit may be started when needed via activation (socket, path, timer,
2026-08-04 12:04:05.756737 | controller |   D-Bus, udev, scripted systemctl call, ...).
2026-08-04 12:04:05.756754 | controller | • In case of template units, the unit is meant to be enabled with some
2026-08-04 12:04:05.756771 | controller |   instance name specified.
2026-08-04 12:04:05.756789 | controller | Stopping 'apt-daily.service', but its triggering units are still active:
2026-08-04 12:04:05.756806 | controller | apt-daily.timer
2026-08-04 12:04:05.756834 | controller | ok: Item: apt-daily.service Runtime: 0:00:00.237555
2026-08-04 12:04:05.768721 | lab-sp-a1 | Disabling 'apt-daily.service', but its triggering units are still active:
2026-08-04 12:04:05.768952 | lab-sp-a1 | apt-daily.timer
2026-08-04 12:04:05.769029 | lab-sp-a1 | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=,
2026-08-04 12:04:05.769059 | lab-sp-a1 | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for
2026-08-04 12:04:05.769083 | lab-sp-a1 | template units). This means they are not meant to be enabled or disabled using systemctl.
2026-08-04 12:04:05.769121 | lab-sp-a1 |
2026-08-04 12:04:05.769147 | lab-sp-a1 | Possible reasons for having these kinds of units are:
2026-08-04 12:04:05.769171 | lab-sp-a1 | • A unit may be statically enabled by being symlinked from another unit's
2026-08-04 12:04:05.769196 | lab-sp-a1 |   .wants/, .requires/, or .upholds/ directory.
2026-08-04 12:04:05.769219 | lab-sp-a1 | • A unit's purpose may be to act as a helper for some other unit which has
2026-08-04 12:04:05.769242 | lab-sp-a1 |   a requirement dependency on it.
2026-08-04 12:04:05.769275 | lab-sp-a1 | • A unit may be started when needed via activation (socket, path, timer,
2026-08-04 12:04:05.769300 | lab-sp-a1 |   D-Bus, udev, scripted systemctl call, ...).
2026-08-04 12:04:05.769323 | lab-sp-a1 | • In case of template units, the unit is meant to be enabled with some
2026-08-04 12:04:05.769347 | lab-sp-a1 |   instance name specified.
2026-08-04 12:04:05.769371 | lab-sp-a1 | Stopping 'apt-daily.service', but its triggering units are still active:
2026-08-04 12:04:05.769393 | lab-sp-a1 | apt-daily.timer
2026-08-04 12:04:05.769431 | lab-sp-a1 | ok: Item: apt-daily.service Runtime: 0:00:00.243732
2026-08-04 12:04:06.216756 | controller | Removed "/etc/systemd/system/timers.target.wants/apt-daily.timer".
2026-08-04 12:04:06.216873 | controller | ok: Item: apt-daily.timer Runtime: 0:00:00.251651
2026-08-04 12:04:06.228025 | undercloud-client | Disabling 'apt-daily.service', but its triggering units are still active:
2026-08-04 12:04:06.228136 | undercloud-client | apt-daily.timer
2026-08-04 12:04:06.228162 | undercloud-client | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=,
2026-08-04 12:04:06.228181 | undercloud-client | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for
2026-08-04 12:04:06.228197 | undercloud-client | template units). This means they are not meant to be enabled or disabled using systemctl.
2026-08-04 12:04:06.228214 | undercloud-client |
2026-08-04 12:04:06.228231 | undercloud-client | Possible reasons for having these kinds of units are:
2026-08-04 12:04:06.228267 | undercloud-client | • A unit may be statically enabled by being symlinked from another unit's
2026-08-04 12:04:06.228289 | undercloud-client |   .wants/, .requires/, or .upholds/ directory.
2026-08-04 12:04:06.228306 | undercloud-client | • A unit's purpose may be to act as a helper for some other unit which has
2026-08-04 12:04:06.228324 | undercloud-client |   a requirement dependency on it.
2026-08-04 12:04:06.228341 | undercloud-client | • A unit may be started when needed via activation (socket, path, timer,
2026-08-04 12:04:06.228357 | undercloud-client |   D-Bus, udev, scripted systemctl call, ...).
2026-08-04 12:04:06.228374 | undercloud-client | • In case of template units, the unit is meant to be enabled with some
2026-08-04 12:04:06.228391 | undercloud-client |   instance name specified.
2026-08-04 12:04:06.228407 | undercloud-client | Stopping 'apt-daily.service', but its triggering units are still active:
2026-08-04 12:04:06.228423 | undercloud-client | apt-daily.timer
2026-08-04 12:04:06.228449 | undercloud-client | ok: Item: apt-daily.service Runtime: 0:00:00.336534
2026-08-04 12:04:06.242579 | lab-sp-a1 | Removed "/etc/systemd/system/timers.target.wants/apt-daily.timer".
2026-08-04 12:04:06.242734 | lab-sp-a1 | ok: Item: apt-daily.timer Runtime: 0:00:00.280005
2026-08-04 12:04:06.703733 | controller | Removed "/etc/systemd/system/timers.target.wants/apt-daily-upgrade.timer".
2026-08-04 12:04:06.703860 | controller | ok: Item: apt-daily-upgrade.timer Runtime: 0:00:00.272749
2026-08-04 12:04:06.719719 | lab-sp-a1 | Removed "/etc/systemd/system/timers.target.wants/apt-daily-upgrade.timer".
2026-08-04 12:04:06.719850 | lab-sp-a1 | ok: Item: apt-daily-upgrade.timer Runtime: 0:00:00.269316
2026-08-04 12:04:07.165136 | controller | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=,
2026-08-04 12:04:07.165253 | controller | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for
2026-08-04 12:04:07.165283 | controller | template units). This means they are not meant to be enabled or disabled using systemctl.
2026-08-04 12:04:07.165313 | controller |
2026-08-04 12:04:07.165342 | controller | Possible reasons for having these kinds of units are:
2026-08-04 12:04:07.165372 | controller | • A unit may be statically enabled by being symlinked from another unit's
2026-08-04 12:04:07.165394 | controller |   .wants/, .requires/, or .upholds/ directory.
2026-08-04 12:04:07.165414 | controller | • A unit's purpose may be to act as a helper for some other unit which has
2026-08-04 12:04:07.165433 | controller |   a requirement dependency on it.
2026-08-04 12:04:07.165451 | controller | • A unit may be started when needed via activation (socket, path, timer,
2026-08-04 12:04:07.165470 | controller |   D-Bus, udev, scripted systemctl call, ...).
2026-08-04 12:04:07.165489 | controller | • In case of template units, the unit is meant to be enabled with some
2026-08-04 12:04:07.165507 | controller |   instance name specified.
2026-08-04 12:04:07.165561 | controller | ok: Item: apt-daily-upgrade.service Runtime: 0:00:00.248488
2026-08-04 12:04:07.205327 | lab-sp-a1 | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=,
2026-08-04 12:04:07.205464 | lab-sp-a1 | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for
2026-08-04 12:04:07.205499 | lab-sp-a1 | template units). This means they are not meant to be enabled or disabled using systemctl.
2026-08-04 12:04:07.205536 | lab-sp-a1 |
2026-08-04 12:04:07.205563 | lab-sp-a1 | Possible reasons for having these kinds of units are:
2026-08-04 12:04:07.205583 | lab-sp-a1 | • A unit may be statically enabled by being symlinked from another unit's
2026-08-04 12:04:07.205603 | lab-sp-a1 |   .wants/, .requires/, or .upholds/ directory.
2026-08-04 12:04:07.205621 | lab-sp-a1 | • A unit's purpose may be to act as a helper for some other unit which has
2026-08-04 12:04:07.205638 | lab-sp-a1 |   a requirement dependency on it.
2026-08-04 12:04:07.205668 | lab-sp-a1 | • A unit may be started when needed via activation (socket, path, timer,
2026-08-04 12:04:07.205685 | lab-sp-a1 |   D-Bus, udev, scripted systemctl call, ...).
2026-08-04 12:04:07.205702 | lab-sp-a1 | • In case of template units, the unit is meant to be enabled with some
2026-08-04 12:04:07.205719 | lab-sp-a1 |   instance name specified.
2026-08-04 12:04:07.205749 | lab-sp-a1 | ok: Item: apt-daily-upgrade.service Runtime: 0:00:00.268786
2026-08-04 12:04:07.299185 | undercloud-client | Removed "/etc/systemd/system/timers.target.wants/apt-daily.timer".
2026-08-04 12:04:07.299302 | undercloud-client | ok: Item: apt-daily.timer Runtime: 0:00:00.669488
2026-08-04 12:04:07.299332 | undercloud-client | changed: All items complete
2026-08-04 12:04:07.299351 | 
2026-08-04 12:04:08.346855 | undercloud-client | Removed "/etc/systemd/system/timers.target.wants/apt-daily-upgrade.timer".
2026-08-04 12:04:08.346985 | undercloud-client | ok: Item: apt-daily-upgrade.timer Runtime: 0:00:00.608328
2026-08-04 12:04:09.019701 | undercloud-client | The unit files have no installation config (WantedBy=, RequiredBy=, UpheldBy=,
2026-08-04 12:04:09.019827 | undercloud-client | Also=, or Alias= settings in the [Install] section, and DefaultInstance= for
2026-08-04 12:04:09.019863 | undercloud-client | template units). This means they are not meant to be enabled or disabled using systemctl.
2026-08-04 12:04:09.019884 | undercloud-client |
2026-08-04 12:04:09.019904 | undercloud-client | Possible reasons for having these kinds of units are:
2026-08-04 12:04:09.019964 | undercloud-client | • A unit may be statically enabled by being symlinked from another unit's
2026-08-04 12:04:09.019993 | undercloud-client |   .wants/, .requires/, or .upholds/ directory.
2026-08-04 12:04:09.020011 | undercloud-client | • A unit's purpose may be to act as a helper for some other unit which has
2026-08-04 12:04:09.020029 | undercloud-client |   a requirement dependency on it.
2026-08-04 12:04:09.020046 | undercloud-client | • A unit may be started when needed via activation (socket, path, timer,
2026-08-04 12:04:09.020063 | undercloud-client |   D-Bus, udev, scripted systemctl call, ...).
2026-08-04 12:04:09.020080 | undercloud-client | • In case of template units, the unit is meant to be enabled with some
2026-08-04 12:04:09.020109 | undercloud-client |   instance name specified.
2026-08-04 12:04:09.020139 | undercloud-client | ok: Item: apt-daily-upgrade.service Runtime: 0:00:00.315454
2026-08-04 12:04:09.036645 | 
2026-08-04 12:04:09.036769 | TASK [Wait for the lock to get freed]
2026-08-04 12:04:09.628172 | controller | ERROR
2026-08-04 12:04:09.628439 | controller | {
2026-08-04 12:04:09.628472 | controller |   "attempts": 1,
2026-08-04 12:04:09.628491 | controller |   "delta": "0:00:00.028674",
2026-08-04 12:04:09.628507 | controller |   "end": "2026-08-04 12:04:09.351654",
2026-08-04 12:04:09.628546 | controller |   "msg": "non-zero return code",
2026-08-04 12:04:09.628565 | controller |   "rc": 1,
2026-08-04 12:04:09.628581 | controller |   "start": "2026-08-04 12:04:09.322980"
2026-08-04 12:04:09.628596 | controller | }
2026-08-04 12:04:09.628617 | controller | ERROR: Ignoring Errors
2026-08-04 12:04:09.638358 | undercloud-client | ERROR
2026-08-04 12:04:09.638506 | undercloud-client | {
2026-08-04 12:04:09.638561 | undercloud-client |   "attempts": 1,
2026-08-04 12:04:09.638585 | undercloud-client |   "delta": "0:00:00.019314",
2026-08-04 12:04:09.638602 | undercloud-client |   "end": "2026-08-04 12:04:09.354840",
2026-08-04 12:04:09.638624 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:04:09.638640 | undercloud-client |   "rc": 1,
2026-08-04 12:04:09.638656 | undercloud-client |   "start": "2026-08-04 12:04:09.335526"
2026-08-04 12:04:09.638671 | undercloud-client | }
2026-08-04 12:04:09.638693 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:04:09.689938 | lab-sp-a1 | ERROR
2026-08-04 12:04:09.690088 | lab-sp-a1 | {
2026-08-04 12:04:09.690135 | lab-sp-a1 |   "attempts": 1,
2026-08-04 12:04:09.690164 | lab-sp-a1 |   "delta": "0:00:00.028814",
2026-08-04 12:04:09.690181 | lab-sp-a1 |   "end": "2026-08-04 12:04:09.377392",
2026-08-04 12:04:09.690197 | lab-sp-a1 |   "msg": "non-zero return code",
2026-08-04 12:04:09.690229 | lab-sp-a1 |   "rc": 1,
2026-08-04 12:04:09.690266 | lab-sp-a1 |   "start": "2026-08-04 12:04:09.348578"
2026-08-04 12:04:09.690285 | lab-sp-a1 | }
2026-08-04 12:04:09.690306 | lab-sp-a1 | ERROR: Ignoring Errors
2026-08-04 12:04:09.691683 | 
2026-08-04 12:04:09.691730 | PLAY RECAP
2026-08-04 12:04:09.691770 | controller | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-04 12:04:09.691795 | lab-sp-a1 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-04 12:04:09.691817 | undercloud-client | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 1
2026-08-04 12:04:09.691833 | 
2026-08-04 12:04:09.850361 | 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 12:04:09.861880 | 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 12:04:10.656936 | 
2026-08-04 12:04:10.657095 | PLAY [all]
2026-08-04 12:04:10.673493 | 
2026-08-04 12:04:10.673669 | TASK [Install the package linux-modules-extra for the currently running kernel]
2026-08-04 12:04:11.886210 | lab-sp-a1 | Get:1 http://security.ubuntu.com/ubuntu noble-security InRelease [126 kB]
2026-08-04 12:04:11.896093 | lab-sp-a1 | Hit:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-08-04 12:04:11.944403 | lab-sp-a1 | Get:3 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB]
2026-08-04 12:04:12.192083 | lab-sp-a1 | Get:4 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB]
2026-08-04 12:04:11.761529 | undercloud-client | Get:1 http://security.ubuntu.com/ubuntu noble-security InRelease [126 kB]
2026-08-04 12:04:11.817914 | undercloud-client | Hit:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-08-04 12:04:11.855794 | undercloud-client | Get:3 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB]
2026-08-04 12:04:12.101966 | undercloud-client | Get:4 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB]
2026-08-04 12:04:12.110379 | undercloud-client | Get:5 http://security.ubuntu.com/ubuntu noble-security/main amd64 Packages [897 kB]
2026-08-04 12:04:12.204229 | undercloud-client | Get:6 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [1174 kB]
2026-08-04 12:04:11.843175 | controller | Get:1 http://security.ubuntu.com/ubuntu noble-security InRelease [126 kB]
2026-08-04 12:04:11.854050 | controller | Hit:2 http://lab.clouds.archive.ubuntu.com/ubuntu noble InRelease
2026-08-04 12:04:11.881975 | controller | Get:3 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates InRelease [126 kB]
2026-08-04 12:04:12.047819 | controller | Get:4 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports InRelease [126 kB]
2026-08-04 12:04:12.086183 | controller | Get:5 http://security.ubuntu.com/ubuntu noble-security/main amd64 Packages [897 kB]
2026-08-04 12:04:12.173268 | controller | Get:6 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [1174 kB]
2026-08-04 12:04:12.283911 | controller | Get:7 http://security.ubuntu.com/ubuntu noble-security/main Translation-en [198 kB]
2026-08-04 12:04:12.223934 | lab-sp-a1 | Get:5 http://security.ubuntu.com/ubuntu noble-security/main amd64 Packages [897 kB]
2026-08-04 12:04:12.307648 | lab-sp-a1 | Get:6 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Packages [1174 kB]
2026-08-04 12:04:12.687636 | lab-sp-a1 | Get:7 http://security.ubuntu.com/ubuntu noble-security/main Translation-en [198 kB]
2026-08-04 12:04:12.698057 | lab-sp-a1 | Get:8 http://security.ubuntu.com/ubuntu noble-security/main amd64 Components [46.3 kB]
2026-08-04 12:04:12.701399 | lab-sp-a1 | Get:9 http://security.ubuntu.com/ubuntu noble-security/main amd64 c-n-f Metadata [11.9 kB]
2026-08-04 12:04:12.701612 | lab-sp-a1 | Get:10 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Packages [1199 kB]
2026-08-04 12:04:12.344126 | undercloud-client | Get:7 http://security.ubuntu.com/ubuntu noble-security/main Translation-en [198 kB]
2026-08-04 12:04:12.351211 | undercloud-client | Get:8 http://security.ubuntu.com/ubuntu noble-security/main amd64 Components [46.3 kB]
2026-08-04 12:04:12.352371 | undercloud-client | Get:9 http://security.ubuntu.com/ubuntu noble-security/main amd64 c-n-f Metadata [11.9 kB]
2026-08-04 12:04:12.352387 | undercloud-client | Get:10 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Packages [1199 kB]
2026-08-04 12:04:12.533176 | undercloud-client | Get:11 http://security.ubuntu.com/ubuntu noble-security/universe Translation-en [239 kB]
2026-08-04 12:04:12.536342 | undercloud-client | Get:12 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Components [76.3 kB]
2026-08-04 12:04:12.536369 | undercloud-client | Get:13 http://security.ubuntu.com/ubuntu noble-security/universe amd64 c-n-f Metadata [24.2 kB]
2026-08-04 12:04:12.537152 | undercloud-client | Get:14 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [1273 kB]
2026-08-04 12:04:12.585330 | undercloud-client | Get:15 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main Translation-en [280 kB]
2026-08-04 12:04:12.612548 | undercloud-client | Get:16 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Components [180 kB]
2026-08-04 12:04:12.632117 | undercloud-client | Get:17 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 c-n-f Metadata [17.7 kB]
2026-08-04 12:04:12.632643 | undercloud-client | Get:18 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1681 kB]
2026-08-04 12:04:12.723642 | undercloud-client | Get:19 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe Translation-en [334 kB]
2026-08-04 12:04:12.733680 | undercloud-client | Get:20 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Components [388 kB]
2026-08-04 12:04:12.749528 | undercloud-client | Get:21 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 c-n-f Metadata [34.9 kB]
2026-08-04 12:04:12.749557 | undercloud-client | Get:22 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [1398 kB]
2026-08-04 12:04:12.291354 | controller | Get:8 http://security.ubuntu.com/ubuntu noble-security/main amd64 Components [46.3 kB]
2026-08-04 12:04:12.293460 | controller | Get:9 http://security.ubuntu.com/ubuntu noble-security/main amd64 c-n-f Metadata [11.9 kB]
2026-08-04 12:04:12.293730 | controller | Get:10 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Packages [1199 kB]
2026-08-04 12:04:12.428862 | controller | Get:11 http://security.ubuntu.com/ubuntu noble-security/universe Translation-en [239 kB]
2026-08-04 12:04:12.430030 | controller | Get:12 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Components [76.3 kB]
2026-08-04 12:04:12.430547 | controller | Get:13 http://security.ubuntu.com/ubuntu noble-security/universe amd64 c-n-f Metadata [24.2 kB]
2026-08-04 12:04:12.430784 | controller | Get:14 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [1273 kB]
2026-08-04 12:04:12.437060 | controller | Get:15 http://security.ubuntu.com/ubuntu noble-security/restricted Translation-en [290 kB]
2026-08-04 12:04:12.438871 | controller | Get:16 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Components [212 B]
2026-08-04 12:04:12.438890 | controller | Get:17 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 c-n-f Metadata [444 B]
2026-08-04 12:04:12.439095 | controller | Get:18 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Packages [40.3 kB]
2026-08-04 12:04:12.439531 | controller | Get:19 http://security.ubuntu.com/ubuntu noble-security/multiverse Translation-en [10.6 kB]
2026-08-04 12:04:12.539193 | controller | Get:20 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main Translation-en [280 kB]
2026-08-04 12:04:12.547771 | controller | Get:21 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Components [208 B]
2026-08-04 12:04:12.548033 | controller | Get:22 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 c-n-f Metadata [468 B]
2026-08-04 12:04:12.578328 | controller | Get:23 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Components [180 kB]
2026-08-04 12:04:12.583443 | controller | Get:24 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 c-n-f Metadata [17.7 kB]
2026-08-04 12:04:12.584441 | controller | Get:25 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1681 kB]
2026-08-04 12:04:12.699149 | controller | Get:26 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe Translation-en [334 kB]
2026-08-04 12:04:12.706229 | controller | Get:27 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Components [388 kB]
2026-08-04 12:04:12.714270 | controller | Get:28 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 c-n-f Metadata [34.9 kB]
2026-08-04 12:04:12.714969 | controller | Get:29 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [1398 kB]
2026-08-04 12:04:12.748457 | controller | Get:30 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted Translation-en [316 kB]
2026-08-04 12:04:12.752909 | controller | Get:31 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Components [212 B]
2026-08-04 12:04:12.752965 | controller | Get:32 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 c-n-f Metadata [456 B]
2026-08-04 12:04:12.752976 | controller | Get:33 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Packages [45.4 kB]
2026-08-04 12:04:12.753616 | controller | Get:34 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse Translation-en [12.3 kB]
2026-08-04 12:04:12.753650 | controller | Get:35 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Components [940 B]
2026-08-04 12:04:12.779857 | controller | Get:36 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 c-n-f Metadata [656 B]
2026-08-04 12:04:12.779901 | controller | Get:37 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Packages [40.6 kB]
2026-08-04 12:04:12.780867 | controller | Get:38 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main Translation-en [9172 B]
2026-08-04 12:04:12.781817 | lab-sp-a1 | Get:11 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main Translation-en [280 kB]
2026-08-04 12:04:12.834063 | lab-sp-a1 | Get:12 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 Components [180 kB]
2026-08-04 12:04:12.846228 | lab-sp-a1 | Get:13 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/main amd64 c-n-f Metadata [17.7 kB]
2026-08-04 12:04:12.850064 | lab-sp-a1 | Get:14 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Packages [1681 kB]
2026-08-04 12:04:12.864490 | lab-sp-a1 | Get:15 http://security.ubuntu.com/ubuntu noble-security/universe Translation-en [239 kB]
2026-08-04 12:04:12.877015 | lab-sp-a1 | Get:16 http://security.ubuntu.com/ubuntu noble-security/universe amd64 Components [76.3 kB]
2026-08-04 12:04:12.878788 | lab-sp-a1 | Get:17 http://security.ubuntu.com/ubuntu noble-security/universe amd64 c-n-f Metadata [24.2 kB]
2026-08-04 12:04:12.879672 | lab-sp-a1 | Get:18 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Packages [1273 kB]
2026-08-04 12:04:12.937108 | lab-sp-a1 | Get:19 http://security.ubuntu.com/ubuntu noble-security/restricted Translation-en [290 kB]
2026-08-04 12:04:12.946679 | lab-sp-a1 | Get:20 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe Translation-en [334 kB]
2026-08-04 12:04:12.957528 | lab-sp-a1 | Get:21 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 Components [388 kB]
2026-08-04 12:04:12.957884 | lab-sp-a1 | Get:22 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Components [212 B]
2026-08-04 12:04:12.958085 | lab-sp-a1 | Get:23 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 c-n-f Metadata [444 B]
2026-08-04 12:04:12.958238 | lab-sp-a1 | Get:24 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Packages [40.3 kB]
2026-08-04 12:04:12.958963 | lab-sp-a1 | Get:25 http://security.ubuntu.com/ubuntu noble-security/multiverse Translation-en [10.6 kB]
2026-08-04 12:04:12.959520 | lab-sp-a1 | Get:26 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Components [208 B]
2026-08-04 12:04:12.970222 | lab-sp-a1 | Get:27 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/universe amd64 c-n-f Metadata [34.9 kB]
2026-08-04 12:04:12.970996 | lab-sp-a1 | Get:28 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Packages [1398 kB]
2026-08-04 12:04:12.973958 | lab-sp-a1 | Get:29 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 c-n-f Metadata [468 B]
2026-08-04 12:04:13.002167 | lab-sp-a1 | Get:30 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted Translation-en [316 kB]
2026-08-04 12:04:13.007434 | lab-sp-a1 | Get:31 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Components [212 B]
2026-08-04 12:04:13.007668 | lab-sp-a1 | Get:32 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 c-n-f Metadata [456 B]
2026-08-04 12:04:13.007817 | lab-sp-a1 | Get:33 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Packages [45.4 kB]
2026-08-04 12:04:13.008364 | lab-sp-a1 | Get:34 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse Translation-en [12.3 kB]
2026-08-04 12:04:13.008684 | lab-sp-a1 | Get:35 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Components [940 B]
2026-08-04 12:04:13.034750 | lab-sp-a1 | Get:36 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 c-n-f Metadata [656 B]
2026-08-04 12:04:13.035620 | lab-sp-a1 | Get:37 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Packages [40.6 kB]
2026-08-04 12:04:13.036392 | lab-sp-a1 | Get:38 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main Translation-en [9172 B]
2026-08-04 12:04:13.049533 | lab-sp-a1 | Get:39 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Components [5772 B]
2026-08-04 12:04:13.051084 | lab-sp-a1 | Get:40 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 c-n-f Metadata [368 B]
2026-08-04 12:04:13.053631 | lab-sp-a1 | Get:41 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Packages [31.0 kB]
2026-08-04 12:04:13.057265 | lab-sp-a1 | Get:42 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe Translation-en [18.6 kB]
2026-08-04 12:04:13.060713 | lab-sp-a1 | Get:43 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Components [12.6 kB]
2026-08-04 12:04:13.080843 | lab-sp-a1 | Get:44 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 c-n-f Metadata [1588 B]
2026-08-04 12:04:13.083721 | lab-sp-a1 | Get:45 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/restricted amd64 Components [212 B]
2026-08-04 12:04:13.120830 | lab-sp-a1 | Get:46 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Packages [748 B]
2026-08-04 12:04:13.121125 | lab-sp-a1 | Get:47 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse Translation-en [340 B]
2026-08-04 12:04:13.121777 | lab-sp-a1 | Get:48 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Components [212 B]
2026-08-04 12:04:12.807339 | undercloud-client | Get:23 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted Translation-en [316 kB]
2026-08-04 12:04:12.816958 | undercloud-client | Get:24 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 Components [212 B]
2026-08-04 12:04:12.817296 | undercloud-client | Get:25 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/restricted amd64 c-n-f Metadata [456 B]
2026-08-04 12:04:12.821373 | undercloud-client | Get:26 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Packages [45.4 kB]
2026-08-04 12:04:12.821404 | undercloud-client | Get:27 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse Translation-en [12.3 kB]
2026-08-04 12:04:12.862399 | undercloud-client | Get:28 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 Components [940 B]
2026-08-04 12:04:12.866727 | undercloud-client | Get:29 http://lab.clouds.archive.ubuntu.com/ubuntu noble-updates/multiverse amd64 c-n-f Metadata [656 B]
2026-08-04 12:04:12.866908 | undercloud-client | Get:30 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Packages [40.6 kB]
2026-08-04 12:04:12.870401 | undercloud-client | Get:31 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main Translation-en [9172 B]
2026-08-04 12:04:12.883293 | undercloud-client | Get:32 http://security.ubuntu.com/ubuntu noble-security/restricted Translation-en [290 kB]
2026-08-04 12:04:12.893386 | undercloud-client | Get:33 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Components [5772 B]
2026-08-04 12:04:12.893414 | undercloud-client | Get:34 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 c-n-f Metadata [368 B]
2026-08-04 12:04:12.893426 | undercloud-client | Get:35 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Packages [31.0 kB]
2026-08-04 12:04:12.897135 | undercloud-client | Get:36 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe Translation-en [18.6 kB]
2026-08-04 12:04:12.897157 | undercloud-client | Get:37 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Components [12.6 kB]
2026-08-04 12:04:12.917601 | undercloud-client | Get:38 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 c-n-f Metadata [1588 B]
2026-08-04 12:04:12.933513 | undercloud-client | Get:39 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 Components [212 B]
2026-08-04 12:04:12.933544 | undercloud-client | Get:40 http://security.ubuntu.com/ubuntu noble-security/restricted amd64 c-n-f Metadata [444 B]
2026-08-04 12:04:12.934997 | undercloud-client | Get:41 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Packages [40.3 kB]
2026-08-04 12:04:12.966911 | undercloud-client | Get:42 http://security.ubuntu.com/ubuntu noble-security/multiverse Translation-en [10.6 kB]
2026-08-04 12:04:12.967219 | undercloud-client | Get:43 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 Components [208 B]
2026-08-04 12:04:12.967343 | undercloud-client | Get:44 http://security.ubuntu.com/ubuntu noble-security/multiverse amd64 c-n-f Metadata [468 B]
2026-08-04 12:04:12.968839 | undercloud-client | Get:45 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/restricted amd64 Components [212 B]
2026-08-04 12:04:12.968854 | undercloud-client | Get:46 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Packages [748 B]
2026-08-04 12:04:12.970299 | undercloud-client | Get:47 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse Translation-en [340 B]
2026-08-04 12:04:12.970457 | undercloud-client | Get:48 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Components [212 B]
2026-08-04 12:04:12.792775 | controller | Get:39 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 Components [5772 B]
2026-08-04 12:04:12.793038 | controller | Get:40 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/main amd64 c-n-f Metadata [368 B]
2026-08-04 12:04:12.793317 | controller | Get:41 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Packages [31.0 kB]
2026-08-04 12:04:12.794005 | controller | Get:42 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe Translation-en [18.6 kB]
2026-08-04 12:04:12.794290 | controller | Get:43 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 Components [12.6 kB]
2026-08-04 12:04:12.818551 | controller | Get:44 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/universe amd64 c-n-f Metadata [1588 B]
2026-08-04 12:04:12.819061 | controller | Get:45 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/restricted amd64 Components [212 B]
2026-08-04 12:04:12.857639 | controller | Get:46 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Packages [748 B]
2026-08-04 12:04:12.858033 | controller | Get:47 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse Translation-en [340 B]
2026-08-04 12:04:12.858262 | controller | Get:48 http://lab.clouds.archive.ubuntu.com/ubuntu noble-backports/multiverse amd64 Components [212 B]
2026-08-04 12:04:18.256055 | controller | Fetched 10.7 MB in 2s (6511 kB/s)
2026-08-04 12:04:18.651707 | lab-sp-a1 | Fetched 10.7 MB in 2s (5369 kB/s)
2026-08-04 12:04:19.180148 | undercloud-client | Fetched 10.7 MB in 2s (4715 kB/s)
2026-08-04 12:04:19.187811 | controller | Reading package lists...
2026-08-04 12:04:19.226331 | controller | Reading package lists...
2026-08-04 12:04:19.560363 | lab-sp-a1 | Reading package lists...
2026-08-04 12:04:19.599126 | lab-sp-a1 | Reading package lists...
2026-08-04 12:04:19.470988 | controller | Building dependency tree...
2026-08-04 12:04:19.471507 | controller | Reading state information...
2026-08-04 12:04:19.740871 | controller | The following additional packages will be installed:
2026-08-04 12:04:19.740924 | controller |   wireless-regdb
2026-08-04 12:04:19.771118 | controller | The following NEW packages will be installed:
2026-08-04 12:04:19.771648 | controller |   linux-modules-extra-6.8.0-100-generic wireless-regdb
2026-08-04 12:04:19.843898 | lab-sp-a1 | Building dependency tree...
2026-08-04 12:04:19.844371 | lab-sp-a1 | Reading state information...
2026-08-04 12:04:20.116075 | lab-sp-a1 | The following additional packages will be installed:
2026-08-04 12:04:20.116136 | lab-sp-a1 |   wireless-regdb
2026-08-04 12:04:20.152132 | lab-sp-a1 | The following NEW packages will be installed:
2026-08-04 12:04:20.152704 | lab-sp-a1 |   linux-modules-extra-6.8.0-100-generic wireless-regdb
2026-08-04 12:04:20.167042 | undercloud-client | Reading package lists...
2026-08-04 12:04:20.208627 | undercloud-client | Reading package lists...
2026-08-04 12:04:20.033078 | controller | 0 upgraded, 2 newly installed, 0 to remove and 172 not upgraded.
2026-08-04 12:04:20.033117 | controller | Need to get 113 MB of archives.
2026-08-04 12:04:20.033122 | controller | After this operation, 112 MB of additional disk space will be used.
2026-08-04 12:04:20.033126 | 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 12:04:20.411818 | lab-sp-a1 | 0 upgraded, 2 newly installed, 0 to remove and 172 not upgraded.
2026-08-04 12:04:20.411907 | lab-sp-a1 | Need to get 113 MB of archives.
2026-08-04 12:04:20.411918 | lab-sp-a1 | After this operation, 112 MB of additional disk space will be used.
2026-08-04 12:04:20.411923 | 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 12:04:20.560076 | 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 12:04:20.430492 | undercloud-client | Building dependency tree...
2026-08-04 12:04:20.431129 | undercloud-client | Reading state information...
2026-08-04 12:04:20.658841 | undercloud-client | The following additional packages will be installed:
2026-08-04 12:04:20.658923 | undercloud-client |   wireless-regdb
2026-08-04 12:04:20.688215 | undercloud-client | The following NEW packages will be installed:
2026-08-04 12:04:20.688828 | undercloud-client |   linux-modules-extra-6.8.0-100-generic wireless-regdb
2026-08-04 12:04:20.714055 | 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 12:04:20.954488 | undercloud-client | 0 upgraded, 2 newly installed, 0 to remove and 172 not upgraded.
2026-08-04 12:04:20.954526 | undercloud-client | Need to get 113 MB of archives.
2026-08-04 12:04:20.954536 | undercloud-client | After this operation, 112 MB of additional disk space will be used.
2026-08-04 12:04:20.954545 | 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 12:04:21.073990 | 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 12:04:35.969301 | lab-sp-a1 | debconf: unable to initialize frontend: Dialog
2026-08-04 12:04:35.969332 | 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 12:04:35.969337 | lab-sp-a1 | debconf: falling back to frontend: Readline
2026-08-04 12:04:35.979961 | lab-sp-a1 | debconf: unable to initialize frontend: Readline
2026-08-04 12:04:35.980000 | lab-sp-a1 | debconf: (This frontend requires a controlling tty.)
2026-08-04 12:04:35.980006 | lab-sp-a1 | debconf: falling back to frontend: Teletype
2026-08-04 12:04:35.985573 | lab-sp-a1 | dpkg-preconfigure: unable to re-open stdin:
2026-08-04 12:04:36.034884 | lab-sp-a1 | Fetched 113 MB in 16s (7266 kB/s)
2026-08-04 12:04:36.057335 | lab-sp-a1 | Selecting previously unselected package wireless-regdb.
2026-08-04 12:04:36.228105 | 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 12:04:36.229903 | lab-sp-a1 | Preparing to unpack .../wireless-regdb_2025.10.07-0ubuntu1~24.04.1_all.deb ...
2026-08-04 12:04:36.232927 | lab-sp-a1 | Unpacking wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ...
2026-08-04 12:04:36.263016 | lab-sp-a1 | Selecting previously unselected package linux-modules-extra-6.8.0-100-generic.
2026-08-04 12:04:36.269562 | lab-sp-a1 | Preparing to unpack .../linux-modules-extra-6.8.0-100-generic_6.8.0-100.100_amd64.deb ...
2026-08-04 12:04:36.271547 | lab-sp-a1 | Unpacking linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:37.577148 | undercloud-client | debconf: unable to initialize frontend: Dialog
2026-08-04 12:04:37.577184 | undercloud-client | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
2026-08-04 12:04:37.577191 | undercloud-client | debconf: falling back to frontend: Readline
2026-08-04 12:04:37.586704 | undercloud-client | debconf: unable to initialize frontend: Readline
2026-08-04 12:04:37.586737 | undercloud-client | debconf: (This frontend requires a controlling tty.)
2026-08-04 12:04:37.586744 | undercloud-client | debconf: falling back to frontend: Teletype
2026-08-04 12:04:37.591266 | undercloud-client | dpkg-preconfigure: unable to re-open stdin:
2026-08-04 12:04:37.636054 | undercloud-client | Fetched 113 MB in 17s (6817 kB/s)
2026-08-04 12:04:37.662189 | undercloud-client | Selecting previously unselected package wireless-regdb.
2026-08-04 12:04:37.754162 | 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 12:04:37.755477 | undercloud-client | Preparing to unpack .../wireless-regdb_2025.10.07-0ubuntu1~24.04.1_all.deb ...
2026-08-04 12:04:37.759358 | undercloud-client | Unpacking wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ...
2026-08-04 12:04:37.860473 | lab-sp-a1 | Setting up wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ...
2026-08-04 12:04:37.865688 | lab-sp-a1 | Setting up linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:37.789300 | undercloud-client | Selecting previously unselected package linux-modules-extra-6.8.0-100-generic.
2026-08-04 12:04:37.795952 | undercloud-client | Preparing to unpack .../linux-modules-extra-6.8.0-100-generic_6.8.0-100.100_amd64.deb ...
2026-08-04 12:04:37.797907 | undercloud-client | Unpacking linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:39.601018 | undercloud-client | Setting up wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ...
2026-08-04 12:04:39.605056 | undercloud-client | Setting up linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:39.878897 | lab-sp-a1 | Processing triggers for man-db (2.12.0-4build2) ...
2026-08-04 12:04:40.151617 | lab-sp-a1 | Processing triggers for linux-image-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:40.161742 | lab-sp-a1 | /etc/kernel/postinst.d/initramfs-tools:
2026-08-04 12:04:40.161766 | lab-sp-a1 | update-initramfs: Generating /boot/initrd.img-6.8.0-100-generic
2026-08-04 12:04:41.570571 | undercloud-client | Processing triggers for man-db (2.12.0-4build2) ...
2026-08-04 12:04:41.822764 | undercloud-client | Processing triggers for linux-image-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:41.831662 | undercloud-client | /etc/kernel/postinst.d/initramfs-tools:
2026-08-04 12:04:41.831753 | undercloud-client | update-initramfs: Generating /boot/initrd.img-6.8.0-100-generic
2026-08-04 12:04:47.084549 | lab-sp-a1 | /etc/kernel/postinst.d/zz-update-grub:
2026-08-04 12:04:47.084608 | lab-sp-a1 | Sourcing file `/etc/default/grub'
2026-08-04 12:04:47.087587 | lab-sp-a1 | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
2026-08-04 12:04:47.090193 | lab-sp-a1 | Generating grub configuration file ...
2026-08-04 12:04:47.204097 | lab-sp-a1 | Found linux image: /boot/vmlinuz-6.8.0-100-generic
2026-08-04 12:04:47.214792 | lab-sp-a1 | Found initrd image: /boot/initrd.img-6.8.0-100-generic
2026-08-04 12:04:47.469229 | lab-sp-a1 | Warning: os-prober will not be executed to detect other bootable partitions.
2026-08-04 12:04:47.469620 | lab-sp-a1 | Systems on them will not be added to the GRUB boot configuration.
2026-08-04 12:04:47.469630 | lab-sp-a1 | Check GRUB_DISABLE_OS_PROBER documentation entry.
2026-08-04 12:04:47.471324 | lab-sp-a1 | Adding boot menu entry for UEFI Firmware Settings ...
2026-08-04 12:04:47.483375 | lab-sp-a1 | done
2026-08-04 12:04:47.857949 | lab-sp-a1 | debconf: unable to initialize frontend: Dialog
2026-08-04 12:04:47.857989 | 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 12:04:47.857995 | lab-sp-a1 | debconf: falling back to frontend: Readline
2026-08-04 12:04:47.865890 | lab-sp-a1 | debconf: unable to initialize frontend: Readline
2026-08-04 12:04:47.865915 | lab-sp-a1 | debconf: (This frontend requires a controlling tty.)
2026-08-04 12:04:47.865925 | lab-sp-a1 | debconf: falling back to frontend: Teletype
2026-08-04 12:04:48.015096 | lab-sp-a1 |
2026-08-04 12:04:48.015140 | lab-sp-a1 | Running kernel seems to be up-to-date.
2026-08-04 12:04:48.015147 | lab-sp-a1 |
2026-08-04 12:04:48.015152 | lab-sp-a1 | No services need to be restarted.
2026-08-04 12:04:48.015158 | lab-sp-a1 |
2026-08-04 12:04:48.015168 | lab-sp-a1 | No containers need to be restarted.
2026-08-04 12:04:48.015175 | lab-sp-a1 |
2026-08-04 12:04:48.015183 | lab-sp-a1 | No user sessions are running outdated binaries.
2026-08-04 12:04:48.015191 | lab-sp-a1 |
2026-08-04 12:04:48.015198 | lab-sp-a1 | No VM guests are running outdated hypervisor (qemu) binaries on this host.
2026-08-04 12:04:48.665804 | undercloud-client | /etc/kernel/postinst.d/zz-update-grub:
2026-08-04 12:04:48.666145 | undercloud-client | Sourcing file `/etc/default/grub'
2026-08-04 12:04:48.667356 | undercloud-client | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
2026-08-04 12:04:48.669330 | undercloud-client | Generating grub configuration file ...
2026-08-04 12:04:48.782861 | undercloud-client | Found linux image: /boot/vmlinuz-6.8.0-100-generic
2026-08-04 12:04:48.791811 | undercloud-client | Found initrd image: /boot/initrd.img-6.8.0-100-generic
2026-08-04 12:04:49.033994 | undercloud-client | Warning: os-prober will not be executed to detect other bootable partitions.
2026-08-04 12:04:49.034022 | undercloud-client | Systems on them will not be added to the GRUB boot configuration.
2026-08-04 12:04:49.034145 | undercloud-client | Check GRUB_DISABLE_OS_PROBER documentation entry.
2026-08-04 12:04:49.035900 | undercloud-client | Adding boot menu entry for UEFI Firmware Settings ...
2026-08-04 12:04:49.045294 | undercloud-client | done
2026-08-04 12:04:49.518402 | undercloud-client | debconf: unable to initialize frontend: Dialog
2026-08-04 12:04:49.518463 | undercloud-client | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
2026-08-04 12:04:49.518487 | undercloud-client | debconf: falling back to frontend: Readline
2026-08-04 12:04:49.526924 | undercloud-client | debconf: unable to initialize frontend: Readline
2026-08-04 12:04:49.526971 | undercloud-client | debconf: (This frontend requires a controlling tty.)
2026-08-04 12:04:49.526993 | undercloud-client | debconf: falling back to frontend: Teletype
2026-08-04 12:04:49.660874 | undercloud-client |
2026-08-04 12:04:49.660946 | undercloud-client | Running kernel seems to be up-to-date.
2026-08-04 12:04:49.660968 | undercloud-client |
2026-08-04 12:04:49.660992 | undercloud-client | No services need to be restarted.
2026-08-04 12:04:49.661009 | undercloud-client |
2026-08-04 12:04:49.661057 | undercloud-client | No containers need to be restarted.
2026-08-04 12:04:49.662108 | undercloud-client |
2026-08-04 12:04:49.662128 | undercloud-client | No user sessions are running outdated binaries.
2026-08-04 12:04:49.662136 | undercloud-client |
2026-08-04 12:04:49.662141 | undercloud-client | No VM guests are running outdated hypervisor (qemu) binaries on this host.
2026-08-04 12:04:50.616646 | controller | debconf: unable to initialize frontend: Dialog
2026-08-04 12:04:50.616697 | controller | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
2026-08-04 12:04:50.616704 | controller | debconf: falling back to frontend: Readline
2026-08-04 12:04:50.626367 | controller | debconf: unable to initialize frontend: Readline
2026-08-04 12:04:50.626390 | controller | debconf: (This frontend requires a controlling tty.)
2026-08-04 12:04:50.626396 | controller | debconf: falling back to frontend: Teletype
2026-08-04 12:04:50.632585 | controller | dpkg-preconfigure: unable to re-open stdin:
2026-08-04 12:04:50.673593 | controller | Fetched 113 MB in 31s (3702 kB/s)
2026-08-04 12:04:50.695321 | controller | Selecting previously unselected package wireless-regdb.
2026-08-04 12:04:50.790086 | 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 12:04:50.792244 | controller | Preparing to unpack .../wireless-regdb_2025.10.07-0ubuntu1~24.04.1_all.deb ...
2026-08-04 12:04:50.797051 | controller | Unpacking wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ...
2026-08-04 12:04:50.831074 | controller | Selecting previously unselected package linux-modules-extra-6.8.0-100-generic.
2026-08-04 12:04:50.836916 | controller | Preparing to unpack .../linux-modules-extra-6.8.0-100-generic_6.8.0-100.100_amd64.deb ...
2026-08-04 12:04:50.838956 | controller | Unpacking linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:52.369723 | controller | Setting up wireless-regdb (2025.10.07-0ubuntu1~24.04.1) ...
2026-08-04 12:04:52.374658 | controller | Setting up linux-modules-extra-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:54.434168 | controller | Processing triggers for man-db (2.12.0-4build2) ...
2026-08-04 12:04:54.719510 | controller | Processing triggers for linux-image-6.8.0-100-generic (6.8.0-100.100) ...
2026-08-04 12:04:54.729453 | controller | /etc/kernel/postinst.d/initramfs-tools:
2026-08-04 12:04:54.729471 | controller | update-initramfs: Generating /boot/initrd.img-6.8.0-100-generic
2026-08-04 12:05:01.525051 | controller | /etc/kernel/postinst.d/zz-update-grub:
2026-08-04 12:05:01.525091 | controller | Sourcing file `/etc/default/grub'
2026-08-04 12:05:01.527287 | controller | Sourcing file `/etc/default/grub.d/50-cloudimg-settings.cfg'
2026-08-04 12:05:01.529959 | controller | Generating grub configuration file ...
2026-08-04 12:05:01.642331 | controller | Found linux image: /boot/vmlinuz-6.8.0-100-generic
2026-08-04 12:05:01.654393 | controller | Found initrd image: /boot/initrd.img-6.8.0-100-generic
2026-08-04 12:05:01.929566 | controller | Warning: os-prober will not be executed to detect other bootable partitions.
2026-08-04 12:05:01.929622 | controller | Systems on them will not be added to the GRUB boot configuration.
2026-08-04 12:05:01.929634 | controller | Check GRUB_DISABLE_OS_PROBER documentation entry.
2026-08-04 12:05:01.931925 | controller | Adding boot menu entry for UEFI Firmware Settings ...
2026-08-04 12:05:01.944299 | controller | done
2026-08-04 12:05:02.318520 | controller | debconf: unable to initialize frontend: Dialog
2026-08-04 12:05:02.318558 | controller | debconf: (Dialog frontend will not work on a dumb terminal, an emacs shell buffer, or without a controlling terminal.)
2026-08-04 12:05:02.318564 | controller | debconf: falling back to frontend: Readline
2026-08-04 12:05:02.327341 | controller | debconf: unable to initialize frontend: Readline
2026-08-04 12:05:02.327362 | controller | debconf: (This frontend requires a controlling tty.)
2026-08-04 12:05:02.327372 | controller | debconf: falling back to frontend: Teletype
2026-08-04 12:05:02.475744 | controller |
2026-08-04 12:05:02.475778 | controller | Running kernel seems to be up-to-date.
2026-08-04 12:05:02.475788 | controller |
2026-08-04 12:05:02.475794 | controller | No services need to be restarted.
2026-08-04 12:05:02.475798 | controller |
2026-08-04 12:05:02.475804 | controller | No containers need to be restarted.
2026-08-04 12:05:02.475809 | controller |
2026-08-04 12:05:02.475813 | controller | No user sessions are running outdated binaries.
2026-08-04 12:05:02.475818 | controller |
2026-08-04 12:05:02.475822 | controller | No VM guests are running outdated hypervisor (qemu) binaries on this host.
2026-08-04 12:05:03.813290 | lab-sp-a1 | ok: Runtime: 0:00:37.506464
2026-08-04 12:05:03.823958 | undercloud-client | ok: Runtime: 0:00:39.287941
2026-08-04 12:05:03.835893 | controller | ok: Runtime: 0:00:51.890053
2026-08-04 12:05:03.838463 | 
2026-08-04 12:05:03.838520 | PLAY RECAP
2026-08-04 12:05:03.838586 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:05:03.838616 | lab-sp-a1 | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:05:03.838641 | undercloud-client | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:05:03.838659 | 
2026-08-04 12:05:03.953430 | 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 12:05:03.967159 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/workarounds/03-provide-unobtainable-novnc.yaml@master]
2026-08-04 12:05:04.704179 | 
2026-08-04 12:05:04.704312 | PLAY [all]
2026-08-04 12:05:04.719433 | 
2026-08-04 12:05:04.719564 | TASK [Copy novnc]
2026-08-04 12:05:05.490006 | lab-sp-a1 | changed
2026-08-04 12:05:05.496204 | controller | changed
2026-08-04 12:05:05.551589 | undercloud-client | changed
2026-08-04 12:05:05.557262 | 
2026-08-04 12:05:05.557360 | TASK [Fix novnc permissions]
2026-08-04 12:05:05.954470 | undercloud-client | changed
2026-08-04 12:05:05.961039 | controller | changed
2026-08-04 12:05:05.965583 | lab-sp-a1 | changed
2026-08-04 12:05:05.966947 | 
2026-08-04 12:05:05.966994 | PLAY RECAP
2026-08-04 12:05:05.967033 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:05:05.967061 | lab-sp-a1 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:05:05.967085 | undercloud-client | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:05:05.967121 | 
2026-08-04 12:05:06.077928 | 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 12:05:06.089116 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/sp-init.yaml@master]
2026-08-04 12:05:06.868545 | 
2026-08-04 12:05:06.868698 | PLAY [tempest]
2026-08-04 12:05:06.883460 | 
2026-08-04 12:05:06.883632 | TASK [Apply the StorPool patches]
2026-08-04 12:05:07.415578 | controller | skipping: Conditional result was False
2026-08-04 12:05:07.421709 | 
2026-08-04 12:05:07.421830 | TASK [Apply the StorPool experimental patches]
2026-08-04 12:05:08.757560 | controller | Applying: Add iSCSI export support to the StorPool driver
2026-08-04 12:05:08.763322 | controller | # 'git am /home/ubuntu/src/gerrit-openstack-ci.sof-k8s.storpool.net/sp-osci/patches/openstack/cinder/sep-sp-iscsi.patch' succeeded
2026-08-04 12:05:09.044007 | controller | ok: Runtime: 0:00:00.570106
2026-08-04 12:05:09.049686 | 
2026-08-04 12:05:09.049798 | TASK [Apply the StorPool patches to the other place]
2026-08-04 12:05:09.588220 | controller | skipping: Conditional result was False
2026-08-04 12:05:09.596068 | 
2026-08-04 12:05:09.596225 | TASK [Apply the StorPool experimental patches to the other place]
2026-08-04 12:05:10.373843 | controller | Applying: Add iSCSI export support to the StorPool driver
2026-08-04 12:05:10.377156 | controller | # 'git am /home/ubuntu/src/gerrit-openstack-ci.sof-k8s.storpool.net/sp-osci/patches/openstack/cinder/sep-sp-iscsi.patch' succeeded
2026-08-04 12:05:10.632485 | controller | ok: Runtime: 0:00:00.549949
2026-08-04 12:05:10.633845 | 
2026-08-04 12:05:10.633893 | PLAY RECAP
2026-08-04 12:05:10.633937 | controller | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 0
2026-08-04 12:05:10.633958 | 
2026-08-04 12:05:10.779834 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/sp-init.yaml@master]
2026-08-04 12:05:10.934458 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-client/pre.yaml@master]
2026-08-04 12:05:11.710668 | 
2026-08-04 12:05:11.710813 | PLAY [undercloud-client]
2026-08-04 12:05:11.727215 | 
2026-08-04 12:05:11.727364 | TASK [Ensure OpenStack Credentials Directory Exists]
2026-08-04 12:05:12.781170 | undercloud-client | changed
2026-08-04 12:05:12.787307 | 
2026-08-04 12:05:12.787438 | TASK [Provision OpenStack Credentials]
2026-08-04 12:05:13.592616 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:05:13.601596 | 
2026-08-04 12:05:13.601746 | TASK [Install python3-pip]
2026-08-04 12:05:33.772582 | undercloud-client | changed
2026-08-04 12:05:33.786452 | 
2026-08-04 12:05:33.786633 | TASK [Install virtualenv]
2026-08-04 12:05:36.019926 | undercloud-client | changed
2026-08-04 12:05:36.065322 | 
2026-08-04 12:05:36.065442 | TASK [Create an OpenStack CLI Venv]
2026-08-04 12:05:49.790621 | undercloud-client | changed
2026-08-04 12:05:49.793966 | 
2026-08-04 12:05:49.794099 | PLAY RECAP
2026-08-04 12:05:49.794176 | undercloud-client | ok: 5 changed: 5 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:05:49.794199 | 
2026-08-04 12:05:50.049707 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-client/pre.yaml@master]
2026-08-04 12:05:50.062359 | PRE-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-iscsi-multipath/pre.yaml@master]
2026-08-04 12:05:50.879362 | 
2026-08-04 12:05:50.879498 | PLAY [undercloud-client]
2026-08-04 12:05:50.893774 | 
2026-08-04 12:05:50.893892 | LOOP [Create Multipath iSCSI Networks]
2026-08-04 12:05:53.363090 | undercloud-client | +---------------------------+------------------------------------------------------+
2026-08-04 12:05:53.363370 | undercloud-client | | Field                     | Value                                                |
2026-08-04 12:05:53.363419 | undercloud-client | +---------------------------+------------------------------------------------------+
2026-08-04 12:05:53.363457 | undercloud-client | | admin_state_up            | UP                                                   |
2026-08-04 12:05:53.363547 | undercloud-client | | availability_zone_hints   | lab                                                  |
2026-08-04 12:05:53.363592 | undercloud-client | | availability_zones        |                                                      |
2026-08-04 12:05:53.363623 | undercloud-client | | created_at                | 2026-08-04T12:05:52Z                                 |
2026-08-04 12:05:53.363663 | undercloud-client | | description               |                                                      |
2026-08-04 12:05:53.363694 | undercloud-client | | dns_domain                |                                                      |
2026-08-04 12:05:53.363722 | undercloud-client | | id                        | 752d0aed-0260-4eee-98a0-cb806d1364e2                 |
2026-08-04 12:05:53.363748 | undercloud-client | | ipv4_address_scope        | None                                                 |
2026-08-04 12:05:53.363773 | undercloud-client | | ipv6_address_scope        | None                                                 |
2026-08-04 12:05:53.363797 | undercloud-client | | is_default                | False                                                |
2026-08-04 12:05:53.363814 | undercloud-client | | is_vlan_qinq              | None                                                 |
2026-08-04 12:05:53.363834 | undercloud-client | | is_vlan_transparent       | False                                                |
2026-08-04 12:05:53.363860 | undercloud-client | | l2_adjacency              | True                                                 |
2026-08-04 12:05:53.363884 | undercloud-client | | mtu                       | 8942                                                 |
2026-08-04 12:05:53.363907 | undercloud-client | | name                      | ce80de7e-274e-4fef-bb61-cf4157f7b079-network-iscsi-1 |
2026-08-04 12:05:53.363937 | undercloud-client | | port_security_enabled     | True                                                 |
2026-08-04 12:05:53.363961 | undercloud-client | | project_id                | d6d455de68894255adf7ce9496d72ce9                     |
2026-08-04 12:05:53.363980 | undercloud-client | | provider:network_type     | None                                                 |
2026-08-04 12:05:53.363995 | undercloud-client | | provider:physical_network | None                                                 |
2026-08-04 12:05:53.364010 | undercloud-client | | provider:segmentation_id  | None                                                 |
2026-08-04 12:05:53.364026 | undercloud-client | | pvlan                     | None                                                 |
2026-08-04 12:05:53.364042 | undercloud-client | | qinq                      | False                                                |
2026-08-04 12:05:53.364058 | undercloud-client | | qos_policy_id             | None                                                 |
2026-08-04 12:05:53.364074 | undercloud-client | | revision_number           | 1                                                    |
2026-08-04 12:05:53.364089 | undercloud-client | | router:external           | Internal                                             |
2026-08-04 12:05:53.364105 | undercloud-client | | segments                  | None                                                 |
2026-08-04 12:05:53.364135 | undercloud-client | | shared                    | False                                                |
2026-08-04 12:05:53.364153 | undercloud-client | | status                    | ACTIVE                                               |
2026-08-04 12:05:53.364169 | undercloud-client | | subnets                   |                                                      |
2026-08-04 12:05:53.364186 | undercloud-client | | tags                      |                                                      |
2026-08-04 12:05:53.364202 | undercloud-client | | updated_at                | 2026-08-04T12:05:52Z                                 |
2026-08-04 12:05:53.364217 | undercloud-client | +---------------------------+------------------------------------------------------+
2026-08-04 12:05:53.364257 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-network-iscsi-1 Runtime: 0:00:01.764837
2026-08-04 12:05:55.357408 | undercloud-client | +---------------------------+------------------------------------------------------+
2026-08-04 12:05:55.357583 | undercloud-client | | Field                     | Value                                                |
2026-08-04 12:05:55.357616 | undercloud-client | +---------------------------+------------------------------------------------------+
2026-08-04 12:05:55.357637 | undercloud-client | | admin_state_up            | UP                                                   |
2026-08-04 12:05:55.357654 | undercloud-client | | availability_zone_hints   | lab                                                  |
2026-08-04 12:05:55.357672 | undercloud-client | | availability_zones        |                                                      |
2026-08-04 12:05:55.357688 | undercloud-client | | created_at                | 2026-08-04T12:05:54Z                                 |
2026-08-04 12:05:55.357706 | undercloud-client | | description               |                                                      |
2026-08-04 12:05:55.357722 | undercloud-client | | dns_domain                |                                                      |
2026-08-04 12:05:55.357737 | undercloud-client | | id                        | eef5461e-2949-42d7-b0e9-d1fb4f726de8                 |
2026-08-04 12:05:55.357752 | undercloud-client | | ipv4_address_scope        | None                                                 |
2026-08-04 12:05:55.357767 | undercloud-client | | ipv6_address_scope        | None                                                 |
2026-08-04 12:05:55.357782 | undercloud-client | | is_default                | False                                                |
2026-08-04 12:05:55.357806 | undercloud-client | | is_vlan_qinq              | None                                                 |
2026-08-04 12:05:55.357823 | undercloud-client | | is_vlan_transparent       | False                                                |
2026-08-04 12:05:55.357839 | undercloud-client | | l2_adjacency              | True                                                 |
2026-08-04 12:05:55.357855 | undercloud-client | | mtu                       | 8942                                                 |
2026-08-04 12:05:55.357874 | undercloud-client | | name                      | ce80de7e-274e-4fef-bb61-cf4157f7b079-network-iscsi-2 |
2026-08-04 12:05:55.357891 | undercloud-client | | port_security_enabled     | True                                                 |
2026-08-04 12:05:55.357908 | undercloud-client | | project_id                | d6d455de68894255adf7ce9496d72ce9                     |
2026-08-04 12:05:55.357925 | undercloud-client | | provider:network_type     | None                                                 |
2026-08-04 12:05:55.357942 | undercloud-client | | provider:physical_network | None                                                 |
2026-08-04 12:05:55.357959 | undercloud-client | | provider:segmentation_id  | None                                                 |
2026-08-04 12:05:55.357976 | undercloud-client | | pvlan                     | None                                                 |
2026-08-04 12:05:55.357992 | undercloud-client | | qinq                      | False                                                |
2026-08-04 12:05:55.358012 | undercloud-client | | qos_policy_id             | None                                                 |
2026-08-04 12:05:55.358029 | undercloud-client | | revision_number           | 1                                                    |
2026-08-04 12:05:55.358046 | undercloud-client | | router:external           | Internal                                             |
2026-08-04 12:05:55.358063 | undercloud-client | | segments                  | None                                                 |
2026-08-04 12:05:55.358081 | undercloud-client | | shared                    | False                                                |
2026-08-04 12:05:55.358097 | undercloud-client | | status                    | ACTIVE                                               |
2026-08-04 12:05:55.358125 | undercloud-client | | subnets                   |                                                      |
2026-08-04 12:05:55.358143 | undercloud-client | | tags                      |                                                      |
2026-08-04 12:05:55.358161 | undercloud-client | | updated_at                | 2026-08-04T12:05:54Z                                 |
2026-08-04 12:05:55.358176 | undercloud-client | +---------------------------+------------------------------------------------------+
2026-08-04 12:05:55.358206 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-network-iscsi-2 Runtime: 0:00:01.799627
2026-08-04 12:05:55.370557 | 
2026-08-04 12:05:55.370674 | LOOP [Create Multipath iSCSI Subnets]
2026-08-04 12:05:57.623427 | undercloud-client | +----------------------+-----------------------------------------------------+
2026-08-04 12:05:57.623630 | undercloud-client | | Field                | Value                                               |
2026-08-04 12:05:57.623668 | undercloud-client | +----------------------+-----------------------------------------------------+
2026-08-04 12:05:57.623692 | undercloud-client | | allocation_pools     | 192.168.40.2-192.168.40.254                         |
2026-08-04 12:05:57.623716 | undercloud-client | | cidr                 | 192.168.40.0/24                                     |
2026-08-04 12:05:57.623738 | undercloud-client | | created_at           | 2026-08-04T12:05:56Z                                |
2026-08-04 12:05:57.623759 | undercloud-client | | description          |                                                     |
2026-08-04 12:05:57.623787 | undercloud-client | | dns_nameservers      |                                                     |
2026-08-04 12:05:57.623808 | undercloud-client | | dns_publish_fixed_ip | False                                               |
2026-08-04 12:05:57.623829 | undercloud-client | | enable_dhcp          | True                                                |
2026-08-04 12:05:57.623964 | undercloud-client | | gateway_ip           | 192.168.40.1                                        |
2026-08-04 12:05:57.623998 | undercloud-client | | host_routes          |                                                     |
2026-08-04 12:05:57.624017 | undercloud-client | | id                   | 11a3793d-9d73-4206-a512-a333a4c5a163                |
2026-08-04 12:05:57.624034 | undercloud-client | | ip_version           | 4                                                   |
2026-08-04 12:05:57.624061 | undercloud-client | | ipv6_address_mode    | None                                                |
2026-08-04 12:05:57.624078 | undercloud-client | | ipv6_ra_mode         | None                                                |
2026-08-04 12:05:57.624094 | undercloud-client | | name                 | ce80de7e-274e-4fef-bb61-cf4157f7b079-subnet-iscsi-1 |
2026-08-04 12:05:57.624120 | undercloud-client | | network_id           | 752d0aed-0260-4eee-98a0-cb806d1364e2                |
2026-08-04 12:05:57.624144 | undercloud-client | | project_id           | d6d455de68894255adf7ce9496d72ce9                    |
2026-08-04 12:05:57.624160 | undercloud-client | | revision_number      | 0                                                   |
2026-08-04 12:05:57.624177 | undercloud-client | | router:external      | False                                               |
2026-08-04 12:05:57.624193 | undercloud-client | | segment_id           | None                                                |
2026-08-04 12:05:57.624211 | undercloud-client | | service_types        |                                                     |
2026-08-04 12:05:57.624228 | undercloud-client | | subnetpool_id        | None                                                |
2026-08-04 12:05:57.624244 | undercloud-client | | tags                 |                                                     |
2026-08-04 12:05:57.624261 | undercloud-client | | updated_at           | 2026-08-04T12:05:56Z                                |
2026-08-04 12:05:57.624280 | undercloud-client | +----------------------+-----------------------------------------------------+
2026-08-04 12:05:57.624330 | undercloud-client | ok: Item: Runtime: 0:00:01.940159
2026-08-04 12:05:57.624366 | undercloud-client | changed: All items complete
2026-08-04 12:05:57.624386 | 
2026-08-04 12:05:59.724848 | undercloud-client | +----------------------+-----------------------------------------------------+
2026-08-04 12:05:59.725000 | undercloud-client | | Field                | Value                                               |
2026-08-04 12:05:59.725038 | undercloud-client | +----------------------+-----------------------------------------------------+
2026-08-04 12:05:59.725069 | undercloud-client | | allocation_pools     | 192.168.50.2-192.168.50.254                         |
2026-08-04 12:05:59.725096 | undercloud-client | | cidr                 | 192.168.50.0/24                                     |
2026-08-04 12:05:59.725137 | undercloud-client | | created_at           | 2026-08-04T12:05:59Z                                |
2026-08-04 12:05:59.725166 | undercloud-client | | description          |                                                     |
2026-08-04 12:05:59.725193 | undercloud-client | | dns_nameservers      |                                                     |
2026-08-04 12:05:59.725220 | undercloud-client | | dns_publish_fixed_ip | False                                               |
2026-08-04 12:05:59.725246 | undercloud-client | | enable_dhcp          | True                                                |
2026-08-04 12:05:59.725273 | undercloud-client | | gateway_ip           | 192.168.50.1                                        |
2026-08-04 12:05:59.725306 | undercloud-client | | host_routes          |                                                     |
2026-08-04 12:05:59.725333 | undercloud-client | | id                   | c8397ebe-7090-40bf-bf80-56c2aa215fa9                |
2026-08-04 12:05:59.725360 | undercloud-client | | ip_version           | 4                                                   |
2026-08-04 12:05:59.725387 | undercloud-client | | ipv6_address_mode    | None                                                |
2026-08-04 12:05:59.725413 | undercloud-client | | ipv6_ra_mode         | None                                                |
2026-08-04 12:05:59.725440 | undercloud-client | | name                 | ce80de7e-274e-4fef-bb61-cf4157f7b079-subnet-iscsi-2 |
2026-08-04 12:05:59.725470 | undercloud-client | | network_id           | eef5461e-2949-42d7-b0e9-d1fb4f726de8                |
2026-08-04 12:05:59.725501 | undercloud-client | | project_id           | d6d455de68894255adf7ce9496d72ce9                    |
2026-08-04 12:05:59.725565 | undercloud-client | | revision_number      | 0                                                   |
2026-08-04 12:05:59.725601 | undercloud-client | | router:external      | False                                               |
2026-08-04 12:05:59.725631 | undercloud-client | | segment_id           | None                                                |
2026-08-04 12:05:59.725659 | undercloud-client | | service_types        |                                                     |
2026-08-04 12:05:59.725686 | undercloud-client | | subnetpool_id        | None                                                |
2026-08-04 12:05:59.725714 | undercloud-client | | tags                 |                                                     |
2026-08-04 12:05:59.725741 | undercloud-client | | updated_at           | 2026-08-04T12:05:59Z                                |
2026-08-04 12:05:59.725768 | undercloud-client | +----------------------+-----------------------------------------------------+
2026-08-04 12:05:59.725812 | undercloud-client | ok: Item: Runtime: 0:00:01.951793
2026-08-04 12:05:59.740409 | 
2026-08-04 12:05:59.740563 | LOOP [Create the Multipath iSCSI Ports]
2026-08-04 12:06:01.887580 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:01.887872 | undercloud-client | | Field                   | Value                                                                                             |
2026-08-04 12:06:01.887909 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:01.887936 | undercloud-client | | admin_state_up          | UP                                                                                                |
2026-08-04 12:06:01.887960 | undercloud-client | | allowed_address_pairs   |                                                                                                   |
2026-08-04 12:06:01.887983 | undercloud-client | | binding_host_id         | None                                                                                              |
2026-08-04 12:06:01.888007 | undercloud-client | | binding_profile         | None                                                                                              |
2026-08-04 12:06:01.888033 | undercloud-client | | binding_vif_details     | None                                                                                              |
2026-08-04 12:06:01.888059 | undercloud-client | | binding_vif_type        | None                                                                                              |
2026-08-04 12:06:01.888086 | undercloud-client | | binding_vnic_type       | normal                                                                                            |
2026-08-04 12:06:01.888129 | undercloud-client | | created_at              | 2026-08-04T12:06:01Z                                                                              |
2026-08-04 12:06:01.888157 | undercloud-client | | data_plane_status       | None                                                                                              |
2026-08-04 12:06:01.888175 | undercloud-client | | description             |                                                                                                   |
2026-08-04 12:06:01.888191 | undercloud-client | | device_id               |                                                                                                   |
2026-08-04 12:06:01.888207 | undercloud-client | | device_owner            |                                                                                                   |
2026-08-04 12:06:01.888222 | undercloud-client | | device_profile          | None                                                                                              |
2026-08-04 12:06:01.888237 | undercloud-client | | dns_assignment          | fqdn='host-192-168-40-5.storpool.local.', hostname='host-192-168-40-5', ip_address='192.168.40.5' |
2026-08-04 12:06:01.888253 | undercloud-client | | dns_domain              |                                                                                                   |
2026-08-04 12:06:01.888268 | undercloud-client | | dns_name                |                                                                                                   |
2026-08-04 12:06:01.888283 | undercloud-client | | extra_dhcp_opts         |                                                                                                   |
2026-08-04 12:06:01.888298 | undercloud-client | | fixed_ips               | ip_address='192.168.40.5', subnet_id='11a3793d-9d73-4206-a512-a333a4c5a163'                       |
2026-08-04 12:06:01.888313 | undercloud-client | | hardware_offload_type   | None                                                                                              |
2026-08-04 12:06:01.888328 | undercloud-client | | hints                   |                                                                                                   |
2026-08-04 12:06:01.888343 | undercloud-client | | id                      | 00e0c341-5d93-40dc-8aa5-b99cfedd5468                                                              |
2026-08-04 12:06:01.888359 | undercloud-client | | ip_allocation           | immediate                                                                                         |
2026-08-04 12:06:01.888374 | undercloud-client | | mac_address             | fa:16:3e:c4:62:0e                                                                                 |
2026-08-04 12:06:01.888389 | undercloud-client | | name                    | ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-0                                          |
2026-08-04 12:06:01.888404 | undercloud-client | | network_id              | 752d0aed-0260-4eee-98a0-cb806d1364e2                                                              |
2026-08-04 12:06:01.888419 | undercloud-client | | numa_affinity_policy    | None                                                                                              |
2026-08-04 12:06:01.888435 | undercloud-client | | port_security_enabled   | False                                                                                             |
2026-08-04 12:06:01.888450 | undercloud-client | | project_id              | d6d455de68894255adf7ce9496d72ce9                                                                  |
2026-08-04 12:06:01.888465 | undercloud-client | | propagate_uplink_status | None                                                                                              |
2026-08-04 12:06:01.888482 | undercloud-client | | pvlan_type              | None                                                                                              |
2026-08-04 12:06:01.888498 | undercloud-client | | pvlan_community         | None                                                                                              |
2026-08-04 12:06:01.888543 | undercloud-client | | resource_request        | None                                                                                              |
2026-08-04 12:06:01.888569 | undercloud-client | | revision_number         | 1                                                                                                 |
2026-08-04 12:06:01.888585 | undercloud-client | | qos_network_policy_id   | None                                                                                              |
2026-08-04 12:06:01.888601 | undercloud-client | | qos_policy_id           | None                                                                                              |
2026-08-04 12:06:01.888617 | undercloud-client | | security_group_ids      |                                                                                                   |
2026-08-04 12:06:01.888633 | undercloud-client | | status                  | DOWN                                                                                              |
2026-08-04 12:06:01.888649 | undercloud-client | | tags                    |                                                                                                   |
2026-08-04 12:06:01.888665 | undercloud-client | | trunk_details           | None                                                                                              |
2026-08-04 12:06:01.888681 | undercloud-client | | trusted                 | None                                                                                              |
2026-08-04 12:06:01.888697 | undercloud-client | | updated_at              | 2026-08-04T12:06:01Z                                                                              |
2026-08-04 12:06:01.888712 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:01.888756 | undercloud-client | ok: Item: Runtime: 0:00:01.828241
2026-08-04 12:06:03.817864 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:03.817967 | undercloud-client | | Field                   | Value                                                                                             |
2026-08-04 12:06:03.817990 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:03.818009 | undercloud-client | | admin_state_up          | UP                                                                                                |
2026-08-04 12:06:03.818026 | undercloud-client | | allowed_address_pairs   |                                                                                                   |
2026-08-04 12:06:03.818042 | undercloud-client | | binding_host_id         | None                                                                                              |
2026-08-04 12:06:03.818060 | undercloud-client | | binding_profile         | None                                                                                              |
2026-08-04 12:06:03.818077 | undercloud-client | | binding_vif_details     | None                                                                                              |
2026-08-04 12:06:03.818094 | undercloud-client | | binding_vif_type        | None                                                                                              |
2026-08-04 12:06:03.818124 | undercloud-client | | binding_vnic_type       | normal                                                                                            |
2026-08-04 12:06:03.818146 | undercloud-client | | created_at              | 2026-08-04T12:06:03Z                                                                              |
2026-08-04 12:06:03.818169 | undercloud-client | | data_plane_status       | None                                                                                              |
2026-08-04 12:06:03.818187 | undercloud-client | | description             |                                                                                                   |
2026-08-04 12:06:03.818204 | undercloud-client | | device_id               |                                                                                                   |
2026-08-04 12:06:03.818222 | undercloud-client | | device_owner            |                                                                                                   |
2026-08-04 12:06:03.818239 | undercloud-client | | device_profile          | None                                                                                              |
2026-08-04 12:06:03.818257 | undercloud-client | | dns_assignment          | fqdn='host-192-168-50-5.storpool.local.', hostname='host-192-168-50-5', ip_address='192.168.50.5' |
2026-08-04 12:06:03.818274 | undercloud-client | | dns_domain              |                                                                                                   |
2026-08-04 12:06:03.818292 | undercloud-client | | dns_name                |                                                                                                   |
2026-08-04 12:06:03.818309 | undercloud-client | | extra_dhcp_opts         |                                                                                                   |
2026-08-04 12:06:03.818327 | undercloud-client | | fixed_ips               | ip_address='192.168.50.5', subnet_id='c8397ebe-7090-40bf-bf80-56c2aa215fa9'                       |
2026-08-04 12:06:03.818345 | undercloud-client | | hardware_offload_type   | None                                                                                              |
2026-08-04 12:06:03.818362 | undercloud-client | | hints                   |                                                                                                   |
2026-08-04 12:06:03.818387 | undercloud-client | | id                      | 2a0ec83d-a3a4-4a76-901d-bc5874e0713a                                                              |
2026-08-04 12:06:03.818408 | undercloud-client | | ip_allocation           | immediate                                                                                         |
2026-08-04 12:06:03.818431 | undercloud-client | | mac_address             | fa:16:3e:7f:99:5f                                                                                 |
2026-08-04 12:06:03.818452 | undercloud-client | | name                    | ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-1                                          |
2026-08-04 12:06:03.818473 | undercloud-client | | network_id              | eef5461e-2949-42d7-b0e9-d1fb4f726de8                                                              |
2026-08-04 12:06:03.818493 | undercloud-client | | numa_affinity_policy    | None                                                                                              |
2026-08-04 12:06:03.818513 | undercloud-client | | port_security_enabled   | False                                                                                             |
2026-08-04 12:06:03.818552 | undercloud-client | | project_id              | d6d455de68894255adf7ce9496d72ce9                                                                  |
2026-08-04 12:06:03.818574 | undercloud-client | | propagate_uplink_status | None                                                                                              |
2026-08-04 12:06:03.818594 | undercloud-client | | pvlan_type              | None                                                                                              |
2026-08-04 12:06:03.818614 | undercloud-client | | pvlan_community         | None                                                                                              |
2026-08-04 12:06:03.818734 | undercloud-client | | resource_request        | None                                                                                              |
2026-08-04 12:06:03.818766 | undercloud-client | | revision_number         | 1                                                                                                 |
2026-08-04 12:06:03.818785 | undercloud-client | | qos_network_policy_id   | None                                                                                              |
2026-08-04 12:06:03.818807 | undercloud-client | | qos_policy_id           | None                                                                                              |
2026-08-04 12:06:03.818837 | undercloud-client | | security_group_ids      |                                                                                                   |
2026-08-04 12:06:03.818856 | undercloud-client | | status                  | DOWN                                                                                              |
2026-08-04 12:06:03.818872 | undercloud-client | | tags                    |                                                                                                   |
2026-08-04 12:06:03.818889 | undercloud-client | | trunk_details           | None                                                                                              |
2026-08-04 12:06:03.818904 | undercloud-client | | trusted                 | None                                                                                              |
2026-08-04 12:06:03.818920 | undercloud-client | | updated_at              | 2026-08-04T12:06:03Z                                                                              |
2026-08-04 12:06:03.818936 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:03.818963 | undercloud-client | ok: Item: Runtime: 0:00:01.752805
2026-08-04 12:06:05.856905 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:05.857016 | undercloud-client | | Field                   | Value                                                                                             |
2026-08-04 12:06:05.857040 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:05.857058 | undercloud-client | | admin_state_up          | UP                                                                                                |
2026-08-04 12:06:05.857075 | undercloud-client | | allowed_address_pairs   |                                                                                                   |
2026-08-04 12:06:05.857091 | undercloud-client | | binding_host_id         | None                                                                                              |
2026-08-04 12:06:05.857107 | undercloud-client | | binding_profile         | None                                                                                              |
2026-08-04 12:06:05.857139 | undercloud-client | | binding_vif_details     | None                                                                                              |
2026-08-04 12:06:05.857155 | undercloud-client | | binding_vif_type        | None                                                                                              |
2026-08-04 12:06:05.857171 | undercloud-client | | binding_vnic_type       | normal                                                                                            |
2026-08-04 12:06:05.857186 | undercloud-client | | created_at              | 2026-08-04T12:06:05Z                                                                              |
2026-08-04 12:06:05.857202 | undercloud-client | | data_plane_status       | None                                                                                              |
2026-08-04 12:06:05.857225 | undercloud-client | | description             |                                                                                                   |
2026-08-04 12:06:05.857241 | undercloud-client | | device_id               |                                                                                                   |
2026-08-04 12:06:05.857256 | undercloud-client | | device_owner            |                                                                                                   |
2026-08-04 12:06:05.857271 | undercloud-client | | device_profile          | None                                                                                              |
2026-08-04 12:06:05.857285 | undercloud-client | | dns_assignment          | fqdn='host-192-168-40-6.storpool.local.', hostname='host-192-168-40-6', ip_address='192.168.40.6' |
2026-08-04 12:06:05.857300 | undercloud-client | | dns_domain              |                                                                                                   |
2026-08-04 12:06:05.857315 | undercloud-client | | dns_name                |                                                                                                   |
2026-08-04 12:06:05.857329 | undercloud-client | | extra_dhcp_opts         |                                                                                                   |
2026-08-04 12:06:05.857344 | undercloud-client | | fixed_ips               | ip_address='192.168.40.6', subnet_id='11a3793d-9d73-4206-a512-a333a4c5a163'                       |
2026-08-04 12:06:05.857359 | undercloud-client | | hardware_offload_type   | None                                                                                              |
2026-08-04 12:06:05.857374 | undercloud-client | | hints                   |                                                                                                   |
2026-08-04 12:06:05.857389 | undercloud-client | | id                      | bf601302-bac1-4efa-9de2-269a3e79af37                                                              |
2026-08-04 12:06:05.857404 | undercloud-client | | ip_allocation           | immediate                                                                                         |
2026-08-04 12:06:05.857421 | undercloud-client | | mac_address             | fa:16:3e:20:a0:81                                                                                 |
2026-08-04 12:06:05.857437 | undercloud-client | | name                    | ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-iscsi-0                                          |
2026-08-04 12:06:05.857452 | undercloud-client | | network_id              | 752d0aed-0260-4eee-98a0-cb806d1364e2                                                              |
2026-08-04 12:06:05.857468 | undercloud-client | | numa_affinity_policy    | None                                                                                              |
2026-08-04 12:06:05.857484 | undercloud-client | | port_security_enabled   | False                                                                                             |
2026-08-04 12:06:05.857501 | undercloud-client | | project_id              | d6d455de68894255adf7ce9496d72ce9                                                                  |
2026-08-04 12:06:05.857517 | undercloud-client | | propagate_uplink_status | None                                                                                              |
2026-08-04 12:06:05.857566 | undercloud-client | | pvlan_type              | None                                                                                              |
2026-08-04 12:06:05.857588 | undercloud-client | | pvlan_community         | None                                                                                              |
2026-08-04 12:06:05.857605 | undercloud-client | | resource_request        | None                                                                                              |
2026-08-04 12:06:05.857627 | undercloud-client | | revision_number         | 1                                                                                                 |
2026-08-04 12:06:05.857645 | undercloud-client | | qos_network_policy_id   | None                                                                                              |
2026-08-04 12:06:05.857661 | undercloud-client | | qos_policy_id           | None                                                                                              |
2026-08-04 12:06:05.857678 | undercloud-client | | security_group_ids      |                                                                                                   |
2026-08-04 12:06:05.857694 | undercloud-client | | status                  | DOWN                                                                                              |
2026-08-04 12:06:05.857709 | undercloud-client | | tags                    |                                                                                                   |
2026-08-04 12:06:05.857725 | undercloud-client | | trunk_details           | None                                                                                              |
2026-08-04 12:06:05.857741 | undercloud-client | | trusted                 | None                                                                                              |
2026-08-04 12:06:05.857757 | undercloud-client | | updated_at              | 2026-08-04T12:06:05Z                                                                              |
2026-08-04 12:06:05.857772 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:05.857804 | undercloud-client | ok: Item: Runtime: 0:00:01.841562
2026-08-04 12:06:07.815853 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:07.815974 | undercloud-client | | Field                   | Value                                                                                             |
2026-08-04 12:06:07.815999 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:07.816018 | undercloud-client | | admin_state_up          | UP                                                                                                |
2026-08-04 12:06:07.816035 | undercloud-client | | allowed_address_pairs   |                                                                                                   |
2026-08-04 12:06:07.816052 | undercloud-client | | binding_host_id         | None                                                                                              |
2026-08-04 12:06:07.816068 | undercloud-client | | binding_profile         | None                                                                                              |
2026-08-04 12:06:07.816085 | undercloud-client | | binding_vif_details     | None                                                                                              |
2026-08-04 12:06:07.816102 | undercloud-client | | binding_vif_type        | None                                                                                              |
2026-08-04 12:06:07.816131 | undercloud-client | | binding_vnic_type       | normal                                                                                            |
2026-08-04 12:06:07.816150 | undercloud-client | | created_at              | 2026-08-04T12:06:07Z                                                                              |
2026-08-04 12:06:07.816166 | undercloud-client | | data_plane_status       | None                                                                                              |
2026-08-04 12:06:07.816182 | undercloud-client | | description             |                                                                                                   |
2026-08-04 12:06:07.816206 | undercloud-client | | device_id               |                                                                                                   |
2026-08-04 12:06:07.816222 | undercloud-client | | device_owner            |                                                                                                   |
2026-08-04 12:06:07.816238 | undercloud-client | | device_profile          | None                                                                                              |
2026-08-04 12:06:07.816254 | undercloud-client | | dns_assignment          | fqdn='host-192-168-50-6.storpool.local.', hostname='host-192-168-50-6', ip_address='192.168.50.6' |
2026-08-04 12:06:07.816270 | undercloud-client | | dns_domain              |                                                                                                   |
2026-08-04 12:06:07.816286 | undercloud-client | | dns_name                |                                                                                                   |
2026-08-04 12:06:07.816302 | undercloud-client | | extra_dhcp_opts         |                                                                                                   |
2026-08-04 12:06:07.816318 | undercloud-client | | fixed_ips               | ip_address='192.168.50.6', subnet_id='c8397ebe-7090-40bf-bf80-56c2aa215fa9'                       |
2026-08-04 12:06:07.816334 | undercloud-client | | hardware_offload_type   | None                                                                                              |
2026-08-04 12:06:07.816350 | undercloud-client | | hints                   |                                                                                                   |
2026-08-04 12:06:07.816366 | undercloud-client | | id                      | a3273d9f-54a9-46ba-9c64-33187de7e221                                                              |
2026-08-04 12:06:07.816382 | undercloud-client | | ip_allocation           | immediate                                                                                         |
2026-08-04 12:06:07.816399 | undercloud-client | | mac_address             | fa:16:3e:e0:1d:09                                                                                 |
2026-08-04 12:06:07.816422 | undercloud-client | | name                    | ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-iscsi-1                                          |
2026-08-04 12:06:07.816449 | undercloud-client | | network_id              | eef5461e-2949-42d7-b0e9-d1fb4f726de8                                                              |
2026-08-04 12:06:07.816476 | undercloud-client | | numa_affinity_policy    | None                                                                                              |
2026-08-04 12:06:07.816502 | undercloud-client | | port_security_enabled   | False                                                                                             |
2026-08-04 12:06:07.816558 | undercloud-client | | project_id              | d6d455de68894255adf7ce9496d72ce9                                                                  |
2026-08-04 12:06:07.816582 | undercloud-client | | propagate_uplink_status | None                                                                                              |
2026-08-04 12:06:07.816599 | undercloud-client | | pvlan_type              | None                                                                                              |
2026-08-04 12:06:07.816615 | undercloud-client | | pvlan_community         | None                                                                                              |
2026-08-04 12:06:07.816631 | undercloud-client | | resource_request        | None                                                                                              |
2026-08-04 12:06:07.816646 | undercloud-client | | revision_number         | 1                                                                                                 |
2026-08-04 12:06:07.816666 | undercloud-client | | qos_network_policy_id   | None                                                                                              |
2026-08-04 12:06:07.816682 | undercloud-client | | qos_policy_id           | None                                                                                              |
2026-08-04 12:06:07.816703 | undercloud-client | | security_group_ids      |                                                                                                   |
2026-08-04 12:06:07.816725 | undercloud-client | | status                  | DOWN                                                                                              |
2026-08-04 12:06:07.816741 | undercloud-client | | tags                    |                                                                                                   |
2026-08-04 12:06:07.816756 | undercloud-client | | trunk_details           | None                                                                                              |
2026-08-04 12:06:07.816771 | undercloud-client | | trusted                 | None                                                                                              |
2026-08-04 12:06:07.816786 | undercloud-client | | updated_at              | 2026-08-04T12:06:07Z                                                                              |
2026-08-04 12:06:07.816801 | undercloud-client | +-------------------------+---------------------------------------------------------------------------------------------------+
2026-08-04 12:06:07.816828 | undercloud-client | ok: Item: Runtime: 0:00:01.765112
2026-08-04 12:06:07.828133 | 
2026-08-04 12:06:07.828244 | LOOP [Attach the Multipath iSCSI Ports to the StorPool Node]
2026-08-04 12:06:13.200509 | undercloud-client | HttpException: 500: Server Error for url: http://os-controller.lab.storpool.local:8774/v2.1/servers/538d0e11-4059-47ce-94e8-5793a62094dd/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:06:13.200988 | undercloud-client | <class 'nova.exception_Remote.PortBindingFailed_Remote'>
2026-08-04 12:06:13.201049 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-0 Result: 1
2026-08-04 12:06:13.201084 | undercloud-client | changed: All items complete
2026-08-04 12:06:13.201103 | 
2026-08-04 12:06:24.590231 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-1 Runtime: 0:00:11.200008
failure
2026-08-04 12:06:24.611643 | 
2026-08-04 12:06:24.611788 | PLAY RECAP
2026-08-04 12:06:24.611845 | undercloud-client | ok: 3 changed: 3 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:06:24.611959 | 
2026-08-04 12:06:24.760169 | PRE-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-iscsi-multipath/pre.yaml@master]
2026-08-04 12:06:24.777483 | POST-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-storpool/post.yaml@master]
2026-08-04 12:06:25.724253 | 
2026-08-04 12:06:25.724436 | PLAY [undercloud-client]
2026-08-04 12:06:25.747178 | 
2026-08-04 12:06:25.747371 | LOOP [Remove the StorPool Ports from the StorPool Node]
2026-08-04 12:06:28.384864 | undercloud-client | No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp-api
2026-08-04 12:06:28.385207 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp-api Result: 1
2026-08-04 12:06:30.130422 | undercloud-client | No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp0
2026-08-04 12:06:30.130601 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp0 Result: 1
2026-08-04 12:06:31.863808 | undercloud-client | No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp1
2026-08-04 12:06:31.863937 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp1 Result: 1
2026-08-04 12:06:31.882712 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:06:31.927653 | 
2026-08-04 12:06:31.927805 | TASK [Remove the StorPool Ports from the OpenStack Node]
2026-08-04 12:06:33.617173 | undercloud-client | No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp-api
2026-08-04 12:06:33.981584 | undercloud-client | ERROR
2026-08-04 12:06:33.981854 | undercloud-client | {
2026-08-04 12:06:33.981890 | undercloud-client |   "delta": "0:00:01.593252",
2026-08-04 12:06:33.981910 | undercloud-client |   "end": "2026-08-04 12:06:33.801488",
2026-08-04 12:06:33.981931 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:06:33.981950 | undercloud-client |   "rc": 1,
2026-08-04 12:06:33.981967 | undercloud-client |   "start": "2026-08-04 12:06:32.208236"
2026-08-04 12:06:33.981983 | undercloud-client | }
2026-08-04 12:06:33.982008 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:06:33.988091 | 
2026-08-04 12:06:33.988196 | LOOP [Remove the StorPool Ports from the Block Client OpenStack Node]
2026-08-04 12:06:34.022407 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:06:34.023581 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:06:34.025474 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:06:34.039975 | 
2026-08-04 12:06:34.040091 | LOOP [Delete the StorPool Ports]
2026-08-04 12:06:35.989256 | undercloud-client | Failed to delete port with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp-api': No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp-api
2026-08-04 12:06:35.989478 | undercloud-client | 1 of 1 ports failed to delete.
2026-08-04 12:06:35.989536 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp-api Result: 1
2026-08-04 12:06:35.989574 | 
2026-08-04 12:06:37.696229 | undercloud-client | Failed to delete port with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp-api': No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp-api
2026-08-04 12:06:37.696349 | undercloud-client | 1 of 1 ports failed to delete.
2026-08-04 12:06:37.696390 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp-api Result: 1
2026-08-04 12:06:39.372684 | undercloud-client | Failed to delete port with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp0': No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp0
2026-08-04 12:06:39.372820 | undercloud-client | 1 of 1 ports failed to delete.
2026-08-04 12:06:39.372868 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp0 Result: 1
2026-08-04 12:06:41.032446 | undercloud-client | Failed to delete port with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp1': No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp1
2026-08-04 12:06:41.032565 | undercloud-client | 1 of 1 ports failed to delete.
2026-08-04 12:06:41.032598 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-sp1 Result: 1
2026-08-04 12:06:41.042959 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:06:41.049796 | 
2026-08-04 12:06:41.049916 | LOOP [Delete the StorPool Block Ports]
2026-08-04 12:06:42.812959 | undercloud-client | Failed to delete port with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp0': No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp0
2026-08-04 12:06:42.813307 | undercloud-client | 1 of 1 ports failed to delete.
2026-08-04 12:06:42.813372 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp0 Result: 1
2026-08-04 12:06:44.479692 | undercloud-client | Failed to delete port with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp1': No Port found for ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp1
2026-08-04 12:06:44.479815 | undercloud-client | 1 of 1 ports failed to delete.
2026-08-04 12:06:44.479862 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-sp1 Result: 1
2026-08-04 12:06:44.488951 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:06:44.494748 | 
2026-08-04 12:06:44.494822 | LOOP [Delete the StorPool Networks]
2026-08-04 12:06:46.219778 | undercloud-client | Failed to delete network with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp-api': No Network found for ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp-api
2026-08-04 12:06:46.220014 | undercloud-client | 1 of 1 networks failed to delete.
2026-08-04 12:06:46.220057 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp-api Result: 1
2026-08-04 12:06:47.865722 | undercloud-client | Failed to delete network with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp0': No Network found for ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp0
2026-08-04 12:06:47.865833 | undercloud-client | 1 of 1 networks failed to delete.
2026-08-04 12:06:47.865869 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp0 Result: 1
2026-08-04 12:06:49.529347 | undercloud-client | Failed to delete network with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp1': No Network found for ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp1
2026-08-04 12:06:49.529482 | undercloud-client | 1 of 1 networks failed to delete.
2026-08-04 12:06:49.529551 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-network-sp1 Result: 1
2026-08-04 12:06:49.546555 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:06:49.554868 | 
2026-08-04 12:06:49.555003 | TASK [Remove the StorPool Volume to the StorPool Node]
2026-08-04 12:06:51.315763 | undercloud-client | No Volume found for ce80de7e-274e-4fef-bb61-cf4157f7b079-storpool-volume
2026-08-04 12:07:12.373212 | undercloud-client | ERROR
2026-08-04 12:07:12.373429 | undercloud-client | {
2026-08-04 12:07:12.373511 | undercloud-client |   "attempts": 3,
2026-08-04 12:07:12.373564 | undercloud-client |   "delta": "0:00:01.665598",
2026-08-04 12:07:12.373591 | undercloud-client |   "end": "2026-08-04 12:07:12.326738",
2026-08-04 12:07:12.373613 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:07:12.373668 | undercloud-client |   "rc": 1,
2026-08-04 12:07:12.373693 | undercloud-client |   "start": "2026-08-04 12:07:10.661140"
2026-08-04 12:07:12.373714 | undercloud-client | }
2026-08-04 12:07:12.373743 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:07:12.382573 | 
2026-08-04 12:07:12.382729 | TASK [Delete the StorPool Volume]
2026-08-04 12:07:13.975969 | undercloud-client | Failed to delete volume with name or ID 'ce80de7e-274e-4fef-bb61-cf4157f7b079-storpool-volume': No Volume found for ce80de7e-274e-4fef-bb61-cf4157f7b079-storpool-volume
2026-08-04 12:07:13.977126 | undercloud-client | 1 of 1 volumes failed to delete.
2026-08-04 12:07:33.983106 | undercloud-client | ERROR
2026-08-04 12:07:33.983275 | undercloud-client | {
2026-08-04 12:07:33.983302 | undercloud-client |   "attempts": 3,
2026-08-04 12:07:33.983321 | undercloud-client |   "delta": "0:00:01.370572",
2026-08-04 12:07:33.983338 | undercloud-client |   "end": "2026-08-04 12:07:33.949935",
2026-08-04 12:07:33.983355 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:07:33.983371 | undercloud-client |   "rc": 1,
2026-08-04 12:07:33.983387 | undercloud-client |   "start": "2026-08-04 12:07:32.579363"
2026-08-04 12:07:33.983403 | undercloud-client | }
2026-08-04 12:07:33.983424 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:07:33.984757 | 
2026-08-04 12:07:33.984804 | PLAY RECAP
2026-08-04 12:07:33.984845 | undercloud-client | ok: 7 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 7
2026-08-04 12:07:33.984866 | 
2026-08-04 12:07:34.100182 | 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:07:34.110509 | POST-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-iscsi-multipath/post.yaml@master]
2026-08-04 12:07:34.836030 | 
2026-08-04 12:07:34.836169 | PLAY [undercloud-client]
2026-08-04 12:07:34.851187 | 
2026-08-04 12:07:34.851323 | LOOP [Remove the Multipath iSCSI Ports from the StorPool Node]
2026-08-04 12:07:37.511685 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-0 Runtime: 0:00:01.676565
2026-08-04 12:07:39.366194 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-1 Runtime: 0:00:01.652710
2026-08-04 12:07:39.377010 | 
2026-08-04 12:07:39.377157 | LOOP [Remove the Multipath iSCSI Ports from the OpenStack Node]
2026-08-04 12:07:41.234038 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-iscsi-0 Runtime: 0:00:01.565616
2026-08-04 12:07:41.234302 | undercloud-client | changed: All items complete
2026-08-04 12:07:41.234334 | 
2026-08-04 12:07:43.067453 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-iscsi-1 Runtime: 0:00:01.613345
2026-08-04 12:07:43.082229 | 
2026-08-04 12:07:43.082332 | LOOP [Delete the Multipath iSCSI Ports]
2026-08-04 12:07:44.995339 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-0 Runtime: 0:00:01.655774
2026-08-04 12:07:46.934652 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-1-iscsi-1 Runtime: 0:00:01.749594
2026-08-04 12:07:48.854671 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-iscsi-0 Runtime: 0:00:01.744105
2026-08-04 12:07:50.749886 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-port-node-2-iscsi-1 Runtime: 0:00:01.710862
2026-08-04 12:07:50.759941 | 
2026-08-04 12:07:50.760076 | LOOP [Delete Multipath iSCSI Networks]
2026-08-04 12:07:53.069265 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-network-iscsi-1 Runtime: 0:00:02.068054
2026-08-04 12:07:53.069549 | undercloud-client | changed: All items complete
2026-08-04 12:07:53.069597 | 
2026-08-04 12:07:55.314721 | undercloud-client | ok: Item: ce80de7e-274e-4fef-bb61-cf4157f7b079-network-iscsi-2 Runtime: 0:00:02.059022
2026-08-04 12:07:55.321938 | 
2026-08-04 12:07:55.322063 | PLAY RECAP
2026-08-04 12:07:55.322140 | undercloud-client | ok: 4 changed: 4 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:07:55.322173 | 
2026-08-04 12:07:55.449684 | POST-RUN END RESULT_NORMAL: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-iscsi-multipath/post.yaml@master]
2026-08-04 12:07:55.460735 | POST-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/setup-openstack-client/post.yaml@master]
2026-08-04 12:07:56.196915 | 
2026-08-04 12:07:56.197039 | PLAY [undercloud-client]
2026-08-04 12:07:56.211717 | 
2026-08-04 12:07:56.211855 | TASK [Deprovision OpenStack Credentials]
2026-08-04 12:07:56.849068 | undercloud-client | changed
2026-08-04 12:07:56.850673 | 
2026-08-04 12:07:56.850737 | PLAY RECAP
2026-08-04 12:07:56.850787 | undercloud-client | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 12:07:56.850838 | 
2026-08-04 12:07:57.017791 | 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:07:57.056195 | POST-RUN START: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master]
2026-08-04 12:07:57.872943 | 
2026-08-04 12:07:57.873083 | PLAY [tempest]
2026-08-04 12:07:57.889666 | 
2026-08-04 12:07:57.889813 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-08-04 12:07:58.622602 | controller | changed: non-zero return code
2026-08-04 12:07:58.628367 | 
2026-08-04 12:07:58.628453 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-08-04 12:07:58.643292 | controller | skipping: Conditional result was False
2026-08-04 12:07:58.649517 | 
2026-08-04 12:07:58.649634 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-08-04 12:07:58.681253 | 
2026-08-04 12:07:58.681451 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-08-04 12:07:58.703419 | 
2026-08-04 12:07:58.703630 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-08-04 12:07:58.719057 | controller | skipping: Conditional result was False
2026-08-04 12:07:58.730066 | 
2026-08-04 12:07:58.730245 | LOOP [fetch-subunit-output : Generate subunit file]
2026-08-04 12:07:58.764962 | 
2026-08-04 12:07:58.765245 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-08-04 12:07:58.781598 | controller | skipping: Conditional result was False
2026-08-04 12:07:58.791479 | 
2026-08-04 12:07:58.791678 | TASK [fetch-subunit-output : Remove the temporary file]
2026-08-04 12:07:58.807880 | controller | skipping: Conditional result was False
2026-08-04 12:07:58.814614 | 
2026-08-04 12:07:58.814722 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-08-04 12:07:58.829825 | controller | skipping: Conditional result was False
2026-08-04 12:07:58.889989 | 
2026-08-04 12:07:58.890141 | TASK [process-stackviz : Devstack checks if stackviz archive exists]
2026-08-04 12:07:59.184894 | controller | ok
2026-08-04 12:07:59.191221 | 
2026-08-04 12:07:59.191348 | TASK [process-stackviz : debug]
2026-08-04 12:07:59.221424 | Stackviz archive could not be found in /opt/cache/files/stackviz-latest.tar.gz
2026-08-04 12:07:59.228193 | 
2026-08-04 12:07:59.228328 | TASK [process-stackviz : Check if subunit data exists]
2026-08-04 12:07:59.452703 | controller | ok
2026-08-04 12:07:59.457944 | 
2026-08-04 12:07:59.458037 | TASK [process-stackviz : debug]
2026-08-04 12:07:59.488551 | Subunit file could not be found at /opt/stack/tempest/testrepository.subunit
2026-08-04 12:07:59.494467 | 
2026-08-04 12:07:59.494609 | TASK [include_role : ensure-pip]
2026-08-04 12:07:59.509816 | controller | skipping: Conditional result was False
2026-08-04 12:07:59.518008 | 
2026-08-04 12:07:59.518161 | TASK [process-stackviz : pip]
2026-08-04 12:07:59.533184 | controller | skipping: Conditional result was False
2026-08-04 12:07:59.541504 | 
2026-08-04 12:07:59.541646 | TASK [process-stackviz : Deploy stackviz static html+js]
2026-08-04 12:08:00.069868 | controller | skipping: Conditional result was False
2026-08-04 12:08:00.076360 | 
2026-08-04 12:08:00.076483 | TASK [process-stackviz : Check if dstat data exists]
2026-08-04 12:08:00.090919 | controller | skipping: Conditional result was False
2026-08-04 12:08:00.097043 | 
2026-08-04 12:08:00.097145 | TASK [process-stackviz : Run stackviz with dstat]
2026-08-04 12:08:00.626702 | controller | skipping: Conditional result was False
2026-08-04 12:08:00.632889 | 
2026-08-04 12:08:00.632984 | TASK [process-stackviz : Run stackviz without dstat]
2026-08-04 12:08:01.163343 | controller | skipping: Conditional result was False
2026-08-04 12:08:01.170333 | 
2026-08-04 12:08:01.170411 | PLAY RECAP
2026-08-04 12:08:01.170453 | controller | ok: 5 changed: 1 unreachable: 0 failed: 0 skipped: 14 rescued: 0 ignored: 0
2026-08-04 12:08:01.170475 | 
2026-08-04 12:08:01.315024 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/tempest/playbooks/post-tempest.yaml@master]
2026-08-04 12:08:01.323854 | POST-RUN START: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-04 12:08:02.112031 | 
2026-08-04 12:08:02.112196 | PLAY [all]
2026-08-04 12:08:02.133073 | 
2026-08-04 12:08:02.133235 | TASK [export-devstack-journal : Ensure /home/ubuntu/logs exists]
2026-08-04 12:08:02.843303 | undercloud-client | changed
2026-08-04 12:08:02.849451 | controller | changed
2026-08-04 12:08:03.066697 | lab-sp-a1 | changed
2026-08-04 12:08:03.072498 | 
2026-08-04 12:08:03.072646 | TASK [export-devstack-journal : Export legacy stack screen log files]
2026-08-04 12:08:04.681056 | lab-sp-a1 | ok: Runtime: 0:00:00.772649
2026-08-04 12:08:04.690907 | controller | ok: Runtime: 0:00:00.755477
2026-08-04 12:08:04.699183 | undercloud-client | ok: Runtime: 0:00:00.794798
2026-08-04 12:08:04.705250 | 
2026-08-04 12:08:04.705361 | TASK [export-devstack-journal : Export legacy syslog.txt]
2026-08-04 12:08:05.278169 | lab-sp-a1 | ok: Runtime: 0:00:00.010812
2026-08-04 12:08:05.287457 | controller | ok: Runtime: 0:00:00.010891
2026-08-04 12:08:05.295468 | undercloud-client | ok: Runtime: 0:00:00.014366
2026-08-04 12:08:05.300482 | 
2026-08-04 12:08:05.300567 | TASK [export-devstack-journal : Export journal]
2026-08-04 12:08:05.920964 | controller | ok: Runtime: 0:00:00.015779
2026-08-04 12:08:05.933326 | lab-sp-a1 | ok: Runtime: 0:00:00.015613
2026-08-04 12:08:05.948355 | undercloud-client | ok: Runtime: 0:00:00.023409
2026-08-04 12:08:05.956692 | 
2026-08-04 12:08:05.956814 | TASK [export-devstack-journal : Save journal README]
2026-08-04 12:08:06.649474 | undercloud-client | changed
2026-08-04 12:08:06.664859 | lab-sp-a1 | changed
2026-08-04 12:08:06.680298 | controller | changed
2026-08-04 12:08:06.701508 | 
2026-08-04 12:08:06.701683 | TASK [apache-logs-conf : Ensure /home/ubuntu/apache exists]
2026-08-04 12:08:06.932602 | controller | changed
2026-08-04 12:08:06.950432 | lab-sp-a1 | changed
2026-08-04 12:08:06.955186 | undercloud-client | changed
2026-08-04 12:08:06.960558 | 
2026-08-04 12:08:06.960658 | TASK [apache-logs-conf : Find logs]
2026-08-04 12:08:07.491681 | controller | Output suppressed because no_log was given
2026-08-04 12:08:07.498694 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:07.504893 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:07.511499 | 
2026-08-04 12:08:07.511651 | LOOP [apache-logs-conf : Dereference files]
2026-08-04 12:08:07.564595 | 
2026-08-04 12:08:07.564809 | LOOP [apache-logs-conf : Create hard links]
2026-08-04 12:08:07.619052 | 
2026-08-04 12:08:07.619296 | TASK [apache-logs-conf : Find logs]
2026-08-04 12:08:07.679339 | 
2026-08-04 12:08:07.679603 | LOOP [apache-logs-conf : Dereference files]
2026-08-04 12:08:07.735635 | 
2026-08-04 12:08:07.735872 | LOOP [apache-logs-conf : Create hard links]
2026-08-04 12:08:07.792239 | 
2026-08-04 12:08:07.792470 | TASK [apache-logs-conf : Ensure /home/ubuntu/apache_config apache_config exists]
2026-08-04 12:08:08.010021 | controller | changed
2026-08-04 12:08:08.037670 | lab-sp-a1 | changed
2026-08-04 12:08:08.044914 | undercloud-client | changed
2026-08-04 12:08:08.052520 | 
2026-08-04 12:08:08.052646 | TASK [apache-logs-conf : Define config paths]
2026-08-04 12:08:08.078040 | controller | ok
2026-08-04 12:08:08.130736 | lab-sp-a1 | ok
2026-08-04 12:08:08.147063 | undercloud-client | ok
2026-08-04 12:08:08.152914 | 
2026-08-04 12:08:08.153007 | TASK [apache-logs-conf : Discover configurations]
2026-08-04 12:08:08.358581 | controller | Output suppressed because no_log was given
2026-08-04 12:08:08.383990 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:08.388569 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:08.393282 | 
2026-08-04 12:08:08.393347 | LOOP [apache-logs-conf : Dereference configurations]
2026-08-04 12:08:08.440009 | 
2026-08-04 12:08:08.440237 | LOOP [apache-logs-conf : Link configurations]
2026-08-04 12:08:08.513454 | 
2026-08-04 12:08:08.513725 | TASK [capture-performance-data : Generate statistics]
2026-08-04 12:08:09.044683 | undercloud-client | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-08-04 12:08:09.053985 | undercloud-client | Using python 3.12 to install setuptools
2026-08-04 12:08:09.027669 | lab-sp-a1 | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-08-04 12:08:09.037414 | lab-sp-a1 | Using python 3.12 to install setuptools
2026-08-04 12:08:09.067589 | lab-sp-a1 | /usr/bin/python3.12: No module named pip
2026-08-04 12:08:09.051991 | controller | /opt/stack/devstack//inc/python: line 43: -m: command not found
2026-08-04 12:08:09.063022 | controller | Using python 3.12 to install setuptools
2026-08-04 12:08:09.466128 | undercloud-client | error: externally-managed-environment
2026-08-04 12:08:09.466163 | undercloud-client |
2026-08-04 12:08:09.466170 | undercloud-client | × This environment is externally managed
2026-08-04 12:08:09.466175 | undercloud-client | ╰─> To install Python packages system-wide, try apt install
2026-08-04 12:08:09.466188 | undercloud-client |     python3-xyz, where xyz is the package you are trying to
2026-08-04 12:08:09.466194 | undercloud-client |     install.
2026-08-04 12:08:09.466199 | undercloud-client |
2026-08-04 12:08:09.466204 | undercloud-client |     If you wish to install a non-Debian-packaged Python package,
2026-08-04 12:08:09.466209 | undercloud-client |     create a virtual environment using python3 -m venv path/to/venv.
2026-08-04 12:08:09.466213 | undercloud-client |     Then use path/to/venv/bin/python and path/to/venv/bin/pip. Make
2026-08-04 12:08:09.466216 | undercloud-client |     sure you have python3-full installed.
2026-08-04 12:08:09.466220 | undercloud-client |
2026-08-04 12:08:09.466223 | undercloud-client |     If you wish to install a non-Debian packaged Python application,
2026-08-04 12:08:09.466226 | undercloud-client |     it may be easiest to use pipx install xyz, which will manage a
2026-08-04 12:08:09.466230 | undercloud-client |     virtual environment for you. Make sure you have pipx installed.
2026-08-04 12:08:09.466233 | undercloud-client |
2026-08-04 12:08:09.466236 | undercloud-client |     See /usr/share/doc/python3.12/README.venv for more information.
2026-08-04 12:08:09.466240 | undercloud-client |
2026-08-04 12:08:09.466244 | 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:08:09.466253 | undercloud-client | hint: See PEP 668 for the detailed specification.
2026-08-04 12:08:09.537828 | undercloud-client | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-08-04 12:08:09.076087 | lab-sp-a1 | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-08-04 12:08:09.090640 | controller | /usr/bin/python3.12: No module named pip
2026-08-04 12:08:09.098342 | controller | /bin/bash: line 4: /opt/stack/data/venv/bin/python3: No such file or directory
2026-08-04 12:08:09.593373 | lab-sp-a1 | ERROR
2026-08-04 12:08:09.593650 | lab-sp-a1 | {
2026-08-04 12:08:09.593694 | lab-sp-a1 |   "delta": "0:00:00.271372",
2026-08-04 12:08:09.593715 | lab-sp-a1 |   "end": "2026-08-04 12:08:09.077264",
2026-08-04 12:08:09.593733 | lab-sp-a1 |   "msg": "non-zero return code",
2026-08-04 12:08:09.593749 | lab-sp-a1 |   "rc": 127,
2026-08-04 12:08:09.593765 | lab-sp-a1 |   "start": "2026-08-04 12:08:08.805892"
2026-08-04 12:08:09.593782 | lab-sp-a1 | }
2026-08-04 12:08:09.593805 | lab-sp-a1 | ERROR: Ignoring Errors
2026-08-04 12:08:09.606420 | controller | ERROR
2026-08-04 12:08:09.606663 | controller | {
2026-08-04 12:08:09.606697 | controller |   "delta": "0:00:00.279641",
2026-08-04 12:08:09.606719 | controller |   "end": "2026-08-04 12:08:09.099306",
2026-08-04 12:08:09.606737 | controller |   "msg": "non-zero return code",
2026-08-04 12:08:09.606753 | controller |   "rc": 127,
2026-08-04 12:08:09.606770 | controller |   "start": "2026-08-04 12:08:08.819665"
2026-08-04 12:08:09.606785 | controller | }
2026-08-04 12:08:09.606806 | controller | ERROR: Ignoring Errors
2026-08-04 12:08:09.619570 | undercloud-client | ERROR
2026-08-04 12:08:09.619759 | undercloud-client | {
2026-08-04 12:08:09.619789 | undercloud-client |   "delta": "0:00:00.712889",
2026-08-04 12:08:09.619809 | undercloud-client |   "end": "2026-08-04 12:08:09.538741",
2026-08-04 12:08:09.619827 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:08:09.619843 | undercloud-client |   "rc": 127,
2026-08-04 12:08:09.619860 | undercloud-client |   "start": "2026-08-04 12:08:08.825852"
2026-08-04 12:08:09.619875 | undercloud-client | }
2026-08-04 12:08:09.619900 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:08:09.648905 | 
2026-08-04 12:08:09.649084 | TASK [devstack-project-conf : Ensure /home/ubuntu/etc exists]
2026-08-04 12:08:09.885448 | controller | changed
2026-08-04 12:08:09.906455 | undercloud-client | changed
2026-08-04 12:08:09.914162 | lab-sp-a1 | changed
2026-08-04 12:08:09.922551 | 
2026-08-04 12:08:09.922701 | LOOP [devstack-project-conf : Check which projects have a config folder]
2026-08-04 12:08:12.551972 | controller | Output suppressed because no_log was given
2026-08-04 12:08:12.604701 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:12.606467 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:12.613809 | 
2026-08-04 12:08:12.613923 | LOOP [devstack-project-conf : Copy configuration files]
2026-08-04 12:08:12.643090 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.643629 | 
2026-08-04 12:08:12.644934 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.649333 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.650027 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.656479 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.657668 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.658354 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.659013 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.659675 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.660310 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.661079 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.661955 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.664042 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.664869 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.667415 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.668564 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.670951 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.671748 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.673812 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.674509 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.675518 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.676359 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.677074 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.679061 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.679739 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.680387 | controller | skipping: Conditional result was False
2026-08-04 12:08:12.681643 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.683716 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.684447 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.685309 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.698393 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:12.698600 | 
2026-08-04 12:08:12.700435 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.701083 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.701720 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.702460 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.703199 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.705629 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.708234 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.719564 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:12.719707 | 
2026-08-04 12:08:12.741766 | 
2026-08-04 12:08:12.741886 | TASK [devstack-project-conf : Check if openstack has a config folder]
2026-08-04 12:08:12.947944 | controller | ok
2026-08-04 12:08:12.964237 | lab-sp-a1 | ok
2026-08-04 12:08:12.978541 | undercloud-client | ok
2026-08-04 12:08:12.983449 | 
2026-08-04 12:08:12.983513 | TASK [devstack-project-conf : Copy configuration files]
2026-08-04 12:08:13.597158 | controller | skipping: Conditional result was False
2026-08-04 12:08:13.597858 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:13.598037 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:13.619762 | 
2026-08-04 12:08:13.619892 | TASK [capture-system-logs : Stage various logs and reports]
2026-08-04 12:08:13.977920 | lab-sp-a1 | /usr/bin/python3: No module named pip
2026-08-04 12:08:13.960153 | controller | /usr/bin/python3: No module named pip
2026-08-04 12:08:15.091474 | lab-sp-a1 | grep: /home/ubuntu/apache/*.log: No such file or directory
2026-08-04 12:08:15.092767 | controller | grep: /home/ubuntu/apache/*.log: No such file or directory
2026-08-04 12:08:15.197627 | lab-sp-a1 | ok: Runtime: 0:00:01.179355
2026-08-04 12:08:15.206379 | controller | ok: Runtime: 0:00:01.195963
2026-08-04 12:08:15.581579 | undercloud-client | ok: Runtime: 0:00:01.580671
2026-08-04 12:08:15.602997 | 
2026-08-04 12:08:15.603181 | LOOP [stage-output : Register sources]
2026-08-04 12:08:15.519203 | undercloud-client | grep: /home/ubuntu/apache/*.log: No such file or directory
2026-08-04 12:08:26.010777 | controller | Output suppressed because no_log was given
2026-08-04 12:08:26.203791 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:26.227811 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:26.235902 | 
2026-08-04 12:08:26.236032 | TASK [stage-output : Check sudo]
2026-08-04 12:08:26.851636 | controller | ok: Runtime: 0:00:00.015926
2026-08-04 12:08:26.866227 | lab-sp-a1 | ok: Runtime: 0:00:00.018065
2026-08-04 12:08:26.878611 | undercloud-client | ok: Runtime: 0:00:00.016139
2026-08-04 12:08:26.885501 | 
2026-08-04 12:08:26.885652 | LOOP [stage-output : Set source and destination for files and folders]
2026-08-04 12:08:26.988021 | controller | Output suppressed because no_log was given
2026-08-04 12:08:26.988404 | 
2026-08-04 12:08:26.989663 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.009292 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.028803 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.031391 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.038926 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.048555 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.054612 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.060448 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.060883 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.066063 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.071669 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.072067 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.072445 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.072815 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.085926 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.086631 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.087071 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.087421 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.098980 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.099876 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.100284 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.100656 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.136020 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.136651 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.136992 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.137320 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.137702 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.138092 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.138442 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.145317 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.146142 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.146630 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.147373 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.147860 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.148349 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.155290 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.155743 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.156090 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.156438 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.156819 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.157162 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.157549 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.157937 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.158389 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.158875 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.159560 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.160035 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.160444 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.167147 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.167663 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.168408 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.168819 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.181554 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.182119 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.182607 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.183065 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.183561 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.184016 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.184738 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.185090 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.185449 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.185792 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.186106 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.192721 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.193184 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.193707 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.194026 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.194387 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.194744 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.195217 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.221110 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.222213 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.230017 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.236562 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.237304 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.237670 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.238066 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.238639 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.244850 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.245309 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.253812 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.297457 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.303021 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.308832 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.398076 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.420649 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.425825 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.431655 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.432049 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.432377 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.446662 | controller | Output suppressed because no_log was given
2026-08-04 12:08:27.451455 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.462611 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.463021 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.470311 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.470869 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.478726 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.479373 | undercloud-client | Output suppressed because no_log was given
2026-08-04 12:08:27.491220 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.502275 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.502674 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.504782 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.507197 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.517003 | lab-sp-a1 | Output suppressed because no_log was given
2026-08-04 12:08:27.526020 | 
2026-08-04 12:08:27.526150 | TASK [stage-output : Build a list of source, dest dictionaries]
2026-08-04 12:08:27.588838 | controller | ok
2026-08-04 12:08:27.605953 | lab-sp-a1 | ok
2026-08-04 12:08:27.611273 | undercloud-client | ok
2026-08-04 12:08:27.620043 | 
2026-08-04 12:08:27.620204 | LOOP [stage-output : Ensure target folders exist]
2026-08-04 12:08:27.839516 | controller | changed: "docs"
2026-08-04 12:08:27.850190 | lab-sp-a1 | changed: "docs"
2026-08-04 12:08:27.868322 | undercloud-client | changed: "docs"
2026-08-04 12:08:28.028653 | controller | changed: "artifacts"
2026-08-04 12:08:28.055868 | lab-sp-a1 | changed: "artifacts"
2026-08-04 12:08:28.072963 | undercloud-client | changed: "artifacts"
2026-08-04 12:08:28.225947 | controller | ok: "logs"
2026-08-04 12:08:28.258466 | lab-sp-a1 | ok: "logs"
2026-08-04 12:08:28.258617 | lab-sp-a1 | changed: All items complete
2026-08-04 12:08:28.258655 | 
2026-08-04 12:08:28.275506 | undercloud-client | ok: "logs"
2026-08-04 12:08:28.288459 | 
2026-08-04 12:08:28.288593 | LOOP [stage-output : Copy files and folders to staging folder]
2026-08-04 12:08:28.541339 | controller | ok: Item: Runtime: 0:00:00.003749
2026-08-04 12:08:28.541625 | controller | changed: All items complete
2026-08-04 12:08:28.541655 | 
2026-08-04 12:08:28.556177 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003902
2026-08-04 12:08:28.590270 | undercloud-client | ok: Item: Runtime: 0:00:00.005063
2026-08-04 12:08:28.789349 | controller | ok: Item: Runtime: 0:00:00.006928
2026-08-04 12:08:28.797834 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.005300
2026-08-04 12:08:28.808745 | undercloud-client | ok: Item: Runtime: 0:00:00.007285
2026-08-04 12:08:28.935711 | controller | ok: Item: Runtime: 0:00:00.003635
2026-08-04 12:08:28.945047 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004906
2026-08-04 12:08:28.996998 | undercloud-client | ok: Item: Runtime: 0:00:00.004984
2026-08-04 12:08:29.138397 | controller | ok: Item: Runtime: 0:00:00.004480
2026-08-04 12:08:29.149144 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004760
2026-08-04 12:08:29.206558 | undercloud-client | ok: Item: Runtime: 0:00:00.005010
2026-08-04 12:08:29.346104 | controller | ok: Item: Runtime: 0:00:00.003800
2026-08-04 12:08:29.360297 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004012
2026-08-04 12:08:29.408807 | undercloud-client | ok: Item: Runtime: 0:00:00.005074
2026-08-04 12:08:29.529544 | controller | ok: Item: Runtime: 0:00:00.004076
2026-08-04 12:08:29.556918 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004714
2026-08-04 12:08:29.624257 | undercloud-client | ok: Item: Runtime: 0:00:00.005359
2026-08-04 12:08:29.737058 | controller | ok: Item: Runtime: 0:00:00.004195
2026-08-04 12:08:29.747316 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003855
2026-08-04 12:08:29.817910 | undercloud-client | ok: Item: Runtime: 0:00:00.004755
2026-08-04 12:08:29.942330 | controller | ok: Item: Runtime: 0:00:00.004095
2026-08-04 12:08:29.954199 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004545
2026-08-04 12:08:30.013225 | undercloud-client | ok: Item: Runtime: 0:00:00.004985
2026-08-04 12:08:30.143880 | controller | ok: Item: Runtime: 0:00:00.004413
2026-08-04 12:08:30.158588 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.005668
2026-08-04 12:08:30.216672 | undercloud-client | ok: Item: Runtime: 0:00:00.005074
2026-08-04 12:08:30.339295 | controller | ok: Item: Runtime: 0:00:00.004265
2026-08-04 12:08:30.353374 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004247
2026-08-04 12:08:30.413787 | undercloud-client | ok: Item: Runtime: 0:00:00.004802
2026-08-04 12:08:30.535417 | controller | ok: Item: Runtime: 0:00:00.004952
2026-08-04 12:08:30.550175 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004426
2026-08-04 12:08:30.613247 | undercloud-client | ok: Item: Runtime: 0:00:00.004874
2026-08-04 12:08:30.738669 | controller | ok: Item: Runtime: 0:00:00.004404
2026-08-04 12:08:30.754253 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004770
2026-08-04 12:08:30.820212 | undercloud-client | ok: Item: Runtime: 0:00:00.004997
2026-08-04 12:08:30.949270 | controller | ok: Item: Runtime: 0:00:00.004375
2026-08-04 12:08:30.983515 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004689
2026-08-04 12:08:31.023366 | undercloud-client | ok: Item: Runtime: 0:00:00.005338
2026-08-04 12:08:31.189440 | controller | ok: Item: Runtime: 0:00:00.003936
2026-08-04 12:08:31.199899 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004502
2026-08-04 12:08:31.229761 | undercloud-client | ok: Item: Runtime: 0:00:00.004953
2026-08-04 12:08:31.337268 | controller | ok: Item: Runtime: 0:00:00.003963
2026-08-04 12:08:31.408612 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.003725
2026-08-04 12:08:31.431350 | undercloud-client | ok: Item: Runtime: 0:00:00.004692
2026-08-04 12:08:31.563418 | controller | ok: Item: Runtime: 0:00:00.005600
2026-08-04 12:08:31.624007 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004435
2026-08-04 12:08:31.634739 | undercloud-client | ok: Item: Runtime: 0:00:00.004664
2026-08-04 12:08:31.808342 | controller | ok: Item: Runtime: 0:00:00.004358
2026-08-04 12:08:31.823659 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004710
2026-08-04 12:08:31.871821 | undercloud-client | ok: Item: Runtime: 0:00:00.005387
2026-08-04 12:08:32.034390 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.004630
2026-08-04 12:08:32.046576 | controller | ok: Item: Runtime: 0:00:00.005204
2026-08-04 12:08:32.087875 | undercloud-client | ok: Item: Runtime: 0:00:00.004930
2026-08-04 12:08:32.253787 | lab-sp-a1 | ok: Item: Runtime: 0:00:00.005014
2026-08-04 12:08:32.263835 | controller | ok: Item: Runtime: 0:00:00.004632
2026-08-04 12:08:32.294843 | undercloud-client | ok: Item: Runtime: 0:00:00.005430
2026-08-04 12:08:32.294960 | undercloud-client | changed: All items complete
2026-08-04 12:08:32.294984 | 
2026-08-04 12:08:32.315030 | 
2026-08-04 12:08:32.315157 | TASK [stage-output : Make all log files readable]
2026-08-04 12:08:32.573655 | controller | changed
2026-08-04 12:08:32.579009 | undercloud-client | changed
2026-08-04 12:08:32.583604 | lab-sp-a1 | changed
2026-08-04 12:08:32.589443 | 
2026-08-04 12:08:32.589508 | TASK [stage-output : Rename log files that match extensions_to_txt]
2026-08-04 12:08:32.969637 | controller | changed: Renamed files for staging.
2026-08-04 12:08:32.977372 | lab-sp-a1 | changed: Renamed files for staging.
2026-08-04 12:08:32.994165 | undercloud-client | changed: Renamed files for staging.
2026-08-04 12:08:32.999789 | 
2026-08-04 12:08:32.999878 | TASK [stage-output : Discover log files for compression]
2026-08-04 12:08:33.019912 | controller | skipping: Conditional result was False
2026-08-04 12:08:33.033629 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:33.044892 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:33.050949 | 
2026-08-04 12:08:33.051057 | LOOP [stage-output : Archive everything from logs]
2026-08-04 12:08:33.176970 | 
2026-08-04 12:08:33.177182 | TASK [fetch-devstack-log-dir : Collect devstack logs]
2026-08-04 12:08:33.870081 | undercloud-client | changed:
2026-08-04 12:08:33.870347 | undercloud-client | created directory /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/logs/undercloud-client
2026-08-04 12:08:33.870377 | undercloud-client | cd+++++++++ logs/
2026-08-04 12:08:33.870397 | undercloud-client | >f+++++++++ logs/deprecations_log.txt
2026-08-04 12:08:33.870415 | undercloud-client | >f+++++++++ logs/devstack.journal.README.txt
2026-08-04 12:08:33.870432 | undercloud-client | >f+++++++++ logs/devstack.journal.gz
2026-08-04 12:08:33.870449 | undercloud-client | >f+++++++++ logs/df.txt
2026-08-04 12:08:33.870466 | undercloud-client | >f+++++++++ logs/dpkg-l.txt
2026-08-04 12:08:33.870482 | undercloud-client | >f+++++++++ logs/iptables.txt
2026-08-04 12:08:33.870499 | undercloud-client | >f+++++++++ logs/iscsid.conf.txt
2026-08-04 12:08:33.870515 | undercloud-client | >f+++++++++ logs/listen53.txt
2026-08-04 12:08:33.870552 | undercloud-client | >f+++++++++ logs/local_conf.txt
2026-08-04 12:08:33.870571 | undercloud-client | >f+++++++++ logs/mount.txt
2026-08-04 12:08:33.870587 | undercloud-client | >f+++++++++ logs/multipath.conf.txt
2026-08-04 12:08:33.870604 | undercloud-client | >f+++++++++ logs/performance.json
2026-08-04 12:08:33.870620 | undercloud-client | >f+++++++++ logs/pip3-freeze.txt
2026-08-04 12:08:33.870636 | undercloud-client | >f+++++++++ logs/resolv_conf.txt
2026-08-04 12:08:33.870652 | undercloud-client | >f+++++++++ logs/services.txt
2026-08-04 12:08:33.870668 | undercloud-client | >f+++++++++ logs/sudoers
2026-08-04 12:08:33.870684 | undercloud-client | >f+++++++++ logs/syslog.txt
2026-08-04 12:08:33.870700 | undercloud-client | cd+++++++++ logs/apache/
2026-08-04 12:08:33.870715 | undercloud-client | cd+++++++++ logs/apache_config/
2026-08-04 12:08:33.870731 | undercloud-client | cd+++++++++ logs/etc/
2026-08-04 12:08:33.870747 | undercloud-client | cd+++++++++ logs/lvm/
2026-08-04 12:08:33.870763 | undercloud-client | >f+++++++++ logs/lvm/lvm_conf.txt
2026-08-04 12:08:33.870780 | undercloud-client | >f+++++++++ logs/lvm/lvmlocal_conf.txt
2026-08-04 12:08:33.870803 | undercloud-client | cd+++++++++ logs/lvm/profile/
2026-08-04 12:08:33.870820 | undercloud-client | >f+++++++++ logs/lvm/profile/cache-mq.profile
2026-08-04 12:08:33.870836 | undercloud-client | >f+++++++++ logs/lvm/profile/cache-smq.profile
2026-08-04 12:08:33.870852 | undercloud-client | >f+++++++++ logs/lvm/profile/command_profile_template.profile
2026-08-04 12:08:33.870868 | undercloud-client | >f+++++++++ logs/lvm/profile/lvmdbusd.profile
2026-08-04 12:08:33.870885 | undercloud-client | >f+++++++++ logs/lvm/profile/metadata_profile_template.profile
2026-08-04 12:08:33.870901 | undercloud-client | >f+++++++++ logs/lvm/profile/thin-generic.profile
2026-08-04 12:08:33.870916 | undercloud-client | >f+++++++++ logs/lvm/profile/thin-performance.profile
2026-08-04 12:08:33.870932 | undercloud-client | >f+++++++++ logs/lvm/profile/vdo-small.profile
2026-08-04 12:08:33.870948 | undercloud-client | cd+++++++++ logs/sudoers.d/
2026-08-04 12:08:33.870965 | undercloud-client | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-08-04 12:08:33.870981 | undercloud-client | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-08-04 12:08:33.870997 | undercloud-client | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-08-04 12:08:33.871014 | undercloud-client | >f+++++++++ logs/sudoers.d/README
2026-08-04 12:08:33.871030 | undercloud-client | >f+++++++++ logs/sudoers.d/zuul
2026-08-04 12:08:33.897987 | controller | changed:
2026-08-04 12:08:33.898227 | controller | created directory /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/logs/controller
2026-08-04 12:08:33.898271 | controller | cd+++++++++ logs/
2026-08-04 12:08:33.898294 | controller | >f+++++++++ logs/deprecations_log.txt
2026-08-04 12:08:33.898313 | controller | >f+++++++++ logs/devstack.journal.README.txt
2026-08-04 12:08:33.898331 | controller | >f+++++++++ logs/devstack.journal.gz
2026-08-04 12:08:33.898352 | controller | >f+++++++++ logs/df.txt
2026-08-04 12:08:33.898369 | controller | >f+++++++++ logs/dpkg-l.txt
2026-08-04 12:08:33.898386 | controller | >f+++++++++ logs/iptables.txt
2026-08-04 12:08:33.898407 | controller | >f+++++++++ logs/iscsid.conf.txt
2026-08-04 12:08:33.898425 | controller | >f+++++++++ logs/listen53.txt
2026-08-04 12:08:33.898443 | controller | >f+++++++++ logs/local_conf.txt
2026-08-04 12:08:33.898460 | controller | >f+++++++++ logs/mount.txt
2026-08-04 12:08:33.898482 | controller | >f+++++++++ logs/multipath.conf.txt
2026-08-04 12:08:33.898501 | controller | >f+++++++++ logs/performance.json
2026-08-04 12:08:33.898518 | controller | >f+++++++++ logs/pip3-freeze.txt
2026-08-04 12:08:33.898581 | controller | >f+++++++++ logs/resolv_conf.txt
2026-08-04 12:08:33.898612 | controller | >f+++++++++ logs/services.txt
2026-08-04 12:08:33.898635 | controller | >f+++++++++ logs/sudoers
2026-08-04 12:08:33.898654 | controller | >f+++++++++ logs/syslog.txt
2026-08-04 12:08:33.898671 | controller | cd+++++++++ logs/apache/
2026-08-04 12:08:33.898689 | controller | cd+++++++++ logs/apache_config/
2026-08-04 12:08:33.898709 | controller | cd+++++++++ logs/etc/
2026-08-04 12:08:33.898726 | controller | cd+++++++++ logs/lvm/
2026-08-04 12:08:33.898742 | controller | >f+++++++++ logs/lvm/lvm_conf.txt
2026-08-04 12:08:33.898761 | controller | >f+++++++++ logs/lvm/lvmlocal_conf.txt
2026-08-04 12:08:33.898780 | controller | cd+++++++++ logs/lvm/profile/
2026-08-04 12:08:33.898796 | controller | >f+++++++++ logs/lvm/profile/cache-mq.profile
2026-08-04 12:08:33.898813 | controller | >f+++++++++ logs/lvm/profile/cache-smq.profile
2026-08-04 12:08:33.898832 | controller | >f+++++++++ logs/lvm/profile/command_profile_template.profile
2026-08-04 12:08:33.898849 | controller | >f+++++++++ logs/lvm/profile/lvmdbusd.profile
2026-08-04 12:08:33.898866 | controller | >f+++++++++ logs/lvm/profile/metadata_profile_template.profile
2026-08-04 12:08:33.898883 | controller | >f+++++++++ logs/lvm/profile/thin-generic.profile
2026-08-04 12:08:33.898902 | controller | >f+++++++++ logs/lvm/profile/thin-performance.profile
2026-08-04 12:08:33.898930 | controller | >f+++++++++ logs/lvm/profile/vdo-small.profile
2026-08-04 12:08:33.898948 | controller | cd+++++++++ logs/sudoers.d/
2026-08-04 12:08:33.898967 | controller | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-08-04 12:08:33.898985 | controller | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-08-04 12:08:33.899002 | controller | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-08-04 12:08:33.899018 | controller | >f+++++++++ logs/sudoers.d/README
2026-08-04 12:08:33.899038 | controller | >f+++++++++ logs/sudoers.d/zuul
2026-08-04 12:08:33.917179 | lab-sp-a1 | changed:
2026-08-04 12:08:33.917341 | lab-sp-a1 | created directory /var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/logs/lab-sp-a1
2026-08-04 12:08:33.917381 | lab-sp-a1 | cd+++++++++ logs/
2026-08-04 12:08:33.917410 | lab-sp-a1 | >f+++++++++ logs/deprecations_log.txt
2026-08-04 12:08:33.917430 | lab-sp-a1 | >f+++++++++ logs/devstack.journal.README.txt
2026-08-04 12:08:33.917447 | lab-sp-a1 | >f+++++++++ logs/devstack.journal.gz
2026-08-04 12:08:33.917464 | lab-sp-a1 | >f+++++++++ logs/df.txt
2026-08-04 12:08:33.917481 | lab-sp-a1 | >f+++++++++ logs/dpkg-l.txt
2026-08-04 12:08:33.917498 | lab-sp-a1 | >f+++++++++ logs/iptables.txt
2026-08-04 12:08:33.917514 | lab-sp-a1 | >f+++++++++ logs/iscsid.conf.txt
2026-08-04 12:08:33.917564 | lab-sp-a1 | >f+++++++++ logs/listen53.txt
2026-08-04 12:08:33.917587 | lab-sp-a1 | >f+++++++++ logs/local_conf.txt
2026-08-04 12:08:33.917605 | lab-sp-a1 | >f+++++++++ logs/mount.txt
2026-08-04 12:08:33.917622 | lab-sp-a1 | >f+++++++++ logs/multipath.conf.txt
2026-08-04 12:08:33.917639 | lab-sp-a1 | >f+++++++++ logs/performance.json
2026-08-04 12:08:33.917655 | lab-sp-a1 | >f+++++++++ logs/pip3-freeze.txt
2026-08-04 12:08:33.917671 | lab-sp-a1 | >f+++++++++ logs/resolv_conf.txt
2026-08-04 12:08:33.917687 | lab-sp-a1 | >f+++++++++ logs/services.txt
2026-08-04 12:08:33.917704 | lab-sp-a1 | >f+++++++++ logs/sudoers
2026-08-04 12:08:33.917720 | lab-sp-a1 | >f+++++++++ logs/syslog.txt
2026-08-04 12:08:33.917736 | lab-sp-a1 | cd+++++++++ logs/apache/
2026-08-04 12:08:33.917752 | lab-sp-a1 | cd+++++++++ logs/apache_config/
2026-08-04 12:08:33.917768 | lab-sp-a1 | cd+++++++++ logs/etc/
2026-08-04 12:08:33.917785 | lab-sp-a1 | cd+++++++++ logs/lvm/
2026-08-04 12:08:33.917876 | lab-sp-a1 | >f+++++++++ logs/lvm/lvm_conf.txt
2026-08-04 12:08:33.917910 | lab-sp-a1 | >f+++++++++ logs/lvm/lvmlocal_conf.txt
2026-08-04 12:08:33.917937 | lab-sp-a1 | cd+++++++++ logs/lvm/profile/
2026-08-04 12:08:33.917964 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/cache-mq.profile
2026-08-04 12:08:33.917992 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/cache-smq.profile
2026-08-04 12:08:33.918011 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/command_profile_template.profile
2026-08-04 12:08:33.918028 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/lvmdbusd.profile
2026-08-04 12:08:33.918044 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/metadata_profile_template.profile
2026-08-04 12:08:33.918061 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/thin-generic.profile
2026-08-04 12:08:33.918077 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/thin-performance.profile
2026-08-04 12:08:33.918094 | lab-sp-a1 | >f+++++++++ logs/lvm/profile/vdo-small.profile
2026-08-04 12:08:33.918111 | lab-sp-a1 | cd+++++++++ logs/sudoers.d/
2026-08-04 12:08:33.918144 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/50_stack_sh
2026-08-04 12:08:33.918164 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/51_tempest_sh
2026-08-04 12:08:33.918182 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/90-cloud-init-users
2026-08-04 12:08:33.918198 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/README
2026-08-04 12:08:33.918215 | lab-sp-a1 | >f+++++++++ logs/sudoers.d/zuul
2026-08-04 12:08:33.940283 | 
2026-08-04 12:08:33.940420 | TASK [Check if a tempest log exits]
2026-08-04 12:08:34.205070 | controller | ok
2026-08-04 12:08:34.210618 | lab-sp-a1 | ok
2026-08-04 12:08:34.236251 | undercloud-client | ok
2026-08-04 12:08:34.241579 | 
2026-08-04 12:08:34.241700 | TASK [Link post-devstack tempest.log]
2026-08-04 12:08:34.277577 | controller | skipping: Conditional result was False
2026-08-04 12:08:34.278716 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:34.289270 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:34.296477 | 
2026-08-04 12:08:34.296637 | TASK [Capture most recent qemu crash dump, if any]
2026-08-04 12:08:34.614670 | undercloud-client | /bin/bash: line 1: coredumpctl: command not found
2026-08-04 12:08:34.601152 | lab-sp-a1 | /bin/bash: line 1: coredumpctl: command not found
2026-08-04 12:08:34.578914 | controller | /bin/bash: line 1: coredumpctl: command not found
2026-08-04 12:08:34.898797 | controller | ERROR
2026-08-04 12:08:34.898966 | controller | {
2026-08-04 12:08:34.898994 | controller |   "delta": "0:00:00.003875",
2026-08-04 12:08:34.899014 | controller |   "end": "2026-08-04 12:08:34.579234",
2026-08-04 12:08:34.899031 | controller |   "msg": "non-zero return code",
2026-08-04 12:08:34.899047 | controller |   "rc": 127,
2026-08-04 12:08:34.899065 | controller |   "start": "2026-08-04 12:08:34.575359"
2026-08-04 12:08:34.899082 | controller | }
2026-08-04 12:08:34.899103 | controller | ERROR: Ignoring Errors
2026-08-04 12:08:34.907633 | lab-sp-a1 | ERROR
2026-08-04 12:08:34.907709 | lab-sp-a1 | {
2026-08-04 12:08:34.907734 | lab-sp-a1 |   "delta": "0:00:00.005419",
2026-08-04 12:08:34.907752 | lab-sp-a1 |   "end": "2026-08-04 12:08:34.601549",
2026-08-04 12:08:34.907769 | lab-sp-a1 |   "msg": "non-zero return code",
2026-08-04 12:08:34.907785 | lab-sp-a1 |   "rc": 127,
2026-08-04 12:08:34.907800 | lab-sp-a1 |   "start": "2026-08-04 12:08:34.596130"
2026-08-04 12:08:34.907815 | lab-sp-a1 | }
2026-08-04 12:08:34.907836 | lab-sp-a1 | ERROR: Ignoring Errors
2026-08-04 12:08:34.916513 | undercloud-client | ERROR
2026-08-04 12:08:34.916618 | undercloud-client | {
2026-08-04 12:08:34.916645 | undercloud-client |   "delta": "0:00:00.005560",
2026-08-04 12:08:34.916664 | undercloud-client |   "end": "2026-08-04 12:08:34.615125",
2026-08-04 12:08:34.916681 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:08:34.916697 | undercloud-client |   "rc": 127,
2026-08-04 12:08:34.916713 | undercloud-client |   "start": "2026-08-04 12:08:34.609565"
2026-08-04 12:08:34.916728 | undercloud-client | }
2026-08-04 12:08:34.916749 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:08:34.921600 | 
2026-08-04 12:08:34.921676 | TASK [Collect Guru Meditation Reports from OpenStack services]
2026-08-04 12:08:35.562849 | undercloud-client | ERROR
2026-08-04 12:08:35.563079 | undercloud-client | {
2026-08-04 12:08:35.563109 | undercloud-client |   "delta": "0:00:00.029687",
2026-08-04 12:08:35.563141 | undercloud-client |   "end": "2026-08-04 12:08:35.324884",
2026-08-04 12:08:35.563162 | undercloud-client |   "msg": "non-zero return code",
2026-08-04 12:08:35.563178 | undercloud-client |   "rc": -12,
2026-08-04 12:08:35.563195 | undercloud-client |   "start": "2026-08-04 12:08:35.295197"
2026-08-04 12:08:35.563211 | undercloud-client | }
2026-08-04 12:08:35.563233 | undercloud-client | ERROR: Ignoring Errors
2026-08-04 12:08:35.573272 | controller | ERROR
2026-08-04 12:08:35.573409 | controller | {
2026-08-04 12:08:35.573439 | controller |   "delta": "0:00:00.053074",
2026-08-04 12:08:35.573459 | controller |   "end": "2026-08-04 12:08:35.330495",
2026-08-04 12:08:35.573477 | controller |   "msg": "non-zero return code",
2026-08-04 12:08:35.573493 | controller |   "rc": -12,
2026-08-04 12:08:35.573511 | controller |   "start": "2026-08-04 12:08:35.277421"
2026-08-04 12:08:35.573547 | controller | }
2026-08-04 12:08:35.573571 | controller | ERROR: Ignoring Errors
2026-08-04 12:08:35.582732 | lab-sp-a1 | ERROR
2026-08-04 12:08:35.582934 | lab-sp-a1 | {
2026-08-04 12:08:35.582987 | lab-sp-a1 |   "delta": "0:00:00.046581",
2026-08-04 12:08:35.583023 | lab-sp-a1 |   "end": "2026-08-04 12:08:35.344061",
2026-08-04 12:08:35.583051 | lab-sp-a1 |   "msg": "non-zero return code",
2026-08-04 12:08:35.583077 | lab-sp-a1 |   "rc": -12,
2026-08-04 12:08:35.583101 | lab-sp-a1 |   "start": "2026-08-04 12:08:35.297480"
2026-08-04 12:08:35.583149 | lab-sp-a1 | }
2026-08-04 12:08:35.583185 | lab-sp-a1 | ERROR: Ignoring Errors
2026-08-04 12:08:35.584882 | 
2026-08-04 12:08:35.584931 | PLAY RECAP
2026-08-04 12:08:35.584975 | controller | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-08-04 12:08:35.585001 | lab-sp-a1 | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-08-04 12:08:35.585024 | undercloud-client | ok: 27 changed: 18 unreachable: 0 failed: 0 skipped: 12 rescued: 0 ignored: 3
2026-08-04 12:08:35.585041 | 
2026-08-04 12:08:35.796407 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/devstack/playbooks/post.yaml@master]
2026-08-04 12:08:35.808388 | POST-RUN START: [trusted : gerrit-openstack-ci.sof-k8s.storpool.net/config/playbooks/base/post.yaml@master]
2026-08-04 12:08:36.714118 | 
2026-08-04 12:08:36.714271 | PLAY [all]
2026-08-04 12:08:36.729779 | 
2026-08-04 12:08:36.729981 | TASK [include_role : fetch-output]
2026-08-04 12:08:36.766978 | controller | ok
2026-08-04 12:08:36.771713 | lab-sp-a1 | ok
2026-08-04 12:08:36.787870 | undercloud-client | ok
2026-08-04 12:08:36.813034 | 
2026-08-04 12:08:36.813207 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-04 12:08:36.869867 | controller | ok
2026-08-04 12:08:36.885428 | lab-sp-a1 | ok
2026-08-04 12:08:36.900558 | undercloud-client | ok
2026-08-04 12:08:36.906807 | 
2026-08-04 12:08:36.906936 | TASK [fetch-output : Set log path for single node]
2026-08-04 12:08:36.938570 | controller | skipping: Conditional result was False
2026-08-04 12:08:36.949950 | lab-sp-a1 | skipping: Conditional result was False
2026-08-04 12:08:36.960629 | undercloud-client | skipping: Conditional result was False
2026-08-04 12:08:36.966861 | 
2026-08-04 12:08:36.966995 | LOOP [fetch-output : Ensure local output dirs]
2026-08-04 12:08:37.350332 | undercloud-client -> localhost | ok: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/logs/undercloud-client"
2026-08-04 12:08:37.365376 | controller -> localhost | ok: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/logs/controller"
2026-08-04 12:08:37.415106 | lab-sp-a1 -> localhost | ok: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/logs/lab-sp-a1"
2026-08-04 12:08:37.564695 | undercloud-client -> localhost | changed: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/artifacts"
2026-08-04 12:08:37.590278 | controller -> localhost | ok: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/artifacts"
2026-08-04 12:08:37.624123 | lab-sp-a1 -> localhost | ok: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/artifacts"
2026-08-04 12:08:37.797686 | undercloud-client -> localhost | changed: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/docs"
2026-08-04 12:08:37.810102 | controller -> localhost | ok: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/docs"
2026-08-04 12:08:37.810301 | controller -> localhost | changed: All items complete
2026-08-04 12:08:37.810348 | 
2026-08-04 12:08:37.862322 | lab-sp-a1 -> localhost | ok: "/var/lib/zuul/builds/9ca4d3e380ca4996acabe952b28f2fcc/work/docs"
2026-08-04 12:08:37.875244 | 
2026-08-04 12:08:37.875372 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-04 12:08:38.651638 | controller | changed: .d..t...... ./
2026-08-04 12:08:38.651932 | controller | ok: All items complete
2026-08-04 12:08:38.651969 | 
2026-08-04 12:08:38.657997 | lab-sp-a1 | changed: .d..t...... ./
2026-08-04 12:08:38.936436 | undercloud-client | changed: .d..t...... ./
2026-08-04 12:08:39.224665 | controller | changed: .d..t...... ./
2026-08-04 12:08:39.264657 | lab-sp-a1 | ok
2026-08-04 12:08:39.495673 | undercloud-client | ok
2026-08-04 12:08:39.817882 | controller | changed: .d..t...... ./
2026-08-04 12:08:39.883308 | lab-sp-a1 | ok
2026-08-04 12:08:40.088723 | undercloud-client | ok
2026-08-04 12:08:40.088892 | undercloud-client | changed: All items complete
2026-08-04 12:08:40.088932 | 
2026-08-04 12:08:40.118691 | 
2026-08-04 12:08:40.118825 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-04 12:08:40.580968 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007928
2026-08-04 12:08:40.844588 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007430
2026-08-04 12:08:40.870401 | 
2026-08-04 12:08:40.882376 | PLAY [localhost]
2026-08-04 12:08:40.892263 | 
2026-08-04 12:08:40.892388 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-04 12:08:41.296281 | localhost | changed
2026-08-04 12:08:41.362151 | 
2026-08-04 12:08:41.362329 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-04 12:08:41.392680 | localhost | ok
2026-08-04 12:08:41.400693 | 
2026-08-04 12:08:41.400799 | TASK [ara-report : Check that ARA is installed]
2026-08-04 12:08:41.654882 | localhost | ok: Runtime: 0:00:00.004998
2026-08-04 12:08:41.662126 | 
2026-08-04 12:08:41.662309 | TASK [ara-report : Warn if ARA is not installed]
2026-08-04 12:08:41.694653 | ARA is not installed, no report will be available.
2026-08-04 12:08:41.701082 | 
2026-08-04 12:08:41.701260 | TASK [ara-report : Check that the ARA database exists]
2026-08-04 12:08:42.028099 | localhost | ok
2026-08-04 12:08:42.034356 | 
2026-08-04 12:08:42.034510 | TASK [ara-report : Warn if no database is found]
2026-08-04 12:08:42.065801 | No report will be available because the ARA database was not found.
2026-08-04 12:08:42.072197 | 
2026-08-04 12:08:42.072353 | TASK [ara-report : Validate role configuration]
2026-08-04 12:08:42.102192 | localhost | ok: All assertions passed
2026-08-04 12:08:42.106613 | 
2026-08-04 12:08:42.106696 | TASK [ara-report : Generate ARA HTML output]
2026-08-04 12:08:42.121170 | localhost | skipping: Conditional result was False
2026-08-04 12:08:42.125900 | 
2026-08-04 12:08:42.125994 | TASK [ara-report : Compress ARA HTML output]
2026-08-04 12:08:42.139431 | localhost | skipping: Conditional result was False
2026-08-04 12:08:42.144036 | 
2026-08-04 12:08:42.144110 | TASK [ara-report : Return ARA report]
2026-08-04 12:08:42.157352 | localhost | skipping: Conditional result was False
2026-08-04 12:08:42.163384 | 
2026-08-04 12:08:42.163468 | TASK [ara-report : Create the ARA database report directory]
2026-08-04 12:08:42.186742 | localhost | skipping: Conditional result was False
2026-08-04 12:08:42.191505 | 
2026-08-04 12:08:42.191591 | TASK [ara-report : Save the ARA database]
2026-08-04 12:08:42.206677 | localhost | skipping: Conditional result was False
2026-08-04 12:08:42.211443 | 
2026-08-04 12:08:42.211513 | TASK [ara-report : Return ARA report]
2026-08-04 12:08:42.234429 | localhost | skipping: Conditional result was False
2026-08-04 12:08:42.240761 | 
2026-08-04 12:08:42.240812 | PLAY [localhost]
2026-08-04 12:08:42.246019 | 
2026-08-04 12:08:42.246082 | TASK [Set zuul-log-path fact]
2026-08-04 12:08:42.263717 | localhost | ok
2026-08-04 12:08:42.274569 | 
2026-08-04 12:08:42.274639 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-04 12:08:42.303818 | localhost | ok
2026-08-04 12:08:42.311455 | 
2026-08-04 12:08:42.311569 | TASK [upload-logs : Create log directories]
2026-08-04 12:08:42.573983 | localhost | changed
2026-08-04 12:08:42.578684 | 
2026-08-04 12:08:42.578810 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-08-04 12:08:42.872218 | localhost -> localhost | ok: Runtime: 0:00:00.005552
2026-08-04 12:08:42.876744 | 
2026-08-04 12:08:42.876858 | TASK [upload-logs : Upload logs to log server]
2026-08-04 12:08:43.879516 | localhost | Output suppressed because no_log was given
2026-08-04 12:08:43.883383 | 
2026-08-04 12:08:43.883456 | LOOP [upload-logs : Compress console log and json output]
2026-08-04 12:08:43.926743 | localhost | skipping: Conditional result was False
2026-08-04 12:08:43.927093 | localhost | changed: All items complete
2026-08-04 12:08:43.927145 | 
2026-08-04 12:08:43.935162 | localhost | skipping: Conditional result was False
2026-08-04 12:08:43.945309 | 
2026-08-04 12:08:43.945457 | LOOP [upload-logs : Upload compressed console log and json output]
2026-08-04 12:08:43.987313 | localhost | skipping: Conditional result was False
2026-08-04 12:08:43.987703 | 
2026-08-04 12:08:43.992263 | localhost | skipping: Conditional result was False
2026-08-04 12:08:44.008281 | 
2026-08-04 12:08:44.008436 | LOOP [upload-logs : Upload console log and json output]