2026-04-18 00:53:29.613885 | Job console starting 2026-04-18 00:53:29.624104 | Updating git repos 2026-04-18 00:53:29.662679 | Cloning repos into workspace 2026-04-18 00:53:29.731217 | Restoring repo states 2026-04-18 00:53:29.737853 | Merging changes 2026-04-18 00:53:30.081313 | Checking out repos 2026-04-18 00:53:30.114159 | Preparing playbooks 2026-04-18 00:53:31.542255 | Running Ansible setup 2026-04-18 00:53:35.483654 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 00:53:36.063763 | 2026-04-18 00:53:36.063950 | PLAY [localhost] 2026-04-18 00:53:36.073649 | 2026-04-18 00:53:36.073791 | TASK [Gathering Facts] 2026-04-18 00:53:37.311003 | localhost | ok 2026-04-18 00:53:37.317651 | 2026-04-18 00:53:37.317728 | TASK [Setup log path fact] 2026-04-18 00:53:37.363527 | localhost | ok 2026-04-18 00:53:37.375601 | 2026-04-18 00:53:37.375777 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 00:53:37.405247 | localhost | ok 2026-04-18 00:53:37.412881 | 2026-04-18 00:53:37.413862 | TASK [emit-job-header : Print job information] 2026-04-18 00:53:37.452656 | # Job Information 2026-04-18 00:53:37.452843 | Ansible Version: 2.16.16 2026-04-18 00:53:37.452890 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-04-18 00:53:37.452916 | Pipeline: check 2026-04-18 00:53:37.452938 | Executor: 0a8996d2b663 2026-04-18 00:53:37.452977 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109 2026-04-18 00:53:37.453001 | Event ID: e69d5b00-3ac0-11f1-8e84-863288ff99e4 2026-04-18 00:53:37.455883 | 2026-04-18 00:53:37.455952 | LOOP [emit-job-header : Print node information] 2026-04-18 00:53:37.544299 | localhost | ok: 2026-04-18 00:53:37.544463 | localhost | # Node Information 2026-04-18 00:53:37.544498 | localhost | Inventory Hostname: rockylinux-9 2026-04-18 00:53:37.544525 | localhost | Hostname: np0000167186 2026-04-18 00:53:37.544549 | localhost | Username: zuul 2026-04-18 00:53:37.544577 | localhost | Distro: Rocky 9.7 2026-04-18 00:53:37.544601 | localhost | Provider: yul1 2026-04-18 00:53:37.544624 | localhost | Region: ca-ymq-1 2026-04-18 00:53:37.544647 | localhost | Label: rockylinux-9 2026-04-18 00:53:37.544669 | localhost | Product Name: OpenStack Nova 2026-04-18 00:53:37.544692 | localhost | Interface IP: 199.204.45.22 2026-04-18 00:53:37.559104 | 2026-04-18 00:53:37.559251 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 00:53:38.146992 | localhost -> localhost | changed 2026-04-18 00:53:38.152388 | 2026-04-18 00:53:38.152474 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 00:53:39.669581 | localhost -> localhost | changed 2026-04-18 00:53:39.676615 | 2026-04-18 00:53:39.676690 | PLAY [all] 2026-04-18 00:53:39.684093 | 2026-04-18 00:53:39.684176 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 00:53:39.932590 | rockylinux-9 -> localhost | ok 2026-04-18 00:53:39.938561 | 2026-04-18 00:53:39.938632 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 00:53:39.963465 | rockylinux-9 | ok 2026-04-18 00:53:39.977560 | rockylinux-9 | included: /var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 00:53:39.982823 | 2026-04-18 00:53:39.982921 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 00:53:41.213941 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-18 00:53:41.214571 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/work/58ed8b12d8ba49098bf7923445816727_id_rsa 2026-04-18 00:53:41.214620 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/work/58ed8b12d8ba49098bf7923445816727_id_rsa.pub 2026-04-18 00:53:41.214649 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-18 00:53:41.214674 | rockylinux-9 -> localhost | SHA256:kIv4sfMyXmE0s7pCxYMxtRTxvCKdfiNLTt+zo9SI8RE zuul-build-sshkey 2026-04-18 00:53:41.214716 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-18 00:53:41.214739 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-18 00:53:41.214764 | rockylinux-9 -> localhost | | .=o | 2026-04-18 00:53:41.214787 | rockylinux-9 -> localhost | | o. + . | 2026-04-18 00:53:41.214809 | rockylinux-9 -> localhost | | =. E | 2026-04-18 00:53:41.214832 | rockylinux-9 -> localhost | | .o++ O | 2026-04-18 00:53:41.214868 | rockylinux-9 -> localhost | | o.B.B S | 2026-04-18 00:53:41.214890 | rockylinux-9 -> localhost | | .+ X = | 2026-04-18 00:53:41.214913 | rockylinux-9 -> localhost | | . @ B . | 2026-04-18 00:53:41.214935 | rockylinux-9 -> localhost | | .+o@ oo | 2026-04-18 00:53:41.214959 | rockylinux-9 -> localhost | | o=o+.o+ | 2026-04-18 00:53:41.214981 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-18 00:53:41.215033 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.750269 2026-04-18 00:53:41.221293 | 2026-04-18 00:53:41.221378 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 00:53:41.256278 | rockylinux-9 | ok 2026-04-18 00:53:41.265599 | rockylinux-9 | included: /var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 00:53:41.273389 | 2026-04-18 00:53:41.273460 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 00:53:41.288332 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:53:41.293334 | 2026-04-18 00:53:41.293432 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 00:53:41.769114 | rockylinux-9 | changed 2026-04-18 00:53:41.775143 | 2026-04-18 00:53:41.775234 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 00:53:42.051740 | rockylinux-9 | ok 2026-04-18 00:53:42.056348 | 2026-04-18 00:53:42.056416 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 00:53:43.007882 | rockylinux-9 | changed 2026-04-18 00:53:43.012651 | 2026-04-18 00:53:43.012776 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 00:53:43.932885 | rockylinux-9 | changed 2026-04-18 00:53:43.938007 | 2026-04-18 00:53:43.938083 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 00:53:43.963235 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:53:43.969005 | 2026-04-18 00:53:43.969084 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 00:53:44.368057 | rockylinux-9 -> localhost | changed 2026-04-18 00:53:44.378084 | 2026-04-18 00:53:44.378174 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 00:53:44.672410 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/work/58ed8b12d8ba49098bf7923445816727_id_rsa (zuul-build-sshkey) 2026-04-18 00:53:44.672687 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011696 2026-04-18 00:53:44.692368 | 2026-04-18 00:53:44.692568 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 00:53:45.129000 | rockylinux-9 | ok 2026-04-18 00:53:45.133663 | 2026-04-18 00:53:45.133754 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 00:53:45.159051 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:53:45.175289 | 2026-04-18 00:53:45.175412 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 00:53:45.603279 | rockylinux-9 | ok 2026-04-18 00:53:45.608620 | 2026-04-18 00:53:45.608694 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 00:53:46.520097 | rockylinux-9 | Output suppressed because no_log was given 2026-04-18 00:53:46.527978 | 2026-04-18 00:53:46.528052 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 00:53:46.772900 | rockylinux-9 | ok: "logs" 2026-04-18 00:53:46.773244 | rockylinux-9 | ok: All items complete 2026-04-18 00:53:46.773288 | 2026-04-18 00:53:46.982077 | rockylinux-9 | ok: "artifacts" 2026-04-18 00:53:47.207470 | rockylinux-9 | ok: "docs" 2026-04-18 00:53:47.222179 | 2026-04-18 00:53:47.222315 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 00:53:47.475167 | rockylinux-9 | changed: "logs" 2026-04-18 00:53:47.698607 | rockylinux-9 | changed: "artifacts" 2026-04-18 00:53:47.935708 | rockylinux-9 | changed: "docs" 2026-04-18 00:53:47.947316 | 2026-04-18 00:53:47.947448 | PLAY RECAP 2026-04-18 00:53:47.947502 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 00:53:47.947534 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 00:53:47.947555 | 2026-04-18 00:53:48.110837 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 00:53:48.112349 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 00:53:48.718032 | 2026-04-18 00:53:48.718205 | PLAY [all] 2026-04-18 00:53:48.729673 | 2026-04-18 00:53:48.729801 | TASK [setup-uv : Extract archive] 2026-04-18 00:53:51.503147 | rockylinux-9 | changed 2026-04-18 00:53:51.508493 | 2026-04-18 00:53:51.508571 | TASK [setup-uv : Print version] 2026-04-18 00:53:51.531189 | [rockylinux-9] Waiting on logger 2026-04-18 00:53:52.047549 | rockylinux-9 | ok: Runtime: 0:00:00.017997 2026-04-18 00:53:52.056392 | 2026-04-18 00:53:52.056528 | PLAY RECAP 2026-04-18 00:53:52.056593 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 00:53:52.056626 | 2026-04-18 00:53:52.258729 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 00:53:52.261266 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 00:53:52.902605 | 2026-04-18 00:53:52.902874 | PLAY [all] 2026-04-18 00:53:52.915960 | 2026-04-18 00:53:52.916076 | TASK [Copy inventory file for Zuul] 2026-04-18 00:53:54.280405 | rockylinux-9 | changed 2026-04-18 00:53:54.285709 | 2026-04-18 00:53:54.285846 | TASK [Switch "ansible_host" to private IP] 2026-04-18 00:53:54.644472 | rockylinux-9 | changed: 1 replacements made 2026-04-18 00:53:54.651449 | 2026-04-18 00:53:54.651559 | TASK [Run Molecule scenario] 2026-04-18 00:53:54.672950 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:02.822061 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:12.998542 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:23.173888 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:33.350062 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:43.526223 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:52.678193 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:02.854162 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:13.029951 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:23.206067 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:33.382140 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:42.540151 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:48.143191 | rockylinux-9 | ok: Runtime: 0:01:52.408613 2026-04-18 00:55:48.149612 | 2026-04-18 00:55:48.149658 | PLAY RECAP 2026-04-18 00:55:48.149701 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 00:55:48.149724 | 2026-04-18 00:55:48.268973 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 00:55:48.270782 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 00:55:48.824762 | 2026-04-18 00:55:48.824900 | PLAY [all] 2026-04-18 00:55:48.835462 | 2026-04-18 00:55:48.835540 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 00:55:48.881001 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:55:48.889008 | 2026-04-18 00:55:48.889115 | TASK [fetch-output : Set log path for single node] 2026-04-18 00:55:48.933318 | rockylinux-9 | ok 2026-04-18 00:55:48.940047 | 2026-04-18 00:55:48.940158 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 00:55:49.347794 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/work/logs" 2026-04-18 00:55:49.549099 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/work/artifacts" 2026-04-18 00:55:49.767525 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/58ed8b12d8ba49098bf7923445816727/work/docs" 2026-04-18 00:55:49.787970 | 2026-04-18 00:55:49.788120 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 00:55:50.455159 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 00:55:50.455375 | rockylinux-9 | changed: All items complete 2026-04-18 00:55:50.455403 | 2026-04-18 00:55:50.955737 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 00:55:51.652950 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 00:55:51.678038 | 2026-04-18 00:55:51.678167 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 00:55:52.086635 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007427 2026-04-18 00:55:52.304470 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006376 2026-04-18 00:55:52.324906 | 2026-04-18 00:55:52.325038 | PLAY [all] 2026-04-18 00:55:52.331048 | 2026-04-18 00:55:52.331117 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 00:55:52.791018 | rockylinux-9 | changed 2026-04-18 00:55:52.798673 | 2026-04-18 00:55:52.798728 | PLAY RECAP 2026-04-18 00:55:52.798776 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 00:55:52.798798 | 2026-04-18 00:55:52.997285 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 00:55:52.999049 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 00:55:53.540118 | 2026-04-18 00:55:53.540254 | PLAY [localhost] 2026-04-18 00:55:53.550207 | 2026-04-18 00:55:53.550282 | TASK [Generate Zuul manifest] 2026-04-18 00:55:53.570388 | localhost | ok 2026-04-18 00:55:53.587095 | 2026-04-18 00:55:53.587173 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 00:55:53.902820 | localhost | changed 2026-04-18 00:55:53.914857 | 2026-04-18 00:55:53.914933 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 00:55:53.976004 | localhost | ok 2026-04-18 00:55:53.987982 | 2026-04-18 00:55:53.988049 | TASK [Upload logs] 2026-04-18 00:55:54.008958 | localhost | ok 2026-04-18 00:55:54.069441 | 2026-04-18 00:55:54.069623 | TASK [Set zuul-log-path fact] 2026-04-18 00:55:54.100628 | localhost | ok 2026-04-18 00:55:54.113588 | 2026-04-18 00:55:54.113654 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 00:55:54.144263 | localhost | ok 2026-04-18 00:55:54.153193 | 2026-04-18 00:55:54.153256 | TASK [upload-logs : Create log directories] 2026-04-18 00:55:54.535955 | localhost | changed 2026-04-18 00:55:54.540320 | 2026-04-18 00:55:54.540383 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 00:55:54.948390 | localhost -> localhost | ok: Runtime: 0:00:00.003543 2026-04-18 00:55:54.953393 | 2026-04-18 00:55:54.953456 | TASK [upload-logs : Upload logs to log server] 2026-04-18 00:55:55.400598 | localhost | Output suppressed because no_log was given 2026-04-18 00:55:55.405098 | 2026-04-18 00:55:55.405161 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 00:55:55.449180 | localhost | skipping: Conditional result was False 2026-04-18 00:55:55.456405 | localhost | skipping: Conditional result was False 2026-04-18 00:55:55.464548 | 2026-04-18 00:55:55.464618 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 00:55:55.507956 | localhost | skipping: Conditional result was False 2026-04-18 00:55:55.508299 | 2026-04-18 00:55:55.512326 | localhost | skipping: Conditional result was False 2026-04-18 00:55:55.528425 | 2026-04-18 00:55:55.528584 | LOOP [upload-logs : Upload console log and json output]