2026-03-17 10:14:41.664914 | Job console starting 2026-03-17 10:14:41.837298 | Updating git repos 2026-03-17 10:14:41.863561 | Cloning repos into workspace 2026-03-17 10:14:42.009331 | Restoring repo states 2026-03-17 10:14:42.015057 | Merging changes 2026-03-17 10:14:42.280757 | Checking out repos 2026-03-17 10:14:43.204451 | Preparing playbooks 2026-03-17 10:15:14.183275 | Running Ansible setup 2026-03-17 10:15:17.856411 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 10:15:18.461204 | 2026-03-17 10:15:18.461334 | PLAY [localhost] 2026-03-17 10:15:18.469058 | 2026-03-17 10:15:18.469146 | TASK [Gathering Facts] 2026-03-17 10:15:19.418410 | localhost | ok 2026-03-17 10:15:19.429462 | 2026-03-17 10:15:19.429584 | TASK [Setup log path fact] 2026-03-17 10:15:19.450605 | localhost | ok 2026-03-17 10:15:19.477503 | 2026-03-17 10:15:19.477649 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 10:15:19.509355 | localhost | ok 2026-03-17 10:15:19.519337 | 2026-03-17 10:15:19.519458 | TASK [emit-job-header : Print job information] 2026-03-17 10:15:19.560900 | # Job Information 2026-03-17 10:15:19.561265 | Ansible Version: 2.16.16 2026-03-17 10:15:19.561334 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-03-17 10:15:19.561373 | Pipeline: check 2026-03-17 10:15:19.561406 | Executor: 0a8996d2b663 2026-03-17 10:15:19.561436 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/102 2026-03-17 10:15:19.561469 | Event ID: a6d8eaf0-219a-11f1-9ba5-6a309433c30d 2026-03-17 10:15:19.567173 | 2026-03-17 10:15:19.568776 | LOOP [emit-job-header : Print node information] 2026-03-17 10:15:19.667176 | localhost | ok: 2026-03-17 10:15:19.667410 | localhost | # Node Information 2026-03-17 10:15:19.667458 | localhost | Inventory Hostname: rockylinux-9 2026-03-17 10:15:19.667490 | localhost | Hostname: np0000159138 2026-03-17 10:15:19.667518 | localhost | Username: zuul 2026-03-17 10:15:19.667542 | localhost | Distro: Rocky 9.7 2026-03-17 10:15:19.667563 | localhost | Provider: yul1 2026-03-17 10:15:19.667588 | localhost | Region: ca-ymq-1 2026-03-17 10:15:19.667611 | localhost | Label: rockylinux-9 2026-03-17 10:15:19.667634 | localhost | Product Name: OpenStack Nova 2026-03-17 10:15:19.667658 | localhost | Interface IP: 199.204.45.113 2026-03-17 10:15:19.690998 | 2026-03-17 10:15:19.691104 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-17 10:15:20.208256 | localhost -> localhost | changed 2026-03-17 10:15:20.217463 | 2026-03-17 10:15:20.217619 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-17 10:15:21.154178 | localhost -> localhost | changed 2026-03-17 10:15:21.168428 | 2026-03-17 10:15:21.168614 | PLAY [all] 2026-03-17 10:15:21.180424 | 2026-03-17 10:15:21.180506 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-17 10:15:21.441716 | rockylinux-9 -> localhost | ok 2026-03-17 10:15:21.453823 | 2026-03-17 10:15:21.453917 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-17 10:15:21.486779 | rockylinux-9 | ok 2026-03-17 10:15:21.515661 | rockylinux-9 | included: /var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-17 10:15:21.521872 | 2026-03-17 10:15:21.521964 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-17 10:15:23.613485 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-17 10:15:23.613664 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/work/2adcdb2fd4134c5cb6c14dbb1d077a3d_id_rsa 2026-03-17 10:15:23.613707 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/work/2adcdb2fd4134c5cb6c14dbb1d077a3d_id_rsa.pub 2026-03-17 10:15:23.613736 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-17 10:15:23.613763 | rockylinux-9 -> localhost | SHA256:YcKWKxnUcK67xKbc4XOWUc1xlGHpWlDWhkdDfX+5mnY zuul-build-sshkey 2026-03-17 10:15:23.613798 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-17 10:15:23.613826 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-17 10:15:23.613925 | rockylinux-9 -> localhost | | oo. o*O+. | 2026-03-17 10:15:23.613957 | rockylinux-9 -> localhost | | . +.. oo= +.o| 2026-03-17 10:15:23.613987 | rockylinux-9 -> localhost | | . * oo = o +| 2026-03-17 10:15:23.614017 | rockylinux-9 -> localhost | | = +..o o .o| 2026-03-17 10:15:23.614047 | rockylinux-9 -> localhost | | + ..S o o| 2026-03-17 10:15:23.614072 | rockylinux-9 -> localhost | | . o. . . | 2026-03-17 10:15:23.614097 | rockylinux-9 -> localhost | | * o o | 2026-03-17 10:15:23.614122 | rockylinux-9 -> localhost | | . *.o+ + E | 2026-03-17 10:15:23.614150 | rockylinux-9 -> localhost | | o ++ . . | 2026-03-17 10:15:23.614177 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-17 10:15:23.614236 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.685795 2026-03-17 10:15:23.620546 | 2026-03-17 10:15:23.620621 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-17 10:15:23.654891 | rockylinux-9 | ok 2026-03-17 10:15:23.663291 | rockylinux-9 | included: /var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-17 10:15:23.671312 | 2026-03-17 10:15:23.671378 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-17 10:15:23.696452 | rockylinux-9 | skipping: Conditional result was False 2026-03-17 10:15:23.704688 | 2026-03-17 10:15:23.704800 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-17 10:15:24.142660 | rockylinux-9 | changed 2026-03-17 10:15:24.148004 | 2026-03-17 10:15:24.148079 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-17 10:15:24.387068 | rockylinux-9 | ok 2026-03-17 10:15:24.391418 | 2026-03-17 10:15:24.391478 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-17 10:15:25.277718 | rockylinux-9 | changed 2026-03-17 10:15:25.356419 | 2026-03-17 10:15:25.356586 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-17 10:15:26.282468 | rockylinux-9 | changed 2026-03-17 10:15:26.380024 | 2026-03-17 10:15:26.380131 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-17 10:15:26.403516 | rockylinux-9 | skipping: Conditional result was False 2026-03-17 10:15:26.414728 | 2026-03-17 10:15:26.414971 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-17 10:15:26.834090 | rockylinux-9 -> localhost | changed 2026-03-17 10:15:26.848247 | 2026-03-17 10:15:26.848403 | TASK [add-build-sshkey : Add back temp key] 2026-03-17 10:15:27.186224 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/work/2adcdb2fd4134c5cb6c14dbb1d077a3d_id_rsa (zuul-build-sshkey) 2026-03-17 10:15:27.186454 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014187 2026-03-17 10:15:27.193881 | 2026-03-17 10:15:27.193970 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-17 10:15:27.588242 | rockylinux-9 | ok 2026-03-17 10:15:27.593706 | 2026-03-17 10:15:27.593803 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-17 10:15:27.617715 | rockylinux-9 | skipping: Conditional result was False 2026-03-17 10:15:27.629150 | 2026-03-17 10:15:27.629283 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-17 10:15:28.037626 | rockylinux-9 | ok 2026-03-17 10:15:28.073723 | 2026-03-17 10:15:28.073871 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-17 10:15:29.099500 | rockylinux-9 | Output suppressed because no_log was given 2026-03-17 10:15:29.109040 | 2026-03-17 10:15:29.109116 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-17 10:15:29.346510 | rockylinux-9 | ok: "logs" 2026-03-17 10:15:29.346937 | rockylinux-9 | ok: All items complete 2026-03-17 10:15:29.346986 | 2026-03-17 10:15:29.624699 | rockylinux-9 | ok: "artifacts" 2026-03-17 10:15:29.840823 | rockylinux-9 | ok: "docs" 2026-03-17 10:15:29.857951 | 2026-03-17 10:15:29.858117 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-17 10:15:30.110043 | rockylinux-9 | changed: "logs" 2026-03-17 10:15:30.344660 | rockylinux-9 | changed: "artifacts" 2026-03-17 10:15:30.572830 | rockylinux-9 | changed: "docs" 2026-03-17 10:15:30.587956 | 2026-03-17 10:15:30.588097 | PLAY RECAP 2026-03-17 10:15:30.588187 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 10:15:30.588255 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-17 10:15:30.588304 | 2026-03-17 10:15:30.705750 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-17 10:15:31.075072 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 10:15:31.746569 | 2026-03-17 10:15:31.858027 | PLAY [all] 2026-03-17 10:15:31.940819 | 2026-03-17 10:15:31.941110 | TASK [setup-uv : Extract archive] 2026-03-17 10:15:35.586501 | rockylinux-9 | changed 2026-03-17 10:15:35.702410 | 2026-03-17 10:15:35.702661 | TASK [setup-uv : Print version] 2026-03-17 10:15:35.726500 | [rockylinux-9] Waiting on logger 2026-03-17 10:15:36.244228 | rockylinux-9 | ok: Runtime: 0:00:00.013549 2026-03-17 10:15:36.252677 | 2026-03-17 10:15:36.252730 | PLAY RECAP 2026-03-17 10:15:36.252772 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 10:15:36.252795 | 2026-03-17 10:15:36.356950 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-17 10:15:36.358340 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 10:15:38.631080 | 2026-03-17 10:15:38.763295 | PLAY [all] 2026-03-17 10:15:38.780678 | 2026-03-17 10:15:38.780804 | TASK [Copy inventory file for Zuul] 2026-03-17 10:15:40.193264 | rockylinux-9 | changed 2026-03-17 10:15:40.200265 | 2026-03-17 10:15:40.200378 | TASK [Switch "ansible_host" to private IP] 2026-03-17 10:15:40.541948 | rockylinux-9 | changed: 1 replacements made 2026-03-17 10:15:40.637180 | 2026-03-17 10:15:40.643030 | TASK [Run Molecule scenario] 2026-03-17 10:15:40.674082 | [rockylinux-9] Waiting on logger 2026-03-17 10:15:46.795511 | [rockylinux-9] Waiting on logger 2026-03-17 10:15:56.966071 | [rockylinux-9] Waiting on logger 2026-03-17 10:16:07.173930 | [rockylinux-9] Waiting on logger 2026-03-17 10:16:17.385886 | [rockylinux-9] Waiting on logger 2026-03-17 10:16:27.558150 | [rockylinux-9] Waiting on logger 2026-03-17 10:16:37.733851 | [rockylinux-9] Waiting on logger 2026-03-17 10:16:46.885800 | [rockylinux-9] Waiting on logger 2026-03-17 10:16:57.065878 | [rockylinux-9] Waiting on logger 2026-03-17 10:17:07.242032 | [rockylinux-9] Waiting on logger 2026-03-17 10:17:17.414089 | [rockylinux-9] Waiting on logger 2026-03-17 10:17:27.589720 | [rockylinux-9] Waiting on logger 2026-03-17 10:17:36.747152 | [rockylinux-9] Waiting on logger 2026-03-17 10:17:43.469868 | rockylinux-9 | ok: Runtime: 0:02:01.315607 2026-03-17 10:17:43.478628 | 2026-03-17 10:17:43.478701 | PLAY RECAP 2026-03-17 10:17:43.478748 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-17 10:17:43.478771 | 2026-03-17 10:17:43.595138 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-17 10:17:43.597488 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 10:17:44.208888 | 2026-03-17 10:17:44.209024 | PLAY [all] 2026-03-17 10:17:44.219020 | 2026-03-17 10:17:44.219099 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-17 10:17:44.263155 | rockylinux-9 | skipping: Conditional result was False 2026-03-17 10:17:44.271832 | 2026-03-17 10:17:44.271939 | TASK [fetch-output : Set log path for single node] 2026-03-17 10:17:44.315299 | rockylinux-9 | ok 2026-03-17 10:17:44.319407 | 2026-03-17 10:17:44.319483 | LOOP [fetch-output : Ensure local output dirs] 2026-03-17 10:17:44.717724 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/work/logs" 2026-03-17 10:17:44.940671 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/work/artifacts" 2026-03-17 10:17:45.164846 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2adcdb2fd4134c5cb6c14dbb1d077a3d/work/docs" 2026-03-17 10:17:45.188240 | 2026-03-17 10:17:45.188368 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-17 10:17:45.970102 | rockylinux-9 | changed: .d..t...... ./ 2026-03-17 10:17:45.970419 | rockylinux-9 | changed: All items complete 2026-03-17 10:17:45.970492 | 2026-03-17 10:17:46.521750 | rockylinux-9 | changed: .d..t...... ./ 2026-03-17 10:17:47.035050 | rockylinux-9 | changed: .d..t...... ./ 2026-03-17 10:17:47.051773 | 2026-03-17 10:17:47.051892 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-17 10:17:47.533205 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006676 2026-03-17 10:17:47.736017 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.005229 2026-03-17 10:17:47.750272 | 2026-03-17 10:17:47.750395 | PLAY [all] 2026-03-17 10:17:47.759389 | 2026-03-17 10:17:47.759569 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-17 10:17:48.227190 | rockylinux-9 | changed 2026-03-17 10:17:48.234122 | 2026-03-17 10:17:48.234181 | PLAY RECAP 2026-03-17 10:17:48.234226 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-17 10:17:48.234247 | 2026-03-17 10:17:48.340912 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-17 10:17:48.343629 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-17 10:17:48.944728 | 2026-03-17 10:17:48.944894 | PLAY [localhost] 2026-03-17 10:17:48.956532 | 2026-03-17 10:17:48.956681 | TASK [Generate Zuul manifest] 2026-03-17 10:17:48.975593 | localhost | ok 2026-03-17 10:17:48.992476 | 2026-03-17 10:17:48.992587 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-17 10:17:49.304042 | localhost | changed 2026-03-17 10:17:49.317879 | 2026-03-17 10:17:49.318006 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-17 10:17:49.345172 | localhost | ok 2026-03-17 10:17:49.352210 | 2026-03-17 10:17:49.352277 | TASK [Upload logs] 2026-03-17 10:17:49.368684 | localhost | ok 2026-03-17 10:17:49.427421 | 2026-03-17 10:17:49.427525 | TASK [Set zuul-log-path fact] 2026-03-17 10:17:49.445059 | localhost | ok 2026-03-17 10:17:49.459010 | 2026-03-17 10:17:49.459086 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-17 10:17:49.489354 | localhost | ok 2026-03-17 10:17:49.500890 | 2026-03-17 10:17:49.501034 | TASK [upload-logs : Create log directories] 2026-03-17 10:17:49.868057 | localhost | changed 2026-03-17 10:17:49.872644 | 2026-03-17 10:17:49.872715 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-17 10:17:50.286725 | localhost -> localhost | ok: Runtime: 0:00:00.003921 2026-03-17 10:17:50.294092 | 2026-03-17 10:17:50.294226 | TASK [upload-logs : Upload logs to log server] 2026-03-17 10:17:50.750881 | localhost | Output suppressed because no_log was given 2026-03-17 10:17:50.755760 | 2026-03-17 10:17:50.761880 | LOOP [upload-logs : Compress console log and json output] 2026-03-17 10:17:50.805231 | localhost | skipping: Conditional result was False 2026-03-17 10:17:50.812881 | localhost | skipping: Conditional result was False 2026-03-17 10:17:50.825678 | 2026-03-17 10:17:50.825879 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-17 10:17:50.870081 | localhost | skipping: Conditional result was False 2026-03-17 10:17:50.870530 | 2026-03-17 10:17:50.874691 | localhost | skipping: Conditional result was False 2026-03-17 10:17:50.886201 | 2026-03-17 10:17:50.886344 | LOOP [upload-logs : Upload console log and json output]