2026-04-14 00:58:20.973195 | Job console starting
2026-04-14 00:58:20.986069 | Updating git repos
2026-04-14 00:58:21.023472 | Cloning repos into workspace
2026-04-14 00:58:21.128423 | Restoring repo states
2026-04-14 00:58:21.140467 | Merging changes
2026-04-14 00:58:21.507807 | Checking out repos
2026-04-14 00:58:21.546238 | Preparing playbooks
2026-04-14 00:58:22.642514 | Running Ansible setup
2026-04-14 00:58:26.439690 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 00:58:27.037272 | 
2026-04-14 00:58:27.037757 | PLAY [localhost]
2026-04-14 00:58:27.046673 | 
2026-04-14 00:58:27.046742 | TASK [Gathering Facts]
2026-04-14 00:58:27.956464 | localhost | ok
2026-04-14 00:58:27.967169 | 
2026-04-14 00:58:27.967253 | TASK [Setup log path fact]
2026-04-14 00:58:27.987721 | localhost | ok
2026-04-14 00:58:27.999384 | 
2026-04-14 00:58:27.999469 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 00:58:28.039888 | localhost | ok
2026-04-14 00:58:28.048620 | 
2026-04-14 00:58:28.048745 | TASK [emit-job-header : Print job information]
2026-04-14 00:58:28.091442 | # Job Information
2026-04-14 00:58:28.091750 | Ansible Version: 2.16.16
2026-04-14 00:58:28.091829 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-14 00:58:28.091887 | Pipeline: check
2026-04-14 00:58:28.091938 | Executor: 0a8996d2b663
2026-04-14 00:58:28.091987 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109
2026-04-14 00:58:28.092042 | Event ID: eeec5e90-379c-11f1-893a-d9171dbe9bef
2026-04-14 00:58:28.097032 | 
2026-04-14 00:58:28.097172 | LOOP [emit-job-header : Print node information]
2026-04-14 00:58:28.205722 | localhost | ok:
2026-04-14 00:58:28.205933 | localhost | # Node Information
2026-04-14 00:58:28.205964 | localhost | Inventory Hostname: rockylinux-9
2026-04-14 00:58:28.205985 | localhost | Hostname: np0000165867
2026-04-14 00:58:28.206005 | localhost | Username: zuul
2026-04-14 00:58:28.206027 | localhost | Distro: Rocky 9.7
2026-04-14 00:58:28.206047 | localhost | Provider: yul1
2026-04-14 00:58:28.206068 | localhost | Region: ca-ymq-1
2026-04-14 00:58:28.206086 | localhost | Label: rockylinux-9
2026-04-14 00:58:28.206104 | localhost | Product Name: OpenStack Nova
2026-04-14 00:58:28.206122 | localhost | Interface IP: 199.204.45.169
2026-04-14 00:58:28.224595 | 
2026-04-14 00:58:28.224783 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-14 00:58:29.144508 | localhost -> localhost | changed
2026-04-14 00:58:29.157123 | 
2026-04-14 00:58:29.157229 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-14 00:58:30.214629 | localhost -> localhost | changed
2026-04-14 00:58:30.224997 | 
2026-04-14 00:58:30.225097 | PLAY [all]
2026-04-14 00:58:30.234525 | 
2026-04-14 00:58:30.234596 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-14 00:58:30.498391 | rockylinux-9 -> localhost | ok
2026-04-14 00:58:30.508119 | 
2026-04-14 00:58:30.508218 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-14 00:58:30.544176 | rockylinux-9 | ok
2026-04-14 00:58:30.564298 | rockylinux-9 | included: /var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-14 00:58:30.569792 | 
2026-04-14 00:58:30.569860 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-14 00:58:31.294994 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-14 00:58:31.295165 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/work/517d33acbe6f45c3962e8abfc7fd5d13_id_rsa
2026-04-14 00:58:31.295201 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/work/517d33acbe6f45c3962e8abfc7fd5d13_id_rsa.pub
2026-04-14 00:58:31.295225 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-14 00:58:31.295246 | rockylinux-9 -> localhost | SHA256:zol8Ryao333Mx+zBJdN3OHA72NPmRsbq/l8DuyzO724 zuul-build-sshkey
2026-04-14 00:58:31.295277 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-14 00:58:31.295299 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-14 00:58:31.295353 | rockylinux-9 -> localhost | |                 |
2026-04-14 00:58:31.295380 | rockylinux-9 -> localhost | |                 |
2026-04-14 00:58:31.295400 | rockylinux-9 -> localhost | |            . .  |
2026-04-14 00:58:31.295420 | rockylinux-9 -> localhost | |       .     = * |
2026-04-14 00:58:31.295440 | rockylinux-9 -> localhost | |      . S o ..X @|
2026-04-14 00:58:31.295463 | rockylinux-9 -> localhost | |     o + =   .o&o|
2026-04-14 00:58:31.295483 | rockylinux-9 -> localhost | |    . o = .o ++.+|
2026-04-14 00:58:31.295505 | rockylinux-9 -> localhost | |     . o o..=E=oo|
2026-04-14 00:58:31.295527 | rockylinux-9 -> localhost | |      . . o+*X+.+|
2026-04-14 00:58:31.295546 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-14 00:58:31.295596 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.257591
2026-04-14 00:58:31.300210 | 
2026-04-14 00:58:31.300272 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-14 00:58:31.331031 | rockylinux-9 | ok
2026-04-14 00:58:31.342269 | rockylinux-9 | included: /var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-14 00:58:31.352997 | 
2026-04-14 00:58:31.353081 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-14 00:58:31.378985 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:58:31.388648 | 
2026-04-14 00:58:31.388729 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-14 00:58:31.948641 | rockylinux-9 | changed
2026-04-14 00:58:31.957047 | 
2026-04-14 00:58:31.957169 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-14 00:58:32.220917 | rockylinux-9 | ok
2026-04-14 00:58:32.225528 | 
2026-04-14 00:58:32.225594 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-14 00:58:33.218835 | rockylinux-9 | changed
2026-04-14 00:58:33.451157 | 
2026-04-14 00:58:33.451360 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-14 00:58:34.396299 | rockylinux-9 | changed
2026-04-14 00:58:34.401564 | 
2026-04-14 00:58:34.401640 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-14 00:58:34.426943 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:58:34.432946 | 
2026-04-14 00:58:34.433049 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-14 00:58:34.855934 | rockylinux-9 -> localhost | changed
2026-04-14 00:58:34.866330 | 
2026-04-14 00:58:34.955752 | TASK [add-build-sshkey : Add back temp key]
2026-04-14 00:58:35.394186 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/work/517d33acbe6f45c3962e8abfc7fd5d13_id_rsa (zuul-build-sshkey)
2026-04-14 00:58:35.394458 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012552
2026-04-14 00:58:35.408545 | 
2026-04-14 00:58:35.408688 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-14 00:58:35.855232 | rockylinux-9 | ok
2026-04-14 00:58:35.860074 | 
2026-04-14 00:58:35.860142 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-14 00:58:35.895573 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:58:35.910701 | 
2026-04-14 00:58:35.910796 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-14 00:58:36.313538 | rockylinux-9 | ok
2026-04-14 00:58:36.469267 | 
2026-04-14 00:58:36.469405 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-14 00:58:37.406092 | rockylinux-9 | Output suppressed because no_log was given
2026-04-14 00:58:37.435842 | 
2026-04-14 00:58:37.435979 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-14 00:58:37.683135 | rockylinux-9 | ok: "logs"
2026-04-14 00:58:37.683550 | rockylinux-9 | ok: All items complete
2026-04-14 00:58:37.683596 | 
2026-04-14 00:58:37.899716 | rockylinux-9 | ok: "artifacts"
2026-04-14 00:58:38.120856 | rockylinux-9 | ok: "docs"
2026-04-14 00:58:38.131219 | 
2026-04-14 00:58:38.131412 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-14 00:58:38.390615 | rockylinux-9 | changed: "logs"
2026-04-14 00:58:38.607835 | rockylinux-9 | changed: "artifacts"
2026-04-14 00:58:38.844295 | rockylinux-9 | changed: "docs"
2026-04-14 00:58:38.860206 | 
2026-04-14 00:58:38.860370 | PLAY RECAP
2026-04-14 00:58:38.860422 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:58:38.860496 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-14 00:58:38.860535 | 
2026-04-14 00:58:39.015333 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-14 00:58:39.016607 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 00:58:39.622572 | 
2026-04-14 00:58:39.623016 | PLAY [all]
2026-04-14 00:58:39.634284 | 
2026-04-14 00:58:39.634363 | TASK [setup-uv : Extract archive]
2026-04-14 00:58:42.377878 | rockylinux-9 | changed
2026-04-14 00:58:42.383373 | 
2026-04-14 00:58:42.383451 | TASK [setup-uv : Print version]
2026-04-14 00:58:42.406868 | [rockylinux-9] Waiting on logger
2026-04-14 00:58:42.919000 | rockylinux-9 | ok: Runtime: 0:00:00.015572
2026-04-14 00:58:42.925144 | 
2026-04-14 00:58:42.925221 | PLAY RECAP
2026-04-14 00:58:42.925267 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:58:42.925298 | 
2026-04-14 00:58:43.064266 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-14 00:58:43.474348 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 00:58:44.102016 | 
2026-04-14 00:58:44.102156 | PLAY [all]
2026-04-14 00:58:44.112392 | 
2026-04-14 00:58:44.112462 | TASK [Copy inventory file for Zuul]
2026-04-14 00:58:45.443763 | rockylinux-9 | changed
2026-04-14 00:58:45.450364 | 
2026-04-14 00:58:45.450495 | TASK [Switch "ansible_host" to private IP]
2026-04-14 00:58:45.786072 | rockylinux-9 | changed: 1 replacements made
2026-04-14 00:58:45.791225 | 
2026-04-14 00:58:45.791289 | TASK [Run Molecule scenario]
2026-04-14 00:58:45.812373 | [rockylinux-9] Waiting on logger
2026-04-14 00:58:53.958085 | [rockylinux-9] Waiting on logger
2026-04-14 00:59:04.134213 | [rockylinux-9] Waiting on logger
2026-04-14 00:59:14.310246 | [rockylinux-9] Waiting on logger
2026-04-14 00:59:20.017890 | rockylinux-9 | ERROR
2026-04-14 00:59:20.018227 | rockylinux-9 | {
2026-04-14 00:59:20.018293 | rockylinux-9 |   "delta": "0:00:33.464953",
2026-04-14 00:59:20.018340 | rockylinux-9 |   "end": "2026-04-14 00:59:18.649551",
2026-04-14 00:59:20.018383 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-14 00:59:20.018428 | rockylinux-9 |   "rc": 2,
2026-04-14 00:59:20.018475 | rockylinux-9 |   "start": "2026-04-14 00:58:45.184598"
2026-04-14 00:59:20.018516 | rockylinux-9 | }
failure
2026-04-14 00:59:20.022475 | 
2026-04-14 00:59:20.022525 | PLAY RECAP
2026-04-14 00:59:20.022574 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-14 00:59:20.022595 | 
2026-04-14 00:59:20.153128 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-14 00:59:20.154867 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 00:59:20.754604 | 
2026-04-14 00:59:20.754774 | PLAY [all]
2026-04-14 00:59:20.766250 | 
2026-04-14 00:59:20.766347 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-14 00:59:20.813074 | rockylinux-9 | skipping: Conditional result was False
2026-04-14 00:59:20.821716 | 
2026-04-14 00:59:20.821825 | TASK [fetch-output : Set log path for single node]
2026-04-14 00:59:20.868650 | rockylinux-9 | ok
2026-04-14 00:59:20.874577 | 
2026-04-14 00:59:20.874669 | LOOP [fetch-output : Ensure local output dirs]
2026-04-14 00:59:21.294115 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/work/logs"
2026-04-14 00:59:21.513671 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/work/artifacts"
2026-04-14 00:59:21.718365 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/517d33acbe6f45c3962e8abfc7fd5d13/work/docs"
2026-04-14 00:59:21.733833 | 
2026-04-14 00:59:21.734009 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-14 00:59:22.435175 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 00:59:22.435379 | rockylinux-9 | changed: All items complete
2026-04-14 00:59:22.435417 | 
2026-04-14 00:59:22.947434 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 00:59:23.462275 | rockylinux-9 | changed: .d..t...... ./
2026-04-14 00:59:23.484829 | 
2026-04-14 00:59:23.485013 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-14 00:59:23.905027 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008050
2026-04-14 00:59:24.129425 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007836
2026-04-14 00:59:24.145308 | 
2026-04-14 00:59:24.145438 | PLAY [all]
2026-04-14 00:59:24.151537 | 
2026-04-14 00:59:24.151599 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-14 00:59:24.653047 | rockylinux-9 | changed
2026-04-14 00:59:24.662859 | 
2026-04-14 00:59:24.662955 | PLAY RECAP
2026-04-14 00:59:24.663002 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-14 00:59:24.663023 | 
2026-04-14 00:59:24.827208 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-14 00:59:24.894140 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-14 00:59:25.521198 | 
2026-04-14 00:59:25.521329 | PLAY [localhost]
2026-04-14 00:59:25.531969 | 
2026-04-14 00:59:25.532051 | TASK [Generate Zuul manifest]
2026-04-14 00:59:25.554667 | localhost | ok
2026-04-14 00:59:25.573445 | 
2026-04-14 00:59:25.573531 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-14 00:59:25.935182 | localhost | changed
2026-04-14 00:59:25.953688 | 
2026-04-14 00:59:25.953934 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-14 00:59:26.408083 | localhost | ok
2026-04-14 00:59:26.414674 | 
2026-04-14 00:59:26.414743 | TASK [Upload logs]
2026-04-14 00:59:26.436022 | localhost | ok
2026-04-14 00:59:26.496005 | 
2026-04-14 00:59:26.496119 | TASK [Set zuul-log-path fact]
2026-04-14 00:59:26.519367 | localhost | ok
2026-04-14 00:59:26.534794 | 
2026-04-14 00:59:26.534887 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-14 00:59:26.592726 | localhost | ok
2026-04-14 00:59:26.605006 | 
2026-04-14 00:59:26.605081 | TASK [upload-logs : Create log directories]
2026-04-14 00:59:27.031787 | localhost | changed
2026-04-14 00:59:27.038391 | 
2026-04-14 00:59:27.038489 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-14 00:59:27.492762 | localhost -> localhost | ok: Runtime: 0:00:00.006179
2026-04-14 00:59:27.497733 | 
2026-04-14 00:59:27.497796 | TASK [upload-logs : Upload logs to log server]
2026-04-14 00:59:27.995140 | localhost | Output suppressed because no_log was given
2026-04-14 00:59:28.000275 | 
2026-04-14 00:59:28.000368 | LOOP [upload-logs : Compress console log and json output]
2026-04-14 00:59:28.048473 | localhost | skipping: Conditional result was False
2026-04-14 00:59:28.057425 | localhost | skipping: Conditional result was False
2026-04-14 00:59:28.076525 | 
2026-04-14 00:59:28.076667 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-14 00:59:28.125876 | localhost | skipping: Conditional result was False
2026-04-14 00:59:28.126197 | 
2026-04-14 00:59:28.131411 | localhost | skipping: Conditional result was False
2026-04-14 00:59:28.140111 | 
2026-04-14 00:59:28.140294 | LOOP [upload-logs : Upload console log and json output]