2026-05-21 02:30:47.731916 | Job console starting
2026-05-21 02:30:47.745907 | Updating git repos
2026-05-21 02:30:47.783268 | Cloning repos into workspace
2026-05-21 02:30:51.335157 | Restoring repo states
2026-05-21 02:30:51.351702 | Merging changes
2026-05-21 02:30:52.281144 | Checking out repos
2026-05-21 02:30:53.296080 | Preparing playbooks
2026-05-21 02:31:21.358827 | Running Ansible setup
2026-05-21 02:31:26.401527 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:31:27.154126 | 
2026-05-21 02:31:27.154454 | PLAY [localhost]
2026-05-21 02:31:27.163131 | 
2026-05-21 02:31:27.163204 | TASK [Gathering Facts]
2026-05-21 02:31:28.150683 | localhost | ok
2026-05-21 02:31:28.184604 | 
2026-05-21 02:31:28.184776 | TASK [Setup log path fact]
2026-05-21 02:31:28.216764 | localhost | ok
2026-05-21 02:31:28.244768 | 
2026-05-21 02:31:28.244974 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:31:28.296801 | localhost | ok
2026-05-21 02:31:28.302857 | 
2026-05-21 02:31:28.302932 | TASK [emit-job-header : Print job information]
2026-05-21 02:31:28.378594 | # Job Information
2026-05-21 02:31:28.378793 | Ansible Version: 2.16.18
2026-05-21 02:31:28.378826 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-05-21 02:31:28.378857 | Pipeline: check
2026-05-21 02:31:28.378882 | Executor: 2d72f0692154
2026-05-21 02:31:28.378902 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/129
2026-05-21 02:31:28.378926 | Event ID: f2afac20-54bc-11f1-906a-0dc10c943ad7
2026-05-21 02:31:28.381830 | 
2026-05-21 02:31:28.381899 | LOOP [emit-job-header : Print node information]
2026-05-21 02:31:28.478308 | localhost | ok:
2026-05-21 02:31:28.478511 | localhost | # Node Information
2026-05-21 02:31:28.478541 | localhost | Inventory Hostname: rockylinux-9
2026-05-21 02:31:28.478563 | localhost | Hostname: np0000175649
2026-05-21 02:31:28.478590 | localhost | Username: zuul
2026-05-21 02:31:28.478613 | localhost | Distro: Rocky 9.7
2026-05-21 02:31:28.478633 | localhost | Provider: yul1
2026-05-21 02:31:28.478652 | localhost | Region: ca-ymq-1
2026-05-21 02:31:28.478671 | localhost | Label: rockylinux-9
2026-05-21 02:31:28.478689 | localhost | Product Name: OpenStack Nova
2026-05-21 02:31:28.478707 | localhost | Interface IP: 199.19.213.179
2026-05-21 02:31:28.487416 | 
2026-05-21 02:31:28.487590 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 02:31:28.924669 | localhost -> localhost | changed
2026-05-21 02:31:28.930533 | 
2026-05-21 02:31:28.930613 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 02:31:29.931132 | localhost -> localhost | changed
2026-05-21 02:31:29.938384 | 
2026-05-21 02:31:29.938485 | PLAY [all]
2026-05-21 02:31:29.951489 | 
2026-05-21 02:31:29.951637 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 02:31:30.339810 | rockylinux-9 -> localhost | ok
2026-05-21 02:31:30.347824 | 
2026-05-21 02:31:30.347985 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 02:31:30.389575 | rockylinux-9 | ok
2026-05-21 02:31:31.526496 | rockylinux-9 | included: /var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 02:31:31.533469 | 
2026-05-21 02:31:31.533534 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 02:31:32.441985 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-21 02:31:32.442164 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/work/e2d449b8bfbd4f4baa866f89ed2a5cb4_id_rsa
2026-05-21 02:31:32.442195 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/work/e2d449b8bfbd4f4baa866f89ed2a5cb4_id_rsa.pub
2026-05-21 02:31:32.442218 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-21 02:31:32.442240 | rockylinux-9 -> localhost | SHA256:36mbeiwoW3x7sOKVWSiE3+G9JT6ZFoPxBde31Ooa6UI zuul-build-sshkey
2026-05-21 02:31:32.442277 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-21 02:31:32.442299 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-21 02:31:32.442325 | rockylinux-9 -> localhost | |          . ..  .|
2026-05-21 02:31:32.442346 | rockylinux-9 -> localhost | |     .     o  ..o|
2026-05-21 02:31:32.442367 | rockylinux-9 -> localhost | |    . . o   . .o.|
2026-05-21 02:31:32.442387 | rockylinux-9 -> localhost | |     o o B .  .. |
2026-05-21 02:31:32.442407 | rockylinux-9 -> localhost | |      o S B .o   |
2026-05-21 02:31:32.442427 | rockylinux-9 -> localhost | |     . ..*EXo..  |
2026-05-21 02:31:32.442446 | rockylinux-9 -> localhost | |      o.==O.oo   |
2026-05-21 02:31:32.442465 | rockylinux-9 -> localhost | |    ..oooo=+o    |
2026-05-21 02:31:32.442489 | rockylinux-9 -> localhost | |    .+..o=+o     |
2026-05-21 02:31:32.442509 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-21 02:31:32.442562 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.442448
2026-05-21 02:31:32.457556 | 
2026-05-21 02:31:32.457638 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 02:31:32.504301 | rockylinux-9 | ok
2026-05-21 02:31:32.516920 | rockylinux-9 | included: /var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 02:31:32.529837 | 
2026-05-21 02:31:32.529973 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 02:31:32.560354 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:32.568963 | 
2026-05-21 02:31:32.569143 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 02:31:33.090658 | rockylinux-9 | changed
2026-05-21 02:31:33.101467 | 
2026-05-21 02:31:33.101538 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 02:31:33.364425 | rockylinux-9 | ok
2026-05-21 02:31:33.369288 | 
2026-05-21 02:31:33.369356 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 02:31:34.338721 | rockylinux-9 | changed
2026-05-21 02:31:34.352627 | 
2026-05-21 02:31:34.352877 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 02:31:35.299295 | rockylinux-9 | changed
2026-05-21 02:31:35.303952 | 
2026-05-21 02:31:35.304019 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 02:31:35.331750 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:35.337564 | 
2026-05-21 02:31:35.337633 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 02:31:35.866934 | rockylinux-9 -> localhost | changed
2026-05-21 02:31:35.876973 | 
2026-05-21 02:31:35.877064 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 02:31:36.206151 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/work/e2d449b8bfbd4f4baa866f89ed2a5cb4_id_rsa (zuul-build-sshkey)
2026-05-21 02:31:36.207979 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.020155
2026-05-21 02:31:36.215645 | 
2026-05-21 02:31:36.215722 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 02:31:36.648439 | rockylinux-9 | ok
2026-05-21 02:31:36.756284 | 
2026-05-21 02:31:36.756419 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 02:31:36.788361 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:36.803580 | 
2026-05-21 02:31:36.803735 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 02:31:37.208586 | rockylinux-9 | ok
2026-05-21 02:31:37.214497 | 
2026-05-21 02:31:37.214568 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 02:31:38.393883 | rockylinux-9 | Output suppressed because no_log was given
2026-05-21 02:31:38.402740 | 
2026-05-21 02:31:38.402814 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 02:31:38.660855 | rockylinux-9 | ok: "logs"
2026-05-21 02:31:38.661901 | rockylinux-9 | ok: All items complete
2026-05-21 02:31:38.661941 | 
2026-05-21 02:31:38.881797 | rockylinux-9 | ok: "artifacts"
2026-05-21 02:31:39.103679 | rockylinux-9 | ok: "docs"
2026-05-21 02:31:39.120222 | 
2026-05-21 02:31:39.120363 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 02:31:39.405256 | rockylinux-9 | changed: "logs"
2026-05-21 02:31:39.757866 | rockylinux-9 | changed: "artifacts"
2026-05-21 02:31:39.866334 | rockylinux-9 | changed: "docs"
2026-05-21 02:31:39.883326 | 
2026-05-21 02:31:39.883415 | PLAY RECAP
2026-05-21 02:31:39.883458 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:31:39.883488 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 02:31:39.883509 | 
2026-05-21 02:31:40.052931 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:31:40.066001 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:31:40.911886 | 
2026-05-21 02:31:41.314643 | PLAY [all]
2026-05-21 02:31:41.329385 | 
2026-05-21 02:31:41.329736 | TASK [setup-uv : Extract archive]
2026-05-21 02:31:44.142509 | rockylinux-9 | changed
2026-05-21 02:31:44.148872 | 
2026-05-21 02:31:44.155975 | TASK [setup-uv : Print version]
2026-05-21 02:31:44.190028 | [rockylinux-9] Waiting on logger
2026-05-21 02:31:45.833630 | rockylinux-9 | ok: Runtime: 0:00:00.014430
2026-05-21 02:31:45.841438 | 
2026-05-21 02:31:45.841500 | TASK [Install Ansible collection siblings]
2026-05-21 02:31:46.317620 | rockylinux-9 | ok:
2026-05-21 02:31:46.317769 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-21 02:31:46.317798 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-21 02:31:46.319718 | 
2026-05-21 02:31:46.319764 | PLAY RECAP
2026-05-21 02:31:46.319809 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:31:46.319830 | 
2026-05-21 02:31:46.452323 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:31:46.454338 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:31:47.095294 | 
2026-05-21 02:31:47.095414 | PLAY [all]
2026-05-21 02:31:47.106740 | 
2026-05-21 02:31:47.106832 | TASK [Copy inventory file for Zuul]
2026-05-21 02:31:48.535908 | rockylinux-9 | changed
2026-05-21 02:31:48.551718 | 
2026-05-21 02:31:48.551962 | TASK [Switch "ansible_host" to private IP]
2026-05-21 02:31:49.025461 | rockylinux-9 | changed: 1 replacements made
2026-05-21 02:31:49.036576 | 
2026-05-21 02:31:49.036788 | TASK [Run Molecule scenario]
2026-05-21 02:31:49.089875 | [rockylinux-9] Waiting on logger
2026-05-21 02:31:56.203469 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:06.310080 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:16.490032 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:26.666066 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:36.869812 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:47.050052 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:56.203386 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:06.406100 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:16.585892 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:26.762175 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:36.933943 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:47.109894 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:56.453918 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:06.630264 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:09.998319 | rockylinux-9 | ok: Runtime: 0:02:20.426007
2026-05-21 02:34:10.007505 | 
2026-05-21 02:34:10.007591 | PLAY RECAP
2026-05-21 02:34:10.007654 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:34:10.007687 | 
2026-05-21 02:34:10.179013 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:34:10.180935 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:34:10.770453 | 
2026-05-21 02:34:10.770922 | PLAY [all]
2026-05-21 02:34:10.781541 | 
2026-05-21 02:34:10.781618 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 02:34:10.817181 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:34:10.824334 | 
2026-05-21 02:34:10.824440 | TASK [fetch-output : Set log path for single node]
2026-05-21 02:34:10.877604 | rockylinux-9 | ok
2026-05-21 02:34:10.883252 | 
2026-05-21 02:34:10.883342 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 02:34:11.293763 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/work/logs"
2026-05-21 02:34:11.543990 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/work/artifacts"
2026-05-21 02:34:11.786468 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e2d449b8bfbd4f4baa866f89ed2a5cb4/work/docs"
2026-05-21 02:34:11.800655 | 
2026-05-21 02:34:11.800824 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 02:34:12.508078 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:12.508307 | rockylinux-9 | changed: All items complete
2026-05-21 02:34:12.508345 | 
2026-05-21 02:34:13.021841 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:13.527066 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:13.548734 | 
2026-05-21 02:34:13.548858 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 02:34:13.963115 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007149
2026-05-21 02:34:14.180715 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008046
2026-05-21 02:34:14.198926 | 
2026-05-21 02:34:14.199032 | PLAY [all]
2026-05-21 02:34:14.205274 | 
2026-05-21 02:34:14.205339 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 02:34:14.679883 | rockylinux-9 | changed
2026-05-21 02:34:14.684785 | 
2026-05-21 02:34:14.684832 | PLAY RECAP
2026-05-21 02:34:14.684874 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 02:34:14.684896 | 
2026-05-21 02:34:14.800504 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:34:14.803448 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 02:34:15.363390 | 
2026-05-21 02:34:15.363508 | PLAY [localhost]
2026-05-21 02:34:15.374632 | 
2026-05-21 02:34:15.374725 | TASK [Generate Zuul manifest]
2026-05-21 02:34:15.396541 | localhost | ok
2026-05-21 02:34:15.412440 | 
2026-05-21 02:34:15.412524 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 02:34:15.821251 | localhost | changed
2026-05-21 02:34:15.837835 | 
2026-05-21 02:34:15.838156 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 02:34:15.870319 | localhost | ok
2026-05-21 02:34:15.879647 | 
2026-05-21 02:34:15.879733 | TASK [Upload logs]
2026-05-21 02:34:15.896814 | localhost | ok
2026-05-21 02:34:15.953348 | 
2026-05-21 02:34:15.953473 | TASK [Set zuul-log-path fact]
2026-05-21 02:34:15.975203 | localhost | ok
2026-05-21 02:34:15.986453 | 
2026-05-21 02:34:15.986521 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:34:16.015794 | localhost | ok
2026-05-21 02:34:16.025270 | 
2026-05-21 02:34:16.025358 | TASK [upload-logs : Create log directories]
2026-05-21 02:34:16.421641 | localhost | changed
2026-05-21 02:34:16.429450 | 
2026-05-21 02:34:16.429549 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 02:34:16.854056 | localhost -> localhost | ok: Runtime: 0:00:00.004933
2026-05-21 02:34:16.860572 | 
2026-05-21 02:34:16.860677 | TASK [upload-logs : Upload logs to log server]
2026-05-21 02:34:17.299436 | localhost | Output suppressed because no_log was given
2026-05-21 02:34:17.304494 | 
2026-05-21 02:34:17.304563 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 02:34:17.348518 | localhost | skipping: Conditional result was False
2026-05-21 02:34:17.355426 | localhost | skipping: Conditional result was False
2026-05-21 02:34:17.367202 | 
2026-05-21 02:34:17.367402 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 02:34:17.404060 | localhost | skipping: Conditional result was False
2026-05-21 02:34:17.404539 | 
2026-05-21 02:34:17.408114 | localhost | skipping: Conditional result was False
2026-05-21 02:34:17.422343 | 
2026-05-21 02:34:17.422532 | LOOP [upload-logs : Upload console log and json output]