2026-04-24 01:01:55.640536 | Job console starting
2026-04-24 01:01:55.654515 | Updating git repos
2026-04-24 01:01:55.673663 | Cloning repos into workspace
2026-04-24 01:01:55.738985 | Restoring repo states
2026-04-24 01:01:55.744917 | Merging changes
2026-04-24 01:01:55.999237 | Checking out repos
2026-04-24 01:01:56.031042 | Preparing playbooks
2026-04-24 01:01:56.931335 | Running Ansible setup
2026-04-24 01:02:00.571071 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-24 01:02:01.164393 | 
2026-04-24 01:02:01.164548 | PLAY [localhost]
2026-04-24 01:02:01.172438 | 
2026-04-24 01:02:01.172511 | TASK [Gathering Facts]
2026-04-24 01:02:02.016361 | localhost | ok
2026-04-24 01:02:02.023421 | 
2026-04-24 01:02:02.023507 | TASK [Setup log path fact]
2026-04-24 01:02:02.044828 | localhost | ok
2026-04-24 01:02:02.056643 | 
2026-04-24 01:02:02.056709 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-24 01:02:02.086185 | localhost | ok
2026-04-24 01:02:02.094383 | 
2026-04-24 01:02:02.094481 | TASK [emit-job-header : Print job information]
2026-04-24 01:02:02.122591 | # Job Information
2026-04-24 01:02:02.122704 | Ansible Version: 2.16.16
2026-04-24 01:02:02.122737 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-24 01:02:02.122759 | Pipeline: check
2026-04-24 01:02:02.122780 | Executor: 0a8996d2b663
2026-04-24 01:02:02.122801 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/120
2026-04-24 01:02:02.122823 | Event ID: f893b090-3f78-11f1-873d-35d7e9189637
2026-04-24 01:02:02.125439 | 
2026-04-24 01:02:02.125506 | LOOP [emit-job-header : Print node information]
2026-04-24 01:02:02.221014 | localhost | ok:
2026-04-24 01:02:02.221216 | localhost | # Node Information
2026-04-24 01:02:02.221251 | localhost | Inventory Hostname: rockylinux-9
2026-04-24 01:02:02.221279 | localhost | Hostname: np0000169900
2026-04-24 01:02:02.221303 | localhost | Username: zuul
2026-04-24 01:02:02.221328 | localhost | Distro: Rocky 9.7
2026-04-24 01:02:02.221351 | localhost | Provider: yul1
2026-04-24 01:02:02.221372 | localhost | Region: ca-ymq-1
2026-04-24 01:02:02.221395 | localhost | Label: rockylinux-9
2026-04-24 01:02:02.221416 | localhost | Product Name: OpenStack Nova
2026-04-24 01:02:02.221438 | localhost | Interface IP: 199.204.45.156
2026-04-24 01:02:02.242764 | 
2026-04-24 01:02:02.242881 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-24 01:02:02.643647 | localhost -> localhost | changed
2026-04-24 01:02:02.649709 | 
2026-04-24 01:02:02.650339 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-24 01:02:03.569261 | localhost -> localhost | changed
2026-04-24 01:02:03.579408 | 
2026-04-24 01:02:03.579486 | PLAY [all]
2026-04-24 01:02:03.587470 | 
2026-04-24 01:02:03.587542 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-24 01:02:03.815141 | rockylinux-9 -> localhost | ok
2026-04-24 01:02:03.824316 | 
2026-04-24 01:02:03.824414 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-24 01:02:03.858614 | rockylinux-9 | ok
2026-04-24 01:02:03.875277 | rockylinux-9 | included: /var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-24 01:02:03.880416 | 
2026-04-24 01:02:03.880477 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-24 01:02:04.661291 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-24 01:02:04.662102 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/work/a384a82bcc4e457f9a6639378002a59a_id_rsa
2026-04-24 01:02:04.662170 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/work/a384a82bcc4e457f9a6639378002a59a_id_rsa.pub
2026-04-24 01:02:04.662206 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-24 01:02:04.662237 | rockylinux-9 -> localhost | SHA256:9/atsSu59ypKT04yz/9ARYYe5q6EeofONiW32RSDfNM zuul-build-sshkey
2026-04-24 01:02:04.662280 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-24 01:02:04.662311 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-24 01:02:04.662349 | rockylinux-9 -> localhost | |               .o|
2026-04-24 01:02:04.662381 | rockylinux-9 -> localhost | |              +o |
2026-04-24 01:02:04.662410 | rockylinux-9 -> localhost | |           . = o.|
2026-04-24 01:02:04.662438 | rockylinux-9 -> localhost | |            o B.E|
2026-04-24 01:02:04.662466 | rockylinux-9 -> localhost | |        S .. o.+ |
2026-04-24 01:02:04.662493 | rockylinux-9 -> localhost | |         .o.+.o  |
2026-04-24 01:02:04.662520 | rockylinux-9 -> localhost | |         .+*=Bo  |
2026-04-24 01:02:04.662548 | rockylinux-9 -> localhost | |        .o=@O.o= |
2026-04-24 01:02:04.662579 | rockylinux-9 -> localhost | |         +++**B*+|
2026-04-24 01:02:04.662655 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-24 01:02:04.662761 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.349230
2026-04-24 01:02:04.669493 | 
2026-04-24 01:02:04.671797 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-24 01:02:04.705668 | rockylinux-9 | ok
2026-04-24 01:02:04.728576 | rockylinux-9 | included: /var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-24 01:02:04.736535 | 
2026-04-24 01:02:04.736602 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-24 01:02:04.761904 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:02:04.773023 | 
2026-04-24 01:02:04.773173 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-24 01:02:05.253228 | rockylinux-9 | changed
2026-04-24 01:02:05.258095 | 
2026-04-24 01:02:05.258163 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-24 01:02:05.531718 | rockylinux-9 | ok
2026-04-24 01:02:05.545150 | 
2026-04-24 01:02:05.545290 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-24 01:02:06.517296 | rockylinux-9 | changed
2026-04-24 01:02:06.523949 | 
2026-04-24 01:02:06.524017 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-24 01:02:07.467964 | rockylinux-9 | changed
2026-04-24 01:02:07.474515 | 
2026-04-24 01:02:07.479474 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-24 01:02:07.504770 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:02:07.513044 | 
2026-04-24 01:02:07.513159 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-24 01:02:07.873043 | rockylinux-9 -> localhost | changed
2026-04-24 01:02:07.887148 | 
2026-04-24 01:02:07.887221 | TASK [add-build-sshkey : Add back temp key]
2026-04-24 01:02:08.174689 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/work/a384a82bcc4e457f9a6639378002a59a_id_rsa (zuul-build-sshkey)
2026-04-24 01:02:08.175091 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013764
2026-04-24 01:02:08.181528 | 
2026-04-24 01:02:08.181592 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-24 01:02:08.602966 | rockylinux-9 | ok
2026-04-24 01:02:08.607980 | 
2026-04-24 01:02:08.608056 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-24 01:02:08.634229 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:02:08.648582 | 
2026-04-24 01:02:08.648727 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-24 01:02:09.048827 | rockylinux-9 | ok
2026-04-24 01:02:09.054385 | 
2026-04-24 01:02:09.054454 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-24 01:02:10.009740 | rockylinux-9 | Output suppressed because no_log was given
2026-04-24 01:02:10.018013 | 
2026-04-24 01:02:10.018080 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-24 01:02:10.302476 | rockylinux-9 | ok: "logs"
2026-04-24 01:02:10.302766 | rockylinux-9 | ok: All items complete
2026-04-24 01:02:10.302795 | 
2026-04-24 01:02:10.572926 | rockylinux-9 | ok: "artifacts"
2026-04-24 01:02:10.807610 | rockylinux-9 | ok: "docs"
2026-04-24 01:02:10.825611 | 
2026-04-24 01:02:10.825760 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-24 01:02:11.106831 | rockylinux-9 | changed: "logs"
2026-04-24 01:02:11.350849 | rockylinux-9 | changed: "artifacts"
2026-04-24 01:02:11.597986 | rockylinux-9 | changed: "docs"
2026-04-24 01:02:11.629786 | 
2026-04-24 01:02:11.629932 | PLAY RECAP
2026-04-24 01:02:11.629988 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-24 01:02:11.630020 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-24 01:02:11.630042 | 
2026-04-24 01:02:11.766079 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-24 01:02:11.768016 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-24 01:02:12.330742 | 
2026-04-24 01:02:12.330868 | PLAY [all]
2026-04-24 01:02:12.354831 | 
2026-04-24 01:02:12.354950 | TASK [setup-uv : Extract archive]
2026-04-24 01:02:16.222250 | rockylinux-9 | changed
2026-04-24 01:02:16.229365 | 
2026-04-24 01:02:16.229473 | TASK [setup-uv : Print version]
2026-04-24 01:02:16.268926 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:16.783343 | rockylinux-9 | ok: Runtime: 0:00:00.015715
2026-04-24 01:02:16.790677 | 
2026-04-24 01:02:16.790728 | PLAY RECAP
2026-04-24 01:02:16.790778 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-24 01:02:16.790802 | 
2026-04-24 01:02:16.905045 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-24 01:02:16.907279 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-24 01:02:17.500661 | 
2026-04-24 01:02:17.500790 | PLAY [all]
2026-04-24 01:02:17.511195 | 
2026-04-24 01:02:17.511266 | TASK [Copy inventory file for Zuul]
2026-04-24 01:02:18.905104 | rockylinux-9 | changed
2026-04-24 01:02:18.911016 | 
2026-04-24 01:02:18.911098 | TASK [Switch "ansible_host" to private IP]
2026-04-24 01:02:19.297536 | rockylinux-9 | changed: 1 replacements made
2026-04-24 01:02:19.305738 | 
2026-04-24 01:02:19.305802 | TASK [Run Molecule scenario]
2026-04-24 01:02:19.327228 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:27.463606 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:37.670129 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:47.846257 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:58.022355 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:59.149394 | rockylinux-9 | ERROR
2026-04-24 01:02:59.149873 | rockylinux-9 | {
2026-04-24 01:02:59.149917 | rockylinux-9 |   "delta": "0:00:38.605950",
2026-04-24 01:02:59.149946 | rockylinux-9 |   "end": "2026-04-24 01:02:58.616851",
2026-04-24 01:02:59.149975 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-24 01:02:59.150001 | rockylinux-9 |   "rc": 2,
2026-04-24 01:02:59.150029 | rockylinux-9 |   "start": "2026-04-24 01:02:20.010901"
2026-04-24 01:02:59.150055 | rockylinux-9 | }
failure
2026-04-24 01:02:59.153664 | 
2026-04-24 01:02:59.153727 | PLAY RECAP
2026-04-24 01:02:59.153787 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-24 01:02:59.153817 | 
2026-04-24 01:02:59.280729 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-24 01:02:59.282340 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-24 01:02:59.847872 | 
2026-04-24 01:02:59.848138 | PLAY [all]
2026-04-24 01:02:59.860251 | 
2026-04-24 01:02:59.860336 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-24 01:02:59.905981 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:02:59.913592 | 
2026-04-24 01:02:59.913771 | TASK [fetch-output : Set log path for single node]
2026-04-24 01:02:59.957290 | rockylinux-9 | ok
2026-04-24 01:02:59.962897 | 
2026-04-24 01:02:59.962964 | LOOP [fetch-output : Ensure local output dirs]
2026-04-24 01:03:00.392041 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/work/logs"
2026-04-24 01:03:00.601782 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/work/artifacts"
2026-04-24 01:03:00.815118 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a384a82bcc4e457f9a6639378002a59a/work/docs"
2026-04-24 01:03:00.831591 | 
2026-04-24 01:03:00.831739 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-24 01:03:01.554679 | rockylinux-9 | ok
2026-04-24 01:03:01.555036 | rockylinux-9 | changed: All items complete
2026-04-24 01:03:01.555085 | 
2026-04-24 01:03:02.108046 | rockylinux-9 | changed: .d..t...... ./
2026-04-24 01:03:02.635028 | rockylinux-9 | changed: .d..t...... ./
2026-04-24 01:03:02.657197 | 
2026-04-24 01:03:02.657395 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-24 01:03:03.091462 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007510
2026-04-24 01:03:03.325055 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009428
2026-04-24 01:03:03.348326 | 
2026-04-24 01:03:03.348475 | PLAY [all]
2026-04-24 01:03:03.356267 | 
2026-04-24 01:03:03.356340 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-24 01:03:03.846102 | rockylinux-9 | changed
2026-04-24 01:03:03.852467 | 
2026-04-24 01:03:03.852518 | PLAY RECAP
2026-04-24 01:03:03.852568 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-24 01:03:03.852591 | 
2026-04-24 01:03:04.000946 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-24 01:03:04.003119 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-24 01:03:04.547186 | 
2026-04-24 01:03:04.547928 | PLAY [localhost]
2026-04-24 01:03:04.557590 | 
2026-04-24 01:03:04.557657 | TASK [Generate Zuul manifest]
2026-04-24 01:03:04.578428 | localhost | ok
2026-04-24 01:03:04.596269 | 
2026-04-24 01:03:04.596347 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-24 01:03:04.910777 | localhost | changed
2026-04-24 01:03:04.924474 | 
2026-04-24 01:03:04.924561 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-24 01:03:04.975004 | localhost | ok
2026-04-24 01:03:04.984147 | 
2026-04-24 01:03:04.984234 | TASK [Upload logs]
2026-04-24 01:03:05.006988 | localhost | ok
2026-04-24 01:03:05.066425 | 
2026-04-24 01:03:05.066570 | TASK [Set zuul-log-path fact]
2026-04-24 01:03:05.089498 | localhost | ok
2026-04-24 01:03:05.103672 | 
2026-04-24 01:03:05.103746 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-24 01:03:05.137269 | localhost | ok
2026-04-24 01:03:05.144873 | 
2026-04-24 01:03:05.144935 | TASK [upload-logs : Create log directories]
2026-04-24 01:03:05.552796 | localhost | changed
2026-04-24 01:03:05.558684 | 
2026-04-24 01:03:05.558754 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-24 01:03:05.956502 | localhost -> localhost | ok: Runtime: 0:00:00.005331
2026-04-24 01:03:05.965974 | 
2026-04-24 01:03:05.966063 | TASK [upload-logs : Upload logs to log server]
2026-04-24 01:03:06.596518 | localhost | Output suppressed because no_log was given
2026-04-24 01:03:06.601787 | 
2026-04-24 01:03:06.601886 | LOOP [upload-logs : Compress console log and json output]
2026-04-24 01:03:06.650671 | localhost | skipping: Conditional result was False
2026-04-24 01:03:06.657737 | localhost | skipping: Conditional result was False
2026-04-24 01:03:06.668648 | 
2026-04-24 01:03:06.668891 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-24 01:03:06.718599 | localhost | skipping: Conditional result was False
2026-04-24 01:03:06.719004 | 
2026-04-24 01:03:06.723135 | localhost | skipping: Conditional result was False
2026-04-24 01:03:06.735357 | 
2026-04-24 01:03:06.735562 | LOOP [upload-logs : Upload console log and json output]