2026-05-06 01:00:45.317193 | Job console starting
2026-05-06 01:00:45.333973 | Updating git repos
2026-05-06 01:00:45.371488 | Cloning repos into workspace
2026-05-06 01:00:45.725225 | Restoring repo states
2026-05-06 01:00:45.737653 | Merging changes
2026-05-06 01:00:46.158682 | Checking out repos
2026-05-06 01:00:46.204930 | Preparing playbooks
2026-05-06 01:00:48.452385 | Running Ansible setup
2026-05-06 01:00:53.492418 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-06 01:00:54.149745 | 
2026-05-06 01:00:54.149903 | PLAY [localhost]
2026-05-06 01:00:54.158826 | 
2026-05-06 01:00:54.158934 | TASK [Gathering Facts]
2026-05-06 01:00:55.394710 | localhost | ok
2026-05-06 01:00:55.402277 | 
2026-05-06 01:00:55.402359 | TASK [Setup log path fact]
2026-05-06 01:00:55.432045 | localhost | ok
2026-05-06 01:00:55.461741 | 
2026-05-06 01:00:55.461934 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-06 01:00:55.506559 | localhost | ok
2026-05-06 01:00:55.514245 | 
2026-05-06 01:00:55.517185 | TASK [emit-job-header : Print job information]
2026-05-06 01:00:55.561546 | # Job Information
2026-05-06 01:00:55.561795 | Ansible Version: 2.16.18
2026-05-06 01:00:55.561870 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-05-06 01:00:55.561922 | Pipeline: check
2026-05-06 01:00:55.561964 | Executor: 2d72f0692154
2026-05-06 01:00:55.562003 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/128
2026-05-06 01:00:55.562049 | Event ID: db2c2f60-48e6-11f1-9c3b-ab1656042994
2026-05-06 01:00:55.566762 | 
2026-05-06 01:00:55.566854 | LOOP [emit-job-header : Print node information]
2026-05-06 01:00:55.660688 | localhost | ok:
2026-05-06 01:00:55.660852 | localhost | # Node Information
2026-05-06 01:00:55.660881 | localhost | Inventory Hostname: rockylinux-9
2026-05-06 01:00:55.660913 | localhost | Hostname: np0000172074
2026-05-06 01:00:55.660935 | localhost | Username: zuul
2026-05-06 01:00:55.660991 | localhost | Distro: Rocky 9.7
2026-05-06 01:00:55.661015 | localhost | Provider: yul1
2026-05-06 01:00:55.661035 | localhost | Region: ca-ymq-1
2026-05-06 01:00:55.661054 | localhost | Label: rockylinux-9
2026-05-06 01:00:55.661074 | localhost | Product Name: OpenStack Nova
2026-05-06 01:00:55.661093 | localhost | Interface IP: 199.19.213.138
2026-05-06 01:00:55.670676 | 
2026-05-06 01:00:55.670834 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-06 01:00:56.169645 | localhost -> localhost | changed
2026-05-06 01:00:56.178305 | 
2026-05-06 01:00:56.179649 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-06 01:00:57.172719 | localhost -> localhost | changed
2026-05-06 01:00:57.179815 | 
2026-05-06 01:00:57.179875 | PLAY [all]
2026-05-06 01:00:57.187557 | 
2026-05-06 01:00:57.187627 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-06 01:00:57.456598 | rockylinux-9 -> localhost | ok
2026-05-06 01:00:57.466601 | 
2026-05-06 01:00:57.466674 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-06 01:00:57.498433 | rockylinux-9 | ok
2026-05-06 01:00:57.512800 | rockylinux-9 | included: /var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-06 01:00:57.519107 | 
2026-05-06 01:00:57.519173 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-06 01:00:59.164324 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-06 01:00:59.164585 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/work/7f25d73cb5334cb599b5f2f3070788ce_id_rsa
2026-05-06 01:00:59.164624 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/work/7f25d73cb5334cb599b5f2f3070788ce_id_rsa.pub
2026-05-06 01:00:59.164649 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-06 01:00:59.164671 | rockylinux-9 -> localhost | SHA256:SG7+S0rAf7XkT4F4iHTvAhUePRIfjcTstj8iQRGll60 zuul-build-sshkey
2026-05-06 01:00:59.164711 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-06 01:00:59.164732 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-06 01:00:59.164760 | rockylinux-9 -> localhost | |        =O+o     |
2026-05-06 01:00:59.164782 | rockylinux-9 -> localhost | |       .o=*+.    |
2026-05-06 01:00:59.164803 | rockylinux-9 -> localhost | |      o =++..    |
2026-05-06 01:00:59.164823 | rockylinux-9 -> localhost | |   . + =.=oo     |
2026-05-06 01:00:59.164843 | rockylinux-9 -> localhost | |    o *.S.E..    |
2026-05-06 01:00:59.164863 | rockylinux-9 -> localhost | |     = ..*.. .   |
2026-05-06 01:00:59.164882 | rockylinux-9 -> localhost | |      + +.+..    |
2026-05-06 01:00:59.164901 | rockylinux-9 -> localhost | |     . =...oo    |
2026-05-06 01:00:59.164924 | rockylinux-9 -> localhost | |      . oo ...   |
2026-05-06 01:00:59.164944 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-06 01:00:59.165017 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.203466
2026-05-06 01:00:59.169669 | 
2026-05-06 01:00:59.169818 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-06 01:00:59.197888 | rockylinux-9 | ok
2026-05-06 01:00:59.208897 | rockylinux-9 | included: /var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-06 01:00:59.216286 | 
2026-05-06 01:00:59.216371 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-06 01:00:59.241453 | rockylinux-9 | skipping: Conditional result was False
2026-05-06 01:00:59.249511 | 
2026-05-06 01:00:59.249615 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-06 01:00:59.769954 | rockylinux-9 | changed
2026-05-06 01:00:59.776277 | 
2026-05-06 01:00:59.776405 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-06 01:01:00.018244 | rockylinux-9 | ok
2026-05-06 01:01:00.023940 | 
2026-05-06 01:01:00.024108 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-06 01:01:00.925450 | rockylinux-9 | changed
2026-05-06 01:01:00.933297 | 
2026-05-06 01:01:00.933461 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-06 01:01:01.877543 | rockylinux-9 | changed
2026-05-06 01:01:01.897969 | 
2026-05-06 01:01:01.898127 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-06 01:01:01.923031 | rockylinux-9 | skipping: Conditional result was False
2026-05-06 01:01:01.930950 | 
2026-05-06 01:01:01.931072 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-06 01:01:02.361956 | rockylinux-9 -> localhost | changed
2026-05-06 01:01:02.377030 | 
2026-05-06 01:01:02.377257 | TASK [add-build-sshkey : Add back temp key]
2026-05-06 01:01:02.767271 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/work/7f25d73cb5334cb599b5f2f3070788ce_id_rsa (zuul-build-sshkey)
2026-05-06 01:01:02.767485 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.018439
2026-05-06 01:01:02.772274 | 
2026-05-06 01:01:02.772342 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-06 01:01:03.230331 | rockylinux-9 | ok
2026-05-06 01:01:03.239148 | 
2026-05-06 01:01:03.239245 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-06 01:01:03.274885 | rockylinux-9 | skipping: Conditional result was False
2026-05-06 01:01:03.292664 | 
2026-05-06 01:01:03.292770 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-06 01:01:03.761027 | rockylinux-9 | ok
2026-05-06 01:01:03.766813 | 
2026-05-06 01:01:03.766884 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-06 01:01:04.760206 | rockylinux-9 | Output suppressed because no_log was given
2026-05-06 01:01:04.776255 | 
2026-05-06 01:01:04.776404 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-06 01:01:05.030665 | rockylinux-9 | ok: "logs"
2026-05-06 01:01:05.030981 | rockylinux-9 | ok: All items complete
2026-05-06 01:01:05.031037 | 
2026-05-06 01:01:05.248866 | rockylinux-9 | ok: "artifacts"
2026-05-06 01:01:05.459426 | rockylinux-9 | ok: "docs"
2026-05-06 01:01:05.471249 | 
2026-05-06 01:01:05.471376 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-06 01:01:05.755788 | rockylinux-9 | changed: "logs"
2026-05-06 01:01:05.993545 | rockylinux-9 | changed: "artifacts"
2026-05-06 01:01:06.286547 | rockylinux-9 | changed: "docs"
2026-05-06 01:01:06.308978 | 
2026-05-06 01:01:06.309112 | PLAY RECAP
2026-05-06 01:01:06.309164 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-06 01:01:06.309196 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-06 01:01:06.309219 | 
2026-05-06 01:01:06.482406 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-06 01:01:06.483738 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-06 01:01:07.093816 | 
2026-05-06 01:01:07.093959 | PLAY [all]
2026-05-06 01:01:07.105688 | 
2026-05-06 01:01:07.105782 | TASK [setup-uv : Extract archive]
2026-05-06 01:01:09.868015 | rockylinux-9 | changed
2026-05-06 01:01:10.271612 | 
2026-05-06 01:01:10.271781 | TASK [setup-uv : Print version]
2026-05-06 01:01:10.300534 | [rockylinux-9] Waiting on logger
2026-05-06 01:01:10.812274 | rockylinux-9 | ok: Runtime: 0:00:00.015612
2026-05-06 01:01:10.818344 | 
2026-05-06 01:01:10.818397 | PLAY RECAP
2026-05-06 01:01:10.818444 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-06 01:01:10.818469 | 
2026-05-06 01:01:10.983039 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-06 01:01:10.985384 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-06 01:01:11.592727 | 
2026-05-06 01:01:11.649234 | PLAY [all]
2026-05-06 01:01:11.668052 | 
2026-05-06 01:01:11.668166 | TASK [Copy inventory file for Zuul]
2026-05-06 01:01:13.119957 | rockylinux-9 | changed
2026-05-06 01:01:13.124824 | 
2026-05-06 01:01:13.124900 | TASK [Switch "ansible_host" to private IP]
2026-05-06 01:01:13.561998 | rockylinux-9 | changed: 1 replacements made
2026-05-06 01:01:13.567284 | 
2026-05-06 01:01:13.567354 | TASK [Run Molecule scenario]
2026-05-06 01:01:13.590965 | [rockylinux-9] Waiting on logger
2026-05-06 01:01:21.733944 | [rockylinux-9] Waiting on logger
2026-05-06 01:01:31.910207 | [rockylinux-9] Waiting on logger
2026-05-06 01:01:42.086347 | [rockylinux-9] Waiting on logger
2026-05-06 01:01:52.262135 | [rockylinux-9] Waiting on logger
2026-05-06 01:02:01.415007 | [rockylinux-9] Waiting on logger
2026-05-06 01:02:11.590189 | [rockylinux-9] Waiting on logger
2026-05-06 01:02:21.798190 | [rockylinux-9] Waiting on logger
2026-05-06 01:02:31.974389 | [rockylinux-9] Waiting on logger
2026-05-06 01:02:42.150442 | [rockylinux-9] Waiting on logger
2026-05-06 01:02:51.312427 | [rockylinux-9] Waiting on logger
2026-05-06 01:03:01.478080 | [rockylinux-9] Waiting on logger
2026-05-06 01:03:11.654108 | [rockylinux-9] Waiting on logger
2026-05-06 01:03:21.830101 | [rockylinux-9] Waiting on logger
2026-05-06 01:03:32.006100 | [rockylinux-9] Waiting on logger
2026-05-06 01:03:42.182472 | [rockylinux-9] Waiting on logger
2026-05-06 01:03:45.644117 | rockylinux-9 | ok: Runtime: 0:02:31.072548
2026-05-06 01:03:45.650599 | 
2026-05-06 01:03:45.650691 | PLAY RECAP
2026-05-06 01:03:45.650737 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-06 01:03:45.650760 | 
2026-05-06 01:03:45.806281 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-06 01:03:45.809088 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-06 01:03:46.416100 | 
2026-05-06 01:03:46.416241 | PLAY [all]
2026-05-06 01:03:46.428129 | 
2026-05-06 01:03:46.428261 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-06 01:03:46.473826 | rockylinux-9 | skipping: Conditional result was False
2026-05-06 01:03:46.482574 | 
2026-05-06 01:03:46.482696 | TASK [fetch-output : Set log path for single node]
2026-05-06 01:03:46.526750 | rockylinux-9 | ok
2026-05-06 01:03:46.532928 | 
2026-05-06 01:03:46.533090 | LOOP [fetch-output : Ensure local output dirs]
2026-05-06 01:03:46.949990 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/work/logs"
2026-05-06 01:03:47.181394 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/work/artifacts"
2026-05-06 01:03:47.414534 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7f25d73cb5334cb599b5f2f3070788ce/work/docs"
2026-05-06 01:03:47.435187 | 
2026-05-06 01:03:47.435338 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-06 01:03:48.169701 | rockylinux-9 | changed: .d..t...... ./
2026-05-06 01:03:48.169963 | rockylinux-9 | changed: All items complete
2026-05-06 01:03:48.170001 | 
2026-05-06 01:03:48.694091 | rockylinux-9 | changed: .d..t...... ./
2026-05-06 01:03:49.218123 | rockylinux-9 | changed: .d..t...... ./
2026-05-06 01:03:49.237036 | 
2026-05-06 01:03:49.237220 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-06 01:03:49.663395 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008020
2026-05-06 01:03:49.895104 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008774
2026-05-06 01:03:49.916010 | 
2026-05-06 01:03:49.916206 | PLAY [all]
2026-05-06 01:03:49.928774 | 
2026-05-06 01:03:49.928861 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-06 01:03:50.376517 | rockylinux-9 | changed
2026-05-06 01:03:50.383983 | 
2026-05-06 01:03:50.384040 | PLAY RECAP
2026-05-06 01:03:50.384087 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-06 01:03:50.384111 | 
2026-05-06 01:03:50.598213 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-06 01:03:50.601352 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-06 01:03:51.192876 | 
2026-05-06 01:03:51.193028 | PLAY [localhost]
2026-05-06 01:03:51.203596 | 
2026-05-06 01:03:51.203667 | TASK [Generate Zuul manifest]
2026-05-06 01:03:51.221970 | localhost | ok
2026-05-06 01:03:51.237831 | 
2026-05-06 01:03:51.237905 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-06 01:03:51.614151 | localhost | changed
2026-05-06 01:03:51.624347 | 
2026-05-06 01:03:51.624460 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-06 01:03:51.707961 | localhost | ok
2026-05-06 01:03:51.715863 | 
2026-05-06 01:03:51.715933 | TASK [Upload logs]
2026-05-06 01:03:51.735818 | localhost | ok
2026-05-06 01:03:51.796173 | 
2026-05-06 01:03:51.796325 | TASK [Set zuul-log-path fact]
2026-05-06 01:03:51.817644 | localhost | ok
2026-05-06 01:03:51.829597 | 
2026-05-06 01:03:51.829666 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-06 01:03:51.860459 | localhost | ok
2026-05-06 01:03:51.869510 | 
2026-05-06 01:03:51.869597 | TASK [upload-logs : Create log directories]
2026-05-06 01:03:52.274178 | localhost | changed
2026-05-06 01:03:52.280727 | 
2026-05-06 01:03:52.280817 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-06 01:03:52.688332 | localhost -> localhost | ok: Runtime: 0:00:00.005323
2026-05-06 01:03:52.693606 | 
2026-05-06 01:03:52.693690 | TASK [upload-logs : Upload logs to log server]
2026-05-06 01:03:53.413252 | localhost | Output suppressed because no_log was given
2026-05-06 01:03:53.418469 | 
2026-05-06 01:03:53.418569 | LOOP [upload-logs : Compress console log and json output]
2026-05-06 01:03:53.464102 | localhost | skipping: Conditional result was False
2026-05-06 01:03:53.471600 | localhost | skipping: Conditional result was False
2026-05-06 01:03:53.482984 | 
2026-05-06 01:03:53.483122 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-06 01:03:53.524092 | localhost | skipping: Conditional result was False
2026-05-06 01:03:53.524528 | 
2026-05-06 01:03:53.529280 | localhost | skipping: Conditional result was False
2026-05-06 01:03:53.545139 | 
2026-05-06 01:03:53.545287 | LOOP [upload-logs : Upload console log and json output]