2026-04-15 00:58:51.917951 | Job console starting
2026-04-15 00:58:51.933819 | Updating git repos
2026-04-15 00:58:51.957625 | Cloning repos into workspace
2026-04-15 00:58:52.727346 | Restoring repo states
2026-04-15 00:58:52.735692 | Merging changes
2026-04-15 00:58:53.520385 | Checking out repos
2026-04-15 00:58:53.785897 | Preparing playbooks
2026-04-15 00:59:10.462049 | Running Ansible setup
2026-04-15 00:59:14.440841 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 00:59:15.024154 | 
2026-04-15 00:59:15.024284 | PLAY [localhost]
2026-04-15 00:59:15.031992 | 
2026-04-15 00:59:15.032088 | TASK [Gathering Facts]
2026-04-15 00:59:15.881169 | localhost | ok
2026-04-15 00:59:15.887720 | 
2026-04-15 00:59:15.887852 | TASK [Setup log path fact]
2026-04-15 00:59:15.904300 | localhost | ok
2026-04-15 00:59:15.915324 | 
2026-04-15 00:59:15.915448 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 00:59:15.943205 | localhost | ok
2026-04-15 00:59:15.951294 | 
2026-04-15 00:59:15.951362 | TASK [emit-job-header : Print job information]
2026-04-15 00:59:15.987726 | # Job Information
2026-04-15 00:59:15.987900 | Ansible Version: 2.16.16
2026-04-15 00:59:15.987934 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-15 00:59:15.987957 | Pipeline: check
2026-04-15 00:59:15.987977 | Executor: 0a8996d2b663
2026-04-15 00:59:15.987997 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/105
2026-04-15 00:59:15.988020 | Event ID: 241ce890-3866-11f1-9831-553fb923615a
2026-04-15 00:59:15.991239 | 
2026-04-15 00:59:15.991319 | LOOP [emit-job-header : Print node information]
2026-04-15 00:59:16.071855 | localhost | ok:
2026-04-15 00:59:16.072010 | localhost | # Node Information
2026-04-15 00:59:16.072038 | localhost | Inventory Hostname: rockylinux-9
2026-04-15 00:59:16.072060 | localhost | Hostname: np0000166289
2026-04-15 00:59:16.072080 | localhost | Username: zuul
2026-04-15 00:59:16.072102 | localhost | Distro: Rocky 9.7
2026-04-15 00:59:16.072121 | localhost | Provider: yul1
2026-04-15 00:59:16.072140 | localhost | Region: ca-ymq-1
2026-04-15 00:59:16.072158 | localhost | Label: rockylinux-9
2026-04-15 00:59:16.072176 | localhost | Product Name: OpenStack Nova
2026-04-15 00:59:16.072194 | localhost | Interface IP: 199.204.45.209
2026-04-15 00:59:16.083003 | 
2026-04-15 00:59:16.083122 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-15 00:59:16.516873 | localhost -> localhost | changed
2026-04-15 00:59:16.521358 | 
2026-04-15 00:59:16.521433 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-15 00:59:17.386985 | localhost -> localhost | changed
2026-04-15 00:59:17.398104 | 
2026-04-15 00:59:17.398193 | PLAY [all]
2026-04-15 00:59:17.420581 | 
2026-04-15 00:59:17.420723 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-15 00:59:17.630081 | rockylinux-9 -> localhost | ok
2026-04-15 00:59:17.637130 | 
2026-04-15 00:59:17.637224 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-15 00:59:17.662596 | rockylinux-9 | ok
2026-04-15 00:59:17.682774 | rockylinux-9 | included: /var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-15 00:59:17.688209 | 
2026-04-15 00:59:17.688273 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-15 00:59:18.795153 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-15 00:59:18.795306 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/work/45fdff596a0d4922aed17669dfdda88d_id_rsa
2026-04-15 00:59:18.795338 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/work/45fdff596a0d4922aed17669dfdda88d_id_rsa.pub
2026-04-15 00:59:18.795361 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-15 00:59:18.795383 | rockylinux-9 -> localhost | SHA256:JhiiCC7Ct19XYJvELoVfGusXcZwGMkipB/CBOQFv0IA zuul-build-sshkey
2026-04-15 00:59:18.795411 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-15 00:59:18.795433 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-15 00:59:18.795455 | rockylinux-9 -> localhost | |.++o=...oo .     |
2026-04-15 00:59:18.795477 | rockylinux-9 -> localhost | |E o=...oo o o .  |
2026-04-15 00:59:18.795497 | rockylinux-9 -> localhost | |. .oo.o. B o =   |
2026-04-15 00:59:18.795518 | rockylinux-9 -> localhost | |=... + .* O +    |
2026-04-15 00:59:18.795538 | rockylinux-9 -> localhost | |=o .. o.SB o     |
2026-04-15 00:59:18.795557 | rockylinux-9 -> localhost | |o . .  oo . .    |
2026-04-15 00:59:18.795577 | rockylinux-9 -> localhost | |   .   . o .     |
2026-04-15 00:59:18.795596 | rockylinux-9 -> localhost | |    . . . .      |
2026-04-15 00:59:18.795618 | rockylinux-9 -> localhost | |     .           |
2026-04-15 00:59:18.795639 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-15 00:59:18.795682 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.695505
2026-04-15 00:59:18.800230 | 
2026-04-15 00:59:18.800290 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-15 00:59:18.852248 | rockylinux-9 | ok
2026-04-15 00:59:18.862973 | rockylinux-9 | included: /var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-15 00:59:18.870814 | 
2026-04-15 00:59:18.870873 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-15 00:59:18.894606 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 00:59:18.903649 | 
2026-04-15 00:59:18.903758 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-15 00:59:19.361206 | rockylinux-9 | changed
2026-04-15 00:59:19.365629 | 
2026-04-15 00:59:19.365698 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-15 00:59:19.644905 | rockylinux-9 | ok
2026-04-15 00:59:19.649877 | 
2026-04-15 00:59:19.649972 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-15 00:59:20.587453 | rockylinux-9 | changed
2026-04-15 00:59:20.594211 | 
2026-04-15 00:59:20.594297 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-15 00:59:21.519710 | rockylinux-9 | changed
2026-04-15 00:59:21.584182 | 
2026-04-15 00:59:21.584308 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-15 00:59:21.598099 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 00:59:21.606436 | 
2026-04-15 00:59:21.606919 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-15 00:59:22.032274 | rockylinux-9 -> localhost | changed
2026-04-15 00:59:22.045669 | 
2026-04-15 00:59:22.045787 | TASK [add-build-sshkey : Add back temp key]
2026-04-15 00:59:22.469198 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/work/45fdff596a0d4922aed17669dfdda88d_id_rsa (zuul-build-sshkey)
2026-04-15 00:59:22.469367 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013927
2026-04-15 00:59:22.475131 | 
2026-04-15 00:59:22.475192 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-15 00:59:22.858177 | rockylinux-9 | ok
2026-04-15 00:59:22.862368 | 
2026-04-15 00:59:22.862433 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-15 00:59:22.886136 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 00:59:22.894530 | 
2026-04-15 00:59:22.894603 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-15 00:59:23.294447 | rockylinux-9 | ok
2026-04-15 00:59:23.479015 | 
2026-04-15 00:59:23.482872 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-15 00:59:24.447802 | rockylinux-9 | Output suppressed because no_log was given
2026-04-15 00:59:24.455089 | 
2026-04-15 00:59:24.455161 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-15 00:59:24.780752 | rockylinux-9 | ok: "logs"
2026-04-15 00:59:24.828248 | rockylinux-9 | ok: All items complete
2026-04-15 00:59:24.828392 | 
2026-04-15 00:59:24.996012 | rockylinux-9 | ok: "artifacts"
2026-04-15 00:59:25.212868 | rockylinux-9 | ok: "docs"
2026-04-15 00:59:25.265760 | 
2026-04-15 00:59:25.265877 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-15 00:59:25.536950 | rockylinux-9 | changed: "logs"
2026-04-15 00:59:25.794109 | rockylinux-9 | changed: "artifacts"
2026-04-15 00:59:26.040523 | rockylinux-9 | changed: "docs"
2026-04-15 00:59:26.062117 | 
2026-04-15 00:59:26.062265 | PLAY RECAP
2026-04-15 00:59:26.062353 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 00:59:26.062416 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-15 00:59:26.062462 | 
2026-04-15 00:59:26.202688 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 00:59:26.205084 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 00:59:26.969003 | 
2026-04-15 00:59:26.969163 | PLAY [all]
2026-04-15 00:59:26.980768 | 
2026-04-15 00:59:26.980913 | TASK [setup-uv : Extract archive]
2026-04-15 00:59:29.803006 | rockylinux-9 | changed
2026-04-15 00:59:29.808772 | 
2026-04-15 00:59:29.808864 | TASK [setup-uv : Print version]
2026-04-15 00:59:29.830562 | [rockylinux-9] Waiting on logger
2026-04-15 00:59:30.343982 | rockylinux-9 | ok: Runtime: 0:00:00.009883
2026-04-15 00:59:30.351217 | 
2026-04-15 00:59:30.351268 | PLAY RECAP
2026-04-15 00:59:30.351310 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 00:59:30.351336 | 
2026-04-15 00:59:30.442837 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 00:59:30.444527 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 00:59:31.081872 | 
2026-04-15 00:59:31.082110 | PLAY [all]
2026-04-15 00:59:31.095447 | 
2026-04-15 00:59:31.095654 | TASK [Copy inventory file for Zuul]
2026-04-15 00:59:32.540675 | rockylinux-9 | changed
2026-04-15 00:59:32.546086 | 
2026-04-15 00:59:32.546165 | TASK [Switch "ansible_host" to private IP]
2026-04-15 00:59:32.952531 | rockylinux-9 | changed: 1 replacements made
2026-04-15 00:59:32.958417 | 
2026-04-15 00:59:32.958502 | TASK [Run Molecule scenario]
2026-04-15 00:59:33.006669 | [rockylinux-9] Waiting on logger
2026-04-15 00:59:41.157897 | [rockylinux-9] Waiting on logger
2026-04-15 00:59:51.333868 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:01.509723 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:11.685837 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:20.843711 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:31.237995 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:41.418010 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:51.082202 | [rockylinux-9] Waiting on logger
2026-04-15 01:01:01.257973 | [rockylinux-9] Waiting on logger
2026-04-15 01:01:11.433833 | [rockylinux-9] Waiting on logger
2026-04-15 01:01:13.678031 | rockylinux-9 | ok: Runtime: 0:01:39.781008
2026-04-15 01:01:13.683338 | 
2026-04-15 01:01:13.683390 | PLAY RECAP
2026-04-15 01:01:13.683436 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:01:13.683459 | 
2026-04-15 01:01:13.854588 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 01:01:13.857545 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:01:14.460395 | 
2026-04-15 01:01:14.460517 | PLAY [all]
2026-04-15 01:01:14.471208 | 
2026-04-15 01:01:14.471281 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-15 01:01:14.515716 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:01:14.520343 | 
2026-04-15 01:01:14.520438 | TASK [fetch-output : Set log path for single node]
2026-04-15 01:01:14.562555 | rockylinux-9 | ok
2026-04-15 01:01:14.569094 | 
2026-04-15 01:01:14.569160 | LOOP [fetch-output : Ensure local output dirs]
2026-04-15 01:01:15.023271 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/work/logs"
2026-04-15 01:01:15.231113 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/work/artifacts"
2026-04-15 01:01:15.436333 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/45fdff596a0d4922aed17669dfdda88d/work/docs"
2026-04-15 01:01:15.453985 | 
2026-04-15 01:01:15.454512 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-15 01:01:16.175361 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:01:16.175789 | rockylinux-9 | changed: All items complete
2026-04-15 01:01:16.175836 | 
2026-04-15 01:01:16.697003 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:01:17.215619 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:01:17.240417 | 
2026-04-15 01:01:17.240561 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-15 01:01:17.640878 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007691
2026-04-15 01:01:17.872239 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008254
2026-04-15 01:01:17.883185 | 
2026-04-15 01:01:17.883315 | PLAY [all]
2026-04-15 01:01:17.889054 | 
2026-04-15 01:01:17.889121 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-15 01:01:18.340665 | rockylinux-9 | changed
2026-04-15 01:01:18.357974 | 
2026-04-15 01:01:18.358054 | PLAY RECAP
2026-04-15 01:01:18.358109 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-15 01:01:18.358135 | 
2026-04-15 01:01:18.545285 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:01:18.547399 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-15 01:01:19.156365 | 
2026-04-15 01:01:19.156494 | PLAY [localhost]
2026-04-15 01:01:19.166504 | 
2026-04-15 01:01:19.166575 | TASK [Generate Zuul manifest]
2026-04-15 01:01:19.187319 | localhost | ok
2026-04-15 01:01:19.215671 | 
2026-04-15 01:01:19.215951 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-15 01:01:19.532694 | localhost | changed
2026-04-15 01:01:19.547906 | 
2026-04-15 01:01:19.548019 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-15 01:01:19.580483 | localhost | ok
2026-04-15 01:01:19.591613 | 
2026-04-15 01:01:19.591752 | TASK [Upload logs]
2026-04-15 01:01:19.611492 | localhost | ok
2026-04-15 01:01:19.679262 | 
2026-04-15 01:01:19.679384 | TASK [Set zuul-log-path fact]
2026-04-15 01:01:19.701143 | localhost | ok
2026-04-15 01:01:19.716908 | 
2026-04-15 01:01:19.717042 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 01:01:19.748078 | localhost | ok
2026-04-15 01:01:19.758435 | 
2026-04-15 01:01:19.758513 | TASK [upload-logs : Create log directories]
2026-04-15 01:01:20.150951 | localhost | changed
2026-04-15 01:01:20.155377 | 
2026-04-15 01:01:20.155444 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-15 01:01:20.540367 | localhost -> localhost | ok: Runtime: 0:00:00.005790
2026-04-15 01:01:20.547726 | 
2026-04-15 01:01:20.547824 | TASK [upload-logs : Upload logs to log server]
2026-04-15 01:01:20.996439 | localhost | Output suppressed because no_log was given
2026-04-15 01:01:21.005732 | 
2026-04-15 01:01:21.005966 | LOOP [upload-logs : Compress console log and json output]
2026-04-15 01:01:21.053732 | localhost | skipping: Conditional result was False
2026-04-15 01:01:21.061135 | localhost | skipping: Conditional result was False
2026-04-15 01:01:21.073985 | 
2026-04-15 01:01:21.074129 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-15 01:01:21.115561 | localhost | skipping: Conditional result was False
2026-04-15 01:01:21.115897 | 
2026-04-15 01:01:21.119727 | localhost | skipping: Conditional result was False
2026-04-15 01:01:21.137981 | 
2026-04-15 01:01:21.138173 | LOOP [upload-logs : Upload console log and json output]