2026-06-10 10:47:49.921983 | Job console starting
2026-06-10 10:47:49.940209 | Updating git repos
2026-06-10 10:47:56.097690 | Cloning repos into workspace
2026-06-10 10:47:57.315926 | Restoring repo states
2026-06-10 10:47:57.429475 | Merging changes
2026-06-10 10:47:59.317778 | Checking out repos
2026-06-10 10:47:59.541310 | Preparing playbooks
2026-06-10 10:48:06.276629 | Running Ansible setup
2026-06-10 10:48:13.731924 | PRE-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2026-06-10 10:48:16.292894 | 
2026-06-10 10:48:16.293116 | PLAY [Create job header and inventory]
2026-06-10 10:48:16.311740 | 
2026-06-10 10:48:16.311927 | TASK [Gathering Facts]
2026-06-10 10:48:17.580662 | localhost | ok
2026-06-10 10:48:17.593724 | 
2026-06-10 10:48:17.593855 | TASK [Setup log path fact]
2026-06-10 10:48:17.615047 | localhost | ok
2026-06-10 10:48:17.628620 | localhost | included: set-zuul-log-path-fact
2026-06-10 10:48:17.635593 | 
2026-06-10 10:48:17.635738 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-10 10:48:17.676767 | localhost | ok
2026-06-10 10:48:17.688703 | 
2026-06-10 10:48:17.688851 | TASK [emit-job-header : Print job information]
2026-06-10 10:48:17.739965 | # Job Information
2026-06-10 10:48:17.740230 | Ansible Version: 2.18.17
2026-06-10 10:48:17.740305 | Job: metal3-baremetal-operator-functional
2026-06-10 10:48:17.740363 | Pipeline: check
2026-06-10 10:48:17.740418 | Executor: ze01.opendev.org
2026-06-10 10:48:17.740474 | Triggered by: https://review.opendev.org/c/openstack/ironic/+/992607
2026-06-10 10:48:17.740528 | Event ID: a62d2776f8b04f2f8090bac2bcf8354e
2026-06-10 10:48:17.745478 | 
2026-06-10 10:48:17.745619 | LOOP [emit-job-header : Print node information]
2026-06-10 10:48:17.909900 | localhost | ok:
2026-06-10 10:48:17.910158 | localhost | # Node Information
2026-06-10 10:48:17.910223 | localhost | Inventory Hostname: controller
2026-06-10 10:48:17.910280 | localhost | Hostname: np76ca3a0dc2114
2026-06-10 10:48:17.910334 | localhost | Username: zuul
2026-06-10 10:48:17.910395 | localhost | Distro: Ubuntu 24.04
2026-06-10 10:48:17.910450 | localhost | Provider: opendev.org%2Fopendev%2Fzuul-providers/vexxhost-ca-ymq-1-main
2026-06-10 10:48:17.910503 | localhost | Region: ca-ymq-1
2026-06-10 10:48:17.910582 | localhost | Label: ubuntu-noble-32GB
2026-06-10 10:48:17.910647 | localhost | Product Name: OpenStack Nova
2026-06-10 10:48:17.910702 | localhost | Interface IP: 199.204.45.78
2026-06-10 10:48:17.927534 | 
2026-06-10 10:48:17.927723 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-06-10 10:48:18.532921 | localhost -> localhost | changed
2026-06-10 10:48:18.541725 | 
2026-06-10 10:48:18.541929 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-06-10 10:48:19.804704 | localhost -> localhost | changed
2026-06-10 10:48:19.814590 | 
2026-06-10 10:48:19.814707 | PLAY [Setup Zuul environment]
2026-06-10 10:48:19.828498 | 
2026-06-10 10:48:19.828726 | TASK [Gather network facts]
2026-06-10 10:48:20.649978 | controller | ok
2026-06-10 10:48:20.660424 | 
2026-06-10 10:48:20.660590 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-06-10 10:48:21.000025 | controller -> localhost | ok
2026-06-10 10:48:21.010077 | 
2026-06-10 10:48:21.010191 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-06-10 10:48:21.043348 | controller | ok
2026-06-10 10:48:21.068036 | controller | included: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-06-10 10:48:21.075853 | 
2026-06-10 10:48:21.075974 | TASK [add-build-sshkey : Create Temp SSH key]
2026-06-10 10:48:24.352118 | controller -> localhost | Generating public/private rsa key pair.
2026-06-10 10:48:24.352417 | controller -> localhost | Your identification has been saved in /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/work/95ca70378023460595755ec737bc751f_id_rsa
2026-06-10 10:48:24.352490 | controller -> localhost | Your public key has been saved in /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/work/95ca70378023460595755ec737bc751f_id_rsa.pub
2026-06-10 10:48:24.352623 | controller -> localhost | The key fingerprint is:
2026-06-10 10:48:24.352694 | controller -> localhost | SHA256:8rN66gMaO++kPeizdx4UJdB2V+WfJlo7nyZZkrYcJ70 zuul-build-sshkey
2026-06-10 10:48:24.352753 | controller -> localhost | The key's randomart image is:
2026-06-10 10:48:24.352809 | controller -> localhost | +---[RSA 3072]----+
2026-06-10 10:48:24.352865 | controller -> localhost | |    .o. .  ....  |
2026-06-10 10:48:24.352921 | controller -> localhost | |      oo. .  .   |
2026-06-10 10:48:24.352976 | controller -> localhost | |     ... .    .  |
2026-06-10 10:48:24.353030 | controller -> localhost | |       .       ..|
2026-06-10 10:48:24.353087 | controller -> localhost | |      o S    ooo.|
2026-06-10 10:48:24.353148 | controller -> localhost | |  . .. o    o*++ |
2026-06-10 10:48:24.353203 | controller -> localhost | |   =... o  .ooO .|
2026-06-10 10:48:24.353256 | controller -> localhost | |  *+o o..o   =oE.|
2026-06-10 10:48:24.353310 | controller -> localhost | | .+B+===.     oo |
2026-06-10 10:48:24.353364 | controller -> localhost | +----[SHA256]-----+
2026-06-10 10:48:24.353465 | controller -> localhost | ok: Runtime: 0:00:02.658526
2026-06-10 10:48:24.361824 | 
2026-06-10 10:48:24.361946 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-06-10 10:48:24.395988 | controller | ok
2026-06-10 10:48:24.413217 | controller | included: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-06-10 10:48:24.424380 | 
2026-06-10 10:48:24.424491 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-06-10 10:48:24.450210 | controller | skipping: Conditional result was False
2026-06-10 10:48:24.461177 | 
2026-06-10 10:48:24.461288 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-06-10 10:48:25.364463 | controller | changed
2026-06-10 10:48:25.372835 | 
2026-06-10 10:48:25.372947 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-06-10 10:48:25.750382 | controller | ok
2026-06-10 10:48:25.758807 | 
2026-06-10 10:48:25.758917 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-06-10 10:48:27.412984 | controller | changed
2026-06-10 10:48:27.421384 | 
2026-06-10 10:48:27.421522 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-06-10 10:48:29.048125 | controller | changed
2026-06-10 10:48:29.056101 | 
2026-06-10 10:48:29.056235 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-06-10 10:48:29.083504 | controller | skipping: Conditional result was False
2026-06-10 10:48:29.094108 | 
2026-06-10 10:48:29.094244 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-06-10 10:48:29.656343 | controller -> localhost | changed
2026-06-10 10:48:29.672703 | 
2026-06-10 10:48:29.672812 | TASK [add-build-sshkey : Add back temp key]
2026-06-10 10:48:30.089865 | controller -> localhost | Identity added: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/work/95ca70378023460595755ec737bc751f_id_rsa (zuul-build-sshkey)
2026-06-10 10:48:30.090161 | controller -> localhost | ok: Runtime: 0:00:00.014682
2026-06-10 10:48:30.098029 | 
2026-06-10 10:48:30.098141 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-06-10 10:48:30.781174 | controller | ok
2026-06-10 10:48:30.788252 | 
2026-06-10 10:48:30.788361 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-06-10 10:48:30.823981 | controller | skipping: Conditional result was False
2026-06-10 10:48:30.840829 | 
2026-06-10 10:48:30.840981 | TASK [start-zuul-console : Start zuul_console daemon.]
2026-06-10 10:48:31.432074 | controller | ok
2026-06-10 10:48:31.446395 | 
2026-06-10 10:48:31.446540 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-06-10 10:48:31.808841 | controller | ok: "logs"
2026-06-10 10:48:31.809407 | controller | ok: All items complete
2026-06-10 10:48:31.809495 | 
2026-06-10 10:48:32.174798 | controller | ok: "artifacts"
2026-06-10 10:48:32.446834 | controller | ok: "docs"
2026-06-10 10:48:32.457269 | 
2026-06-10 10:48:32.457419 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-06-10 10:48:32.802190 | controller | changed: "logs"
2026-06-10 10:48:33.110331 | controller | changed: "artifacts"
2026-06-10 10:48:33.435469 | controller | changed: "docs"
2026-06-10 10:48:33.460073 | 
2026-06-10 10:48:33.460280 | PLAY [Configure unbound]
2026-06-10 10:48:33.472468 | 
2026-06-10 10:48:33.472632 | TASK [configure-unbound : Check that Unbound is installed]
2026-06-10 10:48:33.831194 | controller | ok
2026-06-10 10:48:33.840171 | 
2026-06-10 10:48:33.840283 | TASK [configure-unbound : Ensure that Unbound is installed]
2026-06-10 10:48:33.883623 | controller | ok: All assertions passed
2026-06-10 10:48:33.891228 | 
2026-06-10 10:48:33.891401 | TASK [configure-unbound : Check for IPv6]
2026-06-10 10:48:34.049373 | controller | skipping: Conditional result was False
2026-06-10 10:48:34.058909 | 
2026-06-10 10:48:34.059035 | TASK [configure-unbound : Set IPv6 nameservers]
2026-06-10 10:48:34.096110 | controller | skipping: Conditional result was False
2026-06-10 10:48:34.107463 | 
2026-06-10 10:48:34.107680 | TASK [configure-unbound : Set IPv4 nameservers]
2026-06-10 10:48:34.164601 | controller | ok
2026-06-10 10:48:34.173320 | 
2026-06-10 10:48:34.173453 | LOOP [configure-unbound : Include OS-specific variables]
2026-06-10 10:48:34.221060 | controller | ok: "/var/lib/zuul/builds/95ca70378023460595755ec737bc751f/trusted/project_0/opendev.org/opendev/base-jobs/roles/configure-unbound/vars/Debian.yaml"
2026-06-10 10:48:34.221481 | controller | changed: All items complete
2026-06-10 10:48:34.221585 | 
2026-06-10 10:48:34.245535 | 
2026-06-10 10:48:34.245789 | TASK [configure-unbound : Ensure Unbound conf.d directory exists]
2026-06-10 10:48:34.643688 | controller | ok
2026-06-10 10:48:34.652799 | 
2026-06-10 10:48:34.652919 | TASK [configure-unbound : Configure unbound forwarding]
2026-06-10 10:48:36.288744 | controller | changed
2026-06-10 10:48:36.298475 | 
2026-06-10 10:48:36.298616 | TASK [configure-unbound : Configure unbound TTL]
2026-06-10 10:48:37.894278 | controller | changed
2026-06-10 10:48:37.903374 | 
2026-06-10 10:48:37.903485 | TASK [configure-unbound : Start unbound]
2026-06-10 10:48:38.940159 | controller | ok
2026-06-10 10:48:38.968521 | 
2026-06-10 10:48:38.968672 | TASK [configure-unbound : Restart unbound]
2026-06-10 10:48:39.712979 | controller | changed
2026-06-10 10:48:39.723196 | 
2026-06-10 10:48:39.723288 | PLAY [Prepare workspace and configure mirrors]
2026-06-10 10:48:39.736968 | 
2026-06-10 10:48:39.737076 | TASK [validate-host : Define zuul_info_dir fact]
2026-06-10 10:48:39.779734 | controller | ok
2026-06-10 10:48:39.786988 | 
2026-06-10 10:48:39.787133 | TASK [validate-host : Ensure Zuul Ansible directory exists]
2026-06-10 10:48:40.137005 | controller -> localhost | ok
2026-06-10 10:48:40.148714 | 
2026-06-10 10:48:40.148883 | TASK [validate-host : Collect information about the host]
2026-06-10 10:48:41.166007 | controller | ok
2026-06-10 10:48:41.183329 | 
2026-06-10 10:48:41.183440 | TASK [validate-host : Sanitize hostname]
2026-06-10 10:48:41.266264 | controller | ok
2026-06-10 10:48:41.272820 | 
2026-06-10 10:48:41.272929 | TASK [validate-host : Write out all ansible variables/facts known for each host]
2026-06-10 10:48:41.946281 | controller -> localhost | changed
2026-06-10 10:48:41.955370 | 
2026-06-10 10:48:41.955486 | TASK [validate-host : Collect information about zuul worker]
2026-06-10 10:48:43.193705 | controller | ok
2026-06-10 10:48:43.201359 | 
2026-06-10 10:48:43.201569 | TASK [validate-host : Write out all zuul information for each host]
2026-06-10 10:48:43.896740 | controller -> localhost | changed
2026-06-10 10:48:43.914742 | 
2026-06-10 10:48:43.914896 | TASK [hardware-check : Raise an error if CPU count is too low]
2026-06-10 10:48:43.941765 | controller | skipping: Conditional result was False
2026-06-10 10:48:43.956990 | 
2026-06-10 10:48:43.957153 | LOOP [prepare-workspace-git : Filter zuul projects if sync-only-required-projects flag is set]
2026-06-10 10:48:43.992679 | controller | skipping: Conditional result was False
2026-06-10 10:48:43.995527 | controller | skipping: Conditional result was False
2026-06-10 10:48:44.000699 | controller | skipping: Conditional result was False
2026-06-10 10:48:44.013453 | 
2026-06-10 10:48:44.013672 | TASK [prepare-workspace-git : Don't filter zuul projects if flag is false]
2026-06-10 10:48:44.057484 | controller | ok
2026-06-10 10:48:44.065145 | 
2026-06-10 10:48:44.065279 | TASK [prepare-workspace-git : Set initial repo states in workspace]
2026-06-10 10:48:45.326129 | controller | changed
2026-06-10 10:48:45.334008 | 
2026-06-10 10:48:45.334116 | TASK [prepare-workspace-git : Synchronize src repos to workspace directory]
2026-06-10 10:48:51.197264 | controller -> localhost | changed
2026-06-10 10:48:51.211021 | 
2026-06-10 10:48:51.211179 | TASK [prepare-workspace-git : Update remote repository state]
2026-06-10 10:48:52.466069 | controller | changed
2026-06-10 10:48:52.482702 | 
2026-06-10 10:48:52.482896 | TASK [mirror-info : Create /etc/ci]
2026-06-10 10:48:52.976437 | controller | changed
2026-06-10 10:48:52.983524 | 
2026-06-10 10:48:52.983662 | TASK [mirror-info : Install ci_mirror script]
2026-06-10 10:48:54.635520 | controller | changed
2026-06-10 10:48:54.648282 | 
2026-06-10 10:48:54.648413 | TASK [configure-mirrors : Gather needed facts]
2026-06-10 10:48:54.686393 | controller | skipping: Conditional result was False
2026-06-10 10:48:54.695443 | 
2026-06-10 10:48:54.695597 | TASK [configure-mirrors : Set up infrastructure mirrors]
2026-06-10 10:48:54.789211 | controller | ok
2026-06-10 10:48:54.801045 | controller | included: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror.yaml
2026-06-10 10:48:54.808088 | 
2026-06-10 10:48:54.808208 | TASK [configure-mirrors : Remove .pydistutils.cfg configuration in homedir]
2026-06-10 10:48:55.156778 | controller | ok
2026-06-10 10:48:55.164666 | 
2026-06-10 10:48:55.164807 | LOOP [configure-mirrors : Include OS-specific variables]
2026-06-10 10:48:55.212110 | controller | ok: "/var/lib/zuul/builds/95ca70378023460595755ec737bc751f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/vars/Ubuntu.yaml"
2026-06-10 10:48:55.212445 | 
2026-06-10 10:48:55.232698 | 
2026-06-10 10:48:55.232853 | TASK [configure-mirrors : Install /etc/pip.conf configuration]
2026-06-10 10:48:56.900756 | controller | changed
2026-06-10 10:48:56.908589 | 
2026-06-10 10:48:56.908744 | LOOP [configure-mirrors : Setup distribution specific packaging mirrors]
2026-06-10 10:48:56.953604 | controller | ok: "/var/lib/zuul/builds/95ca70378023460595755ec737bc751f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Ubuntu.yaml"
2026-06-10 10:48:56.970058 | controller | included: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/configure-mirrors/tasks/mirror/Ubuntu.yaml
2026-06-10 10:48:56.979851 | 
2026-06-10 10:48:56.980062 | LOOP [configure-mirrors : Install Ubuntu repository files]
2026-06-10 10:48:58.676369 | controller | changed: "etc/apt/sources.list"
2026-06-10 10:48:58.676955 | controller | ok: All items complete
2026-06-10 10:48:58.677022 | 
2026-06-10 10:49:00.228330 | controller | changed: "etc/apt/apt.conf.d/99unauthenticated"
2026-06-10 10:49:00.249765 | 
2026-06-10 10:49:00.249927 | TASK [configure-mirrors : Update apt cache]
2026-06-10 10:49:00.811080 | controller | Ign:1 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble InRelease
2026-06-10 10:49:00.812002 | controller | Ign:2 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-updates InRelease
2026-06-10 10:49:00.812821 | controller | Ign:3 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-security InRelease
2026-06-10 10:49:00.817170 | controller | Get:4 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble Release [3235 B]
2026-06-10 10:49:00.819653 | controller | Get:5 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-updates Release [3263 B]
2026-06-10 10:49:00.820629 | controller | Get:6 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-security Release [3262 B]
2026-06-10 10:49:00.822891 | controller | Ign:7 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble Release.gpg
2026-06-10 10:49:00.823398 | controller | Ign:8 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-updates Release.gpg
2026-06-10 10:49:00.825922 | controller | Ign:9 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-security Release.gpg
2026-06-10 10:49:00.827748 | controller | Get:10 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble/main amd64 Packages [2203 kB]
2026-06-10 10:49:00.864196 | controller | Get:11 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble/universe amd64 Packages [24.5 MB]
2026-06-10 10:49:01.010907 | controller | Get:12 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-updates/universe amd64 Packages [2411 kB]
2026-06-10 10:49:01.028617 | controller | Get:13 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-updates/main amd64 Packages [2617 kB]
2026-06-10 10:49:01.044881 | controller | Get:14 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-security/universe amd64 Packages [1682 kB]
2026-06-10 10:49:01.055587 | controller | Get:15 https://mirror.ca-ymq-1.vexxhost.opendev.org/ubuntu noble-security/main amd64 Packages [2183 kB]
2026-06-10 10:49:02.198145 | controller | Fetched 35.6 MB in 1s (27.9 MB/s)
2026-06-10 10:49:03.059438 | controller | Reading package lists...
2026-06-10 10:49:03.461251 | controller | ok: Runtime: 0:00:02.458436
2026-06-10 10:49:03.463719 | 
2026-06-10 10:49:03.463796 | PLAY RECAP
2026-06-10 10:49:03.463896 | controller | ok: 44 changed: 20 unreachable: 0 failed: 0 skipped: 8 rescued: 0 ignored: 0
2026-06-10 10:49:03.463967 | localhost | ok: 7 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-10 10:49:03.464021 | 
2026-06-10 10:49:03.663324 | PRE-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/pre.yaml@master]
2026-06-10 10:49:03.666511 | PRE-RUN START: [untrusted : opendev.org/openstack/ironic/playbooks/bmo-ci/pre.yaml@master]
2026-06-10 10:49:05.136694 | 
2026-06-10 10:49:05.136952 | PLAY [BMO Functional Tests - Preparation]
2026-06-10 10:49:05.159268 | 
2026-06-10 10:49:05.159466 | TASK [Install packages]
2026-06-10 10:49:42.622427 | controller | changed
2026-06-10 10:49:42.641359 | 
2026-06-10 10:49:42.641512 | TASK [Activate docker]
2026-06-10 10:49:44.367187 | controller | ok
2026-06-10 10:49:44.381832 | 
2026-06-10 10:49:44.382013 | TASK [Activate libvirtd]
2026-06-10 10:49:45.104083 | controller | ok
2026-06-10 10:49:45.117338 | 
2026-06-10 10:49:45.117501 | TASK [Allow the current user to use docker and libvirt]
2026-06-10 10:49:45.913514 | controller | changed
2026-06-10 10:49:45.922113 | 
2026-06-10 10:49:45.922226 | TASK [Reset connection to apply group changes]
2026-06-10 10:49:45.965439 | 
2026-06-10 10:49:45.965696 | TASK [Create a venv for junit2html]
2026-06-10 10:49:51.525417 | controller | ok: Runtime: 0:00:03.146188
2026-06-10 10:49:51.533123 | 
2026-06-10 10:49:51.533237 | TASK [Install junit2html]
2026-06-10 10:49:53.311701 | controller | changed
2026-06-10 10:49:53.320024 | 
2026-06-10 10:49:53.320150 | TASK [Ensure log directory exists]
2026-06-10 10:49:54.046226 | controller | changed
2026-06-10 10:49:54.056025 | 
2026-06-10 10:49:54.056144 | TASK [Download and install yq]
2026-06-10 10:49:55.182097 | controller | changed: OK (13734050 bytes)
2026-06-10 10:49:55.191720 | 
2026-06-10 10:49:55.191844 | TASK [Get sushy-tools IP address]
2026-06-10 10:49:55.618231 | controller | Error: open /home/zuul/src/github.com/metal3-io/baremetal-operator/test/e2e/config/vbmctl.yaml: no such file or directory
2026-06-10 10:49:55.907851 | controller | ERROR
2026-06-10 10:49:55.908147 | controller | {
2026-06-10 10:49:55.908214 | controller |   "delta": "0:00:00.015600",
2026-06-10 10:49:55.908271 | controller |   "end": "2026-06-10 10:49:55.620556",
2026-06-10 10:49:55.908324 | controller |   "msg": "non-zero return code",
2026-06-10 10:49:55.908375 | controller |   "rc": 1,
2026-06-10 10:49:55.908426 | controller |   "start": "2026-06-10 10:49:55.604956"
2026-06-10 10:49:55.908476 | controller | }
failure
2026-06-10 10:49:55.910826 | 
2026-06-10 10:49:55.910899 | PLAY RECAP
2026-06-10 10:49:55.910993 | controller | ok: 8 changed: 6 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-06-10 10:49:55.911048 | 
2026-06-10 10:49:56.094967 | PRE-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/ironic/playbooks/bmo-ci/pre.yaml@master]
2026-06-10 10:49:56.101046 | POST-RUN START: [untrusted : opendev.org/openstack/ironic/playbooks/bmo-ci/post.yaml@master]
2026-06-10 10:49:57.434667 | 
2026-06-10 10:49:57.434845 | PLAY [BMO Functional Tests - Log Collection]
2026-06-10 10:49:57.456288 | 
2026-06-10 10:49:57.456470 | TASK [Ensure log directory exists]
2026-06-10 10:49:59.824994 | controller | ok
2026-06-10 10:49:59.837801 | 
2026-06-10 10:49:59.837952 | TASK [Copy BMO artifacts to log directory]
2026-06-10 10:50:00.742501 | controller -> 199.204.45.78 | ERROR
2026-06-10 10:50:00.743062 | controller -> 199.204.45.78 | {
2026-06-10 10:50:00.743143 | controller -> 199.204.45.78 |   "msg": "rsync: [sender] change_dir \"/home/zuul/src/github.com/metal3-io/baremetal-operator/test/e2e/_artifacts\" failed: No such file or directory (2)\nrsync error: some files/attrs were not transferred (see previous errors) (code 23) at main.c(1347) [sender=3.2.7]\n",
2026-06-10 10:50:00.743209 | controller -> 199.204.45.78 |   "rc": 23
2026-06-10 10:50:00.743267 | controller -> 199.204.45.78 | }
2026-06-10 10:50:00.743339 | controller -> 199.204.45.78 | ERROR: Ignoring Errors
2026-06-10 10:50:00.753038 | 
2026-06-10 10:50:00.753198 | TASK [Check if JUnit report exists]
2026-06-10 10:50:01.413324 | controller | ok
2026-06-10 10:50:01.422483 | 
2026-06-10 10:50:01.422638 | TASK [Convert the JUnit report to HTML]
2026-06-10 10:50:01.490659 | controller | skipping: Conditional result was False
2026-06-10 10:50:01.504082 | 
2026-06-10 10:50:01.504355 | TASK [Create log location]
2026-06-10 10:50:01.867043 | controller | changed
2026-06-10 10:50:01.876872 | 
2026-06-10 10:50:01.877025 | LOOP [Fetch system information]
2026-06-10 10:50:02.565466 | controller | ok: Item: dmesg Runtime: 0:00:00.019996
2026-06-10 10:50:02.945289 | controller | ok: Item: dpkg -l Runtime: 0:00:00.025419
2026-06-10 10:50:03.301599 | controller | ok: Item: ip addr Runtime: 0:00:00.010267
2026-06-10 10:50:03.663406 | controller | ok: Item: ip route Runtime: 0:00:00.009371
2026-06-10 10:50:04.051326 | controller | ok: Item: iptables -L -v -n Runtime: 0:00:00.012478
2026-06-10 10:50:04.429046 | controller | WARNING: This output is designed for human readability. For machine-readable output, please use --format.
2026-06-10 10:50:04.429192 | controller | ok: Item: docker images Runtime: 0:00:00.038488
2026-06-10 10:50:04.829439 | controller | ok: Item: docker ps --all Runtime: 0:00:00.038532
2026-06-10 10:50:05.197276 | controller | ok: Item: systemctl Runtime: 0:00:00.019515
2026-06-10 10:50:05.547146 | controller | ok: Item: df -h Runtime: 0:00:00.011488
2026-06-10 10:50:05.894141 | controller | ok: Item: df -i Runtime: 0:00:00.008784
2026-06-10 10:50:06.244351 | controller | ok: Item: lsblk -ap Runtime: 0:00:00.010834
2026-06-10 10:50:06.269122 | 
2026-06-10 10:50:06.269308 | TASK [Fetch redfish-emulator logs]
2026-06-10 10:50:07.044506 | controller | ERROR
2026-06-10 10:50:07.045098 | controller | {
2026-06-10 10:50:07.047009 | controller |   "delta": "0:00:00.038956",
2026-06-10 10:50:07.047082 | controller |   "end": "2026-06-10 10:50:06.760488",
2026-06-10 10:50:07.047146 | controller |   "msg": "non-zero return code",
2026-06-10 10:50:07.047207 | controller |   "rc": 1,
2026-06-10 10:50:07.047264 | controller |   "start": "2026-06-10 10:50:06.721532"
2026-06-10 10:50:07.047337 | controller | }
2026-06-10 10:50:07.047413 | controller | ERROR: Ignoring Errors
2026-06-10 10:50:07.058759 | 
2026-06-10 10:50:07.058980 | TASK [Copy logs to the zuul location]
2026-06-10 10:50:08.628739 | controller | changed:
2026-06-10 10:50:08.629008 | controller | created directory /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/work/logs/controller
2026-06-10 10:50:08.629076 | controller | cd+++++++++ ./
2026-06-10 10:50:08.629135 | controller | >f+++++++++ redfish-emulator.log
2026-06-10 10:50:08.629205 | controller | cd+++++++++ system/
2026-06-10 10:50:08.629300 | controller | >f+++++++++ system/df--h.txt
2026-06-10 10:50:08.629360 | controller | >f+++++++++ system/df--i.txt
2026-06-10 10:50:08.629416 | controller | >f+++++++++ system/dmesg.txt
2026-06-10 10:50:08.629472 | controller | >f+++++++++ system/docker-images.txt
2026-06-10 10:50:08.629528 | controller | >f+++++++++ system/docker-ps---all.txt
2026-06-10 10:50:08.629686 | controller | >f+++++++++ system/dpkg--l.txt
2026-06-10 10:50:08.629750 | controller | >f+++++++++ system/ip-addr.txt
2026-06-10 10:50:08.629806 | controller | >f+++++++++ system/ip-route.txt
2026-06-10 10:50:08.629861 | controller | >f+++++++++ system/iptables--L--v--n.txt
2026-06-10 10:50:08.629992 | controller | >f+++++++++ system/lsblk--ap.txt
2026-06-10 10:50:08.630122 | controller | >f+++++++++ system/systemctl.txt
2026-06-10 10:50:08.650937 | 
2026-06-10 10:50:08.651095 | TASK [Store JUnit report as a Zuul artifact]
2026-06-10 10:50:08.677637 | controller | skipping: Conditional result was False
2026-06-10 10:50:08.680035 | 
2026-06-10 10:50:08.680114 | PLAY RECAP
2026-06-10 10:50:08.680211 | controller | ok: 7 changed: 4 unreachable: 0 failed: 0 skipped: 2 rescued: 0 ignored: 2
2026-06-10 10:50:08.680268 | 
2026-06-10 10:50:08.841670 | POST-RUN END RESULT_NORMAL: [untrusted : opendev.org/openstack/ironic/playbooks/bmo-ci/post.yaml@master]
2026-06-10 10:50:08.845238 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2026-06-10 10:50:10.205813 | 
2026-06-10 10:50:10.206019 | PLAY [all]
2026-06-10 10:50:10.229870 | 
2026-06-10 10:50:10.230010 | TASK [fetch-output : Set log path for multiple nodes]
2026-06-10 10:50:10.308699 | controller | skipping: Conditional result was False
2026-06-10 10:50:10.319491 | 
2026-06-10 10:50:10.319726 | TASK [fetch-output : Set log path for single node]
2026-06-10 10:50:10.383166 | controller | ok
2026-06-10 10:50:10.391544 | 
2026-06-10 10:50:10.391720 | LOOP [fetch-output : Ensure local output dirs]
2026-06-10 10:50:11.008130 | controller -> localhost | ok: "/var/lib/zuul/builds/95ca70378023460595755ec737bc751f/work/logs"
2026-06-10 10:50:11.349427 | controller -> localhost | changed: "/var/lib/zuul/builds/95ca70378023460595755ec737bc751f/work/artifacts"
2026-06-10 10:50:11.696460 | controller -> localhost | changed: "/var/lib/zuul/builds/95ca70378023460595755ec737bc751f/work/docs"
2026-06-10 10:50:11.718031 | 
2026-06-10 10:50:11.718210 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-06-10 10:50:13.391830 | controller | changed: .d..t...... ./
2026-06-10 10:50:13.392769 | controller | changed: All items complete
2026-06-10 10:50:13.392853 | 
2026-06-10 10:50:14.792107 | controller | changed: .d..t...... ./
2026-06-10 10:50:16.195087 | controller | changed: .d..t...... ./
2026-06-10 10:50:16.222056 | 
2026-06-10 10:50:16.222186 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-06-10 10:50:16.891276 | controller -> localhost | ok: Item: artifacts Runtime: 0:00:00.007273
2026-06-10 10:50:17.235671 | controller -> localhost | ok: Item: docs Runtime: 0:00:00.007232
2026-06-10 10:50:17.254400 | 
2026-06-10 10:50:17.254509 | TASK [Get df disk usage]
2026-06-10 10:50:19.089186 | controller | Filesystem     1K-blocks     Used Available Use% Mounted on
2026-06-10 10:50:19.089587 | controller | tmpfs            3286612      764   3285848   1% /run
2026-06-10 10:50:19.089664 | controller | /dev/vda1      156694268 15170648 134552244  11% /
2026-06-10 10:50:19.089742 | controller | tmpfs           16433056        0  16433056   0% /dev/shm
2026-06-10 10:50:19.089800 | controller | tmpfs               5120        0      5120   0% /run/lock
2026-06-10 10:50:19.089855 | controller | /dev/sr0             502      502         0 100% /mnt/config
2026-06-10 10:50:19.089909 | controller | tmpfs            3286608        8   3286600   1% /run/user/1000
2026-06-10 10:50:19.089974 | controller | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-06-10 10:50:19.090077 | controller | debug1: auto-mux: Trying existing master
2026-06-10 10:50:19.090156 | controller | debug1: Stale control socket /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/.ansible/cp/a75026fc79, unlinking
2026-06-10 10:50:19.090214 | controller | debug1: Connecting to 199.204.45.78 [199.204.45.78] port 22.
2026-06-10 10:50:19.090267 | controller | debug1: fd 3 clearing O_NONBLOCK
2026-06-10 10:50:19.090321 | controller | debug1: Connection established.
2026-06-10 10:50:19.090374 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_rsa type -1
2026-06-10 10:50:19.090426 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_rsa-cert type -1
2026-06-10 10:50:19.090480 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ecdsa type -1
2026-06-10 10:50:19.090533 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ecdsa-cert type -1
2026-06-10 10:50:19.090622 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ecdsa_sk type -1
2026-06-10 10:50:19.090680 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ecdsa_sk-cert type -1
2026-06-10 10:50:19.090733 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ed25519 type -1
2026-06-10 10:50:19.090785 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ed25519-cert type -1
2026-06-10 10:50:19.090837 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ed25519_sk type -1
2026-06-10 10:50:19.090915 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ed25519_sk-cert type -1
2026-06-10 10:50:19.090973 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_xmss type -1
2026-06-10 10:50:19.091131 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_xmss-cert type -1
2026-06-10 10:50:19.091191 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_dsa type -1
2026-06-10 10:50:19.091245 | controller | debug1: identity file /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_dsa-cert type -1
2026-06-10 10:50:19.091299 | controller | debug1: Local version string SSH-2.0-OpenSSH_9.2p1 Debian-2+deb12u10
2026-06-10 10:50:19.091352 | controller | debug1: Remote protocol version 2.0, remote software version OpenSSH_9.6p1 Ubuntu-3ubuntu13.16
2026-06-10 10:50:19.091405 | controller | debug1: compat_banner: match: OpenSSH_9.6p1 Ubuntu-3ubuntu13.16 pat OpenSSH* compat 0x04000000
2026-06-10 10:50:19.091458 | controller | debug1: Authenticating to 199.204.45.78:22 as 'zuul'
2026-06-10 10:50:19.091517 | controller | debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts: No such file or directory
2026-06-10 10:50:19.091706 | controller | debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts2: No such file or directory
2026-06-10 10:50:19.091769 | controller | debug1: SSH2_MSG_KEXINIT sent
2026-06-10 10:50:19.091824 | controller | debug1: SSH2_MSG_KEXINIT received
2026-06-10 10:50:19.091877 | controller | debug1: kex: algorithm: curve25519-sha256@libssh.org
2026-06-10 10:50:19.091930 | controller | debug1: kex: host key algorithm: ssh-ed25519
2026-06-10 10:50:19.091999 | controller | debug1: kex: server->client cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none
2026-06-10 10:50:19.092064 | controller | debug1: kex: client->server cipher: chacha20-poly1305@openssh.com MAC: <implicit> compression: none
2026-06-10 10:50:19.092166 | controller | debug1: expecting SSH2_MSG_KEX_ECDH_REPLY
2026-06-10 10:50:19.092253 | controller | debug1: SSH2_MSG_KEX_ECDH_REPLY received
2026-06-10 10:50:19.092311 | controller | debug1: Server host key: ssh-ed25519 SHA256:DGD287lpV6XNpi9yyUqw59cCiw8AW7Dt2RNWlZyChnA
2026-06-10 10:50:19.092365 | controller | debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts: No such file or directory
2026-06-10 10:50:19.092417 | controller | debug1: load_hostkeys: fopen /etc/ssh/ssh_known_hosts2: No such file or directory
2026-06-10 10:50:19.092470 | controller | debug1: Host '199.204.45.78' is known and matches the ED25519 host key.
2026-06-10 10:50:19.092522 | controller | debug1: Found key in /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/known_hosts:1
2026-06-10 10:50:19.092639 | controller | debug1: ssh_packet_send2_wrapped: resetting send seqnr 3
2026-06-10 10:50:19.092698 | controller | debug1: rekey out after 134217728 blocks
2026-06-10 10:50:19.092752 | controller | debug1: SSH2_MSG_NEWKEYS sent
2026-06-10 10:50:19.092805 | controller | debug1: expecting SSH2_MSG_NEWKEYS
2026-06-10 10:50:19.092857 | controller | debug1: ssh_packet_read_poll2: resetting read seqnr 3
2026-06-10 10:50:19.092910 | controller | debug1: SSH2_MSG_NEWKEYS received
2026-06-10 10:50:19.092962 | controller | debug1: rekey in after 134217728 blocks
2026-06-10 10:50:19.093015 | controller | debug1: get_agent_identities: bound agent to hostkey
2026-06-10 10:50:19.093067 | controller | debug1: get_agent_identities: agent returned 2 keys
2026-06-10 10:50:19.093120 | controller | debug1: Will attempt key: (stdin) RSA SHA256:GgAnzAPyRp9MWMiJww6jHtWVv6z0hKGP5Zhd9FJH1+s agent
2026-06-10 10:50:19.093174 | controller | debug1: Will attempt key: zuul-build-sshkey RSA SHA256:8rN66gMaO++kPeizdx4UJdB2V+WfJlo7nyZZkrYcJ70 agent
2026-06-10 10:50:19.093227 | controller | debug1: Will attempt key: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_rsa
2026-06-10 10:50:19.093280 | controller | debug1: Will attempt key: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ecdsa
2026-06-10 10:50:19.093333 | controller | debug1: Will attempt key: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ecdsa_sk
2026-06-10 10:50:19.093386 | controller | debug1: Will attempt key: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ed25519
2026-06-10 10:50:19.093438 | controller | debug1: Will attempt key: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_ed25519_sk
2026-06-10 10:50:19.093491 | controller | debug1: Will attempt key: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_xmss
2026-06-10 10:50:19.093544 | controller | debug1: Will attempt key: /var/lib/zuul/builds/95ca70378023460595755ec737bc751f/home/.ssh/id_dsa
2026-06-10 10:50:19.093634 | controller | debug1: SSH2_MSG_EXT_INFO received
2026-06-10 10:50:19.093699 | controller | debug1: kex_input_ext_info: server-sig-algs=<ssh-ed25519,ecdsa-sha2-nistp256,ecdsa-sha2-nistp384,ecdsa-sha2-nistp521,sk-ssh-ed25519@openssh.com,sk-ecdsa-sha2-nistp256@openssh.com,rsa-sha2-512,rsa-sha2-256>
2026-06-10 10:50:19.093757 | controller | debug1: kex_input_ext_info: publickey-hostbound@openssh.com=<0>
2026-06-10 10:50:19.093811 | controller | debug1: kex_input_ext_info: ping@openssh.com (unrecognised)
2026-06-10 10:50:19.093906 | controller | debug1: SSH2_MSG_SERVICE_ACCEPT received
2026-06-10 10:50:19.093996 | controller | debug1: Authentications that can continue: publickey
2026-06-10 10:50:19.094055 | controller | debug1: Next authentication method: publickey
2026-06-10 10:50:19.094125 | controller | debug1: Offering public key: (stdin) RSA SHA256:GgAnzAPyRp9MWMiJww6jHtWVv6z0hKGP5Zhd9FJH1+s agent
2026-06-10 10:50:19.094198 | controller | debug1: Authentications that can continue: publickey
2026-06-10 10:50:19.094255 | controller | debug1: Offering public key: zuul-build-sshkey RSA SHA256:8rN66gMaO++kPeizdx4UJdB2V+WfJlo7nyZZkrYcJ70 agent
2026-06-10 10:50:19.094308 | controller | debug1: Server accepts key: zuul-build-sshkey RSA SHA256:8rN66gMaO++kPeizdx4UJdB2V+WfJlo7nyZZkrYcJ70 agent
2026-06-10 10:50:19.094362 | controller | Authenticated to 199.204.45.78 ([199.204.45.78]:22) using "publickey".
2026-06-10 10:50:19.094415 | controller | debug1: setting up multiplex master socket
2026-06-10 10:50:19.094467 | controller | debug1: channel 0: new mux listener [/var/lib/zuul/builds/95ca70378023460595755ec737bc751f/.ansible/cp/a75026fc79] (inactive timeout: 0)
2026-06-10 10:50:19.094521 | controller | debug1: control_persist_detach: backgrounding master process
2026-06-10 10:50:19.094609 | controller | debug1: forking to background
2026-06-10 10:50:19.094671 | controller | debug1: Entering interactive session.
2026-06-10 10:50:19.094728 | controller | debug1: pledge: id
2026-06-10 10:50:19.094782 | controller | debug1: multiplexing control connection
2026-06-10 10:50:19.094836 | controller | debug1: channel 1: new mux-control [mux-control] (inactive timeout: 0)
2026-06-10 10:50:19.094889 | controller | debug1: channel 2: new session [client-session] (inactive timeout: 0)
2026-06-10 10:50:19.094943 | controller | debug1: client_input_global_request: rtype hostkeys-00@openssh.com want_reply 0
2026-06-10 10:50:19.094997 | controller | debug1: Remote: /home/zuul/.ssh/authorized_keys:2: key options: agent-forwarding port-forwarding pty user-rc x11-forwarding
2026-06-10 10:50:19.095051 | controller | debug1: Remote: /home/zuul/.ssh/authorized_keys:2: key options: agent-forwarding port-forwarding pty user-rc x11-forwarding
2026-06-10 10:50:19.095104 | controller | debug1: Sending command: timeout -k 5 90 df
2026-06-10 10:50:19.095189 | controller | debug1: mux_client_request_session: master session id: 2
2026-06-10 10:50:19.095286 | controller | debug1: client_input_channel_req: channel 2 rtype exit-status reply 0
2026-06-10 10:50:19.095347 | controller | debug1: client_input_channel_req: channel 2 rtype eow@openssh.com reply 0
2026-06-10 10:50:19.095402 | controller | debug1: channel 2: free: client-session, nchannels 3
2026-06-10 10:50:19.095456 | controller | debug1: channel 1: free: mux-control, nchannels 2
2026-06-10 10:50:19.095509 | controller | Shared connection to 199.204.45.78 closed.
2026-06-10 10:50:19.095663 | controller | changed
2026-06-10 10:50:19.105875 | 
2026-06-10 10:50:19.106038 | TASK [Get df inode usage]
2026-06-10 10:50:19.309578 | controller | Filesystem       Inodes  IUsed    IFree IUse% Mounted on
2026-06-10 10:50:19.309863 | controller | tmpfs           4108264    704  4107560    1% /run
2026-06-10 10:50:19.309931 | controller | /dev/vda1      41922560 718247 41204313    2% /
2026-06-10 10:50:19.310002 | controller | tmpfs           4108264      1  4108263    1% /dev/shm
2026-06-10 10:50:19.310109 | controller | tmpfs           4108264      3  4108261    1% /run/lock
2026-06-10 10:50:19.310172 | controller | /dev/sr0              0      0        0     - /mnt/config
2026-06-10 10:50:19.310227 | controller | tmpfs            821652     29   821623    1% /run/user/1000
2026-06-10 10:50:19.310280 | controller | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-06-10 10:50:19.310332 | controller | debug1: auto-mux: Trying existing master
2026-06-10 10:50:19.310385 | controller | debug1: mux_client_request_session: master session id: 2
2026-06-10 10:50:19.310438 | controller | Shared connection to 199.204.45.78 closed.
2026-06-10 10:50:19.310526 | controller | changed
2026-06-10 10:50:19.319813 | 
2026-06-10 10:50:19.319929 | TASK [Get l2 networking]
2026-06-10 10:50:19.522800 | controller | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000
2026-06-10 10:50:19.523117 | controller |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2026-06-10 10:50:19.523210 | controller | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000
2026-06-10 10:50:19.523272 | controller |     link/ether fa:16:3e:13:b5:21 brd ff:ff:ff:ff:ff:ff
2026-06-10 10:50:19.523328 | controller |     altname enp0s3
2026-06-10 10:50:19.523382 | controller | 3: docker0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue state DOWN mode DEFAULT group default
2026-06-10 10:50:19.523435 | controller |     link/ether 0a:ac:89:29:a2:84 brd ff:ff:ff:ff:ff:ff
2026-06-10 10:50:19.523487 | controller | 4: virbr0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue state DOWN mode DEFAULT group default qlen 1000
2026-06-10 10:50:19.523539 | controller |     link/ether 52:54:00:2f:77:45 brd ff:ff:ff:ff:ff:ff
2026-06-10 10:50:19.523628 | controller | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-06-10 10:50:19.523754 | controller | debug1: auto-mux: Trying existing master
2026-06-10 10:50:19.523815 | controller | debug1: mux_client_request_session: master session id: 2
2026-06-10 10:50:19.523868 | controller | Shared connection to 199.204.45.78 closed.
2026-06-10 10:50:19.523954 | controller | changed
2026-06-10 10:50:19.532438 | 
2026-06-10 10:50:19.532548 | TASK [Get l3 networking]
2026-06-10 10:50:19.735780 | controller | 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1000
2026-06-10 10:50:19.736034 | controller |     link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
2026-06-10 10:50:19.736099 | controller |     inet 127.0.0.1/8 scope host lo
2026-06-10 10:50:19.736181 | controller |        valid_lft forever preferred_lft forever
2026-06-10 10:50:19.736240 | controller |     inet6 ::1/128 scope host noprefixroute
2026-06-10 10:50:19.736294 | controller |        valid_lft forever preferred_lft forever
2026-06-10 10:50:19.736346 | controller | 2: ens3: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
2026-06-10 10:50:19.736400 | controller |     link/ether fa:16:3e:13:b5:21 brd ff:ff:ff:ff:ff:ff
2026-06-10 10:50:19.736451 | controller |     altname enp0s3
2026-06-10 10:50:19.736503 | controller |     inet 199.204.45.78/24 brd 199.204.45.255 scope global dynamic ens3
2026-06-10 10:50:19.736579 | controller |        valid_lft 86244sec preferred_lft 86244sec
2026-06-10 10:50:19.736643 | controller |     inet6 2604:e100:1:0:f816:3eff:fe13:b521/64 scope global dynamic mngtmpaddr
2026-06-10 10:50:19.736698 | controller |        valid_lft 2591846sec preferred_lft 604646sec
2026-06-10 10:50:19.736750 | controller |     inet6 fe80::f816:3eff:fe13:b521/64 scope link
2026-06-10 10:50:19.736802 | controller |        valid_lft forever preferred_lft forever
2026-06-10 10:50:19.736853 | controller | 3: docker0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue state DOWN group default
2026-06-10 10:50:19.736906 | controller |     link/ether 0a:ac:89:29:a2:84 brd ff:ff:ff:ff:ff:ff
2026-06-10 10:50:19.736957 | controller |     inet 172.17.0.1/16 brd 172.17.255.255 scope global docker0
2026-06-10 10:50:19.737009 | controller |        valid_lft forever preferred_lft forever
2026-06-10 10:50:19.737061 | controller | 4: virbr0: <NO-CARRIER,BROADCAST,MULTICAST,UP> mtu 1500 qdisc noqueue state DOWN group default qlen 1000
2026-06-10 10:50:19.737127 | controller |     link/ether 52:54:00:2f:77:45 brd ff:ff:ff:ff:ff:ff
2026-06-10 10:50:19.737196 | controller |     inet 192.168.122.1/24 brd 192.168.122.255 scope global virbr0
2026-06-10 10:50:19.737251 | controller |        valid_lft forever preferred_lft forever
2026-06-10 10:50:19.737303 | controller | OpenSSH_9.2p1 Debian-2+deb12u10, OpenSSL 3.0.20 7 Apr 2026
2026-06-10 10:50:19.737355 | controller | debug1: auto-mux: Trying existing master
2026-06-10 10:50:19.737407 | controller | debug1: mux_client_request_session: master session id: 2
2026-06-10 10:50:19.737458 | controller | Shared connection to 199.204.45.78 closed.
2026-06-10 10:50:19.737542 | controller | changed
2026-06-10 10:50:19.739500 | 
2026-06-10 10:50:19.739615 | PLAY RECAP
2026-06-10 10:50:19.739706 | controller | ok: 8 changed: 7 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-06-10 10:50:19.739761 | 
2026-06-10 10:50:19.881907 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post.yaml@master]
2026-06-10 10:50:19.884309 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2026-06-10 10:50:21.230108 | 
2026-06-10 10:50:21.230301 | PLAY [all]
2026-06-10 10:50:21.252464 | 
2026-06-10 10:50:21.252637 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-06-10 10:50:23.694345 | controller | changed
2026-06-10 10:50:23.705515 | 
2026-06-10 10:50:23.705663 | PLAY RECAP
2026-06-10 10:50:23.705782 | controller | ok: 1 changed: 1 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-06-10 10:50:23.705912 | 
2026-06-10 10:50:23.875734 | POST-RUN END RESULT_NORMAL: [trusted : opendev.org/opendev/base-jobs/playbooks/base/cleanup.yaml@master]
2026-06-10 10:50:23.879155 | POST-RUN START: [trusted : opendev.org/opendev/base-jobs/playbooks/base/post-logs.yaml@master]
2026-06-10 10:50:25.274616 | 
2026-06-10 10:50:25.274899 | PLAY [localhost]
2026-06-10 10:50:25.297760 | 
2026-06-10 10:50:25.297978 | TASK [Include Zuul manifest role]
2026-06-10 10:50:25.332229 | localhost | ok
2026-06-10 10:50:25.353171 | localhost | included: generate-zuul-manifest
2026-06-10 10:50:25.361140 | 
2026-06-10 10:50:25.361274 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-06-10 10:50:25.942067 | localhost | changed
2026-06-10 10:50:25.959462 | 
2026-06-10 10:50:25.959623 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-06-10 10:50:26.002676 | localhost | ok
2026-06-10 10:50:26.014613 | 
2026-06-10 10:50:26.014724 | TASK [Generate bulk log download script]
2026-06-10 10:50:26.036719 | localhost | ok
2026-06-10 10:50:26.051959 | localhost | included: local-log-download
2026-06-10 10:50:26.062427 | 
2026-06-10 10:50:26.062547 | TASK [local-log-download : Check API endpoint is defined]
2026-06-10 10:50:26.115188 | localhost | ok: All assertions passed
2026-06-10 10:50:26.122672 | 
2026-06-10 10:50:26.122813 | TASK [local-log-download : Create download script]
2026-06-10 10:50:27.162454 | localhost -> localhost | changed
2026-06-10 10:50:27.177967 | 
2026-06-10 10:50:27.178113 | LOOP [Select random swift provider for logs upload]
2026-06-10 10:50:27.221011 | localhost | ok: "rax_ord"
2026-06-10 10:50:27.237420 | 
2026-06-10 10:50:27.237683 | TASK [Upload swift logs to rax_ord]
2026-06-10 10:50:27.271757 | localhost | Output suppressed because no_log was given
2026-06-10 10:50:27.305841 | localhost | included: upload-logs-swift
2026-06-10 10:50:27.321368 | 
2026-06-10 10:50:27.321469 | TASK [Set zuul-log-path fact]
2026-06-10 10:50:27.364159 | localhost | ok
2026-06-10 10:50:27.376599 | localhost | included: set-zuul-log-path-fact
2026-06-10 10:50:27.383237 | 
2026-06-10 10:50:27.383345 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-06-10 10:50:27.426148 | localhost | ok
2026-06-10 10:50:27.440652 | 
2026-06-10 10:50:27.440769 | TASK [upload-logs-swift : Ensure logs are readable before uploading]
2026-06-10 10:50:28.084345 | localhost -> localhost | ok: Runtime: 0:00:00.005751
2026-06-10 10:50:28.092263 | 
2026-06-10 10:50:28.092414 | TASK [upload-logs-swift : Upload logs to swift]