2026-04-22 09:27:01.617922 | Job console starting
2026-04-22 09:27:01.629591 | Updating git repos
2026-04-22 09:27:01.650553 | Cloning repos into workspace
2026-04-22 09:27:04.977405 | Restoring repo states
2026-04-22 09:27:04.986060 | Merging changes
2026-04-22 09:27:05.576668 | Checking out repos
2026-04-22 09:27:05.767695 | Preparing playbooks
2026-04-22 09:27:11.245587 | Running Ansible setup
2026-04-22 09:27:14.950892 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-22 09:27:15.592546 | 
2026-04-22 09:27:15.592699 | PLAY [localhost]
2026-04-22 09:27:15.600818 | 
2026-04-22 09:27:15.600897 | TASK [Gathering Facts]
2026-04-22 09:27:16.449718 | localhost | ok
2026-04-22 09:27:16.456669 | 
2026-04-22 09:27:16.456744 | TASK [Setup log path fact]
2026-04-22 09:27:16.476133 | localhost | ok
2026-04-22 09:27:16.492163 | 
2026-04-22 09:27:16.492246 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-22 09:27:16.523706 | localhost | ok
2026-04-22 09:27:16.531822 | 
2026-04-22 09:27:16.531891 | TASK [emit-job-header : Print job information]
2026-04-22 09:27:16.590103 | # Job Information
2026-04-22 09:27:16.590279 | Ansible Version: 2.16.16
2026-04-22 09:27:16.590313 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-22 09:27:16.590335 | Pipeline: check
2026-04-22 09:27:16.590355 | Executor: 0a8996d2b663
2026-04-22 09:27:16.590375 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118
2026-04-22 09:27:16.590398 | Event ID: 427684f0-3e2d-11f1-83da-a94e8dbaadfb
2026-04-22 09:27:16.593251 | 
2026-04-22 09:27:16.593321 | LOOP [emit-job-header : Print node information]
2026-04-22 09:27:16.681243 | localhost | ok:
2026-04-22 09:27:16.681421 | localhost | # Node Information
2026-04-22 09:27:16.681450 | localhost | Inventory Hostname: rockylinux-9
2026-04-22 09:27:16.681476 | localhost | Hostname: np0000169252
2026-04-22 09:27:16.681496 | localhost | Username: zuul
2026-04-22 09:27:16.681519 | localhost | Distro: Rocky 9.7
2026-04-22 09:27:16.681539 | localhost | Provider: yul1
2026-04-22 09:27:16.681559 | localhost | Region: ca-ymq-1
2026-04-22 09:27:16.681578 | localhost | Label: rockylinux-9
2026-04-22 09:27:16.681597 | localhost | Product Name: OpenStack Nova
2026-04-22 09:27:16.681616 | localhost | Interface IP: 199.19.213.67
2026-04-22 09:27:16.695747 | 
2026-04-22 09:27:16.695886 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-22 09:27:17.109230 | localhost -> localhost | changed
2026-04-22 09:27:17.117833 | 
2026-04-22 09:27:17.117926 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-22 09:27:17.983969 | localhost -> localhost | changed
2026-04-22 09:27:17.990036 | 
2026-04-22 09:27:17.990101 | PLAY [all]
2026-04-22 09:27:17.997584 | 
2026-04-22 09:27:17.997650 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-22 09:27:18.235896 | rockylinux-9 -> localhost | ok
2026-04-22 09:27:18.245624 | 
2026-04-22 09:27:18.245728 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-22 09:27:18.275699 | rockylinux-9 | ok
2026-04-22 09:27:18.297537 | rockylinux-9 | included: /var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-22 09:27:18.305079 | 
2026-04-22 09:27:18.305172 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-22 09:27:19.185013 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-22 09:27:19.185452 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/work/6a2f5e0a2a854b7e862c46bcd4c63225_id_rsa
2026-04-22 09:27:19.185719 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/work/6a2f5e0a2a854b7e862c46bcd4c63225_id_rsa.pub
2026-04-22 09:27:19.185751 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-22 09:27:19.185774 | rockylinux-9 -> localhost | SHA256:IzqwgRfuVfTyJ1DZu64brxXUKOFbb/nWEu9WfI6WX5A zuul-build-sshkey
2026-04-22 09:27:19.185807 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-22 09:27:19.185828 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-22 09:27:19.186119 | rockylinux-9 -> localhost | |      . oo       |
2026-04-22 09:27:19.186163 | rockylinux-9 -> localhost | |     . +...o     |
2026-04-22 09:27:19.186187 | rockylinux-9 -> localhost | |  .   + + +..    |
2026-04-22 09:27:19.186208 | rockylinux-9 -> localhost | | o . . + =.. . . |
2026-04-22 09:27:19.186229 | rockylinux-9 -> localhost | |. = . . S o.+ E. |
2026-04-22 09:27:19.186248 | rockylinux-9 -> localhost | | o = . . +.o . =+|
2026-04-22 09:27:19.186268 | rockylinux-9 -> localhost | |  o o   ...   ++*|
2026-04-22 09:27:19.186287 | rockylinux-9 -> localhost | |     .   +.  .+o+|
2026-04-22 09:27:19.186309 | rockylinux-9 -> localhost | |        ++.  . oo|
2026-04-22 09:27:19.186329 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-22 09:27:19.186411 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.418755
2026-04-22 09:27:19.194620 | 
2026-04-22 09:27:19.194705 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-22 09:27:19.237562 | rockylinux-9 | ok
2026-04-22 09:27:19.249997 | rockylinux-9 | included: /var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-22 09:27:19.260750 | 
2026-04-22 09:27:19.260869 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-22 09:27:19.286962 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:27:19.297610 | 
2026-04-22 09:27:19.297754 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-22 09:27:19.773649 | rockylinux-9 | changed
2026-04-22 09:27:19.778639 | 
2026-04-22 09:27:19.778704 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-22 09:27:20.034208 | rockylinux-9 | ok
2026-04-22 09:27:20.039415 | 
2026-04-22 09:27:20.039478 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-22 09:27:21.005280 | rockylinux-9 | changed
2026-04-22 09:27:21.010991 | 
2026-04-22 09:27:21.011061 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-22 09:27:21.945407 | rockylinux-9 | changed
2026-04-22 09:27:21.950544 | 
2026-04-22 09:27:21.950610 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-22 09:27:21.975497 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:27:21.983241 | 
2026-04-22 09:27:21.983361 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-22 09:27:22.358053 | rockylinux-9 -> localhost | changed
2026-04-22 09:27:22.367692 | 
2026-04-22 09:27:22.367754 | TASK [add-build-sshkey : Add back temp key]
2026-04-22 09:27:22.691707 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/work/6a2f5e0a2a854b7e862c46bcd4c63225_id_rsa (zuul-build-sshkey)
2026-04-22 09:27:22.691919 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014902
2026-04-22 09:27:22.698189 | 
2026-04-22 09:27:22.698265 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-22 09:27:23.074405 | rockylinux-9 | ok
2026-04-22 09:27:23.084089 | 
2026-04-22 09:27:23.084276 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-22 09:27:23.115423 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:27:23.127045 | 
2026-04-22 09:27:23.127113 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-22 09:27:23.535314 | rockylinux-9 | ok
2026-04-22 09:27:23.540762 | 
2026-04-22 09:27:23.540829 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-22 09:27:24.656500 | rockylinux-9 | Output suppressed because no_log was given
2026-04-22 09:27:24.666588 | 
2026-04-22 09:27:24.666660 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-22 09:27:24.930518 | rockylinux-9 | ok: "logs"
2026-04-22 09:27:24.977093 | rockylinux-9 | ok: All items complete
2026-04-22 09:27:24.977171 | 
2026-04-22 09:27:25.155151 | rockylinux-9 | ok: "artifacts"
2026-04-22 09:27:25.384392 | rockylinux-9 | ok: "docs"
2026-04-22 09:27:25.397927 | 
2026-04-22 09:27:25.398076 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-22 09:27:25.655817 | rockylinux-9 | changed: "logs"
2026-04-22 09:27:25.894495 | rockylinux-9 | changed: "artifacts"
2026-04-22 09:27:26.136864 | rockylinux-9 | changed: "docs"
2026-04-22 09:27:26.156578 | 
2026-04-22 09:27:26.156688 | PLAY RECAP
2026-04-22 09:27:26.156738 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 09:27:26.156768 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-22 09:27:26.156789 | 
2026-04-22 09:27:26.281077 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-22 09:27:26.566880 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-22 09:27:27.225977 | 
2026-04-22 09:27:27.226114 | PLAY [all]
2026-04-22 09:27:27.236796 | 
2026-04-22 09:27:27.242725 | TASK [setup-uv : Extract archive]
2026-04-22 09:27:30.076086 | rockylinux-9 | changed
2026-04-22 09:27:30.138174 | 
2026-04-22 09:27:30.139377 | TASK [setup-uv : Print version]
2026-04-22 09:27:30.171017 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:30.583126 | rockylinux-9 | ok: Runtime: 0:00:00.014302
2026-04-22 09:27:30.590411 | 
2026-04-22 09:27:30.590623 | PLAY RECAP
2026-04-22 09:27:30.590727 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 09:27:30.590786 | 
2026-04-22 09:27:30.719306 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-22 09:27:30.721674 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-22 09:27:31.338928 | 
2026-04-22 09:27:31.339089 | PLAY [all]
2026-04-22 09:27:31.349839 | 
2026-04-22 09:27:31.349930 | TASK [Copy inventory file for Zuul]
2026-04-22 09:27:32.704304 | rockylinux-9 | changed
2026-04-22 09:27:32.806092 | 
2026-04-22 09:27:32.806293 | TASK [Switch "ansible_host" to private IP]
2026-04-22 09:27:33.179635 | rockylinux-9 | changed: 1 replacements made
2026-04-22 09:27:33.191778 | 
2026-04-22 09:27:33.191894 | TASK [Run Molecule scenario]
2026-04-22 09:27:33.230529 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:40.358044 | [rockylinux-9] Waiting on logger
2026-04-22 09:27:50.533954 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:00.837792 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:11.018005 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:20.171102 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:30.342140 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:40.522231 | [rockylinux-9] Waiting on logger
2026-04-22 09:28:50.757889 | [rockylinux-9] Waiting on logger
2026-04-22 09:29:00.934079 | [rockylinux-9] Waiting on logger
2026-04-22 09:29:11.114442 | [rockylinux-9] Waiting on logger
2026-04-22 09:29:12.333685 | rockylinux-9 | ok: Runtime: 0:01:38.626055
2026-04-22 09:29:12.338288 | 
2026-04-22 09:29:12.338337 | PLAY RECAP
2026-04-22 09:29:12.338383 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-22 09:29:12.338404 | 
2026-04-22 09:29:12.504178 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-22 09:29:12.527102 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-22 09:29:13.173523 | 
2026-04-22 09:29:13.173716 | PLAY [all]
2026-04-22 09:29:13.185728 | 
2026-04-22 09:29:13.185909 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-22 09:29:13.235014 | rockylinux-9 | skipping: Conditional result was False
2026-04-22 09:29:13.398033 | 
2026-04-22 09:29:13.398185 | TASK [fetch-output : Set log path for single node]
2026-04-22 09:29:13.444157 | rockylinux-9 | ok
2026-04-22 09:29:13.452980 | 
2026-04-22 09:29:13.453066 | LOOP [fetch-output : Ensure local output dirs]
2026-04-22 09:29:13.928347 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/work/logs"
2026-04-22 09:29:14.251938 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/work/artifacts"
2026-04-22 09:29:14.487124 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/6a2f5e0a2a854b7e862c46bcd4c63225/work/docs"
2026-04-22 09:29:14.509315 | 
2026-04-22 09:29:14.509884 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-22 09:29:15.291359 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 09:29:15.291563 | rockylinux-9 | changed: All items complete
2026-04-22 09:29:15.291591 | 
2026-04-22 09:29:15.829911 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 09:29:16.364923 | rockylinux-9 | changed: .d..t...... ./
2026-04-22 09:29:16.377784 | 
2026-04-22 09:29:16.377925 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-22 09:29:16.815309 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008558
2026-04-22 09:29:17.038501 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008137
2026-04-22 09:29:17.056877 | 
2026-04-22 09:29:17.057012 | PLAY [all]
2026-04-22 09:29:17.062877 | 
2026-04-22 09:29:17.062966 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-22 09:29:17.571537 | rockylinux-9 | changed
2026-04-22 09:29:17.578578 | 
2026-04-22 09:29:17.578687 | PLAY RECAP
2026-04-22 09:29:17.578747 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-22 09:29:17.578777 | 
2026-04-22 09:29:17.782060 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-22 09:29:17.783796 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-22 09:29:18.431975 | 
2026-04-22 09:29:18.432141 | PLAY [localhost]
2026-04-22 09:29:18.443494 | 
2026-04-22 09:29:18.443640 | TASK [Generate Zuul manifest]
2026-04-22 09:29:18.466035 | localhost | ok
2026-04-22 09:29:18.486128 | 
2026-04-22 09:29:18.486325 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-22 09:29:18.873920 | localhost | changed
2026-04-22 09:29:18.899379 | 
2026-04-22 09:29:18.899580 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-22 09:29:18.931032 | localhost | ok
2026-04-22 09:29:18.939715 | 
2026-04-22 09:29:18.939809 | TASK [Upload logs]
2026-04-22 09:29:18.973237 | localhost | ok
2026-04-22 09:29:19.040547 | 
2026-04-22 09:29:19.040700 | TASK [Set zuul-log-path fact]
2026-04-22 09:29:19.063654 | localhost | ok
2026-04-22 09:29:19.077647 | 
2026-04-22 09:29:19.077718 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-22 09:29:19.109277 | localhost | ok
2026-04-22 09:29:19.118547 | 
2026-04-22 09:29:19.118615 | TASK [upload-logs : Create log directories]
2026-04-22 09:29:19.549966 | localhost | changed
2026-04-22 09:29:19.555709 | 
2026-04-22 09:29:19.555781 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-22 09:29:20.009460 | localhost -> localhost | ok: Runtime: 0:00:00.006187
2026-04-22 09:29:20.015967 | 
2026-04-22 09:29:20.016031 | TASK [upload-logs : Upload logs to log server]
2026-04-22 09:29:20.814477 | localhost | Output suppressed because no_log was given
2026-04-22 09:29:20.836267 | 
2026-04-22 09:29:20.836354 | LOOP [upload-logs : Compress console log and json output]
2026-04-22 09:29:20.886848 | localhost | skipping: Conditional result was False
2026-04-22 09:29:20.894267 | localhost | skipping: Conditional result was False
2026-04-22 09:29:20.909633 | 
2026-04-22 09:29:20.909769 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-22 09:29:20.954332 | localhost | skipping: Conditional result was False
2026-04-22 09:29:20.954729 | 
2026-04-22 09:29:20.958791 | localhost | skipping: Conditional result was False
2026-04-22 09:29:20.975480 | 
2026-04-22 09:29:20.975731 | LOOP [upload-logs : Upload console log and json output]