2026-08-04 08:37:15.701730 | Job console starting
2026-08-04 08:37:15.725358 | Updating git repos
2026-08-04 08:37:16.044876 | Cloning repos into workspace
2026-08-04 08:37:21.758439 | Restoring repo states
2026-08-04 08:37:21.886022 | Merging changes
2026-08-04 08:37:23.259361 | Checking out repos
2026-08-04 08:37:24.069813 | Preparing playbooks
2026-08-04 08:37:35.394014 | Running Ansible setup
2026-08-04 08:37:45.841459 | PRE-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2026-08-04 08:37:47.109084 | 
2026-08-04 08:37:47.109329 | PLAY [Create job header and inventory]
2026-08-04 08:37:47.127241 | 
2026-08-04 08:37:47.127406 | TASK [Gathering Facts]
2026-08-04 08:37:48.398407 | localhost | ok
2026-08-04 08:37:48.412271 | 
2026-08-04 08:37:48.412486 | TASK [Setup log path fact]
2026-08-04 08:37:48.439738 | localhost | ok
2026-08-04 08:37:48.452405 | localhost | included: set-zuul-log-path-fact
2026-08-04 08:37:48.459835 | 
2026-08-04 08:37:48.460017 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-04 08:37:48.503338 | localhost | ok
2026-08-04 08:37:48.515905 | 
2026-08-04 08:37:48.516121 | TASK [emit-job-header : Print job information]
2026-08-04 08:37:48.579822 | # Job Information
2026-08-04 08:37:48.580415 | Ansible Version: 2.18.18
2026-08-04 08:37:48.580496 | Job: kolla-ansible-debian-trixie
2026-08-04 08:37:48.580559 | Pipeline: check
2026-08-04 08:37:48.580619 | Executor: ze06.opendev.org
2026-08-04 08:37:48.580679 | Triggered by: https://review.opendev.org/c/openstack/kolla/+/999090
2026-08-04 08:37:48.580738 | Event ID: 42a6d7d3c4c64214b355b71927d233a4
2026-08-04 08:37:48.586128 | 
2026-08-04 08:37:48.586310 | LOOP [emit-job-header : Print node information]
2026-08-04 08:37:48.738067 | localhost | ok:
2026-08-04 08:37:48.738340 | localhost | # Node Information
2026-08-04 08:37:48.738410 | localhost | Inventory Hostname: primary
2026-08-04 08:37:48.738472 | localhost | Hostname: np6fb4121c6a214
2026-08-04 08:37:48.738531 | localhost | Username: zuul
2026-08-04 08:37:48.738596 | localhost | Distro: Debian 13.6
2026-08-04 08:37:48.738654 | localhost | Provider: opendev.org%2Fopendev%2Fzuul-providers/vexxhost-ca-ymq-1-main
2026-08-04 08:37:48.738711 | localhost | Region: ca-ymq-1
2026-08-04 08:37:48.738767 | localhost | Label: debian-trixie-16GB
2026-08-04 08:37:48.738822 | localhost | Product Name: OpenStack Nova
2026-08-04 08:37:48.738878 | localhost | Interface IP: 199.204.45.109
2026-08-04 08:37:48.755874 | 
2026-08-04 08:37:48.756025 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-08-04 08:37:49.324719 | localhost -> localhost | changed
2026-08-04 08:37:49.333527 | 
2026-08-04 08:37:49.333653 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-08-04 08:37:50.611632 | localhost -> localhost | changed
2026-08-04 08:37:50.622447 | 
2026-08-04 08:37:50.622555 | PLAY [Setup Zuul environment]
2026-08-04 08:37:50.633572 | 
2026-08-04 08:37:50.633693 | TASK [Gather network facts]
2026-08-04 08:37:51.483046 | primary | ok
2026-08-04 08:37:51.495712 | 
2026-08-04 08:37:51.495825 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-08-04 08:37:51.856500 | primary -> localhost | ok
2026-08-04 08:37:51.864681 | 
2026-08-04 08:37:51.864822 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-08-04 08:37:51.896362 | primary | ok
2026-08-04 08:37:51.919862 | primary | included: /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-08-04 08:37:51.927053 | 
2026-08-04 08:37:51.927200 | TASK [add-build-sshkey : Create Temp SSH key]
2026-08-04 08:37:55.707043 | primary -> localhost | Generating public/private rsa key pair.
2026-08-04 08:37:55.707315 | primary -> localhost | Your identification has been saved in /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/work/b5f7e4cb6dd44f508f50ccb681e944ac_id_rsa
2026-08-04 08:37:55.707389 | primary -> localhost | Your public key has been saved in /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/work/b5f7e4cb6dd44f508f50ccb681e944ac_id_rsa.pub
2026-08-04 08:37:55.707515 | primary -> localhost | The key fingerprint is:
2026-08-04 08:37:55.707582 | primary -> localhost | SHA256:9n5rg/3ShVKL0p7IfZEyoK2BiLEp04BYGY7PjQzBsRY zuul-build-sshkey
2026-08-04 08:37:55.707642 | primary -> localhost | The key's randomart image is:
2026-08-04 08:37:55.707700 | primary -> localhost | +---[RSA 3072]----+
2026-08-04 08:37:55.707757 | primary -> localhost | |oEoo             |
2026-08-04 08:37:55.707814 | primary -> localhost | | ==              |
2026-08-04 08:37:55.707869 | primary -> localhost | |=+.              |
2026-08-04 08:37:55.707925 | primary -> localhost | |==.o      .   .  |
2026-08-04 08:37:55.707984 | primary -> localhost | | o==.. .So o o + |
2026-08-04 08:37:55.708039 | primary -> localhost | |o = . ..o.o * = .|
2026-08-04 08:37:55.708123 | primary -> localhost | | o       +.B * o |
2026-08-04 08:37:55.708190 | primary -> localhost | |        ..+ X o  |
2026-08-04 08:37:55.708289 | primary -> localhost | |          .o.*.  |
2026-08-04 08:37:55.708393 | primary -> localhost | +----[SHA256]-----+
2026-08-04 08:37:55.708506 | primary -> localhost | ok: Runtime: 0:00:03.150658
2026-08-04 08:37:55.720956 | 
2026-08-04 08:37:55.721186 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-08-04 08:37:55.756782 | primary | ok
2026-08-04 08:37:55.771768 | primary | included: /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-08-04 08:37:55.782065 | 
2026-08-04 08:37:55.782209 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-08-04 08:37:55.818122 | primary | skipping: Conditional result was False
2026-08-04 08:37:55.826589 | 
2026-08-04 08:37:55.826740 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-08-04 08:37:56.615003 | primary | changed
2026-08-04 08:37:56.621961 | 
2026-08-04 08:37:56.622074 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-08-04 08:37:57.016427 | primary | ok
2026-08-04 08:37:57.023466 | 
2026-08-04 08:37:57.023590 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-08-04 08:37:58.678591 | primary | changed
2026-08-04 08:37:58.685493 | 
2026-08-04 08:37:58.685630 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-08-04 08:38:00.407255 | primary | changed
2026-08-04 08:38:00.438222 | 
2026-08-04 08:38:00.438465 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-08-04 08:38:00.494385 | primary | skipping: Conditional result was False
2026-08-04 08:38:00.526063 | 
2026-08-04 08:38:00.526230 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-08-04 08:38:01.367501 | primary -> localhost | changed
2026-08-04 08:38:01.382165 | 
2026-08-04 08:38:01.382289 | TASK [add-build-sshkey : Add back temp key]
2026-08-04 08:38:01.875299 | primary -> localhost | Identity added: /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/work/b5f7e4cb6dd44f508f50ccb681e944ac_id_rsa (zuul-build-sshkey)
2026-08-04 08:38:01.875626 | primary -> localhost | ok: Runtime: 0:00:00.017462
2026-08-04 08:38:01.882277 | 
2026-08-04 08:38:01.882387 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-08-04 08:38:02.572541 | primary | ok
2026-08-04 08:38:02.579530 | 
2026-08-04 08:38:02.579712 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-08-04 08:38:02.615912 | primary | skipping: Conditional result was False
2026-08-04 08:38:02.629491 | 
2026-08-04 08:38:02.629710 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-08-04 08:38:03.281309 | primary | ok
2026-08-04 08:38:03.301600 | 
2026-08-04 08:38:03.301774 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-08-04 08:38:03.653671 | primary | ok: "logs"
2026-08-04 08:38:03.654129 | primary | ok: All items complete
2026-08-04 08:38:03.654214 | 
2026-08-04 08:38:03.960166 | primary | ok: "artifacts"
2026-08-04 08:38:04.263665 | primary | ok: "docs"
2026-08-04 08:38:04.277230 | 
2026-08-04 08:38:04.277420 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-08-04 08:38:04.646304 | primary | changed: "logs"
2026-08-04 08:38:04.962243 | primary | changed: "artifacts"
2026-08-04 08:38:05.285110 | primary | changed: "docs"
2026-08-04 08:38:05.310455 | 
2026-08-04 08:38:05.310619 | PLAY [Configure unbound]
2026-08-04 08:38:05.327876 | 
2026-08-04 08:38:05.328046 | TASK [configure-unbound : Check that Unbound is installed]
2026-08-04 08:38:05.710305 | primary | ok
2026-08-04 08:38:05.718512 | 
2026-08-04 08:38:05.718697 | TASK [configure-unbound : Ensure that Unbound is installed]
2026-08-04 08:38:05.762752 | primary | ok: All assertions passed
2026-08-04 08:38:05.770242 | 
2026-08-04 08:38:05.770355 | TASK [configure-unbound : Check for IPv6]
2026-08-04 08:38:05.996250 | primary | skipping: Conditional result was False
2026-08-04 08:38:06.004776 | 
2026-08-04 08:38:06.005001 | TASK [configure-unbound : Set IPv6 nameservers]
2026-08-04 08:38:06.041714 | primary | skipping: Conditional result was False
2026-08-04 08:38:06.049389 | 
2026-08-04 08:38:06.049520 | TASK [configure-unbound : Set IPv4 nameservers]
2026-08-04 08:38:06.116528 | primary | ok
2026-08-04 08:38:06.126734 | 
2026-08-04 08:38:06.128192 | LOOP [configure-unbound : Include OS-specific variables]
2026-08-04 08:38:06.182700 | primary | ok: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/trusted/project_0/opendev.org/opendev/base-jobs/roles/configure-unbound/vars/Debian.yaml"
2026-08-04 08:38:06.182985 | primary | changed: All items complete
2026-08-04 08:38:06.183050 | 
2026-08-04 08:38:06.193324 | 
2026-08-04 08:38:06.193527 | TASK [configure-unbound : Ensure Unbound conf.d directory exists]
2026-08-04 08:38:06.631618 | primary | ok
2026-08-04 08:38:06.642399 | 
2026-08-04 08:38:06.642601 | TASK [configure-unbound : Configure unbound forwarding]
2026-08-04 08:38:08.482566 | primary | changed
2026-08-04 08:38:08.491005 | 
2026-08-04 08:38:08.491155 | TASK [configure-unbound : Configure unbound TTL]
2026-08-04 08:38:10.076820 | primary | changed
2026-08-04 08:38:10.085070 | 
2026-08-04 08:38:10.085299 | TASK [configure-unbound : Start unbound]
2026-08-04 08:38:11.198275 | primary | ok
2026-08-04 08:38:11.222248 | 
2026-08-04 08:38:11.222416 | TASK [configure-unbound : Restart unbound]
2026-08-04 08:38:12.015155 | primary | changed
2026-08-04 08:38:12.026383 | 
2026-08-04 08:38:12.026475 | PLAY [Prepare workspace and configure mirrors]
2026-08-04 08:38:12.041898 | 
2026-08-04 08:38:12.042026 | TASK [validate-host : Define zuul_info_dir fact]
2026-08-04 08:38:12.083501 | primary | ok
2026-08-04 08:38:12.089564 | 
2026-08-04 08:38:12.089687 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-08-04 08:38:12.450961 | primary -> localhost | ok
2026-08-04 08:38:12.460208 | 
2026-08-04 08:38:12.460365 | TASK [validate-host : Collect information about the host]
2026-08-04 08:38:13.517473 | primary | ok
2026-08-04 08:38:13.531743 | 
2026-08-04 08:38:13.531894 | TASK [validate-host : Sanitize hostname]
2026-08-04 08:38:13.619859 | primary | ok
2026-08-04 08:38:13.625123 | 
2026-08-04 08:38:13.625246 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-08-04 08:38:14.310518 | primary -> localhost | changed
2026-08-04 08:38:14.318864 | 
2026-08-04 08:38:14.318996 | TASK [validate-host : Collect information about zuul worker]
2026-08-04 08:38:16.188221 | primary | ok
2026-08-04 08:38:16.195455 | 
2026-08-04 08:38:16.195571 | TASK [validate-host : Write out all zuul information for each host]
2026-08-04 08:38:16.870190 | primary -> localhost | changed
2026-08-04 08:38:16.884333 | 
2026-08-04 08:38:16.884495 | TASK [hardware-check : Raise an error if CPU count is too low]
2026-08-04 08:38:16.911907 | primary | skipping: Conditional result was False
2026-08-04 08:38:16.925625 | 
2026-08-04 08:38:16.925810 | LOOP [prepare-workspace-git : Filter zuul projects if sync-only-required-projects flag is set]
2026-08-04 08:38:16.961510 | primary | skipping: Conditional result was False
2026-08-04 08:38:16.964551 | primary | skipping: Conditional result was False
2026-08-04 08:38:16.973367 | primary | skipping: Conditional result was False
2026-08-04 08:38:16.982786 | primary | skipping: Conditional result was False
2026-08-04 08:38:16.984893 | primary | skipping: Conditional result was False
2026-08-04 08:38:17.003230 | 
2026-08-04 08:38:17.003452 | TASK [prepare-workspace-git : Don't filter zuul projects if flag is false]
2026-08-04 08:38:17.045715 | primary | ok
2026-08-04 08:38:17.052686 | 
2026-08-04 08:38:17.052825 | TASK [prepare-workspace-git : Set initial repo states in workspace]
2026-08-04 08:38:18.139939 | primary | changed
2026-08-04 08:38:18.147614 | 
2026-08-04 08:38:18.147745 | TASK [prepare-workspace-git : Synchronize src repos to workspace directory]
2026-08-04 08:38:19.611755 | primary -> localhost | changed
2026-08-04 08:38:19.620878 | 
2026-08-04 08:38:19.621004 | TASK [prepare-workspace-git : Update remote repository state]
2026-08-04 08:38:20.790024 | primary | changed
2026-08-04 08:38:20.805399 | 
2026-08-04 08:38:20.805521 | TASK [mirror-info : Create /etc/ci]
2026-08-04 08:38:21.262702 | primary | changed
2026-08-04 08:38:21.268705 | 
2026-08-04 08:38:21.268819 | TASK [mirror-info : Install ci_mirror script]
2026-08-04 08:38:22.877599 | primary | changed
2026-08-04 08:38:22.888881 | 
2026-08-04 08:38:22.889038 | TASK [configure-mirrors : Gather needed facts]
2026-08-04 08:38:22.925652 | primary | skipping: Conditional result was False
2026-08-04 08:38:22.933122 | 
2026-08-04 08:38:22.933304 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-08-04 08:38:23.025816 | primary | ok
2026-08-04 08:38:23.037258 | primary | included: /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-08-04 08:38:23.042596 | 
2026-08-04 08:38:23.042730 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-08-04 08:38:23.384890 | primary | ok
2026-08-04 08:38:23.391333 | 
2026-08-04 08:38:23.391446 | LOOP [configure-mirrors : Include OS-specific variables]
2026-08-04 08:38:23.438576 | primary | ok: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/Debian.yaml"
2026-08-04 08:38:23.438909 | 
2026-08-04 08:38:23.446508 | 
2026-08-04 08:38:23.446661 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-08-04 08:38:25.084581 | primary | changed
2026-08-04 08:38:25.091172 | 
2026-08-04 08:38:25.091291 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-08-04 08:38:25.133482 | primary | ok: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Debian.yaml"
2026-08-04 08:38:25.152972 | primary | included: /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Debian.yaml
2026-08-04 08:38:25.159821 | 
2026-08-04 08:38:25.159926 | LOOP [configure-mirrors : Install Debian repository files]
2026-08-04 08:38:26.850556 | primary | changed:
2026-08-04 08:38:26.851157 | primary | {
2026-08-04 08:38:26.851271 | primary |   "repo": "etc/apt/sources.list.d/default.list"
2026-08-04 08:38:26.851369 | primary | }
2026-08-04 08:38:26.851497 | primary | ok: All items complete
2026-08-04 08:38:26.851595 | 
2026-08-04 08:38:28.429372 | primary | changed:
2026-08-04 08:38:28.429492 | primary | {
2026-08-04 08:38:28.429551 | primary |   "repo": "etc/apt/sources.list.d/updates.list"
2026-08-04 08:38:28.429604 | primary | }
2026-08-04 08:38:28.434201 | primary | skipping: Conditional result was False
2026-08-04 08:38:30.051851 | primary | changed:
2026-08-04 08:38:30.051999 | primary | {
2026-08-04 08:38:30.052072 | primary |   "repo": "etc/apt/sources.list.d/security.list"
2026-08-04 08:38:30.052168 | primary | }
2026-08-04 08:38:31.606776 | primary | changed:
2026-08-04 08:38:31.606918 | primary | {
2026-08-04 08:38:31.606976 | primary |   "repo": "etc/apt/apt.conf.d/99unauthenticated"
2026-08-04 08:38:31.607028 | primary | }
2026-08-04 08:38:31.636035 | 
2026-08-04 08:38:31.636204 | TASK [configure-mirrors : Update apt cache]
2026-08-04 08:38:32.162695 | primary | Ign:1 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie InRelease
2026-08-04 08:38:32.163499 | primary | Ign:2 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian-security trixie-security InRelease
2026-08-04 08:38:32.164101 | primary | Ign:3 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie-updates InRelease
2026-08-04 08:38:32.164746 | primary | Get:4 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie Release [4454 B]
2026-08-04 08:38:32.165620 | primary | Get:5 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian-security trixie-security Release [4452 B]
2026-08-04 08:38:32.166447 | primary | Get:6 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie-updates Release [4344 B]
2026-08-04 08:38:32.167987 | primary | Ign:7 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie Release.gpg
2026-08-04 08:38:32.168675 | primary | Ign:8 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian-security trixie-security Release.gpg
2026-08-04 08:38:32.170048 | primary | Ign:9 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie-updates Release.gpg
2026-08-04 08:38:32.170968 | primary | Get:10 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie/main amd64 Packages [20.6 MB]
2026-08-04 08:38:58.803077 | primary | Get:11 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian-security trixie-security/main amd64 Packages [489 kB]
2026-08-04 08:38:59.283492 | primary | Get:12 https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie-updates/main amd64 Packages [15.8 kB]
2026-08-04 08:38:59.301469 | primary | Fetched 21.1 MB in 27s (776 kB/s)
2026-08-04 08:38:59.843168 | primary | Reading package lists...
2026-08-04 08:38:59.852311 | primary | W: The repository 'https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie Release' is not signed.
2026-08-04 08:38:59.852380 | primary | W: The repository 'https://mirror.ca-ymq-1.vexxhost.opendev.org/debian-security trixie-security Release' is not signed.
2026-08-04 08:38:59.852418 | primary | W: The repository 'https://mirror.ca-ymq-1.vexxhost.opendev.org/debian trixie-updates Release' is not signed.
2026-08-04 08:39:00.355181 | primary | ok: Runtime: 0:00:27.859338
2026-08-04 08:39:00.357928 | 
2026-08-04 08:39:00.358051 | PLAY RECAP
2026-08-04 08:39:00.358238 | localhost | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 08:39:00.358345 | primary | ok: 44 changed: 20 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-08-04 08:39:00.358432 | 
2026-08-04 08:39:00.557830 | PRE-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2026-08-04 08:39:00.564828 | PRE-RUN START: [untrusted : opendev.org/openstack/kolla-ansible/tests/pre.yml@master]
2026-08-04 08:39:01.919569 | 
2026-08-04 08:39:01.919827 | PLAY [all]
2026-08-04 08:39:01.958980 | 
2026-08-04 08:39:01.959217 | TASK [configure-ephemeral : Set ephemeral device if /dev/xvde exists]
2026-08-04 08:39:01.997726 | primary | skipping: Conditional result was False
2026-08-04 08:39:02.008402 | 
2026-08-04 08:39:02.008596 | TASK [configure-ephemeral : Get ephemeral0 device node]
2026-08-04 08:39:03.216373 | primary | ok: Runtime: 0:00:00.008798
2026-08-04 08:39:03.223582 | 
2026-08-04 08:39:03.223697 | TASK [configure-ephemeral : Set ephemeral device if LABEL exists]
2026-08-04 08:39:03.269549 | primary | skipping: Conditional result was False
2026-08-04 08:39:03.277817 | 
2026-08-04 08:39:03.277960 | LOOP [configure-ephemeral : Ensure ephemeral device is unmounted]
2026-08-04 08:39:03.318840 | primary | skipping: Conditional result was False
2026-08-04 08:39:03.324233 | primary | skipping: Conditional result was False
2026-08-04 08:39:03.342983 | 
2026-08-04 08:39:03.343184 | TASK [configure-ephemeral : Get existing partitions]
2026-08-04 08:39:03.379147 | primary | skipping: Conditional result was False
2026-08-04 08:39:03.387269 | 
2026-08-04 08:39:03.387410 | LOOP [configure-ephemeral : Remove any existing partitions]
2026-08-04 08:39:03.431484 | 
2026-08-04 08:39:03.431779 | TASK [configure-ephemeral : Create filesystem on additional disk]
2026-08-04 08:39:03.467823 | primary | skipping: Conditional result was False
2026-08-04 08:39:03.479357 | 
2026-08-04 08:39:03.479560 | TASK [configure-ephemeral : Ensure mountpoint /var/lib/docker]
2026-08-04 08:39:03.516841 | primary | skipping: Conditional result was False
2026-08-04 08:39:03.525758 | 
2026-08-04 08:39:03.525915 | TASK [configure-ephemeral : Mount additional filesystem]
2026-08-04 08:39:03.561994 | primary | skipping: Conditional result was False
2026-08-04 08:39:03.582355 | 
2026-08-04 08:39:03.582555 | LOOP [multi-node-firewall : Include operating system specific vars]
2026-08-04 08:39:03.629809 | primary | ok: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/multi-node-firewall/vars/default.yaml"
2026-08-04 08:39:03.630130 | 
2026-08-04 08:39:03.647143 | 
2026-08-04 08:39:03.647310 | TASK [multi-node-firewall : Ensure iptables]
2026-08-04 08:39:07.738424 | primary | ok
2026-08-04 08:39:07.745627 | 
2026-08-04 08:39:07.745765 | TASK [multi-node-firewall : Set up the host ip addresses]
2026-08-04 08:39:07.859704 | primary | ok
2026-08-04 08:39:07.866190 | 
2026-08-04 08:39:07.866456 | LOOP [multi-node-firewall : Set up ipv4 iptables rules]
2026-08-04 08:39:08.606566 | primary | changed: "199.204.45.109"
2026-08-04 08:39:08.622854 | 
2026-08-04 08:39:08.623055 | LOOP [multi-node-firewall : Set up ipv6 iptables rules]
2026-08-04 08:39:08.659671 | 
2026-08-04 08:39:08.659980 | TASK [Persist iptables rules]
2026-08-04 08:39:08.692916 | primary | ok
2026-08-04 08:39:08.713875 | primary | included: persistent-firewall
2026-08-04 08:39:08.722391 | 
2026-08-04 08:39:08.722569 | TASK [persistent-firewall : List current ipv4 rules]
2026-08-04 08:39:09.150311 | primary | # Generated by iptables-save v1.8.11 (nf_tables) on Tue Aug  4 08:39:09 2026
2026-08-04 08:39:09.150437 | primary | *filter
2026-08-04 08:39:09.150451 | primary | :INPUT ACCEPT [0:0]
2026-08-04 08:39:09.150461 | primary | :FORWARD ACCEPT [0:0]
2026-08-04 08:39:09.150470 | primary | :OUTPUT ACCEPT [2229:355472]
2026-08-04 08:39:09.150478 | primary | :openstack-INPUT - [0:0]
2026-08-04 08:39:09.150492 | primary | -A INPUT -s 199.204.45.109/32 -j ACCEPT
2026-08-04 08:39:09.150500 | primary | -A INPUT -j openstack-INPUT
2026-08-04 08:39:09.150508 | primary | -A openstack-INPUT -i lo -j ACCEPT
2026-08-04 08:39:09.150523 | primary | -A openstack-INPUT -p icmp -m icmp --icmp-type any -j ACCEPT
2026-08-04 08:39:09.150540 | primary | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2026-08-04 08:39:09.150551 | primary | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2026-08-04 08:39:09.150560 | primary | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2026-08-04 08:39:09.150568 | primary | -A openstack-INPUT -s 172.24.4.0/23 -p udp -m udp --dport 69 -j ACCEPT
2026-08-04 08:39:09.150576 | primary | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 6385 -j ACCEPT
2026-08-04 08:39:09.150583 | primary | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 80 -j ACCEPT
2026-08-04 08:39:09.150590 | primary | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8000 -j ACCEPT
2026-08-04 08:39:09.150597 | primary | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8003 -j ACCEPT
2026-08-04 08:39:09.150604 | primary | -A openstack-INPUT -s 172.24.4.0/23 -p tcp -m tcp --dport 8004 -j ACCEPT
2026-08-04 08:39:09.150611 | primary | -A openstack-INPUT -m limit --limit 2/min -j LOG --log-prefix "iptables dropped: "
2026-08-04 08:39:09.150624 | primary | -A openstack-INPUT -j REJECT --reject-with icmp-host-prohibited
2026-08-04 08:39:09.150632 | primary | COMMIT
2026-08-04 08:39:09.150639 | primary | # Completed on Tue Aug  4 08:39:09 2026
2026-08-04 08:39:09.433599 | primary | ok: Runtime: 0:00:00.009745
2026-08-04 08:39:09.440984 | 
2026-08-04 08:39:09.441088 | TASK [persistent-firewall : List current ipv6 rules]
2026-08-04 08:39:09.891788 | primary | # Generated by ip6tables-save v1.8.11 (nf_tables) on Tue Aug  4 08:39:09 2026
2026-08-04 08:39:09.891870 | primary | *filter
2026-08-04 08:39:09.891884 | primary | :INPUT ACCEPT [0:0]
2026-08-04 08:39:09.891894 | primary | :FORWARD ACCEPT [0:0]
2026-08-04 08:39:09.891903 | primary | :OUTPUT ACCEPT [766:65748]
2026-08-04 08:39:09.891911 | primary | :openstack-INPUT - [0:0]
2026-08-04 08:39:09.891925 | primary | -A INPUT -j openstack-INPUT
2026-08-04 08:39:09.891940 | primary | -A openstack-INPUT -i lo -j ACCEPT
2026-08-04 08:39:09.891951 | primary | -A openstack-INPUT -p ipv6-icmp -j ACCEPT
2026-08-04 08:39:09.891989 | primary | -A openstack-INPUT -p tcp -m tcp --dport 22 -j ACCEPT
2026-08-04 08:39:09.892016 | primary | -A openstack-INPUT -m state --state RELATED,ESTABLISHED -j ACCEPT
2026-08-04 08:39:09.892029 | primary | -A openstack-INPUT -p tcp -m state --state NEW -m tcp --dport 19885 -j ACCEPT
2026-08-04 08:39:09.892040 | primary | -A openstack-INPUT -j REJECT --reject-with icmp6-adm-prohibited
2026-08-04 08:39:09.892050 | primary | COMMIT
2026-08-04 08:39:09.892058 | primary | # Completed on Tue Aug  4 08:39:09 2026
2026-08-04 08:39:10.186349 | primary | ok: Runtime: 0:00:00.009350
2026-08-04 08:39:10.193858 | 
2026-08-04 08:39:10.193985 | LOOP [persistent-firewall : Configure persistent iptables rules]
2026-08-04 08:39:10.232616 | primary | ok: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml"
2026-08-04 08:39:10.232872 | 
2026-08-04 08:39:10.251928 | primary | included: /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/persistent-firewall/tasks/persist/Debian.yaml
2026-08-04 08:39:10.259776 | 
2026-08-04 08:39:10.259898 | TASK [persistent-firewall : Install iptables-persistent]
2026-08-04 08:39:11.306084 | primary | ok
2026-08-04 08:39:11.314569 | 
2026-08-04 08:39:11.314705 | TASK [persistent-firewall : Persist ipv4 rules]
2026-08-04 08:39:13.386936 | primary | changed
2026-08-04 08:39:13.394282 | 
2026-08-04 08:39:13.394393 | TASK [persistent-firewall : Persist ipv6 rules]
2026-08-04 08:39:15.004244 | primary | changed
2026-08-04 08:39:15.012196 | 
2026-08-04 08:39:15.012311 | TASK [persistent-firewall : Ensure netfilter-persistent is started]
2026-08-04 08:39:16.030703 | primary | ok
2026-08-04 08:39:16.055953 | 
2026-08-04 08:39:16.056066 | TASK [multi-node-vxlan-overlay : Ensure mandatory variables are defined]
2026-08-04 08:39:16.107131 | primary | ok: All assertions passed
2026-08-04 08:39:16.113847 | 
2026-08-04 08:39:16.113965 | TASK [multi-node-vxlan-overlay : Set VXLAN interface facts]
2026-08-04 08:39:16.196055 | primary | ok
2026-08-04 08:39:16.202743 | 
2026-08-04 08:39:16.202857 | TASK [multi-node-vxlan-overlay : Assert that the nodepool private or public IPv4 address is assigned]
2026-08-04 08:39:16.245564 | primary | ok: All assertions passed
2026-08-04 08:39:16.251995 | 
2026-08-04 08:39:16.252140 | TASK [multi-node-vxlan-overlay : Create VXLAN interface]
2026-08-04 08:39:16.956979 | primary | ok: Runtime: 0:00:00.052345
2026-08-04 08:39:16.965927 | 
2026-08-04 08:39:16.966147 | TASK [multi-node-vxlan-overlay : Set VXLAN interface MTU]
2026-08-04 08:39:17.672878 | primary | ok: Runtime: 0:00:00.007540
2026-08-04 08:39:17.681560 | 
2026-08-04 08:39:17.681736 | LOOP [multi-node-vxlan-overlay : Add fdb entries for BUM traffic]
2026-08-04 08:39:17.712657 | primary | skipping: Conditional result was False
2026-08-04 08:39:17.726580 | 
2026-08-04 08:39:17.726795 | TASK [multi-node-vxlan-overlay : Bring VXLAN interface up]
2026-08-04 08:39:18.435742 | primary | ok: Runtime: 0:00:00.009527
2026-08-04 08:39:18.449473 | 
2026-08-04 08:39:18.449585 | TASK [multi-node-managed-addressing : Ensure mandatory variables are defined]
2026-08-04 08:39:18.512231 | primary | ok: All assertions passed
2026-08-04 08:39:18.519051 | 
2026-08-04 08:39:18.519205 | TASK [multi-node-managed-addressing : Set managed interface facts]
2026-08-04 08:39:18.571581 | primary | ok
2026-08-04 08:39:18.578703 | 
2026-08-04 08:39:18.578830 | TASK [multi-node-managed-addressing : Add IPv4 address for managed network]
2026-08-04 08:39:19.287683 | primary | ok: Runtime: 0:00:00.008336
2026-08-04 08:39:19.294994 | 
2026-08-04 08:39:19.295132 | TASK [multi-node-managed-addressing : Add IPv6 address for managed network]
2026-08-04 08:39:19.345500 | primary | skipping: Conditional result was False
2026-08-04 08:39:19.353889 | 
2026-08-04 08:39:19.354014 | TASK [multi-node-managed-addressing : Accept traffic on the managed network (IN)]
2026-08-04 08:39:19.775206 | primary | changed
2026-08-04 08:39:19.783473 | 
2026-08-04 08:39:19.783679 | TASK [multi-node-managed-addressing : Accept traffic on the managed network (OUT)]
2026-08-04 08:39:20.173091 | primary | changed
2026-08-04 08:39:20.180683 | 
2026-08-04 08:39:20.180800 | TASK [multi-node-managed-addressing : Ensure IPv6 addresses on the managed interface are no longer tentative]
2026-08-04 08:39:20.234450 | primary | skipping: Conditional result was False
2026-08-04 08:39:20.243434 | 
2026-08-04 08:39:20.243572 | LOOP [multi-node-managed-addressing : Ping across the managed network]
2026-08-04 08:39:20.674676 | primary | PING 192.0.2.1 (192.0.2.1) 56(84) bytes of data.
2026-08-04 08:39:20.674929 | primary | 64 bytes from 192.0.2.1: icmp_seq=1 ttl=64 time=0.056 ms
2026-08-04 08:39:20.675000 | primary |
2026-08-04 08:39:20.675067 | primary | --- 192.0.2.1 ping statistics ---
2026-08-04 08:39:20.675162 | primary | 1 packets transmitted, 1 received, 0% packet loss, time 0ms
2026-08-04 08:39:20.675223 | primary | rtt min/avg/max/mdev = 0.056/0.056/0.056/0.000 ms
2026-08-04 08:39:20.675306 | primary | ok: Item: primary Runtime: 0:00:00.011216
2026-08-04 08:39:20.675368 | 
2026-08-04 08:39:20.693016 | 
2026-08-04 08:39:20.693234 | TASK [multi-node-vxlan-overlay : Ensure mandatory variables are defined]
2026-08-04 08:39:20.747429 | primary | ok: All assertions passed
2026-08-04 08:39:20.754897 | 
2026-08-04 08:39:20.755130 | TASK [multi-node-vxlan-overlay : Set VXLAN interface facts]
2026-08-04 08:39:20.839422 | primary | ok
2026-08-04 08:39:20.846828 | 
2026-08-04 08:39:20.846993 | TASK [multi-node-vxlan-overlay : Assert that the nodepool private or public IPv4 address is assigned]
2026-08-04 08:39:20.890533 | primary | ok: All assertions passed
2026-08-04 08:39:20.897711 | 
2026-08-04 08:39:20.897831 | TASK [multi-node-vxlan-overlay : Create VXLAN interface]
2026-08-04 08:39:21.668261 | primary | ok: Runtime: 0:00:00.008309
2026-08-04 08:39:21.675483 | 
2026-08-04 08:39:21.675603 | TASK [multi-node-vxlan-overlay : Set VXLAN interface MTU]
2026-08-04 08:39:22.380613 | primary | ok: Runtime: 0:00:00.007353
2026-08-04 08:39:22.388026 | 
2026-08-04 08:39:22.388191 | LOOP [multi-node-vxlan-overlay : Add fdb entries for BUM traffic]
2026-08-04 08:39:22.420145 | primary | skipping: Conditional result was False
2026-08-04 08:39:22.433302 | 
2026-08-04 08:39:22.433480 | TASK [multi-node-vxlan-overlay : Bring VXLAN interface up]
2026-08-04 08:39:23.140060 | primary | ok: Runtime: 0:00:00.009166
2026-08-04 08:39:23.153285 | 
2026-08-04 08:39:23.153401 | TASK [bridge : Create bridge]
2026-08-04 08:39:23.858787 | primary | ok: Runtime: 0:00:00.058602
2026-08-04 08:39:23.866329 | 
2026-08-04 08:39:23.866458 | TASK [bridge : Bring bridge interface up]
2026-08-04 08:39:24.572864 | primary | ok: Runtime: 0:00:00.008348
2026-08-04 08:39:24.579729 | 
2026-08-04 08:39:24.579840 | TASK [bridge : Add member interface to bridge]
2026-08-04 08:39:25.282370 | primary | ok: Runtime: 0:00:00.023788
2026-08-04 08:39:25.300921 | 
2026-08-04 08:39:25.301157 | TASK [multi-node-vxlan-overlay : Ensure mandatory variables are defined]
2026-08-04 08:39:25.337774 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.346541 | 
2026-08-04 08:39:25.346663 | TASK [multi-node-vxlan-overlay : Set VXLAN interface facts]
2026-08-04 08:39:25.382263 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.390141 | 
2026-08-04 08:39:25.390264 | TASK [multi-node-vxlan-overlay : Assert that the nodepool private or public IPv4 address is assigned]
2026-08-04 08:39:25.425614 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.433236 | 
2026-08-04 08:39:25.433359 | TASK [multi-node-vxlan-overlay : Create VXLAN interface]
2026-08-04 08:39:25.494864 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.503218 | 
2026-08-04 08:39:25.503355 | TASK [multi-node-vxlan-overlay : Set VXLAN interface MTU]
2026-08-04 08:39:25.567889 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.576075 | 
2026-08-04 08:39:25.576257 | LOOP [multi-node-vxlan-overlay : Add fdb entries for BUM traffic]
2026-08-04 08:39:25.616117 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.616540 | 
2026-08-04 08:39:25.631717 | 
2026-08-04 08:39:25.631879 | TASK [multi-node-vxlan-overlay : Bring VXLAN interface up]
2026-08-04 08:39:25.692346 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.706671 | 
2026-08-04 08:39:25.706792 | TASK [multi-node-managed-addressing : Ensure mandatory variables are defined]
2026-08-04 08:39:25.742461 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.751068 | 
2026-08-04 08:39:25.751239 | TASK [multi-node-managed-addressing : Set managed interface facts]
2026-08-04 08:39:25.786424 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.794788 | 
2026-08-04 08:39:25.794953 | TASK [multi-node-managed-addressing : Add IPv4 address for managed network]
2026-08-04 08:39:25.858342 | primary | skipping: Conditional result was False
2026-08-04 08:39:25.867361 | 
2026-08-04 08:39:25.867661 | TASK [multi-node-managed-addressing : Add IPv6 address for managed network]
2026-08-04 08:39:26.065948 | primary | skipping: Conditional result was False
2026-08-04 08:39:26.074758 | 
2026-08-04 08:39:26.074881 | TASK [multi-node-managed-addressing : Accept traffic on the managed network (IN)]
2026-08-04 08:39:26.111063 | primary | skipping: Conditional result was False
2026-08-04 08:39:26.119868 | 
2026-08-04 08:39:26.119991 | TASK [multi-node-managed-addressing : Accept traffic on the managed network (OUT)]
2026-08-04 08:39:26.156556 | primary | skipping: Conditional result was False
2026-08-04 08:39:26.166662 | 
2026-08-04 08:39:26.166844 | TASK [multi-node-managed-addressing : Ensure IPv6 addresses on the managed interface are no longer tentative]
2026-08-04 08:39:26.228393 | primary | skipping: Conditional result was False
2026-08-04 08:39:26.237436 | 
2026-08-04 08:39:26.237576 | LOOP [multi-node-managed-addressing : Ping across the managed network]
2026-08-04 08:39:26.275703 | primary | skipping: Conditional result was False
2026-08-04 08:39:26.276220 | 
2026-08-04 08:39:26.297130 | 
2026-08-04 08:39:26.297268 | TASK [multi-node-managed-addressing : Ensure mandatory variables are defined]
2026-08-04 08:39:26.467283 | primary | ok: All assertions passed
2026-08-04 08:39:26.475141 | 
2026-08-04 08:39:26.475255 | TASK [multi-node-managed-addressing : Set managed interface facts]
2026-08-04 08:39:26.521323 | primary | ok
2026-08-04 08:39:26.530281 | 
2026-08-04 08:39:26.530405 | TASK [multi-node-managed-addressing : Add IPv4 address for managed network]
2026-08-04 08:39:27.233290 | primary | ok: Runtime: 0:00:00.007614
2026-08-04 08:39:27.240321 | 
2026-08-04 08:39:27.240436 | TASK [multi-node-managed-addressing : Add IPv6 address for managed network]
2026-08-04 08:39:27.289759 | primary | skipping: Conditional result was False
2026-08-04 08:39:27.298758 | 
2026-08-04 08:39:27.298880 | TASK [multi-node-managed-addressing : Accept traffic on the managed network (IN)]
2026-08-04 08:39:27.686965 | primary | changed
2026-08-04 08:39:27.694315 | 
2026-08-04 08:39:27.694425 | TASK [multi-node-managed-addressing : Accept traffic on the managed network (OUT)]
2026-08-04 08:39:28.083016 | primary | changed
2026-08-04 08:39:28.091027 | 
2026-08-04 08:39:28.091169 | TASK [multi-node-managed-addressing : Ensure IPv6 addresses on the managed interface are no longer tentative]
2026-08-04 08:39:28.153695 | primary | skipping: Conditional result was False
2026-08-04 08:39:28.163714 | 
2026-08-04 08:39:28.163881 | LOOP [multi-node-managed-addressing : Ping across the managed network]
2026-08-04 08:39:28.587028 | primary | PING 198.51.100.1 (198.51.100.1) 56(84) bytes of data.
2026-08-04 08:39:28.587304 | primary | 64 bytes from 198.51.100.1: icmp_seq=1 ttl=64 time=0.070 ms
2026-08-04 08:39:28.587371 | primary |
2026-08-04 08:39:28.587429 | primary | --- 198.51.100.1 ping statistics ---
2026-08-04 08:39:28.587485 | primary | 1 packets transmitted, 1 received, 0% packet loss, time 0ms
2026-08-04 08:39:28.587539 | primary | rtt min/avg/max/mdev = 0.070/0.070/0.070/0.000 ms
2026-08-04 08:39:28.587617 | primary | ok: Item: primary Runtime: 0:00:00.009914
2026-08-04 08:39:28.587676 | 
2026-08-04 08:39:28.609719 | 
2026-08-04 08:39:28.609847 | TASK [veth : Create veth pair]
2026-08-04 08:39:29.318480 | primary | ok: Runtime: 0:00:00.029075
2026-08-04 08:39:29.333020 | 
2026-08-04 08:39:29.333238 | TASK [veth : Bring veth pair up]
2026-08-04 08:39:30.041980 | primary | ok: Runtime: 0:00:00.010246
2026-08-04 08:39:30.049196 | 
2026-08-04 08:39:30.049322 | TASK [veth : Add first veth pair port to bridge]
2026-08-04 08:39:30.753833 | primary | ok: Runtime: 0:00:00.071903
2026-08-04 08:39:30.766837 | 
2026-08-04 08:39:30.766965 | TASK [Set Rockylinux mirror to download.rockylinux.org]
2026-08-04 08:39:30.820154 | primary | skipping: Conditional result was False
2026-08-04 08:39:30.829351 | 
2026-08-04 08:39:30.829472 | TASK [Install kernel-modules-extra for running kernel]
2026-08-04 08:39:30.854513 | primary | skipping: Conditional result was False
2026-08-04 08:39:30.862732 | 
2026-08-04 08:39:30.862872 | TASK [Install gawk and required Python modules]
2026-08-04 08:39:42.057670 | primary | changed
2026-08-04 08:39:42.067261 | 
2026-08-04 08:39:42.067370 | TASK [Install stestr]
2026-08-04 08:39:46.673232 | primary | changed
2026-08-04 08:39:46.681490 | 
2026-08-04 08:39:46.681627 | TASK [Install lvm on storage scenarios]
2026-08-04 08:39:46.707761 | primary | skipping: Conditional result was False
2026-08-04 08:39:46.719187 | 
2026-08-04 08:39:46.719347 | TASK [Ensure /tmp/logs/ dir]
2026-08-04 08:39:47.414785 | primary | changed
2026-08-04 08:39:47.421911 | 
2026-08-04 08:39:47.422016 | TASK [Ensure /tmp/logs/pre dir]
2026-08-04 08:39:47.747509 | primary | changed
2026-08-04 08:39:47.755717 | 
2026-08-04 08:39:47.755846 | TASK [Run diagnostics script]
2026-08-04 08:40:02.157232 | primary | changed
2026-08-04 08:40:02.166743 | 
2026-08-04 08:40:02.166855 | TASK [Print get_logs output]
2026-08-04 08:40:02.212210 | cp: cannot stat '/home/zuul/tempest': No such file or directory
2026-08-04 08:40:02.212476 | cp: cannot stat '/etc/docker/': No such file or directory
2026-08-04 08:40:02.212545 | /home/zuul/.ansible/tmp/ansible-tmp-1785832787.8942957-160-273492499360688/get_logs.sh: line 32: /tmp/logs/pre/system_logs/ls_lr_var_log_kolla.txt: No such file or directory
2026-08-04 08:40:02.212609 | unlink: cannot unlink '/var/log/kolla': No such file or directory
2026-08-04 08:40:02.212670 | cp: target '/tmp/logs/pre/system_logs/': No such file or directory
2026-08-04 08:40:02.212729 | /home/zuul/.ansible/tmp/ansible-tmp-1785832787.8942957-160-273492499360688/get_logs.sh: line 39: /tmp/logs/pre/system_logs/syslog.txt: No such file or directory
2026-08-04 08:40:02.212788 | /home/zuul/.ansible/tmp/ansible-tmp-1785832787.8942957-160-273492499360688/get_logs.sh: line 40: /tmp/logs/pre/system_logs/docker.log: No such file or directory
2026-08-04 08:40:02.212845 | /home/zuul/.ansible/tmp/ansible-tmp-1785832787.8942957-160-273492499360688/get_logs.sh: line 42: /tmp/logs/pre/system_logs/containerd.log: No such file or directory
2026-08-04 08:40:02.212903 | /home/zuul/.ansible/tmp/ansible-tmp-1785832787.8942957-160-273492499360688/get_logs.sh: line 125: docker: command not found
2026-08-04 08:40:02.212973 | /home/zuul/.ansible/tmp/ansible-tmp-1785832787.8942957-160-273492499360688/get_logs.sh: line 134: docker: command not found
2026-08-04 08:40:02.213030 | /home/zuul/.ansible/tmp/ansible-tmp-1785832787.8942957-160-273492499360688/get_logs.sh: line 139: docker: command not found
2026-08-04 08:40:02.213083 | find: ‘/tmp/logs/pre/kolla’: No such file or directory
2026-08-04 08:40:02.213178 | find: ‘/tmp/logs/pre/docker_logs’: No such file or directory
2026-08-04 08:40:02.213236 | 1	/tmp/logs/pre
2026-08-04 08:40:02.220255 | 
2026-08-04 08:40:02.220398 | LOOP [Ensure node directories]
2026-08-04 08:40:02.698060 | primary | changed: "container_logs"
2026-08-04 08:40:03.000018 | primary | changed: "kolla_configs"
2026-08-04 08:40:03.293976 | primary | changed: "system_logs"
2026-08-04 08:40:03.597986 | primary | changed: "kolla"
2026-08-04 08:40:03.902349 | primary | changed: "ansible"
2026-08-04 08:40:03.921563 | 
2026-08-04 08:40:03.921749 | TASK [Iptables - LOG FORWARD]
2026-08-04 08:40:04.322827 | primary | changed
2026-08-04 08:40:04.330772 | 
2026-08-04 08:40:04.330907 | TASK [Set new hostname based on ansible inventory file]
2026-08-04 08:40:05.663823 | primary | changed
2026-08-04 08:40:05.670598 | 
2026-08-04 08:40:05.670717 | TASK [Wait for ntp time sync]
2026-08-04 08:40:06.201213 | primary |                Local time: Tue 2026-08-04 08:40:06 UTC
2026-08-04 08:40:06.201313 | primary |            Universal time: Tue 2026-08-04 08:40:06 UTC
2026-08-04 08:40:06.201327 | primary |                  RTC time: Tue 2026-08-04 08:40:05
2026-08-04 08:40:06.201341 | primary |                 Time zone: Etc/UTC (UTC, +0000)
2026-08-04 08:40:06.201358 | primary | System clock synchronized: yes
2026-08-04 08:40:06.201367 | primary |               NTP service: active
2026-08-04 08:40:06.201382 | primary |           RTC in local TZ: no
2026-08-04 08:40:06.423993 | primary | ok: Runtime: 0:00:00.106362
2026-08-04 08:40:06.426677 | 
2026-08-04 08:40:06.426758 | PLAY RECAP
2026-08-04 08:40:06.426858 | primary | ok: 53 changed: 29 unreachable: 0 failed: 0 skipped: 33 rescued: 0 ignored: 0
2026-08-04 08:40:06.426960 | 
2026-08-04 08:40:06.592257 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/kolla-ansible/tests/pre.yml@master]
2026-08-04 08:40:06.598018 | RUN START: [untrusted : opendev.org/openstack/kolla-ansible/tests/run.yml@master]
2026-08-04 08:40:08.105470 | 
2026-08-04 08:40:08.105665 | PLAY [all]
2026-08-04 08:40:08.124896 | 
2026-08-04 08:40:08.125060 | TASK [Refresh facts]
2026-08-04 08:40:10.739533 | primary | ok
2026-08-04 08:40:10.755877 | 
2026-08-04 08:40:10.756017 | TASK [Set facts for commonly used variables]
2026-08-04 08:40:10.880735 | primary | ok
2026-08-04 08:40:10.887707 | 
2026-08-04 08:40:10.887838 | TASK [Install dig for Designate testing]
2026-08-04 08:40:10.914819 | primary | skipping: Conditional result was False
2026-08-04 08:40:10.923658 | 
2026-08-04 08:40:10.923809 | TASK [kolla-ansible-setup-disks : Check if kolla_ansible_setup_disks_file_path is set]
2026-08-04 08:40:10.950073 | primary | skipping: Conditional result was False
2026-08-04 08:40:10.958658 | 
2026-08-04 08:40:10.958811 | TASK [kolla-ansible-setup-disks : Check if kolla_ansible_setup_disks_vg_name is set]
2026-08-04 08:40:10.984551 | primary | skipping: Conditional result was False
2026-08-04 08:40:10.992890 | 
2026-08-04 08:40:10.993048 | TASK [kolla-ansible-setup-disks : Allocate file for disk backing]
2026-08-04 08:40:11.018804 | primary | skipping: Conditional result was False
2026-08-04 08:40:11.027675 | 
2026-08-04 08:40:11.028062 | TASK [kolla-ansible-setup-disks : Get free loop device]
2026-08-04 08:40:11.077878 | primary | skipping: Conditional result was False
2026-08-04 08:40:11.085904 | 
2026-08-04 08:40:11.086253 | TASK [kolla-ansible-setup-disks : Mount file on loop device]
2026-08-04 08:40:11.138717 | primary | skipping: Conditional result was False
2026-08-04 08:40:11.146712 | 
2026-08-04 08:40:11.146860 | TASK [kolla-ansible-setup-disks : Create LVM extents on loop device]
2026-08-04 08:40:11.184641 | primary | skipping: Conditional result was False
2026-08-04 08:40:11.194952 | 
2026-08-04 08:40:11.195211 | TASK [kolla-ansible-setup-disks : Create LV]
2026-08-04 08:40:11.225132 | primary | skipping: Conditional result was False
2026-08-04 08:40:11.238181 | 
2026-08-04 08:40:11.238300 | TASK [Update /etc/hosts with internal API FQDN]
2026-08-04 08:40:11.264614 | primary | skipping: Conditional result was False
2026-08-04 08:40:11.272170 | 
2026-08-04 08:40:11.272298 | TASK [Install python3-virtualenv]
2026-08-04 08:40:15.118315 | primary | changed
2026-08-04 08:40:15.123302 | 
2026-08-04 08:40:15.123393 | PLAY [primary]
2026-08-04 08:40:15.137841 | 
2026-08-04 08:40:15.137967 | LOOP [Detect whether need build images]
2026-08-04 08:40:15.186015 | primary | ok:
2026-08-04 08:40:15.186365 | primary | {
2026-08-04 08:40:15.186459 | primary |   "branch": "master",
2026-08-04 08:40:15.186574 | primary |   "change": "999090",
2026-08-04 08:40:15.186685 | primary |   "change_message": "Add step-ca image\n\nChange-Id: I2d8f88cc9a4c022f531587ff920fde579564cd80\nSigned-off-by: Michal Arbet <michal.arbet@ultimum.io>\n",
2026-08-04 08:40:15.186759 | primary |   "change_url": "https://review.opendev.org/c/openstack/kolla/+/999090",
2026-08-04 08:40:15.186826 | primary |   "commit_id": "a941812d726d4e9cecc22f8ad70b90a4e91a4cef",
2026-08-04 08:40:15.186891 | primary |   "patchset": "3",
2026-08-04 08:40:15.186957 | primary |   "project": {
2026-08-04 08:40:15.187036 | primary |     "canonical_hostname": "opendev.org",
2026-08-04 08:40:15.187164 | primary |     "canonical_name": "opendev.org/openstack/kolla",
2026-08-04 08:40:15.187234 | primary |     "name": "openstack/kolla",
2026-08-04 08:40:15.187300 | primary |     "short_name": "kolla",
2026-08-04 08:40:15.187364 | primary |     "src_dir": "src/opendev.org/openstack/kolla"
2026-08-04 08:40:15.187430 | primary |   },
2026-08-04 08:40:15.187508 | primary |   "topic": null
2026-08-04 08:40:15.187575 | primary | }
2026-08-04 08:40:15.194823 | 
2026-08-04 08:40:15.194938 | TASK [Use base_image from mirror on Debian/Ubuntu]
2026-08-04 08:40:15.297173 | primary | ok
2026-08-04 08:40:15.304525 | 
2026-08-04 08:40:15.304644 | TASK [Include kolla Zuul vars if building new images]
2026-08-04 08:40:15.346181 | primary | ok
2026-08-04 08:40:15.352529 | 
2026-08-04 08:40:15.352641 | TASK [Checkout the previous ansible-collection-kolla branch]
2026-08-04 08:40:15.471841 | primary | skipping: Conditional result was False
2026-08-04 08:40:15.480309 | 
2026-08-04 08:40:15.480447 | TASK [Checkout the previous kolla-ansible branch]
2026-08-04 08:40:15.538584 | primary | skipping: Conditional result was False
2026-08-04 08:40:15.546083 | 
2026-08-04 08:40:15.546261 | TASK [Checkout the previous requirements branch]
2026-08-04 08:40:15.605249 | primary | skipping: Conditional result was False
2026-08-04 08:40:15.613479 | 
2026-08-04 08:40:15.613642 | TASK [Ensure /etc/kolla exists]
2026-08-04 08:40:16.265268 | primary | changed
2026-08-04 08:40:16.272839 | 
2026-08-04 08:40:16.272959 | TASK [kolla-build-config : Ensure kolla-build.conf exists]
2026-08-04 08:40:18.195908 | primary | changed
2026-08-04 08:40:18.207542 | 
2026-08-04 08:40:18.207653 | TASK [Template template_overrides.j2]
2026-08-04 08:40:19.741328 | primary | changed
2026-08-04 08:40:19.748033 | 
2026-08-04 08:40:19.748176 | TASK [Ensure /etc/docker exists]
2026-08-04 08:40:20.113951 | primary | changed
2026-08-04 08:40:20.120333 | 
2026-08-04 08:40:20.120444 | LOOP [Ensure configuration directories exist]
2026-08-04 08:40:20.474847 | primary | changed: "neutron"
2026-08-04 08:40:20.475360 | primary | ok: All items complete
2026-08-04 08:40:20.475429 | 
2026-08-04 08:40:20.770894 | primary | changed: "nova"
2026-08-04 08:40:21.108860 | primary | changed: "bifrost"
2026-08-04 08:40:21.413479 | primary | changed: "keystone-httpd/federation/oidc/metadata"
2026-08-04 08:40:21.711354 | primary | changed: "keystone-httpd/federation/oidc/attribute_maps"
2026-08-04 08:40:21.725927 | 
2026-08-04 08:40:21.726053 | LOOP [Generate configuration files]
2026-08-04 08:40:23.366546 | primary | changed:
2026-08-04 08:40:23.366952 | primary | {
2026-08-04 08:40:23.367021 | primary |   "dest": "/etc/kolla/inventory",
2026-08-04 08:40:23.367076 | primary |   "src": "tests/templates/inventory.j2"
2026-08-04 08:40:23.367166 | primary | }
2026-08-04 08:40:24.934267 | primary | changed:
2026-08-04 08:40:24.934406 | primary | {
2026-08-04 08:40:24.934467 | primary |   "dest": "/etc/kolla/globals.yml",
2026-08-04 08:40:24.934520 | primary |   "src": "tests/templates/globals-default.j2"
2026-08-04 08:40:24.934571 | primary | }
2026-08-04 08:40:26.415503 | primary | changed:
2026-08-04 08:40:26.415649 | primary | {
2026-08-04 08:40:26.415708 | primary |   "dest": "/etc/kolla/config/global.conf",
2026-08-04 08:40:26.415762 | primary |   "src": "tests/templates/global.conf.j2",
2026-08-04 08:40:26.415812 | primary |   "when": true
2026-08-04 08:40:26.415869 | primary | }
2026-08-04 08:40:26.417209 | primary | skipping: Conditional result was False
2026-08-04 08:40:27.912251 | primary | changed:
2026-08-04 08:40:27.912386 | primary | {
2026-08-04 08:40:27.912443 | primary |   "dest": "/etc/kolla/config/galera.cnf",
2026-08-04 08:40:27.912496 | primary |   "src": "tests/templates/mariadb-overrides.j2"
2026-08-04 08:40:27.912546 | primary | }
2026-08-04 08:40:29.400549 | primary | changed:
2026-08-04 08:40:29.400761 | primary | {
2026-08-04 08:40:29.400826 | primary |   "dest": "/etc/kolla/config/nova/nova-compute.conf",
2026-08-04 08:40:29.400880 | primary |   "src": "tests/templates/nova-compute-overrides.j2",
2026-08-04 08:40:29.400931 | primary |   "when": true
2026-08-04 08:40:29.400982 | primary | }
2026-08-04 08:40:30.924780 | primary | changed:
2026-08-04 08:40:30.924951 | primary | {
2026-08-04 08:40:30.925024 | primary |   "dest": "/etc/kolla/config/neutron.conf",
2026-08-04 08:40:30.925083 | primary |   "src": "tests/templates/neutron-server-overrides.j2",
2026-08-04 08:40:30.927269 | primary |   "when": true
2026-08-04 08:40:30.927337 | primary | }
2026-08-04 08:40:30.928942 | primary | skipping: Conditional result was False
2026-08-04 08:40:30.936066 | primary | skipping: Conditional result was False
2026-08-04 08:40:30.944817 | primary | skipping: Conditional result was False
2026-08-04 08:40:30.953558 | primary | skipping: Conditional result was False
2026-08-04 08:40:30.961722 | primary | skipping: Conditional result was False
2026-08-04 08:40:30.971381 | primary | skipping: Conditional result was False
2026-08-04 08:40:30.983192 | primary | skipping: Conditional result was False
2026-08-04 08:40:31.000874 | 
2026-08-04 08:40:31.001073 | TASK [Ensure ironic config directory exists]
2026-08-04 08:40:31.047656 | primary | skipping: Conditional result was False
2026-08-04 08:40:31.056401 | 
2026-08-04 08:40:31.056594 | LOOP [Download Ironic Python Agent (IPA) images]
2026-08-04 08:40:31.091069 | primary | skipping: Conditional result was False
2026-08-04 08:40:31.091703 | primary | changed: All items complete
2026-08-04 08:40:31.091964 | 
2026-08-04 08:40:31.095648 | primary | skipping: Conditional result was False
2026-08-04 08:40:31.112938 | 
2026-08-04 08:40:31.113185 | TASK [Slurp requirements.yml]
2026-08-04 08:40:31.583080 | primary | ok
2026-08-04 08:40:31.590540 | 
2026-08-04 08:40:31.590681 | TASK [Write requirements.yml]
2026-08-04 08:40:33.220671 | primary | changed
2026-08-04 08:40:33.227386 | 
2026-08-04 08:40:33.227510 | TASK [Ensure /etc/ansible exists]
2026-08-04 08:40:33.619254 | primary | changed
2026-08-04 08:40:33.628291 | 
2026-08-04 08:40:33.628475 | TASK [Pin bcrypt to <5 in upper-constraints in CI]
2026-08-04 08:40:34.134913 | primary | changed: line replaced
2026-08-04 08:40:34.141879 | 
2026-08-04 08:40:34.142126 | TASK [Create Kolla Ansible venv]
2026-08-04 08:40:37.348389 | primary | ok: Runtime: 0:00:02.202443
2026-08-04 08:40:37.358936 | 
2026-08-04 08:40:37.359148 | TASK [Install kolla-ansible and dependencies]
2026-08-04 08:40:57.998612 | primary | changed
2026-08-04 08:40:58.011124 | 
2026-08-04 08:40:58.011282 | TASK [Install Ansible collections]
2026-08-04 08:40:58.671621 | primary | Installing Ansible Galaxy dependencies
2026-08-04 08:40:59.181632 | primary | Starting galaxy collection install process
2026-08-04 08:40:59.181706 | primary | Process install dependency map
2026-08-04 08:40:59.181719 | primary | Starting collection install process
2026-08-04 08:40:59.181740 | primary | Installing 'openstack.kolla:1.0.0' to '/home/zuul/.ansible/collections/ansible_collections/openstack/kolla'
2026-08-04 08:40:59.181752 | primary | Created collection for openstack.kolla:1.0.0 at /home/zuul/.ansible/collections/ansible_collections/openstack/kolla
2026-08-04 08:40:59.181766 | primary | openstack.kolla:1.0.0 was installed successfully
2026-08-04 08:41:36.189091 | primary | Starting galaxy collection install process
2026-08-04 08:41:36.189142 | primary | Process install dependency map
2026-08-04 08:41:36.189148 | primary | Starting collection install process
2026-08-04 08:41:36.189167 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-posix-2.2.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/ansible-posix-2.2.2-1w15sopg
2026-08-04 08:41:36.189179 | primary | Installing 'ansible.posix:2.2.2' to '/home/zuul/.ansible/collections/ansible_collections/ansible/posix'
2026-08-04 08:41:36.189184 | primary | ansible.posix:2.2.2 was installed successfully
2026-08-04 08:41:36.189237 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-utils-6.0.3.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/ansible-utils-6.0.3-y75v4sh9
2026-08-04 08:41:36.189243 | primary | Installing 'ansible.utils:6.0.3' to '/home/zuul/.ansible/collections/ansible_collections/ansible/utils'
2026-08-04 08:41:36.189247 | primary | ansible.utils:6.0.3 was installed successfully
2026-08-04 08:41:36.189255 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/ansible-netcommon-8.6.1.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/ansible-netcommon-8.6.1-omoigyop
2026-08-04 08:41:36.189260 | primary | Installing 'ansible.netcommon:8.6.1' to '/home/zuul/.ansible/collections/ansible_collections/ansible/netcommon'
2026-08-04 08:41:36.189265 | primary | ansible.netcommon:8.6.1 was installed successfully
2026-08-04 08:41:36.189269 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/containers-podman-1.20.2.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/containers-podman-1.20.2-80ue6ixb
2026-08-04 08:41:36.189273 | primary | Installing 'containers.podman:1.20.2' to '/home/zuul/.ansible/collections/ansible_collections/containers/podman'
2026-08-04 08:41:36.189277 | primary | containers.podman:1.20.2 was installed successfully
2026-08-04 08:41:36.189281 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-crypto-3.3.0.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/community-crypto-3.3.0-noaui9ex
2026-08-04 08:41:36.189286 | primary | Installing 'community.crypto:3.3.0' to '/home/zuul/.ansible/collections/ansible_collections/community/crypto'
2026-08-04 08:41:36.189290 | primary | community.crypto:3.3.0 was installed successfully
2026-08-04 08:41:36.189294 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-docker-4.8.7.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/community-docker-4.8.7-xjbnt8x5
2026-08-04 08:41:36.189298 | primary | Installing 'community.docker:4.8.7' to '/home/zuul/.ansible/collections/ansible_collections/community/docker'
2026-08-04 08:41:36.189302 | primary | community.docker:4.8.7 was installed successfully
2026-08-04 08:41:36.189307 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-library_inventory_filtering_v1-1.1.5.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/community-library_inventory_filtering_v1-1.1.5-3n985fni
2026-08-04 08:41:36.189316 | primary | Installing 'community.library_inventory_filtering_v1:1.1.5' to '/home/zuul/.ansible/collections/ansible_collections/community/library_inventory_filtering_v1'
2026-08-04 08:41:36.189333 | primary | community.library_inventory_filtering_v1:1.1.5 was installed successfully
2026-08-04 08:41:36.189338 | primary | Downloading https://galaxy.ansible.com/api/v3/plugin/ansible/content/published/collections/artifacts/community-general-11.4.9.tar.gz to /home/zuul/.ansible/tmp/ansible-local-3267ln59rior/tmpu16ttbe3/community-general-11.4.9-2o023ubi
2026-08-04 08:41:36.189342 | primary | Installing 'community.general:11.4.9' to '/home/zuul/.ansible/collections/ansible_collections/community/general'
2026-08-04 08:41:36.189346 | primary | community.general:11.4.9 was installed successfully
2026-08-04 08:41:36.797517 | primary | ok: Runtime: 0:00:37.778824
2026-08-04 08:41:36.804529 | 
2026-08-04 08:41:36.804679 | TASK [Get ARA callback plugin path]
2026-08-04 08:41:37.288379 | primary | /home/zuul/kolla-ansible-venv/lib/python3.13/site-packages/ara/plugins/callback
2026-08-04 08:41:37.626547 | primary | ok: Runtime: 0:00:00.023591
2026-08-04 08:41:37.633644 | 
2026-08-04 08:41:37.633788 | TASK [Template ansible.cfg]
2026-08-04 08:41:39.332428 | primary | changed
2026-08-04 08:41:39.338943 | 
2026-08-04 08:41:39.339053 | TASK [Copy passwords.yml file]
2026-08-04 08:41:40.015123 | primary | changed
2026-08-04 08:41:40.021586 | 
2026-08-04 08:41:40.021693 | TASK [Generate passwords]
2026-08-04 08:41:44.236624 | primary | ok: Runtime: 0:00:03.739712
2026-08-04 08:41:44.243246 | 
2026-08-04 08:41:44.243363 | TASK [Record the running state of the environment as seen by the setup module]
2026-08-04 08:41:45.083360 | primary | [WARNING]: Invalid characters were found in group names but not replaced, use -vvvv to see details
2026-08-04 08:41:46.577480 | primary | [WARNING]: Host 'primary' is using the discovered Python interpreter at '/usr/bin/python3.13', but future installation of another Python interpreter could cause a different interpreter to be discovered. See https://docs.ansible.com/ansible-core/2.19/reference_appendices/interpreter_discovery.html for more information.
2026-08-04 08:41:46.948438 | primary | ok: Runtime: 0:00:01.949412
2026-08-04 08:41:46.955944 | 
2026-08-04 08:41:46.956070 | TASK [Create TLS certificates for octavia]
2026-08-04 08:41:47.016679 | primary | skipping: Conditional result was False
2026-08-04 08:41:47.024679 | 
2026-08-04 08:41:47.024815 | LOOP [openstack-clients : Setup OpenStack clients]
2026-08-04 08:41:47.070798 | primary | skipping: Conditional result was False
2026-08-04 08:41:47.071312 | 
2026-08-04 08:41:47.073783 | primary | skipping: Conditional result was False
2026-08-04 08:41:47.078593 | primary | skipping: Conditional result was False
2026-08-04 08:41:47.082759 | primary | skipping: Conditional result was False
2026-08-04 08:42:07.184152 | primary | changed:
2026-08-04 08:42:07.184305 | primary | {
2026-08-04 08:42:07.184370 | primary |   "enabled": true,
2026-08-04 08:42:07.184429 | primary |   "package": "python-heatclient"
2026-08-04 08:42:07.184486 | primary | }
2026-08-04 08:42:07.186825 | primary | skipping: Conditional result was False
2026-08-04 08:42:07.187564 | primary | skipping: Conditional result was False
2026-08-04 08:42:07.188277 | primary | skipping: Conditional result was False
2026-08-04 08:42:07.191374 | primary | skipping: Conditional result was False
2026-08-04 08:42:07.195467 | primary | skipping: Conditional result was False
2026-08-04 08:42:07.199512 | primary | skipping: Conditional result was False
2026-08-04 08:42:14.979313 | primary | changed:
2026-08-04 08:42:14.979473 | primary | {
2026-08-04 08:42:14.980608 | primary |   "enabled": true,
2026-08-04 08:42:14.980696 | primary |   "package": "python-openstackclient"
2026-08-04 08:42:14.980756 | primary | }
2026-08-04 08:42:14.983535 | primary | skipping: Conditional result was False
2026-08-04 08:42:14.984252 | primary | skipping: Conditional result was False
2026-08-04 08:42:15.009600 | 
2026-08-04 08:42:15.009788 | TASK [Run setup_gate.sh script]
2026-08-04 08:50:53.212229 | primary | changed
2026-08-04 08:50:53.227725 | 
2026-08-04 08:50:53.227931 | TASK [cephadm : Include package installation]
2026-08-04 08:50:53.265455 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.281080 | 
2026-08-04 08:50:53.281318 | TASK [cephadm : Ensure /etc/ceph exists]
2026-08-04 08:50:53.318888 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.329659 | 
2026-08-04 08:50:53.329838 | TASK [cephadm : Generate ssh key for cephadm]
2026-08-04 08:50:53.373314 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.384257 | 
2026-08-04 08:50:53.384445 | TASK [cephadm : Save public key]
2026-08-04 08:50:53.425727 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.434475 | 
2026-08-04 08:50:53.434700 | LOOP [cephadm : Copy cephadm public key to all hosts]
2026-08-04 08:50:53.478961 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.491757 | 
2026-08-04 08:50:53.491961 | TASK [cephadm : Bootstrap cephadm]
2026-08-04 08:50:53.556897 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.565455 | 
2026-08-04 08:50:53.565655 | TASK [cephadm : Get ceph fsid]
2026-08-04 08:50:53.615016 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.624934 | 
2026-08-04 08:50:53.625353 | TASK [cephadm : Template out cluster spec]
2026-08-04 08:50:53.672465 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.681759 | 
2026-08-04 08:50:53.681949 | TASK [cephadm : Template out command spec]
2026-08-04 08:50:53.718767 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.727392 | 
2026-08-04 08:50:53.727550 | TASK [cephadm : Run commands]
2026-08-04 08:50:53.789877 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.802847 | 
2026-08-04 08:50:53.803045 | TASK [cephadm : Generate ceph.conf without tabs]
2026-08-04 08:50:53.840934 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.854544 | 
2026-08-04 08:50:53.854706 | TASK [Show free space]
2026-08-04 08:50:53.927672 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.946375 | 
2026-08-04 08:50:53.946555 | LOOP [Ensure required kolla config directories exist]
2026-08-04 08:50:53.993308 | primary | skipping: Conditional result was False
2026-08-04 08:50:53.993887 | 
2026-08-04 08:50:53.999657 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.005161 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.010130 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.015352 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.030297 | 
2026-08-04 08:50:54.030484 | LOOP [Copy ceph.conf to enabled services]
2026-08-04 08:50:54.086755 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.087551 | 
2026-08-04 08:50:54.089838 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.092980 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.098898 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.103075 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.119083 | 
2026-08-04 08:50:54.119284 | LOOP [Copy keyrings to enabled services]
2026-08-04 08:50:54.163505 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.164032 | 
2026-08-04 08:50:54.168859 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.175580 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.181523 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.188501 | primary | skipping: Conditional result was False
2026-08-04 08:50:54.205055 | 
2026-08-04 08:50:54.205256 | TASK [kolla-ansible-deploy : Ensure /etc/kolla is writable]
2026-08-04 08:50:54.725214 | primary | changed
2026-08-04 08:50:54.732782 | 
2026-08-04 08:50:54.732948 | TASK [kolla-ansible-deploy : Generate self-signed certificates for the optional internal TLS tests]
2026-08-04 08:51:20.459519 | primary | ok: Runtime: 0:00:25.032442
2026-08-04 08:51:20.471746 | 
2026-08-04 08:51:20.472008 | TASK [kolla-ansible-deploy : Run pebble container]
2026-08-04 08:51:20.553073 | primary | skipping: Conditional result was False
2026-08-04 08:51:20.562394 | 
2026-08-04 08:51:20.562535 | TASK [kolla-ansible-deploy : Wait for pebble to start]
2026-08-04 08:51:20.641371 | primary | skipping: Conditional result was False
2026-08-04 08:51:20.651432 | 
2026-08-04 08:51:20.651557 | TASK [kolla-ansible-deploy : Copy pebble miniCA to /etc/kolla/certificates]
2026-08-04 08:51:20.849989 | primary | skipping: Conditional result was False
2026-08-04 08:51:20.859769 | 
2026-08-04 08:51:20.860013 | TASK [kolla-ansible-deploy : Fetch pebble.crt and store it in /etc/kolla/certificates/ca/]
2026-08-04 08:51:20.947843 | primary | skipping: Conditional result was False
2026-08-04 08:51:20.957673 | 
2026-08-04 08:51:20.957811 | TASK [kolla-ansible-deploy : Run kolla-ansible prechecks]
2026-08-04 08:52:25.734419 | primary | ok: Runtime: 0:01:03.774338
2026-08-04 08:52:25.743571 | 
2026-08-04 08:52:25.743686 | TASK [kolla-ansible-deploy : Run kolla-ansible pull]
2026-08-04 08:54:37.545076 | primary | ok: Runtime: 0:02:10.917063
2026-08-04 08:54:37.552488 | 
2026-08-04 08:54:37.552604 | TASK [kolla-ansible-deploy : Run kolla-ansible deploy]
2026-08-04 09:11:13.995977 | primary | ok: Runtime: 0:16:34.891753
2026-08-04 09:11:14.156152 | 
2026-08-04 09:11:14.158744 | TASK [kolla-ansible-deploy : Run kolla-ansible post-deploy]
2026-08-04 09:11:22.930571 | primary | ok: Runtime: 0:00:07.363868
2026-08-04 09:11:22.937945 | 
2026-08-04 09:11:22.938921 | TASK [kolla-ansible-deploy : Run kolla-ansible validate-config]
2026-08-04 09:13:54.234377 | primary | ok: Runtime: 0:02:30.262178
2026-08-04 09:13:54.242276 | 
2026-08-04 09:13:54.242395 | TASK [kolla-ansible-deploy : Run kolla-ansible check]
2026-08-04 09:14:22.964067 | primary | ok: Runtime: 0:00:26.259527
2026-08-04 09:14:23.074733 | 
2026-08-04 09:14:23.074908 | TASK [kolla-ansible-test-dashboard : Get contents of clouds.yaml]
2026-08-04 09:14:23.598414 | primary | ok
2026-08-04 09:14:23.612181 | 
2026-08-04 09:14:23.612433 | TASK [kolla-ansible-test-dashboard : Query dashboard and check that the returned page looks like a login page]
2026-08-04 09:14:24.895018 | primary | ok: OK (9425 bytes)
2026-08-04 09:14:24.903569 | 
2026-08-04 09:14:24.903746 | TASK [kolla-ansible-test-dashboard : Check if testinfra subdirectory exists]
2026-08-04 09:14:25.340544 | primary | ok
2026-08-04 09:14:25.348713 | 
2026-08-04 09:14:25.348919 | TASK [kolla-ansible-test-dashboard : Ensure testinfra subdirectory exists]
2026-08-04 09:14:25.396279 | primary | skipping: Conditional result was False
2026-08-04 09:14:25.408954 | 
2026-08-04 09:14:25.409191 | TASK [kolla-ansible-test-dashboard : Ensure screenshots directory exists]
2026-08-04 09:14:25.458639 | primary | skipping: Conditional result was False
2026-08-04 09:14:25.476039 | 
2026-08-04 09:14:25.476357 | TASK [kolla-ansible-test-dashboard : Ensure required packages are installed]
2026-08-04 09:14:25.517841 | primary | skipping: Conditional result was False
2026-08-04 09:14:25.530242 | 
2026-08-04 09:14:25.530413 | TASK [kolla-ansible-test-dashboard : Run Selenium Firefox container (Docker)]
2026-08-04 09:14:25.578345 | primary | skipping: Conditional result was False
2026-08-04 09:14:25.591906 | 
2026-08-04 09:14:25.592164 | TASK [kolla-ansible-test-dashboard : Run Selenium Firefox container (Podman)]
2026-08-04 09:14:25.639010 | primary | skipping: Conditional result was False
2026-08-04 09:14:25.654582 | 
2026-08-04 09:14:25.654894 | TASK [kolla-ansible-test-dashboard : Wait for port 4500 to be up]
2026-08-04 09:14:25.698366 | primary | skipping: Conditional result was False
2026-08-04 09:14:25.710319 | 
2026-08-04 09:14:25.710537 | TASK [kolla-ansible-test-dashboard : Run testinfra tests]
2026-08-04 09:14:25.783499 | primary | skipping: Conditional result was False
2026-08-04 09:14:25.798605 | 
2026-08-04 09:14:25.798770 | TASK [Run init-core-openstack.sh script]
2026-08-04 09:15:19.061704 | primary | changed
2026-08-04 09:15:19.068854 | 
2026-08-04 09:15:19.068973 | TASK [Run test-ovn.sh script]
2026-08-04 09:15:19.104406 | primary | skipping: Conditional result was False
2026-08-04 09:15:19.112737 | 
2026-08-04 09:15:19.112878 | TASK [kolla-ansible-tempest : Install required packages]
2026-08-04 09:15:41.496039 | primary | changed
2026-08-04 09:15:41.507985 | 
2026-08-04 09:15:41.508192 | TASK [kolla-ansible-tempest : Init tempest workspace]
2026-08-04 09:15:49.720003 | primary | ok: Runtime: 0:00:00.850287
2026-08-04 09:15:49.726887 | 
2026-08-04 09:15:49.727011 | TASK [kolla-ansible-tempest : Discover tempest config]
2026-08-04 09:15:54.934014 | primary | ok: Runtime: 0:00:04.443015
2026-08-04 09:15:54.944361 | 
2026-08-04 09:15:54.944559 | LOOP [kolla-ansible-tempest : Set Tempest image properties on aarch64]
2026-08-04 09:15:54.992673 | primary | skipping: Conditional result was False
2026-08-04 09:15:54.993431 | 
2026-08-04 09:15:55.003326 | primary | skipping: Conditional result was False
2026-08-04 09:15:55.015948 | 
2026-08-04 09:15:55.016193 | TASK [kolla-ansible-tempest : Run tempest tests]
2026-08-04 09:18:13.803478 | primary | ERROR
2026-08-04 09:18:13.803894 | primary | {
2026-08-04 09:18:13.803965 | primary |   "delta": "0:02:18.084470",
2026-08-04 09:18:13.804023 | primary |   "end": "2026-08-04 09:18:13.628899",
2026-08-04 09:18:13.804079 | primary |   "msg": "non-zero return code",
2026-08-04 09:18:13.804457 | primary |   "rc": 1,
2026-08-04 09:18:13.804551 | primary |   "start": "2026-08-04 09:15:55.544429"
2026-08-04 09:18:13.804615 | primary | }
failure
2026-08-04 09:18:13.807528 | 
2026-08-04 09:18:13.807612 | PLAY RECAP
2026-08-04 09:18:13.807739 | primary | ok: 41 changed: 27 unreachable: 0 failed: 1 skipped: 43 rescued: 0 ignored: 0
2026-08-04 09:18:13.807807 | 
2026-08-04 09:18:14.181082 | RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/kolla-ansible/tests/run.yml@master]
2026-08-04 09:18:14.185977 | POST-RUN START: [untrusted : opendev.org/openstack/kolla-ansible/tests/post.yml@master]
2026-08-04 09:18:15.482695 | 
2026-08-04 09:18:15.482913 | PLAY [all]
2026-08-04 09:18:15.508596 | 
2026-08-04 09:18:15.508902 | TASK [fetch-subunit-output : Find stestr or testr executable]
2026-08-04 09:18:16.584075 | primary | changed
2026-08-04 09:18:16.594060 | 
2026-08-04 09:18:16.594241 | TASK [fetch-subunit-output : Get the list of directories with subunit files]
2026-08-04 09:18:16.638618 | primary | ok
2026-08-04 09:18:16.646192 | 
2026-08-04 09:18:16.646323 | LOOP [fetch-subunit-output : Find any inflight partial subunit files]
2026-08-04 09:18:17.361859 | primary | ok: Not all paths examined, check warnings for details
2026-08-04 09:18:17.387089 | 
2026-08-04 09:18:17.387383 | LOOP [fetch-subunit-output : Copy any inflight subunit files]
2026-08-04 09:18:17.435740 | 
2026-08-04 09:18:17.436057 | TASK [fetch-subunit-output : Create a temporary file to store the subunit stream]
2026-08-04 09:18:18.064721 | primary | changed
2026-08-04 09:18:18.076182 | 
2026-08-04 09:18:18.076463 | LOOP [fetch-subunit-output : Generate subunit file]
2026-08-04 09:18:19.288976 | primary | ok: Item: /home/zuul/tempest Runtime: 0:00:00.459957
2026-08-04 09:18:19.289252 | 
2026-08-04 09:18:19.301916 | 
2026-08-04 09:18:19.302072 | TASK [fetch-subunit-output : Copy the combined subunit file to the zuul work directory]
2026-08-04 09:18:20.391714 | primary | changed
2026-08-04 09:18:20.400838 | 
2026-08-04 09:18:20.401030 | TASK [fetch-subunit-output : Remove the temporary file]
2026-08-04 09:18:21.056229 | primary | changed
2026-08-04 09:18:21.067058 | 
2026-08-04 09:18:21.067258 | TASK [fetch-subunit-output : Process and fetch subunit results]
2026-08-04 09:18:21.110730 | primary | ok
2026-08-04 09:18:21.131152 | primary | included: /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/untrusted/project_3/opendev.org/zuul/zuul-jobs/roles/fetch-subunit-output/tasks/process.yaml
2026-08-04 09:18:21.140428 | 
2026-08-04 09:18:21.140538 | TASK [fetch-subunit-output : Look for subunit2html command]
2026-08-04 09:18:22.241587 | primary | changed
2026-08-04 09:18:22.252747 | 
2026-08-04 09:18:22.252926 | TASK [fetch-subunit-output : Define subunit_html_command fact]
2026-08-04 09:18:22.361313 | primary | ok
2026-08-04 09:18:22.369404 | 
2026-08-04 09:18:22.369534 | TASK [fetch-subunit-output : Emit a debug line so we can search logstash for fallback hits]
2026-08-04 09:18:22.434972 | subunit2html found in: /usr/os-testr-env/bin/subunit2html
2026-08-04 09:18:22.444034 | 
2026-08-04 09:18:22.444195 | TASK [fetch-subunit-output : Generate testr_results.html file]
2026-08-04 09:18:23.122334 | primary | setUpClass (tempest.api.compute.servers.test_server_addresses.ServerAddressesTestJSON)
2026-08-04 09:18:23.122433 | primary | setUpClass (tempest.api.compute.servers.test_create_server.ServersTestManualDisk)
2026-08-04 09:18:23.122460 | primary | setUpClass (tempest.api.compute.servers.test_create_server.ServersTestJSON)
2026-08-04 09:18:23.122477 | primary | tempest.api.compute.servers.test_attach_interfaces.AttachInterfacesUnderV243Test.test_add_remove_fixed_ip[id-c7e0e60b-ee45-43d0-abeb-8596fd42a2f9,network,smoke]
2026-08-04 09:18:23.122489 | primary | setUpClass (tempest.api.compute.servers.test_server_actions.ServerActionsTestJSON)
2026-08-04 09:18:23.122498 | primary | tempest.scenario.test_network_basic_ops.TestNetworkBasicOps.test_network_basic_ops[compute,id-f323b3ba-82f8-4db7-8ea6-6a895869ec49,network,smoke]
2026-08-04 09:18:23.122505 | primary | tempest.scenario.test_server_basic_ops.TestServerBasicOps.test_server_basic_ops[compute,id-7fff3fb3-91d8-4fd0-bd7d-0204f1f180ba,network,smoke]
2026-08-04 09:18:23.659749 | primary | ok: Runtime: 0:00:00.229071
2026-08-04 09:18:23.667929 | 
2026-08-04 09:18:23.668086 | TASK [fetch-subunit-output : Find subunit files]
2026-08-04 09:18:24.209669 | primary | ok: All paths examined
2026-08-04 09:18:24.222156 | 
2026-08-04 09:18:24.222301 | LOOP [fetch-subunit-output : Collect test-results]
2026-08-04 09:18:25.891744 | primary | changed: >f+++++++++ testr_results.html
2026-08-04 09:18:27.318059 | primary | changed: >f+++++++++ testrepository.subunit
2026-08-04 09:18:27.336174 | 
2026-08-04 09:18:27.336321 | LOOP [fetch-subunit-output : Copy test-results]
2026-08-04 09:18:27.422329 | primary | skipping: Conditional result was False
2026-08-04 09:18:27.422899 | primary | changed: All items complete
2026-08-04 09:18:27.422967 | 
2026-08-04 09:18:27.437847 | primary | skipping: Conditional result was False
2026-08-04 09:18:27.466434 | 
2026-08-04 09:18:27.466633 | LOOP [fetch-subunit-output : Return artifact to Zuul]
2026-08-04 09:18:27.530978 | primary | ok:
2026-08-04 09:18:27.531439 | primary | {
2026-08-04 09:18:27.531505 | primary |   "atime": 1785835103.1182868,
2026-08-04 09:18:27.531560 | primary |   "ctime": 1785835103.1182868,
2026-08-04 09:18:27.531610 | primary |   "dev": 65025,
2026-08-04 09:18:27.531661 | primary |   "gid": 1000,
2026-08-04 09:18:27.531711 | primary |   "gr_name": "zuul",
2026-08-04 09:18:27.531761 | primary |   "inode": 10488799,
2026-08-04 09:18:27.531810 | primary |   "isblk": false,
2026-08-04 09:18:27.531859 | primary |   "ischr": false,
2026-08-04 09:18:27.531908 | primary |   "isdir": false,
2026-08-04 09:18:27.531957 | primary |   "isfifo": false,
2026-08-04 09:18:27.532006 | primary |   "isgid": false,
2026-08-04 09:18:27.532054 | primary |   "islnk": false,
2026-08-04 09:18:27.532148 | primary |   "isreg": true,
2026-08-04 09:18:27.532206 | primary |   "issock": false,
2026-08-04 09:18:27.532256 | primary |   "isuid": false,
2026-08-04 09:18:27.532304 | primary |   "mode": "0664",
2026-08-04 09:18:27.532353 | primary |   "mtime": 1785835103.1182868,
2026-08-04 09:18:27.532417 | primary |   "nlink": 1,
2026-08-04 09:18:27.532470 | primary |   "path": "/home/zuul/tempest/testr_results.html",
2026-08-04 09:18:27.532519 | primary |   "pw_name": "zuul",
2026-08-04 09:18:27.532568 | primary |   "rgrp": true,
2026-08-04 09:18:27.532616 | primary |   "roth": true,
2026-08-04 09:18:27.532664 | primary |   "rusr": true,
2026-08-04 09:18:27.532712 | primary |   "size": 264759,
2026-08-04 09:18:27.532760 | primary |   "uid": 1000,
2026-08-04 09:18:27.532808 | primary |   "wgrp": true,
2026-08-04 09:18:27.532857 | primary |   "woth": false,
2026-08-04 09:18:27.532905 | primary |   "wusr": true,
2026-08-04 09:18:27.532953 | primary |   "xgrp": false,
2026-08-04 09:18:27.533001 | primary |   "xoth": false,
2026-08-04 09:18:27.533049 | primary |   "xusr": false
2026-08-04 09:18:27.533136 | primary | }
2026-08-04 09:18:27.533254 | 
2026-08-04 09:18:27.535375 | primary | skipping: Conditional result was False
2026-08-04 09:18:27.592622 | 
2026-08-04 09:18:27.592807 | TASK [Change container engine after the migration]
2026-08-04 09:18:27.618893 | primary | skipping: Conditional result was False
2026-08-04 09:18:27.628269 | 
2026-08-04 09:18:27.628446 | TASK [Print all facts]
2026-08-04 09:18:29.568306 | primary | changed
2026-08-04 09:18:29.575991 | 
2026-08-04 09:18:29.576150 | TASK [Run check-logs.sh script]
2026-08-04 09:18:37.822429 | primary | changed
2026-08-04 09:18:37.829672 | 
2026-08-04 09:18:37.829785 | TASK [Print check-logs output]
2026-08-04 09:18:37.914015 | Checking for CRITICAL log messages
2026-08-04 09:18:37.914302 | Checking for ERROR log messages
2026-08-04 09:18:37.914366 | Found some ERROR log messages. Matches in /tmp/logs/kolla/all-ERROR.log
2026-08-04 09:18:37.914423 | Checking for WARNING log messages
2026-08-04 09:18:37.914477 | Found some WARNING log messages. Matches in /tmp/logs/kolla/all-WARNING.log
2026-08-04 09:18:37.923050 | 
2026-08-04 09:18:37.923197 | TASK [Run diagnostics script]
2026-08-04 09:18:52.219092 | primary | changed
2026-08-04 09:18:52.226696 | 
2026-08-04 09:18:52.226828 | TASK [Print get_logs output]
2026-08-04 09:18:52.269321 | cp: cannot stat '/etc/systemd/system/sysinit.target.wants/open-iscsi.service': No such file or directory
2026-08-04 09:18:52.269587 | cp: cannot stat '/etc/systemd/system/sockets.target.wants/iscsid.socket': No such file or directory
2026-08-04 09:18:52.269654 | cp: cannot stat '/etc/systemd/system/iscsi.service': No such file or directory
2026-08-04 09:18:52.269715 | '/var/log/README' -> '/tmp/logs/system_logs/README'
2026-08-04 09:18:52.269774 | '/var/log/alternatives.log' -> '/tmp/logs/system_logs/alternatives.log'
2026-08-04 09:18:52.269833 | '/var/log/apt' -> '/tmp/logs/system_logs/apt'
2026-08-04 09:18:52.269889 | '/var/log/apt/eipp.log.xz' -> '/tmp/logs/system_logs/apt/eipp.log.xz'
2026-08-04 09:18:52.269949 | '/var/log/apt/term.log' -> '/tmp/logs/system_logs/apt/term.log'
2026-08-04 09:18:52.270004 | '/var/log/apt/history.log' -> '/tmp/logs/system_logs/apt/history.log'
2026-08-04 09:18:52.270060 | '/var/log/auth.log' -> '/tmp/logs/system_logs/auth.log'
2026-08-04 09:18:52.270155 | '/var/log/bootstrap.log' -> '/tmp/logs/system_logs/bootstrap.log'
2026-08-04 09:18:52.270214 | '/var/log/btmp' -> '/tmp/logs/system_logs/btmp'
2026-08-04 09:18:52.270270 | '/var/log/cron.log' -> '/tmp/logs/system_logs/cron.log'
2026-08-04 09:18:52.270326 | '/var/log/dpkg.log' -> '/tmp/logs/system_logs/dpkg.log'
2026-08-04 09:18:52.270382 | '/var/log/journal' -> '/tmp/logs/system_logs/journal'
2026-08-04 09:18:52.270437 | '/var/log/journal/157f4d4e4013417884a86dba7e2b9c5e' -> '/tmp/logs/system_logs/journal/157f4d4e4013417884a86dba7e2b9c5e'
2026-08-04 09:18:52.270493 | '/var/log/journal/157f4d4e4013417884a86dba7e2b9c5e/system.journal' -> '/tmp/logs/system_logs/journal/157f4d4e4013417884a86dba7e2b9c5e/system.journal'
2026-08-04 09:18:52.270549 | '/var/log/journal/157f4d4e4013417884a86dba7e2b9c5e/user-1000.journal' -> '/tmp/logs/system_logs/journal/157f4d4e4013417884a86dba7e2b9c5e/user-1000.journal'
2026-08-04 09:18:52.270657 | '/var/log/journal/157f4d4e4013417884a86dba7e2b9c5e/user-42401.journal' -> '/tmp/logs/system_logs/journal/157f4d4e4013417884a86dba7e2b9c5e/user-42401.journal'
2026-08-04 09:18:52.270716 | '/var/log/journal/157f4d4e4013417884a86dba7e2b9c5e/user-42439.journal' -> '/tmp/logs/system_logs/journal/157f4d4e4013417884a86dba7e2b9c5e/user-42439.journal'
2026-08-04 09:18:52.271089 | '/var/log/journal/157f4d4e4013417884a86dba7e2b9c5e/user-42436.journal' -> '/tmp/logs/system_logs/journal/157f4d4e4013417884a86dba7e2b9c5e/user-42436.journal'
2026-08-04 09:18:52.271186 | '/var/log/kern.log' -> '/tmp/logs/system_logs/kern.log'
2026-08-04 09:18:52.271242 | '/var/log/lastlog' -> '/tmp/logs/system_logs/lastlog'
2026-08-04 09:18:52.271293 | '/var/log/private' -> '/tmp/logs/system_logs/private'
2026-08-04 09:18:52.271343 | '/var/log/runit' -> '/tmp/logs/system_logs/runit'
2026-08-04 09:18:52.271394 | '/var/log/runit/acpid' -> '/tmp/logs/system_logs/runit/acpid'
2026-08-04 09:18:52.271445 | '/var/log/runit/ssh' -> '/tmp/logs/system_logs/runit/ssh'
2026-08-04 09:18:52.271495 | '/var/log/syslog' -> '/tmp/logs/system_logs/syslog'
2026-08-04 09:18:52.271545 | '/var/log/unbound.log' -> '/tmp/logs/system_logs/unbound.log'
2026-08-04 09:18:52.271596 | '/var/log/user.log' -> '/tmp/logs/system_logs/user.log'
2026-08-04 09:18:52.271646 | '/var/log/wtmp' -> '/tmp/logs/system_logs/wtmp'
2026-08-04 09:18:52.271697 | '/var/log/wtmp.db' -> '/tmp/logs/system_logs/wtmp.db'
2026-08-04 09:18:52.271747 | WARNING: IPv4 forwarding is disabled
2026-08-04 09:18:52.271798 | find: ‘/tmp/logs/docker_logs’: No such file or directory
2026-08-04 09:18:52.271859 | 204	/tmp/logs
2026-08-04 09:18:52.280829 | 
2026-08-04 09:18:52.281385 | TASK [Download /etc/hosts file to executor]
2026-08-04 09:18:53.669058 | primary | changed:
2026-08-04 09:18:53.669371 | primary | created directory /var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/work/logs/primary
2026-08-04 09:18:53.669436 | primary | >f+++++++++ hosts
2026-08-04 09:18:53.676932 | 
2026-08-04 09:18:53.677060 | TASK [Dump all iptables rules]
2026-08-04 09:18:54.386879 | primary | ok: Runtime: 0:00:00.011522
2026-08-04 09:18:54.394877 | 
2026-08-04 09:18:54.394982 | TASK [Download /tmp/logs file to executor]
2026-08-04 09:18:57.090046 | primary | ok
2026-08-04 09:18:57.094361 | 
2026-08-04 09:18:57.094458 | PLAY [primary]
2026-08-04 09:18:57.103893 | 
2026-08-04 09:18:57.104006 | TASK [Download testinfra to executor]
2026-08-04 09:18:58.414526 | primary | ERROR
2026-08-04 09:18:58.414879 | primary | {
2026-08-04 09:18:58.414950 | primary |   "msg": "Warning: Permanently added '199.204.45.109' (ED25519) to the list of known hosts.\r\nrsync: [sender] link_stat \"/home/zuul/testinfra\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1874) [Receiver=3.2.7]\nrsync: [Receiver] write error: Broken pipe (32)\n",
2026-08-04 09:18:58.415007 | primary |   "rc": 23
2026-08-04 09:18:58.415058 | primary | }
2026-08-04 09:18:58.415154 | primary | ERROR: Ignoring Errors
2026-08-04 09:18:58.423793 | 
2026-08-04 09:18:58.423960 | TASK [Return artifact to Zuul]
2026-08-04 09:18:58.469215 | primary | ok
2026-08-04 09:18:58.479881 | 
2026-08-04 09:18:58.480077 | TASK [Check for existence of ara sqlite]
2026-08-04 09:18:58.960693 | primary | ok
2026-08-04 09:18:58.968079 | 
2026-08-04 09:18:58.968218 | TASK [Ensure ara-report folder existence]
2026-08-04 09:18:59.004372 | primary | skipping: Conditional result was False
2026-08-04 09:18:59.014440 | 
2026-08-04 09:18:59.014571 | TASK [Download ara sqlite]
2026-08-04 09:18:59.041567 | primary | skipping: Conditional result was False
2026-08-04 09:18:59.052090 | 
2026-08-04 09:18:59.052330 | TASK [Get ara version]
2026-08-04 09:18:59.120202 | primary | skipping: Conditional result was False
2026-08-04 09:18:59.131026 | 
2026-08-04 09:18:59.131234 | TASK [Template ara readme]
2026-08-04 09:18:59.168393 | primary | skipping: Conditional result was False
2026-08-04 09:18:59.178450 | 
2026-08-04 09:18:59.178781 | TASK [Generate HTML from ARA database]
2026-08-04 09:18:59.249892 | primary | skipping: Conditional result was False
2026-08-04 09:18:59.260976 | 
2026-08-04 09:18:59.261249 | TASK [Download ARA HTML]
2026-08-04 09:18:59.298037 | primary | skipping: Conditional result was False
2026-08-04 09:18:59.299930 | 
2026-08-04 09:18:59.300009 | PLAY RECAP
2026-08-04 09:18:59.300137 | primary | ok: 26 changed: 13 unreachable: 0 failed: 0 skipped: 9 rescued: 0 ignored: 1
2026-08-04 09:18:59.300201 | 
2026-08-04 09:18:59.496568 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/kolla-ansible/tests/post.yml@master]
2026-08-04 09:18:59.503252 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2026-08-04 09:19:00.711890 | 
2026-08-04 09:19:00.712146 | PLAY [all]
2026-08-04 09:19:00.735309 | 
2026-08-04 09:19:00.735536 | TASK [fetch-output : Set log path for multiple nodes]
2026-08-04 09:19:00.814008 | primary | skipping: Conditional result was False
2026-08-04 09:19:00.822725 | 
2026-08-04 09:19:00.822871 | TASK [fetch-output : Set log path for single node]
2026-08-04 09:19:00.878388 | primary | ok
2026-08-04 09:19:00.887623 | 
2026-08-04 09:19:00.887772 | LOOP [fetch-output : Ensure local output dirs]
2026-08-04 09:19:01.484728 | primary -> localhost | ok: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/work/logs"
2026-08-04 09:19:01.829255 | primary -> localhost | changed: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/work/artifacts"
2026-08-04 09:19:02.160450 | primary -> localhost | changed: "/var/lib/zuul/builds/b5f7e4cb6dd44f508f50ccb681e944ac/work/docs"
2026-08-04 09:19:02.176644 | 
2026-08-04 09:19:02.176798 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-08-04 09:19:04.305442 | primary | changed: .d..t...... ./
2026-08-04 09:19:04.305816 | primary | changed: All items complete
2026-08-04 09:19:04.305882 | 
2026-08-04 09:19:05.769817 | primary | changed: .d..t...... ./
2026-08-04 09:19:07.150589 | primary | changed: .d..t...... ./
2026-08-04 09:19:07.175072 | 
2026-08-04 09:19:07.175370 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-08-04 09:19:07.842598 | primary -> localhost | ok: Item: artifacts Runtime: 0:00:00.009783
2026-08-04 09:19:08.184123 | primary -> localhost | ok: Item: docs Runtime: 0:00:00.007818
2026-08-04 09:19:08.207895 | 
2026-08-04 09:19:08.208039 | TASK [Get df disk usage]
2026-08-04 09:19:08.419967 | primary | Filesystem     1K-blocks     Used Available Use% Mounted on
2026-08-04 09:19:08.420421 | primary | udev             8169484        0   8169484   0% /dev
2026-08-04 09:19:08.420495 | primary | tmpfs            1638220     3696   1634524   1% /run
2026-08-04 09:19:08.420556 | primary | /dev/vda1       78062860 33103796  41365288  45% /
2026-08-04 09:19:08.420611 | primary | tmpfs            8191092      328   8190764   1% /dev/shm
2026-08-04 09:19:08.420666 | primary | tmpfs               5120        0      5120   0% /run/lock
2026-08-04 09:19:08.420720 | primary | tmpfs            8191092   208440   7982652   3% /tmp
2026-08-04 09:19:08.420775 | primary | tmpfs               1024        0      1024   0% /run/credentials/systemd-journald.service
2026-08-04 09:19:08.420837 | primary | /dev/sr0             502      502         0 100% /mnt/config
2026-08-04 09:19:08.420913 | primary | tmpfs               1024        0      1024   0% /run/credentials/getty@tty1.service
2026-08-04 09:19:08.420971 | primary | tmpfs               1024        0      1024   0% /run/credentials/serial-getty@ttyS0.service
2026-08-04 09:19:08.421025 | primary | tmpfs            1638216        4   1638212   1% /run/user/1000
2026-08-04 09:19:08.421078 | primary | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-08-04 09:19:08.421175 | primary | debug1: auto-mux: Trying existing master
2026-08-04 09:19:08.421231 | primary | debug1: mux_client_request_session: master session id: 2
2026-08-04 09:19:08.421284 | primary | Shared connection to 199.204.45.109 closed.
2026-08-04 09:19:08.421392 | primary | changed
2026-08-04 09:19:08.430176 | 
2026-08-04 09:19:08.430298 | TASK [Get df inode usage]
2026-08-04 09:19:08.632689 | primary | Filesystem       Inodes   IUsed    IFree IUse% Mounted on
2026-08-04 09:19:08.632943 | primary | udev            2042371     353  2042018    1% /dev
2026-08-04 09:19:08.633007 | primary | tmpfs           2047773    1533  2046240    1% /run
2026-08-04 09:19:08.633065 | primary | /dev/vda1      20940800 1290159 19650641    7% /
2026-08-04 09:19:08.633157 | primary | tmpfs           2047773      83  2047690    1% /dev/shm
2026-08-04 09:19:08.633216 | primary | tmpfs           2047773       2  2047771    1% /run/lock
2026-08-04 09:19:08.633270 | primary | tmpfs           1048576     989  1047587    1% /tmp
2026-08-04 09:19:08.633323 | primary | tmpfs              1024       1     1023    1% /run/credentials/systemd-journald.service
2026-08-04 09:19:08.633376 | primary | /dev/sr0              0       0        0     - /mnt/config
2026-08-04 09:19:08.633429 | primary | tmpfs              1024       1     1023    1% /run/credentials/getty@tty1.service
2026-08-04 09:19:08.633482 | primary | tmpfs              1024       1     1023    1% /run/credentials/serial-getty@ttyS0.service
2026-08-04 09:19:08.633534 | primary | tmpfs            409554      27   409527    1% /run/user/1000
2026-08-04 09:19:08.633592 | primary | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-08-04 09:19:08.633646 | primary | debug1: auto-mux: Trying existing master
2026-08-04 09:19:08.633699 | primary | debug1: mux_client_request_session: master session id: 2
2026-08-04 09:19:08.633751 | primary | Shared connection to 199.204.45.109 closed.
2026-08-04 09:19:08.633839 | primary | changed
2026-08-04 09:19:08.640957 | 
2026-08-04 09:19:08.641072 | TASK [Get l2 networking]
2026-08-04 09:19:08.877374 | primary | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.877629 | primary |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2026-08-04 09:19:08.877693 | primary | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.877751 | primary |     link/ether fa:16:3e:01:4e:fc brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.877806 | primary |     altname enp0s3
2026-08-04 09:19:08.877861 | primary |     altname enxfa163e014efc
2026-08-04 09:19:08.877914 | primary | 3: vxlan-0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1450 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.877967 | primary |     link/ether ca:4c:45:c8:90:de brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878020 | primary | 4: vxlan-1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1450 qdisc noqueue master br-0 state UNKNOWN mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878074 | primary |     link/ether 6e:a9:52:85:df:a1 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878165 | primary | 5: br-0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1450 qdisc noqueue state UP mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878222 | primary |     link/ether d6:77:da:15:36:11 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878274 | primary | 6: veth-br-0-ext@veth-br-0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue master ovs-system state UP mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878327 | primary |     link/ether e2:ac:41:97:8b:b4 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878378 | primary | 7: veth-br-0@veth-br-0-ext: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue master br-0 state UP mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878430 | primary |     link/ether f2:48:98:68:09:b0 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878481 | primary | 8: ovs-system: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878549 | primary |     link/ether 72:e5:e6:11:91:ac brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878605 | primary | 9: br-ex: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878658 | primary |     link/ether 6a:74:06:71:e4:42 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878710 | primary | 10: br-int: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878762 | primary |     link/ether 0a:67:1c:5a:f5:4a brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878814 | primary | 11: br-tun: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN mode DEFAULT group default qlen 1000
2026-08-04 09:19:08.878865 | primary |     link/ether aa:45:a9:95:75:42 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:08.878924 | primary | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-08-04 09:19:08.878977 | primary | debug1: auto-mux: Trying existing master
2026-08-04 09:19:08.879030 | primary | debug1: mux_client_request_session: master session id: 2
2026-08-04 09:19:08.879082 | primary | Shared connection to 199.204.45.109 closed.
2026-08-04 09:19:08.879205 | primary | changed
2026-08-04 09:19:08.888594 | 
2026-08-04 09:19:08.888708 | TASK [Get l3 networking]
2026-08-04 09:19:09.130395 | primary | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
2026-08-04 09:19:09.130678 | primary |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2026-08-04 09:19:09.130741 | primary |     inet 127.0.0.1/8 scope host lo
2026-08-04 09:19:09.130797 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.130850 | primary |     inet6 ::1/128 scope host noprefixroute
2026-08-04 09:19:09.130903 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.130955 | primary | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
2026-08-04 09:19:09.131008 | primary |     link/ether fa:16:3e:01:4e:fc brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.131059 | primary |     altname enp0s3
2026-08-04 09:19:09.131165 | primary |     altname enxfa163e014efc
2026-08-04 09:19:09.131225 | primary |     inet 199.204.45.109/24 brd 199.204.45.255 scope global dynamic ens3
2026-08-04 09:19:09.131279 | primary |        valid_lft 83877sec preferred_lft 83877sec
2026-08-04 09:19:09.131332 | primary |     inet6 2604:e100:1:0:f816:3eff:fe01:4efc/64 scope global dynamic mngtmpaddr proto kernel_ra
2026-08-04 09:19:09.131385 | primary |        valid_lft 2591846sec preferred_lft 604646sec
2026-08-04 09:19:09.131437 | primary |     inet6 fe80::f816:3eff:fe01:4efc/64 scope link proto kernel_ll
2026-08-04 09:19:09.131489 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.131542 | primary | 3: vxlan-0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1450 qdisc noqueue state UNKNOWN group default qlen 1000
2026-08-04 09:19:09.131594 | primary |     link/ether ca:4c:45:c8:90:de brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.131647 | primary |     inet 192.0.2.1/24 brd 192.0.2.255 scope global vxlan-0
2026-08-04 09:19:09.131699 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.131751 | primary |     inet 192.0.2.10/32 scope global proto 0x12 vxlan-0
2026-08-04 09:19:09.131803 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.131855 | primary |     inet6 fe80::c84c:45ff:fec8:90de/64 scope link proto kernel_ll
2026-08-04 09:19:09.131907 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.131959 | primary | 4: vxlan-1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1450 qdisc noqueue master br-0 state UNKNOWN group default qlen 1000
2026-08-04 09:19:09.132037 | primary |     link/ether 6e:a9:52:85:df:a1 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.132127 | primary |     inet6 fe80::6ca9:52ff:fe85:dfa1/64 scope link proto kernel_ll
2026-08-04 09:19:09.132202 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.132257 | primary | 5: br-0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1450 qdisc noqueue state UP group default qlen 1000
2026-08-04 09:19:09.132310 | primary |     link/ether d6:77:da:15:36:11 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.132369 | primary |     inet 198.51.100.1/24 brd 198.51.100.255 scope global br-0
2026-08-04 09:19:09.132424 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.132477 | primary |     inet6 fe80::d477:daff:fe15:3611/64 scope link proto kernel_ll
2026-08-04 09:19:09.132532 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.132584 | primary | 6: veth-br-0-ext@veth-br-0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue master ovs-system state UP group default qlen 1000
2026-08-04 09:19:09.132637 | primary |     link/ether e2:ac:41:97:8b:b4 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.132689 | primary |     inet6 fe80::e0ac:41ff:fe97:8bb4/64 scope link proto kernel_ll
2026-08-04 09:19:09.132742 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.132800 | primary | 7: veth-br-0@veth-br-0-ext: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc noqueue master br-0 state UP group default qlen 1000
2026-08-04 09:19:09.132852 | primary |     link/ether f2:48:98:68:09:b0 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.132904 | primary |     inet6 fe80::f048:98ff:fe68:9b0/64 scope link proto kernel_ll
2026-08-04 09:19:09.132956 | primary |        valid_lft forever preferred_lft forever
2026-08-04 09:19:09.133007 | primary | 8: ovs-system: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2026-08-04 09:19:09.133059 | primary |     link/ether 72:e5:e6:11:91:ac brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.133163 | primary | 9: br-ex: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2026-08-04 09:19:09.133223 | primary |     link/ether 6a:74:06:71:e4:42 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.133462 | primary | 10: br-int: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2026-08-04 09:19:09.133521 | primary |     link/ether 0a:67:1c:5a:f5:4a brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.133573 | primary | 11: br-tun: <BROADCAST,MULTICAST> mtu 1500 qdisc noop state DOWN group default qlen 1000
2026-08-04 09:19:09.133625 | primary |     link/ether aa:45:a9:95:75:42 brd ff:ff:ff:ff:ff:ff
2026-08-04 09:19:09.133677 | primary | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-08-04 09:19:09.133728 | primary | debug1: auto-mux: Trying existing master
2026-08-04 09:19:09.133779 | primary | debug1: mux_client_request_session: master session id: 2
2026-08-04 09:19:09.133831 | primary | Shared connection to 199.204.45.109 closed.
2026-08-04 09:19:09.133919 | primary | changed
2026-08-04 09:19:09.136471 | 
2026-08-04 09:19:09.136550 | PLAY RECAP
2026-08-04 09:19:09.136644 | primary | ok: 8 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-08-04 09:19:09.136700 | 
2026-08-04 09:19:09.305409 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2026-08-04 09:19:09.310991 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2026-08-04 09:19:10.515659 | 
2026-08-04 09:19:10.515857 | PLAY [all]
2026-08-04 09:19:10.537226 | 
2026-08-04 09:19:10.537418 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-08-04 09:19:11.314917 | primary | changed
2026-08-04 09:19:11.323667 | 
2026-08-04 09:19:11.323816 | PLAY RECAP
2026-08-04 09:19:11.323921 | primary | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-08-04 09:19:11.324017 | 
2026-08-04 09:19:11.466115 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2026-08-04 09:19:11.471482 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post-logs.yaml@master]
2026-08-04 09:19:12.667984 | 
2026-08-04 09:19:12.668210 | PLAY [localhost]
2026-08-04 09:19:12.688807 | 
2026-08-04 09:19:12.688993 | TASK [Include Zuul manifest role]
2026-08-04 09:19:12.722754 | localhost | ok
2026-08-04 09:19:12.745120 | localhost | included: generate-zuul-manifest
2026-08-04 09:19:12.752499 | 
2026-08-04 09:19:12.752675 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-08-04 09:19:13.406008 | localhost | changed
2026-08-04 09:19:13.422979 | 
2026-08-04 09:19:13.423205 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-08-04 09:19:13.465524 | localhost | ok
2026-08-04 09:19:13.477152 | 
2026-08-04 09:19:13.477287 | TASK [Generate bulk log download script]
2026-08-04 09:19:13.501059 | localhost | ok
2026-08-04 09:19:13.517081 | localhost | included: local-log-download
2026-08-04 09:19:13.527742 | 
2026-08-04 09:19:13.527900 | TASK [local-log-download : Check API endpoint is defined]
2026-08-04 09:19:13.580956 | localhost | ok: All assertions passed
2026-08-04 09:19:13.588009 | 
2026-08-04 09:19:13.588175 | TASK [local-log-download : Create download script]
2026-08-04 09:19:14.577524 | localhost -> localhost | changed
2026-08-04 09:19:14.591498 | 
2026-08-04 09:19:14.591615 | LOOP [Select random swift provider for logs upload]
2026-08-04 09:19:14.627953 | localhost | ok: "rax_dfw"
2026-08-04 09:19:14.647959 | 
2026-08-04 09:19:14.648236 | TASK [Upload swift logs to rax_dfw]
2026-08-04 09:19:14.670045 | localhost | Output suppressed because no_log was given
2026-08-04 09:19:14.704718 | localhost | included: upload-logs-swift
2026-08-04 09:19:14.719980 | 
2026-08-04 09:19:14.720085 | TASK [Set zuul-log-path fact]
2026-08-04 09:19:14.762070 | localhost | ok
2026-08-04 09:19:14.775413 | localhost | included: set-zuul-log-path-fact
2026-08-04 09:19:14.781636 | 
2026-08-04 09:19:14.781742 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-08-04 09:19:14.823732 | localhost | ok
2026-08-04 09:19:14.837390 | 
2026-08-04 09:19:14.837516 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-08-04 09:19:15.457829 | localhost -> localhost | ok: Runtime: 0:00:00.016595
2026-08-04 09:19:15.464354 | 
2026-08-04 09:19:15.464461 | TASK [upload-logs-swift : Upload logs to swift]