2026-03-17 10:15:41.315807 | Job console starting
2026-03-17 10:15:41.418763 | Updating git repos
2026-03-17 10:15:41.453587 | Cloning repos into workspace
2026-03-17 10:15:42.034139 | Restoring repo states
2026-03-17 10:15:42.042142 | Merging changes
2026-03-17 10:15:42.800786 | Checking out repos
2026-03-17 10:15:42.931552 | Preparing playbooks
2026-03-17 10:16:13.254211 | Running Ansible setup
2026-03-17 10:16:17.184842 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-17 10:16:17.802102 | 
2026-03-17 10:16:17.904786 | PLAY [localhost]
2026-03-17 10:16:17.944330 | 
2026-03-17 10:16:17.944495 | TASK [Gathering Facts]
2026-03-17 10:16:19.067533 | localhost | ok
2026-03-17 10:16:19.079882 | 
2026-03-17 10:16:19.080029 | TASK [Setup log path fact]
2026-03-17 10:16:19.101272 | localhost | ok
2026-03-17 10:16:19.113533 | 
2026-03-17 10:16:19.113647 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 10:16:19.143898 | localhost | ok
2026-03-17 10:16:19.154550 | 
2026-03-17 10:16:19.154675 | TASK [emit-job-header : Print job information]
2026-03-17 10:16:19.195127 | # Job Information
2026-03-17 10:16:19.195432 | Ansible Version: 2.16.16
2026-03-17 10:16:19.195538 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9
2026-03-17 10:16:19.195610 | Pipeline: check
2026-03-17 10:16:19.195662 | Executor: 0a8996d2b663
2026-03-17 10:16:19.195712 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/102
2026-03-17 10:16:19.195776 | Event ID: a6d8eaf0-219a-11f1-9ba5-6a309433c30d
2026-03-17 10:16:19.200763 | 
2026-03-17 10:16:19.200844 | LOOP [emit-job-header : Print node information]
2026-03-17 10:16:19.312241 | localhost | ok:
2026-03-17 10:16:19.312555 | localhost | # Node Information
2026-03-17 10:16:19.312601 | localhost | Inventory Hostname: rockylinux-9
2026-03-17 10:16:19.312638 | localhost | Hostname: np0000159142
2026-03-17 10:16:19.312669 | localhost | Username: zuul
2026-03-17 10:16:19.312702 | localhost | Distro: Rocky 9.7
2026-03-17 10:16:19.312732 | localhost | Provider: yul1
2026-03-17 10:16:19.312760 | localhost | Region: ca-ymq-1
2026-03-17 10:16:19.312787 | localhost | Label: rockylinux-9
2026-03-17 10:16:19.312814 | localhost | Product Name: OpenStack Nova
2026-03-17 10:16:19.312842 | localhost | Interface IP: 199.204.45.132
2026-03-17 10:16:19.337461 | 
2026-03-17 10:16:19.338263 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-17 10:16:19.812772 | localhost -> localhost | changed
2026-03-17 10:16:19.819788 | 
2026-03-17 10:16:19.819884 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-17 10:16:20.781097 | localhost -> localhost | changed
2026-03-17 10:16:20.795129 | 
2026-03-17 10:16:20.795262 | PLAY [all]
2026-03-17 10:16:20.804381 | 
2026-03-17 10:16:20.804490 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-17 10:16:21.055230 | rockylinux-9 -> localhost | ok
2026-03-17 10:16:21.063597 | 
2026-03-17 10:16:21.063788 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-17 10:16:21.093169 | rockylinux-9 | ok
2026-03-17 10:16:21.110344 | rockylinux-9 | included: /var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-17 10:16:21.115797 | 
2026-03-17 10:16:21.115865 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-17 10:16:22.912824 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-03-17 10:16:22.913273 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/work/9a49ab301ce64445ba0a8c05cfa0d69d_id_rsa
2026-03-17 10:16:22.913357 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/work/9a49ab301ce64445ba0a8c05cfa0d69d_id_rsa.pub
2026-03-17 10:16:22.913391 | rockylinux-9 -> localhost | The key fingerprint is:
2026-03-17 10:16:22.913423 | rockylinux-9 -> localhost | SHA256:a0sXelHPgqVecO1lmCB9VuKLQ5nl5EUOLPg/yihumSM zuul-build-sshkey
2026-03-17 10:16:22.913484 | rockylinux-9 -> localhost | The key's randomart image is:
2026-03-17 10:16:22.913523 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-03-17 10:16:22.913562 | rockylinux-9 -> localhost | |          .o..=o+|
2026-03-17 10:16:22.913593 | rockylinux-9 -> localhost | |          ..o@+B |
2026-03-17 10:16:22.913621 | rockylinux-9 -> localhost | |          ..B+B +|
2026-03-17 10:16:22.913650 | rockylinux-9 -> localhost | |           O.= + |
2026-03-17 10:16:22.913678 | rockylinux-9 -> localhost | |        S = =.=  |
2026-03-17 10:16:22.913705 | rockylinux-9 -> localhost | |         + + oo  |
2026-03-17 10:16:22.913733 | rockylinux-9 -> localhost | |        =o+o . . |
2026-03-17 10:16:22.913763 | rockylinux-9 -> localhost | |      Eo*+. o    |
2026-03-17 10:16:22.913797 | rockylinux-9 -> localhost | |       +oo       |
2026-03-17 10:16:22.913827 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-03-17 10:16:22.913901 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.347381
2026-03-17 10:16:22.923717 | 
2026-03-17 10:16:22.923837 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-17 10:16:22.944110 | rockylinux-9 | ok
2026-03-17 10:16:23.027897 | rockylinux-9 | included: /var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-17 10:16:23.040163 | 
2026-03-17 10:16:23.040300 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-17 10:16:23.075414 | rockylinux-9 | skipping: Conditional result was False
2026-03-17 10:16:23.083034 | 
2026-03-17 10:16:23.083130 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-17 10:16:23.603726 | rockylinux-9 | changed
2026-03-17 10:16:23.610197 | 
2026-03-17 10:16:23.610320 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-17 10:16:23.894600 | rockylinux-9 | ok
2026-03-17 10:16:23.901634 | 
2026-03-17 10:16:23.901731 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-17 10:16:24.854660 | rockylinux-9 | changed
2026-03-17 10:16:24.861328 | 
2026-03-17 10:16:24.861418 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-17 10:16:25.757111 | rockylinux-9 | changed
2026-03-17 10:16:25.762065 | 
2026-03-17 10:16:25.762136 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-17 10:16:25.786147 | rockylinux-9 | skipping: Conditional result was False
2026-03-17 10:16:25.795801 | 
2026-03-17 10:16:25.795891 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-17 10:16:26.126690 | rockylinux-9 -> localhost | changed
2026-03-17 10:16:26.142571 | 
2026-03-17 10:16:26.142683 | TASK [add-build-sshkey : Add back temp key]
2026-03-17 10:16:26.428099 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/work/9a49ab301ce64445ba0a8c05cfa0d69d_id_rsa (zuul-build-sshkey)
2026-03-17 10:16:26.428547 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012910
2026-03-17 10:16:26.436694 | 
2026-03-17 10:16:26.436760 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-17 10:16:26.814366 | rockylinux-9 | ok
2026-03-17 10:16:26.825053 | 
2026-03-17 10:16:26.825134 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-17 10:16:26.855120 | rockylinux-9 | skipping: Conditional result was False
2026-03-17 10:16:26.870931 | 
2026-03-17 10:16:26.871020 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-17 10:16:27.264517 | rockylinux-9 | ok
2026-03-17 10:16:27.500323 | 
2026-03-17 10:16:27.500448 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-17 10:16:28.422476 | rockylinux-9 | Output suppressed because no_log was given
2026-03-17 10:16:28.430036 | 
2026-03-17 10:16:28.430102 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-17 10:16:28.704274 | rockylinux-9 | ok: "logs"
2026-03-17 10:16:28.772426 | rockylinux-9 | ok: All items complete
2026-03-17 10:16:28.772598 | 
2026-03-17 10:16:28.935793 | rockylinux-9 | ok: "artifacts"
2026-03-17 10:16:29.186962 | rockylinux-9 | ok: "docs"
2026-03-17 10:16:29.204761 | 
2026-03-17 10:16:29.204871 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-17 10:16:29.509980 | rockylinux-9 | changed: "logs"
2026-03-17 10:16:29.772122 | rockylinux-9 | changed: "artifacts"
2026-03-17 10:16:30.009260 | rockylinux-9 | changed: "docs"
2026-03-17 10:16:30.025238 | 
2026-03-17 10:16:30.101960 | PLAY RECAP
2026-03-17 10:16:30.102380 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 10:16:30.102887 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-17 10:16:30.103025 | 
2026-03-17 10:16:30.297399 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-17 10:16:30.298831 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-17 10:16:31.032482 | 
2026-03-17 10:16:31.032645 | PLAY [all]
2026-03-17 10:16:31.045913 | 
2026-03-17 10:16:31.046069 | TASK [setup-uv : Extract archive]
2026-03-17 10:16:33.978017 | rockylinux-9 | changed
2026-03-17 10:16:34.166358 | 
2026-03-17 10:16:34.166778 | TASK [setup-uv : Print version]
2026-03-17 10:16:34.220208 | [rockylinux-9] Waiting on logger
2026-03-17 10:16:34.736543 | rockylinux-9 | ok: Runtime: 0:00:00.016214
2026-03-17 10:16:34.754349 | 
2026-03-17 10:16:34.754449 | PLAY RECAP
2026-03-17 10:16:34.754508 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 10:16:34.754543 | 
2026-03-17 10:16:34.868492 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-17 10:16:34.870576 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-17 10:16:35.489074 | 
2026-03-17 10:16:35.489235 | PLAY [all]
2026-03-17 10:16:35.499587 | 
2026-03-17 10:16:35.626991 | TASK [Copy inventory file for Zuul]
2026-03-17 10:16:37.028105 | rockylinux-9 | changed
2026-03-17 10:16:37.107581 | 
2026-03-17 10:16:37.107720 | TASK [Switch "ansible_host" to private IP]
2026-03-17 10:16:37.495693 | rockylinux-9 | changed: 1 replacements made
2026-03-17 10:16:37.504472 | 
2026-03-17 10:16:37.504550 | TASK [Run Molecule scenario]
2026-03-17 10:16:37.527875 | [rockylinux-9] Waiting on logger
2026-03-17 10:16:45.670196 | [rockylinux-9] Waiting on logger
2026-03-17 10:16:55.846219 | [rockylinux-9] Waiting on logger
2026-03-17 10:17:06.022141 | [rockylinux-9] Waiting on logger
2026-03-17 10:17:16.198281 | [rockylinux-9] Waiting on logger
2026-03-17 10:17:25.350045 | [rockylinux-9] Waiting on logger
2026-03-17 10:17:35.525944 | [rockylinux-9] Waiting on logger
2026-03-17 10:17:45.701742 | [rockylinux-9] Waiting on logger
2026-03-17 10:17:55.877914 | [rockylinux-9] Waiting on logger
2026-03-17 10:18:06.059650 | [rockylinux-9] Waiting on logger
2026-03-17 10:18:16.234040 | [rockylinux-9] Waiting on logger
2026-03-17 10:18:25.382099 | [rockylinux-9] Waiting on logger
2026-03-17 10:18:27.630239 | rockylinux-9 | ok: Runtime: 0:01:49.326262
2026-03-17 10:18:27.717455 | 
2026-03-17 10:18:27.717560 | PLAY RECAP
2026-03-17 10:18:27.717627 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-17 10:18:27.717660 | 
2026-03-17 10:18:27.818457 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-17 10:18:27.820040 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-17 10:18:28.460847 | 
2026-03-17 10:18:29.289003 | PLAY [all]
2026-03-17 10:18:29.304595 | 
2026-03-17 10:18:29.304737 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-17 10:18:29.341014 | rockylinux-9 | skipping: Conditional result was False
2026-03-17 10:18:29.348528 | 
2026-03-17 10:18:29.348659 | TASK [fetch-output : Set log path for single node]
2026-03-17 10:18:29.390095 | rockylinux-9 | ok
2026-03-17 10:18:29.397817 | 
2026-03-17 10:18:29.397932 | LOOP [fetch-output : Ensure local output dirs]
2026-03-17 10:18:29.802588 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/work/logs"
2026-03-17 10:18:30.046348 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/work/artifacts"
2026-03-17 10:18:30.283898 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9a49ab301ce64445ba0a8c05cfa0d69d/work/docs"
2026-03-17 10:18:30.294096 | 
2026-03-17 10:18:30.294209 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-17 10:18:32.163974 | rockylinux-9 | changed: .d..t...... ./
2026-03-17 10:18:32.164272 | rockylinux-9 | changed: All items complete
2026-03-17 10:18:32.164318 | 
2026-03-17 10:18:32.695169 | rockylinux-9 | changed: .d..t...... ./
2026-03-17 10:18:33.223267 | rockylinux-9 | changed: .d..t...... ./
2026-03-17 10:18:33.244710 | 
2026-03-17 10:18:33.244835 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-17 10:18:33.694140 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006031
2026-03-17 10:18:34.107454 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.157104
2026-03-17 10:18:34.127626 | 
2026-03-17 10:18:34.127751 | PLAY [all]
2026-03-17 10:18:34.134939 | 
2026-03-17 10:18:34.135003 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-17 10:18:34.657948 | rockylinux-9 | changed
2026-03-17 10:18:34.662943 | 
2026-03-17 10:18:34.662991 | PLAY RECAP
2026-03-17 10:18:34.663036 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-17 10:18:34.663059 | 
2026-03-17 10:18:34.754771 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-17 10:18:34.756128 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-17 10:18:35.311249 | 
2026-03-17 10:18:35.376262 | PLAY [localhost]
2026-03-17 10:18:35.391466 | 
2026-03-17 10:18:35.391568 | TASK [Generate Zuul manifest]
2026-03-17 10:18:35.412552 | localhost | ok
2026-03-17 10:18:35.428992 | 
2026-03-17 10:18:35.429072 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-17 10:18:35.731046 | localhost | changed
2026-03-17 10:18:35.748528 | 
2026-03-17 10:18:35.748743 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-17 10:18:35.953575 | localhost | ok
2026-03-17 10:18:35.960598 | 
2026-03-17 10:18:35.960675 | TASK [Upload logs]
2026-03-17 10:18:35.983627 | localhost | ok
2026-03-17 10:18:36.053027 | 
2026-03-17 10:18:36.053145 | TASK [Set zuul-log-path fact]
2026-03-17 10:18:36.073816 | localhost | ok
2026-03-17 10:18:36.093225 | 
2026-03-17 10:18:36.188337 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-17 10:18:36.223726 | localhost | ok
2026-03-17 10:18:36.232499 | 
2026-03-17 10:18:36.232571 | TASK [upload-logs : Create log directories]
2026-03-17 10:18:36.944113 | localhost | changed
2026-03-17 10:18:36.950445 | 
2026-03-17 10:18:36.950510 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-17 10:18:37.376812 | localhost -> localhost | ok: Runtime: 0:00:00.004800
2026-03-17 10:18:37.384616 | 
2026-03-17 10:18:37.384729 | TASK [upload-logs : Upload logs to log server]
2026-03-17 10:18:37.874685 | localhost | Output suppressed because no_log was given
2026-03-17 10:18:37.881863 | 
2026-03-17 10:18:37.881994 | LOOP [upload-logs : Compress console log and json output]
2026-03-17 10:18:37.932069 | localhost | skipping: Conditional result was False
2026-03-17 10:18:37.939646 | localhost | skipping: Conditional result was False
2026-03-17 10:18:37.954878 | 
2026-03-17 10:18:37.955033 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-17 10:18:37.997133 | localhost | skipping: Conditional result was False
2026-03-17 10:18:37.997396 | 
2026-03-17 10:18:38.001911 | localhost | skipping: Conditional result was False
2026-03-17 10:18:38.018881 | 
2026-03-17 10:18:38.019028 | LOOP [upload-logs : Upload console log and json output]