2026-04-27 12:32:10.877421 | Job console starting 2026-04-27 12:32:10.922115 | Updating git repos 2026-04-27 12:32:11.531240 | Cloning repos into workspace 2026-04-27 12:32:12.951403 | Restoring repo states 2026-04-27 12:32:12.959135 | Merging changes 2026-04-27 12:32:13.587504 | Checking out repos 2026-04-27 12:32:13.872431 | Preparing playbooks 2026-04-27 12:32:18.074068 | Running Ansible setup 2026-04-27 12:32:22.995716 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-27 12:32:23.658784 | 2026-04-27 12:32:23.658915 | PLAY [localhost] 2026-04-27 12:32:23.666588 | 2026-04-27 12:32:23.666700 | TASK [Gathering Facts] 2026-04-27 12:32:25.146160 | localhost | ok 2026-04-27 12:32:25.153700 | 2026-04-27 12:32:25.153788 | TASK [Setup log path fact] 2026-04-27 12:32:25.177334 | localhost | ok 2026-04-27 12:32:25.189213 | 2026-04-27 12:32:25.189285 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-27 12:32:25.217739 | localhost | ok 2026-04-27 12:32:25.228341 | 2026-04-27 12:32:25.228417 | TASK [emit-job-header : Print job information] 2026-04-27 12:32:25.271381 | # Job Information 2026-04-27 12:32:25.271628 | Ansible Version: 2.16.16 2026-04-27 12:32:25.271699 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-27 12:32:25.271750 | Pipeline: check 2026-04-27 12:32:25.271795 | Executor: 0a8996d2b663 2026-04-27 12:32:25.271838 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/123 2026-04-27 12:32:25.271887 | Event ID: b6724230-4234-11f1-96cd-837a86eff685 2026-04-27 12:32:25.274678 | 2026-04-27 12:32:25.274750 | LOOP [emit-job-header : Print node information] 2026-04-27 12:32:25.377922 | localhost | ok: 2026-04-27 12:32:25.378158 | localhost | # Node Information 2026-04-27 12:32:25.378201 | localhost | Inventory Hostname: rockylinux-9 2026-04-27 12:32:25.378233 | localhost | Hostname: np0000170566 2026-04-27 12:32:25.378264 | localhost | Username: zuul 2026-04-27 12:32:25.378297 | localhost | Distro: Rocky 9.7 2026-04-27 12:32:25.378327 | localhost | Provider: yul1 2026-04-27 12:32:25.378355 | localhost | Region: ca-ymq-1 2026-04-27 12:32:25.378383 | localhost | Label: rockylinux-9 2026-04-27 12:32:25.378411 | localhost | Product Name: OpenStack Nova 2026-04-27 12:32:25.378438 | localhost | Interface IP: 199.19.213.204 2026-04-27 12:32:25.394150 | 2026-04-27 12:32:25.394309 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-27 12:32:25.831021 | localhost -> localhost | changed 2026-04-27 12:32:25.841268 | 2026-04-27 12:32:25.841347 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-27 12:32:26.767170 | localhost -> localhost | changed 2026-04-27 12:32:26.777035 | 2026-04-27 12:32:26.777124 | PLAY [all] 2026-04-27 12:32:26.785269 | 2026-04-27 12:32:26.785365 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-27 12:32:27.032740 | rockylinux-9 -> localhost | ok 2026-04-27 12:32:27.038590 | 2026-04-27 12:32:27.038661 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-27 12:32:27.072585 | rockylinux-9 | ok 2026-04-27 12:32:27.091587 | rockylinux-9 | included: /var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-27 12:32:27.097507 | 2026-04-27 12:32:27.097595 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-27 12:32:29.353008 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-27 12:32:29.353203 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/work/b33c171b3f08413b98de28dda7497d84_id_rsa 2026-04-27 12:32:29.353250 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/work/b33c171b3f08413b98de28dda7497d84_id_rsa.pub 2026-04-27 12:32:29.353285 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-27 12:32:29.353317 | rockylinux-9 -> localhost | SHA256:L28+1R/37CfXBXs3zRje4dwXuN1EA+fk3AiiAJWBbag zuul-build-sshkey 2026-04-27 12:32:29.353364 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-27 12:32:29.353395 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-27 12:32:29.353432 | rockylinux-9 -> localhost | | .*+o . ...o | 2026-04-27 12:32:29.353464 | rockylinux-9 -> localhost | | o +. . . .*oo| 2026-04-27 12:32:29.353492 | rockylinux-9 -> localhost | | . . . o=o| 2026-04-27 12:32:29.353521 | rockylinux-9 -> localhost | | E .oo.| 2026-04-27 12:32:29.353548 | rockylinux-9 -> localhost | | S o=O*| 2026-04-27 12:32:29.353576 | rockylinux-9 -> localhost | | . ..=*%| 2026-04-27 12:32:29.353604 | rockylinux-9 -> localhost | | . .. +X| 2026-04-27 12:32:29.353631 | rockylinux-9 -> localhost | | oo . B| 2026-04-27 12:32:29.353662 | rockylinux-9 -> localhost | | oo. +o| 2026-04-27 12:32:29.353690 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-27 12:32:29.353794 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.808572 2026-04-27 12:32:29.359003 | 2026-04-27 12:32:29.359069 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-27 12:32:29.391118 | rockylinux-9 | ok 2026-04-27 12:32:29.401512 | rockylinux-9 | included: /var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-27 12:32:29.409364 | 2026-04-27 12:32:29.409435 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-27 12:32:29.433399 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 12:32:29.442406 | 2026-04-27 12:32:29.442531 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-27 12:32:29.948727 | rockylinux-9 | changed 2026-04-27 12:32:29.956876 | 2026-04-27 12:32:29.957065 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-27 12:32:30.206571 | rockylinux-9 | ok 2026-04-27 12:32:30.216487 | 2026-04-27 12:32:30.216627 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-27 12:32:31.153325 | rockylinux-9 | changed 2026-04-27 12:32:31.160737 | 2026-04-27 12:32:31.160861 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-27 12:32:32.063102 | rockylinux-9 | changed 2026-04-27 12:32:32.067721 | 2026-04-27 12:32:32.067786 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-27 12:32:32.092482 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 12:32:32.104072 | 2026-04-27 12:32:32.104178 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-27 12:32:32.521186 | rockylinux-9 -> localhost | changed 2026-04-27 12:32:32.533035 | 2026-04-27 12:32:32.533163 | TASK [add-build-sshkey : Add back temp key] 2026-04-27 12:32:32.826959 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/work/b33c171b3f08413b98de28dda7497d84_id_rsa (zuul-build-sshkey) 2026-04-27 12:32:32.827285 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012118 2026-04-27 12:32:32.836889 | 2026-04-27 12:32:32.837097 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-27 12:32:33.227192 | rockylinux-9 | ok 2026-04-27 12:32:34.128462 | 2026-04-27 12:32:34.128632 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-27 12:32:34.174544 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 12:32:34.189517 | 2026-04-27 12:32:34.189599 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-27 12:32:34.608727 | rockylinux-9 | ok 2026-04-27 12:32:34.614420 | 2026-04-27 12:32:34.614554 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-27 12:32:35.582886 | rockylinux-9 | Output suppressed because no_log was given 2026-04-27 12:32:35.594031 | 2026-04-27 12:32:35.594150 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-27 12:32:35.849436 | rockylinux-9 | ok: "logs" 2026-04-27 12:32:35.849692 | rockylinux-9 | ok: All items complete 2026-04-27 12:32:35.849722 | 2026-04-27 12:32:36.065917 | rockylinux-9 | ok: "artifacts" 2026-04-27 12:32:36.360017 | rockylinux-9 | ok: "docs" 2026-04-27 12:32:36.373479 | 2026-04-27 12:32:36.373666 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-27 12:32:36.626164 | rockylinux-9 | changed: "logs" 2026-04-27 12:32:36.851306 | rockylinux-9 | changed: "artifacts" 2026-04-27 12:32:37.087430 | rockylinux-9 | changed: "docs" 2026-04-27 12:32:37.099006 | 2026-04-27 12:32:37.099085 | PLAY RECAP 2026-04-27 12:32:37.099126 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 12:32:37.099155 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-27 12:32:37.099175 | 2026-04-27 12:32:37.233840 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-27 12:32:37.236141 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-27 12:32:37.860463 | 2026-04-27 12:32:37.860593 | PLAY [all] 2026-04-27 12:32:37.872089 | 2026-04-27 12:32:37.872176 | TASK [setup-uv : Extract archive] 2026-04-27 12:32:40.637480 | rockylinux-9 | changed 2026-04-27 12:32:40.645325 | 2026-04-27 12:32:40.645511 | TASK [setup-uv : Print version] 2026-04-27 12:32:40.668657 | [rockylinux-9] Waiting on logger 2026-04-27 12:32:41.183288 | rockylinux-9 | ok: Runtime: 0:00:00.012293 2026-04-27 12:32:41.190536 | 2026-04-27 12:32:41.190586 | PLAY RECAP 2026-04-27 12:32:41.190631 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 12:32:41.190655 | 2026-04-27 12:32:41.331520 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-27 12:32:41.716993 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-27 12:32:42.335559 | 2026-04-27 12:32:42.335723 | PLAY [all] 2026-04-27 12:32:42.347611 | 2026-04-27 12:32:42.347697 | TASK [Copy inventory file for Zuul] 2026-04-27 12:32:43.704752 | rockylinux-9 | changed 2026-04-27 12:32:43.712148 | 2026-04-27 12:32:43.712330 | TASK [Switch "ansible_host" to private IP] 2026-04-27 12:32:44.083126 | rockylinux-9 | changed: 1 replacements made 2026-04-27 12:32:44.088067 | 2026-04-27 12:32:44.088145 | TASK [Run Molecule scenario] 2026-04-27 12:32:44.109965 | [rockylinux-9] Waiting on logger 2026-04-27 12:32:52.266096 | [rockylinux-9] Waiting on logger 2026-04-27 12:33:02.438334 | [rockylinux-9] Waiting on logger 2026-04-27 12:33:12.613972 | [rockylinux-9] Waiting on logger 2026-04-27 12:33:21.771419 | [rockylinux-9] Waiting on logger 2026-04-27 12:33:31.946171 | [rockylinux-9] Waiting on logger 2026-04-27 12:33:42.118783 | [rockylinux-9] Waiting on logger 2026-04-27 12:33:52.294241 | [rockylinux-9] Waiting on logger 2026-04-27 12:34:02.470064 | [rockylinux-9] Waiting on logger 2026-04-27 12:34:12.646691 | [rockylinux-9] Waiting on logger 2026-04-27 12:34:21.801928 | [rockylinux-9] Waiting on logger 2026-04-27 12:34:31.978019 | [rockylinux-9] Waiting on logger 2026-04-27 12:34:42.182017 | [rockylinux-9] Waiting on logger 2026-04-27 12:34:52.358158 | [rockylinux-9] Waiting on logger 2026-04-27 12:35:02.542307 | rockylinux-9 | ok: Runtime: 0:02:17.482735 2026-04-27 12:35:02.550054 | 2026-04-27 12:35:02.550123 | PLAY RECAP 2026-04-27 12:35:02.550185 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-27 12:35:02.550216 | 2026-04-27 12:35:02.675688 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-27 12:35:02.678328 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-27 12:35:03.304660 | 2026-04-27 12:35:03.304828 | PLAY [all] 2026-04-27 12:35:03.316097 | 2026-04-27 12:35:03.316198 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-27 12:35:03.362260 | rockylinux-9 | skipping: Conditional result was False 2026-04-27 12:35:03.372591 | 2026-04-27 12:35:03.372893 | TASK [fetch-output : Set log path for single node] 2026-04-27 12:35:03.422461 | rockylinux-9 | ok 2026-04-27 12:35:03.427699 | 2026-04-27 12:35:03.427863 | LOOP [fetch-output : Ensure local output dirs] 2026-04-27 12:35:03.810394 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/work/logs" 2026-04-27 12:35:04.033700 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/work/artifacts" 2026-04-27 12:35:04.254393 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/b33c171b3f08413b98de28dda7497d84/work/docs" 2026-04-27 12:35:04.270634 | 2026-04-27 12:35:04.270838 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-27 12:35:04.973465 | rockylinux-9 | changed: .d..t...... ./ 2026-04-27 12:35:04.973934 | rockylinux-9 | changed: All items complete 2026-04-27 12:35:04.973997 | 2026-04-27 12:35:05.494263 | rockylinux-9 | changed: .d..t...... ./ 2026-04-27 12:35:06.025097 | rockylinux-9 | changed: .d..t...... ./ 2026-04-27 12:35:06.055301 | 2026-04-27 12:35:06.055518 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-27 12:35:06.484272 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006462 2026-04-27 12:35:06.718671 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008183 2026-04-27 12:35:06.742125 | 2026-04-27 12:35:06.742318 | PLAY [all] 2026-04-27 12:35:06.748750 | 2026-04-27 12:35:06.748819 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-27 12:35:07.248385 | rockylinux-9 | changed 2026-04-27 12:35:07.256840 | 2026-04-27 12:35:07.256911 | PLAY RECAP 2026-04-27 12:35:07.256994 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-27 12:35:07.257029 | 2026-04-27 12:35:07.390743 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-27 12:35:07.393092 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-27 12:35:07.955637 | 2026-04-27 12:35:07.955951 | PLAY [localhost] 2026-04-27 12:35:07.966721 | 2026-04-27 12:35:07.966861 | TASK [Generate Zuul manifest] 2026-04-27 12:35:07.989390 | localhost | ok 2026-04-27 12:35:08.007987 | 2026-04-27 12:35:08.008106 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-27 12:35:08.341479 | localhost | changed 2026-04-27 12:35:08.356034 | 2026-04-27 12:35:08.356216 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-27 12:35:08.387942 | localhost | ok 2026-04-27 12:35:08.398050 | 2026-04-27 12:35:08.398171 | TASK [Upload logs] 2026-04-27 12:35:08.419343 | localhost | ok 2026-04-27 12:35:08.484945 | 2026-04-27 12:35:08.485102 | TASK [Set zuul-log-path fact] 2026-04-27 12:35:08.503181 | localhost | ok 2026-04-27 12:35:08.514978 | 2026-04-27 12:35:08.515048 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-27 12:35:08.543825 | localhost | ok 2026-04-27 12:35:08.551184 | 2026-04-27 12:35:08.551280 | TASK [upload-logs : Create log directories] 2026-04-27 12:35:09.010558 | localhost | changed 2026-04-27 12:35:09.015689 | 2026-04-27 12:35:09.015761 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-27 12:35:09.438492 | localhost -> localhost | ok: Runtime: 0:00:00.008783 2026-04-27 12:35:09.445452 | 2026-04-27 12:35:09.445543 | TASK [upload-logs : Upload logs to log server] 2026-04-27 12:35:09.914273 | localhost | Output suppressed because no_log was given 2026-04-27 12:35:09.919835 | 2026-04-27 12:35:09.919924 | LOOP [upload-logs : Compress console log and json output] 2026-04-27 12:35:09.972089 | localhost | skipping: Conditional result was False 2026-04-27 12:35:09.980384 | localhost | skipping: Conditional result was False 2026-04-27 12:35:09.995330 | 2026-04-27 12:35:09.995445 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-27 12:35:10.042480 | localhost | skipping: Conditional result was False 2026-04-27 12:35:10.042938 | 2026-04-27 12:35:10.046323 | localhost | skipping: Conditional result was False 2026-04-27 12:35:10.059481 | 2026-04-27 12:35:10.059648 | LOOP [upload-logs : Upload console log and json output]