2026-05-21 04:27:22.182127 | Job console starting
2026-05-21 04:27:22.194499 | Updating git repos
2026-05-21 04:27:22.215777 | Cloning repos into workspace
2026-05-21 04:27:24.592719 | Restoring repo states
2026-05-21 04:27:24.598460 | Merging changes
2026-05-21 04:27:25.762758 | Checking out repos
2026-05-21 04:27:25.990717 | Preparing playbooks
2026-05-21 04:27:59.335346 | Running Ansible setup
2026-05-21 04:28:05.283297 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 04:28:05.957888 | 
2026-05-21 04:28:05.958060 | PLAY [localhost]
2026-05-21 04:28:05.971830 | 
2026-05-21 04:28:05.972003 | TASK [Gathering Facts]
2026-05-21 04:28:07.634488 | localhost | ok
2026-05-21 04:28:07.644366 | 
2026-05-21 04:28:07.644463 | TASK [Setup log path fact]
2026-05-21 04:28:07.668127 | localhost | ok
2026-05-21 04:28:07.681318 | 
2026-05-21 04:28:07.681432 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 04:28:07.715063 | localhost | ok
2026-05-21 04:28:07.725270 | 
2026-05-21 04:28:07.725419 | TASK [emit-job-header : Print job information]
2026-05-21 04:28:07.759899 | # Job Information
2026-05-21 04:28:07.760176 | Ansible Version: 2.16.18
2026-05-21 04:28:07.760249 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-05-21 04:28:07.760304 | Pipeline: check
2026-05-21 04:28:07.760354 | Executor: 2d72f0692154
2026-05-21 04:28:07.760389 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124
2026-05-21 04:28:07.760431 | Event ID: 2550b0b0-54cd-11f1-825c-3bf074c91116
2026-05-21 04:28:07.765825 | 
2026-05-21 04:28:07.765953 | LOOP [emit-job-header : Print node information]
2026-05-21 04:28:07.871483 | localhost | ok:
2026-05-21 04:28:07.871691 | localhost | # Node Information
2026-05-21 04:28:07.871729 | localhost | Inventory Hostname: rockylinux-9
2026-05-21 04:28:07.871759 | localhost | Hostname: np0000175782
2026-05-21 04:28:07.871786 | localhost | Username: zuul
2026-05-21 04:28:07.871818 | localhost | Distro: Rocky 9.7
2026-05-21 04:28:07.871845 | localhost | Provider: yul1
2026-05-21 04:28:07.871898 | localhost | Region: ca-ymq-1
2026-05-21 04:28:07.871926 | localhost | Label: rockylinux-9
2026-05-21 04:28:07.871951 | localhost | Product Name: OpenStack Nova
2026-05-21 04:28:07.871977 | localhost | Interface IP: 199.204.45.109
2026-05-21 04:28:07.888774 | 
2026-05-21 04:28:07.889013 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 04:28:10.733099 | localhost -> localhost | changed
2026-05-21 04:28:10.741333 | 
2026-05-21 04:28:10.741438 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 04:28:12.869850 | localhost -> localhost | changed
2026-05-21 04:28:12.881004 | 
2026-05-21 04:28:12.881085 | PLAY [all]
2026-05-21 04:28:12.888537 | 
2026-05-21 04:28:12.888642 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 04:28:13.127962 | rockylinux-9 -> localhost | ok
2026-05-21 04:28:13.137446 | 
2026-05-21 04:28:13.137577 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 04:28:13.171280 | rockylinux-9 | ok
2026-05-21 04:28:13.192989 | rockylinux-9 | included: /var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 04:28:13.200446 | 
2026-05-21 04:28:13.200516 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 04:28:16.384695 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-21 04:28:16.384894 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/work/9bf1cb65cbab48fabb125693ed9c56af_id_rsa
2026-05-21 04:28:16.384926 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/work/9bf1cb65cbab48fabb125693ed9c56af_id_rsa.pub
2026-05-21 04:28:16.384950 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-21 04:28:16.385006 | rockylinux-9 -> localhost | SHA256:mCn9hW9CYE4F1TE9Sv+HGg61XYhkmLGc1rmCfniWZQg zuul-build-sshkey
2026-05-21 04:28:16.385043 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-21 04:28:16.385064 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-21 04:28:16.385090 | rockylinux-9 -> localhost | |      .oo.+*     |
2026-05-21 04:28:16.385111 | rockylinux-9 -> localhost | |       . .===.   |
2026-05-21 04:28:16.385301 | rockylinux-9 -> localhost | |      + E.==oo . |
2026-05-21 04:28:16.385321 | rockylinux-9 -> localhost | |     = = =..+.. .|
2026-05-21 04:28:16.385341 | rockylinux-9 -> localhost | |    . * S +.++ o |
2026-05-21 04:28:16.385360 | rockylinux-9 -> localhost | |     . + +.=o + .|
2026-05-21 04:28:16.385379 | rockylinux-9 -> localhost | |        = Bo o . |
2026-05-21 04:28:16.385398 | rockylinux-9 -> localhost | |         *  o    |
2026-05-21 04:28:16.385420 | rockylinux-9 -> localhost | |                 |
2026-05-21 04:28:16.385440 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-21 04:28:16.385490 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.152479
2026-05-21 04:28:16.391730 | 
2026-05-21 04:28:16.391809 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 04:28:16.425661 | rockylinux-9 | ok
2026-05-21 04:28:16.436312 | rockylinux-9 | included: /var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 04:28:16.443749 | 
2026-05-21 04:28:16.443814 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 04:28:16.468817 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:28:16.479612 | 
2026-05-21 04:28:16.479896 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 04:28:17.036714 | rockylinux-9 | changed
2026-05-21 04:28:17.044068 | 
2026-05-21 04:28:17.044187 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 04:28:17.312359 | rockylinux-9 | ok
2026-05-21 04:28:17.320888 | 
2026-05-21 04:28:17.321019 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 04:28:18.274249 | rockylinux-9 | changed
2026-05-21 04:28:19.438946 | 
2026-05-21 04:28:19.439200 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 04:28:20.350667 | rockylinux-9 | changed
2026-05-21 04:28:20.356059 | 
2026-05-21 04:28:20.356255 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 04:28:20.383706 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:28:20.393066 | 
2026-05-21 04:28:20.393235 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 04:28:20.785948 | rockylinux-9 -> localhost | changed
2026-05-21 04:28:20.799342 | 
2026-05-21 04:28:20.799413 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 04:28:21.102170 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/work/9bf1cb65cbab48fabb125693ed9c56af_id_rsa (zuul-build-sshkey)
2026-05-21 04:28:21.102409 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012513
2026-05-21 04:28:21.108461 | 
2026-05-21 04:28:21.108541 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 04:28:21.498635 | rockylinux-9 | ok
2026-05-21 04:28:21.504529 | 
2026-05-21 04:28:21.504631 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 04:28:21.545911 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:28:21.559650 | 
2026-05-21 04:28:21.559751 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 04:28:21.956861 | rockylinux-9 | ok
2026-05-21 04:28:21.964552 | 
2026-05-21 04:28:21.964643 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 04:28:24.922754 | rockylinux-9 | Output suppressed because no_log was given
2026-05-21 04:28:24.934859 | 
2026-05-21 04:28:24.934991 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 04:28:25.182506 | rockylinux-9 | ok: "logs"
2026-05-21 04:28:25.182780 | rockylinux-9 | ok: All items complete
2026-05-21 04:28:25.182809 | 
2026-05-21 04:28:25.400517 | rockylinux-9 | ok: "artifacts"
2026-05-21 04:28:25.623283 | rockylinux-9 | ok: "docs"
2026-05-21 04:28:25.633529 | 
2026-05-21 04:28:25.633697 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 04:28:25.898829 | rockylinux-9 | changed: "logs"
2026-05-21 04:28:26.211981 | rockylinux-9 | changed: "artifacts"
2026-05-21 04:28:26.351786 | rockylinux-9 | changed: "docs"
2026-05-21 04:28:26.371474 | 
2026-05-21 04:28:26.371599 | PLAY RECAP
2026-05-21 04:28:26.371658 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 04:28:26.371700 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 04:28:26.371732 | 
2026-05-21 04:28:26.512639 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 04:28:26.514946 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 04:28:27.173245 | 
2026-05-21 04:28:27.173390 | PLAY [all]
2026-05-21 04:28:27.187304 | 
2026-05-21 04:28:27.187466 | TASK [setup-uv : Extract archive]
2026-05-21 04:28:30.030837 | rockylinux-9 | changed
2026-05-21 04:28:30.259444 | 
2026-05-21 04:28:30.259631 | TASK [setup-uv : Print version]
2026-05-21 04:28:30.289473 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:30.801411 | rockylinux-9 | ok: Runtime: 0:00:00.014211
2026-05-21 04:28:30.809274 | 
2026-05-21 04:28:30.809340 | TASK [Install Ansible collection siblings]
2026-05-21 04:28:31.281859 | rockylinux-9 | ok:
2026-05-21 04:28:31.282146 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-21 04:28:31.282213 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-21 04:28:31.284773 | 
2026-05-21 04:28:31.284829 | PLAY RECAP
2026-05-21 04:28:31.284873 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 04:28:31.284895 | 
2026-05-21 04:28:31.462792 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 04:28:33.176623 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 04:28:33.833869 | 
2026-05-21 04:28:35.963530 | PLAY [all]
2026-05-21 04:28:35.986926 | 
2026-05-21 04:28:35.987073 | TASK [Copy inventory file for Zuul]
2026-05-21 04:28:37.384894 | rockylinux-9 | changed
2026-05-21 04:28:37.392719 | 
2026-05-21 04:28:37.392848 | TASK [Switch "ansible_host" to private IP]
2026-05-21 04:28:37.802675 | rockylinux-9 | changed: 1 replacements made
2026-05-21 04:28:37.809890 | 
2026-05-21 04:28:37.810255 | TASK [Run Molecule scenario]
2026-05-21 04:28:37.833932 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:42.926832 | [rockylinux-9] Waiting on logger
2026-05-21 04:28:53.093932 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:03.270074 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:13.445944 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:22.860374 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:32.843207 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:43.014345 | [rockylinux-9] Waiting on logger
2026-05-21 04:29:53.190717 | [rockylinux-9] Waiting on logger
2026-05-21 04:30:03.397952 | [rockylinux-9] Waiting on logger
2026-05-21 04:30:13.573822 | [rockylinux-9] Waiting on logger
2026-05-21 04:30:23.750439 | [rockylinux-9] Waiting on logger
2026-05-21 04:30:32.906955 | [rockylinux-9] Waiting on logger
2026-05-21 04:30:43.078317 | [rockylinux-9] Waiting on logger
2026-05-21 04:30:53.257999 | [rockylinux-9] Waiting on logger
2026-05-21 04:30:56.621399 | rockylinux-9 | ok: Runtime: 0:02:17.330842
2026-05-21 04:30:56.628462 | 
2026-05-21 04:30:56.628526 | PLAY RECAP
2026-05-21 04:30:56.628583 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 04:30:56.628613 | 
2026-05-21 04:30:56.749824 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 04:30:56.751719 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 04:30:57.443613 | 
2026-05-21 04:30:57.443757 | PLAY [all]
2026-05-21 04:30:57.455211 | 
2026-05-21 04:30:57.455308 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 04:30:57.500140 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 04:30:57.507915 | 
2026-05-21 04:30:57.508038 | TASK [fetch-output : Set log path for single node]
2026-05-21 04:30:57.555599 | rockylinux-9 | ok
2026-05-21 04:30:57.562159 | 
2026-05-21 04:30:57.562300 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 04:30:57.993961 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/work/logs"
2026-05-21 04:30:58.287854 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/work/artifacts"
2026-05-21 04:30:58.493969 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9bf1cb65cbab48fabb125693ed9c56af/work/docs"
2026-05-21 04:30:58.507691 | 
2026-05-21 04:30:58.507874 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 04:30:59.204705 | rockylinux-9 | ok
2026-05-21 04:30:59.205008 | rockylinux-9 | changed: All items complete
2026-05-21 04:30:59.205046 | 
2026-05-21 04:30:59.725476 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 04:31:00.261074 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 04:31:00.281070 | 
2026-05-21 04:31:00.281831 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 04:31:00.757049 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.009086
2026-05-21 04:31:00.984311 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007375
2026-05-21 04:31:00.997449 | 
2026-05-21 04:31:00.997655 | PLAY [all]
2026-05-21 04:31:01.003994 | 
2026-05-21 04:31:01.004072 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 04:31:01.475468 | rockylinux-9 | changed
2026-05-21 04:31:01.482625 | 
2026-05-21 04:31:01.482701 | PLAY RECAP
2026-05-21 04:31:01.482780 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 04:31:01.482817 | 
2026-05-21 04:31:01.614309 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 04:31:01.617484 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 04:31:02.190401 | 
2026-05-21 04:31:02.190700 | PLAY [localhost]
2026-05-21 04:31:02.202966 | 
2026-05-21 04:31:02.203129 | TASK [Generate Zuul manifest]
2026-05-21 04:31:02.225657 | localhost | ok
2026-05-21 04:31:02.240770 | 
2026-05-21 04:31:02.240941 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 04:31:02.627728 | localhost | changed
2026-05-21 04:31:02.640700 | 
2026-05-21 04:31:02.640853 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 04:31:02.676040 | localhost | ok
2026-05-21 04:31:02.683489 | 
2026-05-21 04:31:02.683599 | TASK [Upload logs]
2026-05-21 04:31:02.705579 | localhost | ok
2026-05-21 04:31:02.767722 | 
2026-05-21 04:31:02.767847 | TASK [Set zuul-log-path fact]
2026-05-21 04:31:02.790324 | localhost | ok
2026-05-21 04:31:02.802686 | 
2026-05-21 04:31:02.802753 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 04:31:02.833932 | localhost | ok
2026-05-21 04:31:02.844275 | 
2026-05-21 04:31:02.844362 | TASK [upload-logs : Create log directories]
2026-05-21 04:31:03.271720 | localhost | changed
2026-05-21 04:31:03.278369 | 
2026-05-21 04:31:03.278439 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 04:31:03.704853 | localhost -> localhost | ok: Runtime: 0:00:00.005917
2026-05-21 04:31:03.716945 | 
2026-05-21 04:31:03.717331 | TASK [upload-logs : Upload logs to log server]
2026-05-21 04:31:04.213201 | localhost | Output suppressed because no_log was given
2026-05-21 04:31:04.219751 | 
2026-05-21 04:31:04.219853 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 04:31:04.291856 | localhost | skipping: Conditional result was False
2026-05-21 04:31:04.299856 | localhost | skipping: Conditional result was False
2026-05-21 04:31:04.312512 | 
2026-05-21 04:31:04.312674 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 04:31:04.356631 | localhost | skipping: Conditional result was False
2026-05-21 04:31:04.356917 | 
2026-05-21 04:31:04.363065 | localhost | skipping: Conditional result was False
2026-05-21 04:31:04.376980 | 
2026-05-21 04:31:04.377134 | LOOP [upload-logs : Upload console log and json output]