2026-04-15 00:59:56.892440 | Job console starting
2026-04-15 00:59:56.900195 | Updating git repos
2026-04-15 00:59:56.927374 | Cloning repos into workspace
2026-04-15 00:59:56.976275 | Restoring repo states
2026-04-15 00:59:56.982277 | Merging changes
2026-04-15 00:59:57.251545 | Checking out repos
2026-04-15 00:59:57.277956 | Preparing playbooks
2026-04-15 00:59:58.386053 | Running Ansible setup
2026-04-15 01:00:02.313479 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 01:00:02.891176 | 
2026-04-15 01:00:03.199949 | PLAY [localhost]
2026-04-15 01:00:03.214275 | 
2026-04-15 01:00:03.214347 | TASK [Gathering Facts]
2026-04-15 01:00:04.049224 | localhost | ok
2026-04-15 01:00:04.058709 | 
2026-04-15 01:00:04.058808 | TASK [Setup log path fact]
2026-04-15 01:00:04.076635 | localhost | ok
2026-04-15 01:00:04.092448 | 
2026-04-15 01:00:04.092516 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 01:00:04.121672 | localhost | ok
2026-04-15 01:00:04.130112 | 
2026-04-15 01:00:04.130217 | TASK [emit-job-header : Print job information]
2026-04-15 01:00:04.161761 | # Job Information
2026-04-15 01:00:04.162002 | Ansible Version: 2.16.16
2026-04-15 01:00:04.162100 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-04-15 01:00:04.162157 | Pipeline: check
2026-04-15 01:00:04.162203 | Executor: 0a8996d2b663
2026-04-15 01:00:04.162246 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/102
2026-04-15 01:00:04.162295 | Event ID: 24745760-3866-11f1-9631-53252c2f9552
2026-04-15 01:00:04.166996 | 
2026-04-15 01:00:04.167079 | LOOP [emit-job-header : Print node information]
2026-04-15 01:00:04.269938 | localhost | ok:
2026-04-15 01:00:04.270770 | localhost | # Node Information
2026-04-15 01:00:04.270856 | localhost | Inventory Hostname: rockylinux-9
2026-04-15 01:00:04.270918 | localhost | Hostname: np0000166309
2026-04-15 01:00:04.270968 | localhost | Username: zuul
2026-04-15 01:00:04.271022 | localhost | Distro: Rocky 9.7
2026-04-15 01:00:04.271068 | localhost | Provider: yul1
2026-04-15 01:00:04.271113 | localhost | Region: ca-ymq-1
2026-04-15 01:00:04.271155 | localhost | Label: rockylinux-9
2026-04-15 01:00:04.271197 | localhost | Product Name: OpenStack Nova
2026-04-15 01:00:04.271240 | localhost | Interface IP: 199.19.213.14
2026-04-15 01:00:04.284409 | 
2026-04-15 01:00:04.284546 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-15 01:00:04.693160 | localhost -> localhost | changed
2026-04-15 01:00:04.699712 | 
2026-04-15 01:00:04.699821 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-15 01:00:05.942315 | localhost -> localhost | changed
2026-04-15 01:00:05.951398 | 
2026-04-15 01:00:05.951483 | PLAY [all]
2026-04-15 01:00:05.960225 | 
2026-04-15 01:00:05.960294 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-15 01:00:06.201764 | rockylinux-9 -> localhost | ok
2026-04-15 01:00:06.207017 | 
2026-04-15 01:00:06.207080 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-15 01:00:06.234761 | rockylinux-9 | ok
2026-04-15 01:00:06.255469 | rockylinux-9 | included: /var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-15 01:00:06.261237 | 
2026-04-15 01:00:06.261308 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-15 01:00:07.661715 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-15 01:00:07.661873 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/work/d9bedd12af164d4eb3df6aa4e549eb01_id_rsa
2026-04-15 01:00:07.661903 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/work/d9bedd12af164d4eb3df6aa4e549eb01_id_rsa.pub
2026-04-15 01:00:07.661928 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-15 01:00:07.661953 | rockylinux-9 -> localhost | SHA256:bJb4ccp24nOJXzkKTC0o8gLSEZgXy1OzA8r047awUmg zuul-build-sshkey
2026-04-15 01:00:07.661987 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-15 01:00:07.662008 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-15 01:00:07.662033 | rockylinux-9 -> localhost | | ++.o            |
2026-04-15 01:00:07.662054 | rockylinux-9 -> localhost | |=oo= o           |
2026-04-15 01:00:07.662073 | rockylinux-9 -> localhost | |.o=oo            |
2026-04-15 01:00:07.662093 | rockylinux-9 -> localhost | | o.o.. + o       |
2026-04-15 01:00:07.662112 | rockylinux-9 -> localhost | |+E+o. o S o      |
2026-04-15 01:00:07.662132 | rockylinux-9 -> localhost | |oo+o.. B =   .   |
2026-04-15 01:00:07.662152 | rockylinux-9 -> localhost | |.....   O...+    |
2026-04-15 01:00:07.662171 | rockylinux-9 -> localhost | |.  .   oo+oo .   |
2026-04-15 01:00:07.662193 | rockylinux-9 -> localhost | |        .+o      |
2026-04-15 01:00:07.662213 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-15 01:00:07.662256 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.987933
2026-04-15 01:00:07.666820 | 
2026-04-15 01:00:07.666880 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-15 01:00:07.698738 | rockylinux-9 | ok
2026-04-15 01:00:07.707483 | rockylinux-9 | included: /var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-15 01:00:07.714978 | 
2026-04-15 01:00:07.715037 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-15 01:00:07.738664 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:00:07.748080 | 
2026-04-15 01:00:07.748140 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-15 01:00:08.255730 | rockylinux-9 | changed
2026-04-15 01:00:08.262269 | 
2026-04-15 01:00:08.262355 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-15 01:00:08.538485 | rockylinux-9 | ok
2026-04-15 01:00:08.544423 | 
2026-04-15 01:00:08.544507 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-15 01:00:09.526404 | rockylinux-9 | changed
2026-04-15 01:00:09.532401 | 
2026-04-15 01:00:09.532471 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-15 01:00:10.514253 | rockylinux-9 | changed
2026-04-15 01:00:10.518761 | 
2026-04-15 01:00:10.518829 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-15 01:00:10.552358 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:00:10.557843 | 
2026-04-15 01:00:10.557915 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-15 01:00:10.926539 | rockylinux-9 -> localhost | changed
2026-04-15 01:00:10.935711 | 
2026-04-15 01:00:10.935777 | TASK [add-build-sshkey : Add back temp key]
2026-04-15 01:00:11.377903 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/work/d9bedd12af164d4eb3df6aa4e549eb01_id_rsa (zuul-build-sshkey)
2026-04-15 01:00:11.378204 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.015828
2026-04-15 01:00:11.385490 | 
2026-04-15 01:00:11.385551 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-15 01:00:11.776935 | rockylinux-9 | ok
2026-04-15 01:00:11.781163 | 
2026-04-15 01:00:11.781226 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-15 01:00:11.805446 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:00:11.820482 | 
2026-04-15 01:00:11.820585 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-15 01:00:12.249571 | rockylinux-9 | ok
2026-04-15 01:00:12.256824 | 
2026-04-15 01:00:12.256916 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-15 01:00:13.224908 | rockylinux-9 | Output suppressed because no_log was given
2026-04-15 01:00:13.236009 | 
2026-04-15 01:00:13.236082 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-15 01:00:13.502403 | rockylinux-9 | ok: "logs"
2026-04-15 01:00:13.504805 | rockylinux-9 | ok: All items complete
2026-04-15 01:00:13.504859 | 
2026-04-15 01:00:13.764733 | rockylinux-9 | ok: "artifacts"
2026-04-15 01:00:14.020630 | rockylinux-9 | ok: "docs"
2026-04-15 01:00:14.338667 | 
2026-04-15 01:00:14.338793 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-15 01:00:14.591801 | rockylinux-9 | changed: "logs"
2026-04-15 01:00:14.835554 | rockylinux-9 | changed: "artifacts"
2026-04-15 01:00:15.087721 | rockylinux-9 | changed: "docs"
2026-04-15 01:00:15.096021 | 
2026-04-15 01:00:15.096125 | PLAY RECAP
2026-04-15 01:00:15.096171 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:00:15.096203 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-15 01:00:15.096225 | 
2026-04-15 01:00:15.201905 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-15 01:00:15.204755 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 01:00:15.777311 | 
2026-04-15 01:00:15.777438 | PLAY [all]
2026-04-15 01:00:15.788191 | 
2026-04-15 01:00:15.788289 | TASK [setup-uv : Extract archive]
2026-04-15 01:00:18.751058 | rockylinux-9 | changed
2026-04-15 01:00:18.759359 | 
2026-04-15 01:00:18.759541 | TASK [setup-uv : Print version]
2026-04-15 01:00:18.786198 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:19.298651 | rockylinux-9 | ok: Runtime: 0:00:00.014759
2026-04-15 01:00:19.305608 | 
2026-04-15 01:00:19.305661 | PLAY RECAP
2026-04-15 01:00:19.305708 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:00:19.305732 | 
2026-04-15 01:00:19.402512 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-15 01:00:19.403725 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 01:00:20.022611 | 
2026-04-15 01:00:20.022764 | PLAY [all]
2026-04-15 01:00:20.033864 | 
2026-04-15 01:00:20.033974 | TASK [Copy inventory file for Zuul]
2026-04-15 01:00:21.372084 | rockylinux-9 | changed
2026-04-15 01:00:21.378520 | 
2026-04-15 01:00:21.378674 | TASK [Switch "ansible_host" to private IP]
2026-04-15 01:00:21.735455 | rockylinux-9 | changed: 1 replacements made
2026-04-15 01:00:21.741588 | 
2026-04-15 01:00:21.741653 | TASK [Run Molecule scenario]
2026-04-15 01:00:21.763180 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:29.908166 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:40.074033 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:50.246085 | [rockylinux-9] Waiting on logger
2026-04-15 01:00:57.591294 | localhost | Timeout exception waiting for the logger. Please check connectivity to [199.19.213.14:19885]
2026-04-15 01:02:39.085537 | rockylinux-9 | ok: Runtime: 0:02:16.847538
2026-04-15 01:02:39.091328 | 
2026-04-15 01:02:39.091390 | PLAY RECAP
2026-04-15 01:02:39.091441 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-15 01:02:39.091468 | 
2026-04-15 01:02:39.202247 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-15 01:02:39.203771 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:02:39.760278 | 
2026-04-15 01:02:39.760390 | PLAY [all]
2026-04-15 01:02:39.770734 | 
2026-04-15 01:02:39.770808 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-15 01:02:39.815207 | rockylinux-9 | skipping: Conditional result was False
2026-04-15 01:02:39.824627 | 
2026-04-15 01:02:39.824788 | TASK [fetch-output : Set log path for single node]
2026-04-15 01:02:39.866373 | rockylinux-9 | ok
2026-04-15 01:02:39.873286 | 
2026-04-15 01:02:39.873377 | LOOP [fetch-output : Ensure local output dirs]
2026-04-15 01:02:40.226726 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/work/logs"
2026-04-15 01:02:40.435933 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/work/artifacts"
2026-04-15 01:02:40.633382 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d9bedd12af164d4eb3df6aa4e549eb01/work/docs"
2026-04-15 01:02:40.646777 | 
2026-04-15 01:02:40.646971 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-15 01:02:41.301820 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:02:41.302069 | rockylinux-9 | changed: All items complete
2026-04-15 01:02:41.302108 | 
2026-04-15 01:02:41.801751 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:02:42.326509 | rockylinux-9 | changed: .d..t...... ./
2026-04-15 01:02:42.351868 | 
2026-04-15 01:02:42.352021 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-15 01:02:42.731775 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006854
2026-04-15 01:02:42.943221 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006678
2026-04-15 01:02:42.959014 | 
2026-04-15 01:02:42.959133 | PLAY [all]
2026-04-15 01:02:42.964810 | 
2026-04-15 01:02:42.964883 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-15 01:02:43.400368 | rockylinux-9 | changed
2026-04-15 01:02:43.407061 | 
2026-04-15 01:02:43.407138 | PLAY RECAP
2026-04-15 01:02:43.407199 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-15 01:02:43.407233 | 
2026-04-15 01:02:43.522763 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-15 01:02:43.524789 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-15 01:02:44.059765 | 
2026-04-15 01:02:44.059881 | PLAY [localhost]
2026-04-15 01:02:44.069286 | 
2026-04-15 01:02:44.069355 | TASK [Generate Zuul manifest]
2026-04-15 01:02:44.088435 | localhost | ok
2026-04-15 01:02:44.106383 | 
2026-04-15 01:02:44.106457 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-15 01:02:44.411152 | localhost | changed
2026-04-15 01:02:44.424661 | 
2026-04-15 01:02:44.424735 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-15 01:02:44.465621 | localhost | ok
2026-04-15 01:02:44.474401 | 
2026-04-15 01:02:44.474488 | TASK [Upload logs]
2026-04-15 01:02:44.494610 | localhost | ok
2026-04-15 01:02:44.554482 | 
2026-04-15 01:02:44.554632 | TASK [Set zuul-log-path fact]
2026-04-15 01:02:44.573058 | localhost | ok
2026-04-15 01:02:44.589118 | 
2026-04-15 01:02:44.589237 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-15 01:02:44.620159 | localhost | ok
2026-04-15 01:02:44.630133 | 
2026-04-15 01:02:44.630220 | TASK [upload-logs : Create log directories]
2026-04-15 01:02:45.046581 | localhost | changed
2026-04-15 01:02:45.053135 | 
2026-04-15 01:02:45.053230 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-15 01:02:45.451982 | localhost -> localhost | ok: Runtime: 0:00:00.005523
2026-04-15 01:02:45.458949 | 
2026-04-15 01:02:45.459011 | TASK [upload-logs : Upload logs to log server]
2026-04-15 01:02:45.895020 | localhost | Output suppressed because no_log was given
2026-04-15 01:02:45.900071 | 
2026-04-15 01:02:45.900158 | LOOP [upload-logs : Compress console log and json output]
2026-04-15 01:02:45.945904 | localhost | skipping: Conditional result was False
2026-04-15 01:02:45.952842 | localhost | skipping: Conditional result was False
2026-04-15 01:02:45.964347 | 
2026-04-15 01:02:45.964512 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-15 01:02:46.006785 | localhost | skipping: Conditional result was False
2026-04-15 01:02:46.007203 | 
2026-04-15 01:02:46.010858 | localhost | skipping: Conditional result was False
2026-04-15 01:02:46.028637 | 
2026-04-15 01:02:46.028769 | LOOP [upload-logs : Upload console log and json output]