2026-04-14 02:56:55.995577 | Job console starting
2026-04-14 02:56:56.004927 | Updating git repos
2026-04-14 02:56:56.031266 | Cloning repos into workspace
2026-04-14 02:56:56.079339 | Restoring repo states
2026-04-14 02:56:56.086715 | Merging changes
2026-04-14 02:56:56.341006 | Checking out repos
2026-04-14 02:56:56.375536 | Preparing playbooks
2026-04-14 02:56:57.484597 | Running Ansible setup
2026-04-14 02:57:01.090573 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 02:57:01.711061 | 
2026-04-14 02:57:01.711229 | PLAY [localhost]
2026-04-14 02:57:01.719550 | 
2026-04-14 02:57:01.719667 | TASK [Gathering Facts]
2026-04-14 02:57:02.660520 | localhost | ok
2026-04-14 02:57:02.669003 | 
2026-04-14 02:57:02.669097 | TASK [Setup log path fact]
2026-04-14 02:57:02.689607 | localhost | ok
2026-04-14 02:57:02.704075 | 
2026-04-14 02:57:02.704203 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 02:57:02.732694 | localhost | ok
2026-04-14 02:57:02.741861 | 
2026-04-14 02:57:02.741971 | TASK [emit-job-header : Print job information]
2026-04-14 02:57:02.780956 | # Job Information
2026-04-14 02:57:02.781182 | Ansible Version: 2.16.16
2026-04-14 02:57:02.781229 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-14 02:57:02.781263 | Pipeline: check
2026-04-14 02:57:02.781293 | Executor: 0a8996d2b663
2026-04-14 02:57:02.781322 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/113
2026-04-14 02:57:02.781353 | Event ID: 81b63d30-37ad-11f1-8198-64bbaeb12eb2
2026-04-14 02:57:02.785533 | 
2026-04-14 02:57:02.785616 | LOOP [emit-job-header : Print node information]
2026-04-14 02:57:02.885163 | localhost | ok:
2026-04-14 02:57:02.885368 | localhost | # Node Information
2026-04-14 02:57:02.885409 | localhost | Inventory Hostname: rockylinux-9
2026-04-14 02:57:02.885444 | localhost | Hostname: np0000166038
2026-04-14 02:57:02.885473 | localhost | Username: zuul
2026-04-14 02:57:02.885505 | localhost | Distro: Rocky 9.7
2026-04-14 02:57:02.885533 | localhost | Provider: yul1
2026-04-14 02:57:02.885609 | localhost | Region: ca-ymq-1
2026-04-14 02:57:02.885641 | localhost | Label: rockylinux-9
2026-04-14 02:57:02.885668 | localhost | Product Name: OpenStack Nova
2026-04-14 02:57:02.885695 | localhost | Interface IP: 199.204.45.57
2026-04-14 02:57:02.899551 | 
2026-04-14 02:57:02.899676 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-14 02:57:03.392906 | localhost -> localhost | changed
2026-04-14 02:57:03.402762 | 
2026-04-14 02:57:03.402905 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-14 02:57:04.406858 | localhost -> localhost | changed
2026-04-14 02:57:04.412599 | 
2026-04-14 02:57:04.412663 | PLAY [all]
2026-04-14 02:57:04.419833 | 
2026-04-14 02:57:04.419920 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-14 02:57:04.927380 | rockylinux-9 -> localhost | ok
2026-04-14 02:57:04.938916 | 
2026-04-14 02:57:04.938999 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-14 02:57:04.972986 | rockylinux-9 | ok
2026-04-14 02:57:05.004454 | rockylinux-9 | included: /var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-14 02:57:05.010355 | 
2026-04-14 02:57:05.010482 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-14 02:57:06.970042 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-14 02:57:06.970218 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/work/661ac963d0874f348f76433305b7b1da_id_rsa
2026-04-14 02:57:06.970332 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/work/661ac963d0874f348f76433305b7b1da_id_rsa.pub
2026-04-14 02:57:06.970372 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-14 02:57:06.970396 | rockylinux-9 -> localhost | SHA256:Xx4A8dNEZP6xi/DTCt/1f/e1ffaiVkLktSKaYx6B6Eo zuul-build-sshkey
2026-04-14 02:57:06.970432 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-14 02:57:06.970454 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-14 02:57:06.970493 | rockylinux-9 -> localhost | |        o. o=    |
2026-04-14 02:57:06.970525 | rockylinux-9 -> localhost | |         o =. .  |
2026-04-14 02:57:06.970550 | rockylinux-9 -> localhost | |     . .  +oo... |
2026-04-14 02:57:06.970572 | rockylinux-9 -> localhost | |    . . . .o+..o |
2026-04-14 02:57:06.970594 | rockylinux-9 -> localhost | |   .    S+.oo.o  |
2026-04-14 02:57:06.970616 | rockylinux-9 -> localhost | |  E .   *. =.+.. |
2026-04-14 02:57:06.970636 | rockylinux-9 -> localhost | | . .   o oo =oo o|
2026-04-14 02:57:06.970657 | rockylinux-9 -> localhost | |  .     .  o.+..X|
2026-04-14 02:57:06.970681 | rockylinux-9 -> localhost | |           .+..+&|
2026-04-14 02:57:06.970702 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-14 02:57:06.970756 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.509715
2026-04-14 02:57:06.976885 | 
2026-04-14 02:57:06.976979 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-14 02:57:07.013800 | rockylinux-9 | ok
2026-04-14 02:57:07.023921 | rockylinux-9 | included: /var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-14 02:57:07.034946 | 
2026-04-14 02:57:07.035195 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-14 02:57:07.051366 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:57:07.061821 | 
2026-04-14 02:57:07.061932 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-14 02:57:07.608725 | rockylinux-9 | changed
2026-04-14 02:57:07.614776 | 
2026-04-14 02:57:07.614851 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-14 02:57:07.883129 | rockylinux-9 | ok
2026-04-14 02:57:07.889392 | 
2026-04-14 02:57:07.889457 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-14 02:57:08.811820 | rockylinux-9 | changed
2026-04-14 02:57:08.820400 | 
2026-04-14 02:57:08.820497 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-14 02:57:09.729166 | rockylinux-9 | changed
2026-04-14 02:57:09.738881 | 
2026-04-14 02:57:09.739054 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-14 02:57:09.767335 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:57:09.794836 | 
2026-04-14 02:57:09.794972 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-14 02:57:10.187401 | rockylinux-9 -> localhost | changed
2026-04-14 02:57:10.201348 | 
2026-04-14 02:57:10.201481 | TASK [add-build-sshkey : Add back temp key]
2026-04-14 02:57:10.509142 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/work/661ac963d0874f348f76433305b7b1da_id_rsa (zuul-build-sshkey)
2026-04-14 02:57:10.509451 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012828
2026-04-14 02:57:10.518755 | 
2026-04-14 02:57:10.518939 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-14 02:57:10.900812 | rockylinux-9 | ok
2026-04-14 02:57:10.907660 | 
2026-04-14 02:57:10.907770 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-14 02:57:10.932477 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:57:10.946301 | 
2026-04-14 02:57:10.946417 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-14 02:57:11.371089 | rockylinux-9 | ok
2026-04-14 02:57:11.376926 | 
2026-04-14 02:57:11.377074 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-14 02:57:12.491704 | rockylinux-9 | Output suppressed because no_log was given
2026-04-14 02:57:12.499193 | 
2026-04-14 02:57:12.499260 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-14 02:57:12.754856 | rockylinux-9 | ok: "logs"
2026-04-14 02:57:12.755295 | rockylinux-9 | ok: All items complete
2026-04-14 02:57:12.755325 | 
2026-04-14 02:57:12.991977 | rockylinux-9 | ok: "artifacts"
2026-04-14 02:57:13.221401 | rockylinux-9 | ok: "docs"
2026-04-14 02:57:13.237145 | 
2026-04-14 02:57:13.237271 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-14 02:57:13.534811 | rockylinux-9 | changed: "logs"
2026-04-14 02:57:13.768641 | rockylinux-9 | changed: "artifacts"
2026-04-14 02:57:13.996090 | rockylinux-9 | changed: "docs"
2026-04-14 02:57:14.005928 | 
2026-04-14 02:57:14.006041 | PLAY RECAP
2026-04-14 02:57:14.006087 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:57:14.006117 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-14 02:57:14.006136 | 
2026-04-14 02:57:14.132853 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 02:57:14.134401 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 02:57:14.764718 | 
2026-04-14 02:57:14.764866 | PLAY [all]
2026-04-14 02:57:14.776044 | 
2026-04-14 02:57:14.776126 | TASK [setup-uv : Extract archive]
2026-04-14 02:57:17.556712 | rockylinux-9 | changed
2026-04-14 02:57:17.563424 | 
2026-04-14 02:57:17.563527 | TASK [setup-uv : Print version]
2026-04-14 02:57:17.596067 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:18.107951 | rockylinux-9 | ok: Runtime: 0:00:00.015313
2026-04-14 02:57:18.114113 | 
2026-04-14 02:57:18.114185 | PLAY RECAP
2026-04-14 02:57:18.114235 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:57:18.114263 | 
2026-04-14 02:57:18.292322 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 02:57:18.295597 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 02:57:18.919293 | 
2026-04-14 02:57:19.054182 | PLAY [all]
2026-04-14 02:57:19.074588 | 
2026-04-14 02:57:19.074758 | TASK [Copy inventory file for Zuul]
2026-04-14 02:57:20.485680 | rockylinux-9 | changed
2026-04-14 02:57:20.569092 | 
2026-04-14 02:57:20.569279 | TASK [Switch "ansible_host" to private IP]
2026-04-14 02:57:20.920725 | rockylinux-9 | changed: 1 replacements made
2026-04-14 02:57:20.931581 | 
2026-04-14 02:57:20.931728 | TASK [Run Molecule scenario]
2026-04-14 02:57:20.959738 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:29.098171 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:39.269990 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:49.450582 | [rockylinux-9] Waiting on logger
2026-04-14 02:57:58.604192 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:08.775465 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:18.950421 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:29.126025 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:39.302275 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:49.478191 | [rockylinux-9] Waiting on logger
2026-04-14 02:58:58.635696 | [rockylinux-9] Waiting on logger
2026-04-14 02:59:00.877151 | rockylinux-9 | ok: Runtime: 0:01:38.804853
2026-04-14 02:59:00.883217 | 
2026-04-14 02:59:00.883324 | PLAY RECAP
2026-04-14 02:59:00.883407 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 02:59:00.883454 | 
2026-04-14 02:59:01.011576 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 02:59:01.014134 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 02:59:01.598913 | 
2026-04-14 02:59:01.599086 | PLAY [all]
2026-04-14 02:59:01.610303 | 
2026-04-14 02:59:01.610404 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-14 02:59:01.656054 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 02:59:01.662844 | 
2026-04-14 02:59:01.662968 | TASK [fetch-output : Set log path for single node]
2026-04-14 02:59:01.698694 | rockylinux-9 | ok
2026-04-14 02:59:01.705512 | 
2026-04-14 02:59:01.705581 | LOOP [fetch-output : Ensure local output dirs]
2026-04-14 02:59:02.090969 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/work/logs"
2026-04-14 02:59:02.299249 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/work/artifacts"
2026-04-14 02:59:02.505729 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/661ac963d0874f348f76433305b7b1da/work/docs"
2026-04-14 02:59:02.522973 | 
2026-04-14 02:59:02.523112 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-14 02:59:03.237361 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 02:59:03.237663 | rockylinux-9 | changed: All items complete
2026-04-14 02:59:03.237712 | 
2026-04-14 02:59:03.752270 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 02:59:04.309470 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 02:59:04.330357 | 
2026-04-14 02:59:04.330539 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-14 02:59:04.779880 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007920
2026-04-14 02:59:05.018802 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009195
2026-04-14 02:59:05.038856 | 
2026-04-14 02:59:05.039011 | PLAY [all]
2026-04-14 02:59:05.044796 | 
2026-04-14 02:59:05.044864 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-14 02:59:05.516748 | rockylinux-9 | changed
2026-04-14 02:59:05.523702 | 
2026-04-14 02:59:05.523769 | PLAY RECAP
2026-04-14 02:59:05.523829 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-14 02:59:05.523862 | 
2026-04-14 02:59:05.630451 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 02:59:05.633267 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-14 02:59:06.231823 | 
2026-04-14 02:59:06.231978 | PLAY [localhost]
2026-04-14 02:59:06.242516 | 
2026-04-14 02:59:06.242609 | TASK [Generate Zuul manifest]
2026-04-14 02:59:06.263203 | localhost | ok
2026-04-14 02:59:06.280767 | 
2026-04-14 02:59:06.280881 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-14 02:59:06.637867 | localhost | changed
2026-04-14 02:59:06.654882 | 
2026-04-14 02:59:06.655060 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-14 02:59:06.698448 | localhost | ok
2026-04-14 02:59:06.707463 | 
2026-04-14 02:59:06.709872 | TASK [Upload logs]
2026-04-14 02:59:06.733042 | localhost | ok
2026-04-14 02:59:06.792519 | 
2026-04-14 02:59:06.792667 | TASK [Set zuul-log-path fact]
2026-04-14 02:59:06.811591 | localhost | ok
2026-04-14 02:59:06.826568 | 
2026-04-14 02:59:06.826692 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 02:59:06.868823 | localhost | ok
2026-04-14 02:59:06.879061 | 
2026-04-14 02:59:06.879136 | TASK [upload-logs : Create log directories]
2026-04-14 02:59:07.286990 | localhost | changed
2026-04-14 02:59:07.293956 | 
2026-04-14 02:59:07.294071 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-14 02:59:07.721729 | localhost -> localhost | ok: Runtime: 0:00:00.005440
2026-04-14 02:59:07.731110 | 
2026-04-14 02:59:07.731212 | TASK [upload-logs : Upload logs to log server]
2026-04-14 02:59:08.218940 | localhost | Output suppressed because no_log was given
2026-04-14 02:59:08.224609 | 
2026-04-14 02:59:08.224748 | LOOP [upload-logs : Compress console log and json output]
2026-04-14 02:59:08.275319 | localhost | skipping: Conditional result was False
2026-04-14 02:59:08.282758 | localhost | skipping: Conditional result was False
2026-04-14 02:59:08.298635 | 
2026-04-14 02:59:08.298826 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-14 02:59:08.342504 | localhost | skipping: Conditional result was False
2026-04-14 02:59:08.342898 | 
2026-04-14 02:59:08.346938 | localhost | skipping: Conditional result was False
2026-04-14 02:59:08.362417 | 
2026-04-14 02:59:08.362611 | LOOP [upload-logs : Upload console log and json output]