2026-04-28 06:34:32.867702 | Job console starting
2026-04-28 06:34:32.876581 | Updating git repos
2026-04-28 06:34:32.899333 | Cloning repos into workspace
2026-04-28 06:34:33.264431 | Restoring repo states
2026-04-28 06:34:33.320649 | Merging changes
2026-04-28 06:34:33.768879 | Checking out repos
2026-04-28 06:34:33.814317 | Preparing playbooks
2026-04-28 06:34:35.345688 | Running Ansible setup
2026-04-28 06:34:39.134071 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-28 06:34:39.798630 | 
2026-04-28 06:34:39.921564 | PLAY [localhost]
2026-04-28 06:34:39.933438 | 
2026-04-28 06:34:39.933569 | TASK [Gathering Facts]
2026-04-28 06:34:40.812863 | localhost | ok
2026-04-28 06:34:40.838128 | 
2026-04-28 06:34:40.838295 | TASK [Setup log path fact]
2026-04-28 06:34:40.869394 | localhost | ok
2026-04-28 06:34:40.885192 | 
2026-04-28 06:34:40.885341 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-28 06:34:40.915877 | localhost | ok
2026-04-28 06:34:40.926009 | 
2026-04-28 06:34:40.926119 | TASK [emit-job-header : Print job information]
2026-04-28 06:34:40.986018 | # Job Information
2026-04-28 06:34:40.986212 | Ansible Version: 2.16.16
2026-04-28 06:34:40.986260 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-28 06:34:40.986292 | Pipeline: check
2026-04-28 06:34:40.986322 | Executor: 0a8996d2b663
2026-04-28 06:34:40.986351 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/123
2026-04-28 06:34:40.986383 | Event ID: dc61fe80-42cb-11f1-988f-93e08c803a2a
2026-04-28 06:34:40.990177 | 
2026-04-28 06:34:40.990270 | LOOP [emit-job-header : Print node information]
2026-04-28 06:34:41.110375 | localhost | ok:
2026-04-28 06:34:41.110561 | localhost | # Node Information
2026-04-28 06:34:41.110602 | localhost | Inventory Hostname: rockylinux-9
2026-04-28 06:34:41.110634 | localhost | Hostname: np0000170967
2026-04-28 06:34:41.110664 | localhost | Username: zuul
2026-04-28 06:34:41.110699 | localhost | Distro: Rocky 9.7
2026-04-28 06:34:41.110729 | localhost | Provider: yul1
2026-04-28 06:34:41.110758 | localhost | Region: ca-ymq-1
2026-04-28 06:34:41.110786 | localhost | Label: rockylinux-9
2026-04-28 06:34:41.110813 | localhost | Product Name: OpenStack Nova
2026-04-28 06:34:41.110841 | localhost | Interface IP: 199.204.45.115
2026-04-28 06:34:41.125869 | 
2026-04-28 06:34:41.126032 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-28 06:34:41.697759 | localhost -> localhost | changed
2026-04-28 06:34:41.702815 | 
2026-04-28 06:34:41.702885 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-28 06:34:42.701652 | localhost -> localhost | changed
2026-04-28 06:34:42.709323 | 
2026-04-28 06:34:42.709411 | PLAY [all]
2026-04-28 06:34:42.718417 | 
2026-04-28 06:34:42.718517 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-28 06:34:42.966679 | rockylinux-9 -> localhost | ok
2026-04-28 06:34:42.972491 | 
2026-04-28 06:34:42.972562 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-28 06:34:43.005564 | rockylinux-9 | ok
2026-04-28 06:34:43.028128 | rockylinux-9 | included: /var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-28 06:34:43.034989 | 
2026-04-28 06:34:43.035067 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-28 06:34:43.714668 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-28 06:34:43.715656 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/work/a77863f0050841ada3174c8b10f277ad_id_rsa
2026-04-28 06:34:43.715704 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/work/a77863f0050841ada3174c8b10f277ad_id_rsa.pub
2026-04-28 06:34:43.715736 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-28 06:34:43.715767 | rockylinux-9 -> localhost | SHA256:XeX7hE53t97E8gYbIFVqP1Q6Y+uEx966/yZDT+sRtTo zuul-build-sshkey
2026-04-28 06:34:43.715813 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-28 06:34:43.715848 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-28 06:34:43.715890 | rockylinux-9 -> localhost | |             .o .|
2026-04-28 06:34:43.715924 | rockylinux-9 -> localhost | |            .+ o |
2026-04-28 06:34:43.715955 | rockylinux-9 -> localhost | |           .+ B .|
2026-04-28 06:34:43.715986 | rockylinux-9 -> localhost | |         ..o.* *o|
2026-04-28 06:34:43.716017 | rockylinux-9 -> localhost | |        S ....@o=|
2026-04-28 06:34:43.716465 | rockylinux-9 -> localhost | |             B=B*|
2026-04-28 06:34:43.716626 | rockylinux-9 -> localhost | |             E=O*|
2026-04-28 06:34:43.716668 | rockylinux-9 -> localhost | |              ***|
2026-04-28 06:34:43.716704 | rockylinux-9 -> localhost | |              +X*|
2026-04-28 06:34:43.716736 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-28 06:34:43.716826 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.222299
2026-04-28 06:34:43.731586 | 
2026-04-28 06:34:43.731954 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-28 06:34:43.763076 | rockylinux-9 | ok
2026-04-28 06:34:43.779862 | rockylinux-9 | included: /var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-28 06:34:43.796415 | 
2026-04-28 06:34:43.796556 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-28 06:34:43.825062 | rockylinux-9 | skipping: Conditional result was False
2026-04-28 06:34:43.837950 | 
2026-04-28 06:34:43.838021 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-28 06:34:44.360890 | rockylinux-9 | changed
2026-04-28 06:34:44.367872 | 
2026-04-28 06:34:44.367940 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-28 06:34:44.645703 | rockylinux-9 | ok
2026-04-28 06:34:44.651594 | 
2026-04-28 06:34:44.651660 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-28 06:34:45.725488 | rockylinux-9 | changed
2026-04-28 06:34:45.731881 | 
2026-04-28 06:34:45.732013 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-28 06:34:46.696879 | rockylinux-9 | changed
2026-04-28 06:34:46.701491 | 
2026-04-28 06:34:46.701555 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-28 06:34:46.726608 | rockylinux-9 | skipping: Conditional result was False
2026-04-28 06:34:46.736007 | 
2026-04-28 06:34:46.736172 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-28 06:34:47.254574 | rockylinux-9 -> localhost | changed
2026-04-28 06:34:47.270804 | 
2026-04-28 06:34:47.270895 | TASK [add-build-sshkey : Add back temp key]
2026-04-28 06:34:47.709185 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/work/a77863f0050841ada3174c8b10f277ad_id_rsa (zuul-build-sshkey)
2026-04-28 06:34:47.709370 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014736
2026-04-28 06:34:47.717365 | 
2026-04-28 06:34:47.717444 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-28 06:34:48.170757 | rockylinux-9 | ok
2026-04-28 06:34:48.175973 | 
2026-04-28 06:34:48.176040 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-28 06:34:48.211619 | rockylinux-9 | skipping: Conditional result was False
2026-04-28 06:34:48.227195 | 
2026-04-28 06:34:48.227323 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-28 06:34:48.673292 | rockylinux-9 | ok
2026-04-28 06:34:48.680032 | 
2026-04-28 06:34:48.680096 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-28 06:34:49.701321 | rockylinux-9 | Output suppressed because no_log was given
2026-04-28 06:34:49.709665 | 
2026-04-28 06:34:49.709736 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-28 06:34:49.964103 | rockylinux-9 | ok: "logs"
2026-04-28 06:34:50.047652 | rockylinux-9 | ok: All items complete
2026-04-28 06:34:50.047780 | 
2026-04-28 06:34:50.205137 | rockylinux-9 | ok: "artifacts"
2026-04-28 06:34:50.429290 | rockylinux-9 | ok: "docs"
2026-04-28 06:34:50.454971 | 
2026-04-28 06:34:50.455510 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-28 06:34:50.741747 | rockylinux-9 | changed: "logs"
2026-04-28 06:34:50.985234 | rockylinux-9 | changed: "artifacts"
2026-04-28 06:34:51.219899 | rockylinux-9 | changed: "docs"
2026-04-28 06:34:51.307434 | 
2026-04-28 06:34:51.307631 | PLAY RECAP
2026-04-28 06:34:51.307721 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-28 06:34:51.307774 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-28 06:34:51.307811 | 
2026-04-28 06:34:51.459154 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-28 06:34:51.461686 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-28 06:34:52.030612 | 
2026-04-28 06:34:52.030745 | PLAY [all]
2026-04-28 06:34:52.041733 | 
2026-04-28 06:34:52.041815 | TASK [setup-uv : Extract archive]
2026-04-28 06:34:54.974998 | rockylinux-9 | changed
2026-04-28 06:34:54.980668 | 
2026-04-28 06:34:54.980747 | TASK [setup-uv : Print version]
2026-04-28 06:34:55.003545 | [rockylinux-9] Waiting on logger
2026-04-28 06:34:55.526826 | rockylinux-9 | ok: Runtime: 0:00:00.015109
2026-04-28 06:34:55.535679 | 
2026-04-28 06:34:55.535811 | PLAY RECAP
2026-04-28 06:34:55.535867 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-28 06:34:55.535897 | 
2026-04-28 06:34:55.666999 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-28 06:34:55.670195 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-28 06:34:56.256548 | 
2026-04-28 06:34:56.333143 | PLAY [all]
2026-04-28 06:34:56.352350 | 
2026-04-28 06:34:56.352509 | TASK [Copy inventory file for Zuul]
2026-04-28 06:34:57.711893 | rockylinux-9 | changed
2026-04-28 06:34:57.717555 | 
2026-04-28 06:34:57.717694 | TASK [Switch "ansible_host" to private IP]
2026-04-28 06:34:58.058228 | rockylinux-9 | changed: 1 replacements made
2026-04-28 06:34:58.064150 | 
2026-04-28 06:34:58.064237 | TASK [Run Molecule scenario]
2026-04-28 06:34:58.086724 | [rockylinux-9] Waiting on logger
2026-04-28 06:35:06.214233 | [rockylinux-9] Waiting on logger
2026-04-28 06:35:16.389992 | [rockylinux-9] Waiting on logger
2026-04-28 06:35:26.566109 | [rockylinux-9] Waiting on logger
2026-04-28 06:35:36.742195 | [rockylinux-9] Waiting on logger
2026-04-28 06:35:46.922326 | [rockylinux-9] Waiting on logger
2026-04-28 06:35:56.076472 | [rockylinux-9] Waiting on logger
2026-04-28 06:36:06.246140 | [rockylinux-9] Waiting on logger
2026-04-28 06:36:16.422375 | [rockylinux-9] Waiting on logger
2026-04-28 06:36:26.597989 | [rockylinux-9] Waiting on logger
2026-04-28 06:36:36.778215 | [rockylinux-9] Waiting on logger
2026-04-28 06:36:46.950705 | [rockylinux-9] Waiting on logger
2026-04-28 06:36:56.108152 | [rockylinux-9] Waiting on logger
2026-04-28 06:37:06.278987 | [rockylinux-9] Waiting on logger
2026-04-28 06:37:16.459212 | [rockylinux-9] Waiting on logger
2026-04-28 06:37:19.821940 | rockylinux-9 | ok: Runtime: 0:02:21.206738
2026-04-28 06:37:19.829677 | 
2026-04-28 06:37:19.829743 | PLAY RECAP
2026-04-28 06:37:19.829800 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-28 06:37:19.829829 | 
2026-04-28 06:37:19.956516 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-28 06:37:19.959984 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-28 06:37:20.591446 | 
2026-04-28 06:37:20.591636 | PLAY [all]
2026-04-28 06:37:20.604614 | 
2026-04-28 06:37:20.604745 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-28 06:37:20.651259 | rockylinux-9 | skipping: Conditional result was False
2026-04-28 06:37:20.661360 | 
2026-04-28 06:37:20.661502 | TASK [fetch-output : Set log path for single node]
2026-04-28 06:37:20.708983 | rockylinux-9 | ok
2026-04-28 06:37:20.715040 | 
2026-04-28 06:37:20.715136 | LOOP [fetch-output : Ensure local output dirs]
2026-04-28 06:37:21.106274 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/work/logs"
2026-04-28 06:37:21.323191 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/work/artifacts"
2026-04-28 06:37:21.551378 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a77863f0050841ada3174c8b10f277ad/work/docs"
2026-04-28 06:37:21.565608 | 
2026-04-28 06:37:21.565787 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-28 06:37:22.285207 | rockylinux-9 | ok
2026-04-28 06:37:22.285461 | rockylinux-9 | changed: All items complete
2026-04-28 06:37:22.285500 | 
2026-04-28 06:37:22.854552 | rockylinux-9 | changed: .d..t...... ./
2026-04-28 06:37:23.369021 | rockylinux-9 | changed: .d..t...... ./
2026-04-28 06:37:23.387787 | 
2026-04-28 06:37:23.387930 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-28 06:37:23.847985 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008430
2026-04-28 06:37:24.076303 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008426
2026-04-28 06:37:24.095839 | 
2026-04-28 06:37:24.095984 | PLAY [all]
2026-04-28 06:37:24.103628 | 
2026-04-28 06:37:24.103714 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-28 06:37:24.584254 | rockylinux-9 | changed
2026-04-28 06:37:24.590335 | 
2026-04-28 06:37:24.590385 | PLAY RECAP
2026-04-28 06:37:24.590429 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-28 06:37:24.590450 | 
2026-04-28 06:37:24.705353 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-28 06:37:24.707484 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-28 06:37:25.299994 | 
2026-04-28 06:37:25.300147 | PLAY [localhost]
2026-04-28 06:37:25.310659 | 
2026-04-28 06:37:25.310761 | TASK [Generate Zuul manifest]
2026-04-28 06:37:25.335273 | localhost | ok
2026-04-28 06:37:25.354808 | 
2026-04-28 06:37:25.354896 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-28 06:37:25.670993 | localhost | changed
2026-04-28 06:37:25.682765 | 
2026-04-28 06:37:25.682846 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-28 06:37:25.714404 | localhost | ok
2026-04-28 06:37:25.723421 | 
2026-04-28 06:37:25.723508 | TASK [Upload logs]
2026-04-28 06:37:25.744831 | localhost | ok
2026-04-28 06:37:25.808806 | 
2026-04-28 06:37:25.808981 | TASK [Set zuul-log-path fact]
2026-04-28 06:37:25.831910 | localhost | ok
2026-04-28 06:37:25.843976 | 
2026-04-28 06:37:25.844047 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-28 06:37:25.875150 | localhost | ok
2026-04-28 06:37:25.884990 | 
2026-04-28 06:37:25.885083 | TASK [upload-logs : Create log directories]
2026-04-28 06:37:26.291743 | localhost | changed
2026-04-28 06:37:26.298039 | 
2026-04-28 06:37:26.298128 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-28 06:37:26.730766 | localhost -> localhost | ok: Runtime: 0:00:00.005798
2026-04-28 06:37:26.736918 | 
2026-04-28 06:37:26.737034 | TASK [upload-logs : Upload logs to log server]
2026-04-28 06:37:27.225152 | localhost | Output suppressed because no_log was given
2026-04-28 06:37:27.230777 | 
2026-04-28 06:37:27.230873 | LOOP [upload-logs : Compress console log and json output]
2026-04-28 06:37:27.272212 | localhost | skipping: Conditional result was False
2026-04-28 06:37:27.279304 | localhost | skipping: Conditional result was False
2026-04-28 06:37:27.294659 | 
2026-04-28 06:37:27.294795 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-28 06:37:27.339609 | localhost | skipping: Conditional result was False
2026-04-28 06:37:27.340075 | 
2026-04-28 06:37:27.343888 | localhost | skipping: Conditional result was False
2026-04-28 06:37:27.361098 | 
2026-04-28 06:37:27.361221 | LOOP [upload-logs : Upload console log and json output]