2026-04-22 13:04:32.683117 | Job console starting 2026-04-22 13:04:32.692103 | Updating git repos 2026-04-22 13:04:32.711793 | Cloning repos into workspace 2026-04-22 13:04:33.752646 | Restoring repo states 2026-04-22 13:04:33.765220 | Merging changes 2026-04-22 13:04:34.040251 | Checking out repos 2026-04-22 13:04:34.070220 | Preparing playbooks 2026-04-22 13:04:36.120875 | Running Ansible setup 2026-04-22 13:04:39.966866 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 13:04:40.579722 | 2026-04-22 13:04:40.580027 | PLAY [localhost] 2026-04-22 13:04:40.590061 | 2026-04-22 13:04:40.590185 | TASK [Gathering Facts] 2026-04-22 13:04:41.394860 | localhost | ok 2026-04-22 13:04:41.401668 | 2026-04-22 13:04:41.401757 | TASK [Setup log path fact] 2026-04-22 13:04:41.423102 | localhost | ok 2026-04-22 13:04:41.435914 | 2026-04-22 13:04:41.435987 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 13:04:41.465755 | localhost | ok 2026-04-22 13:04:41.474765 | 2026-04-22 13:04:41.474905 | TASK [emit-job-header : Print job information] 2026-04-22 13:04:41.505966 | # Job Information 2026-04-22 13:04:41.506141 | Ansible Version: 2.16.16 2026-04-22 13:04:41.506186 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-22 13:04:41.506218 | Pipeline: check 2026-04-22 13:04:41.506246 | Executor: 0a8996d2b663 2026-04-22 13:04:41.506274 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/118 2026-04-22 13:04:41.506305 | Event ID: b61206a0-3e4b-11f1-997d-32a58ba417cb 2026-04-22 13:04:41.510191 | 2026-04-22 13:04:41.510286 | LOOP [emit-job-header : Print node information] 2026-04-22 13:04:41.617682 | localhost | ok: 2026-04-22 13:04:41.617873 | localhost | # Node Information 2026-04-22 13:04:41.617928 | localhost | Inventory Hostname: rockylinux-9 2026-04-22 13:04:41.617975 | localhost | Hostname: np0000169412 2026-04-22 13:04:41.618012 | localhost | Username: zuul 2026-04-22 13:04:41.618052 | localhost | Distro: Rocky 9.7 2026-04-22 13:04:41.618087 | localhost | Provider: yul1 2026-04-22 13:04:41.618121 | localhost | Region: ca-ymq-1 2026-04-22 13:04:41.618154 | localhost | Label: rockylinux-9 2026-04-22 13:04:41.618186 | localhost | Product Name: OpenStack Nova 2026-04-22 13:04:41.618219 | localhost | Interface IP: 199.19.213.138 2026-04-22 13:04:41.628613 | 2026-04-22 13:04:41.628759 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-22 13:04:42.029988 | localhost -> localhost | changed 2026-04-22 13:04:42.036543 | 2026-04-22 13:04:42.036663 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-22 13:04:42.951163 | localhost -> localhost | changed 2026-04-22 13:04:42.960153 | 2026-04-22 13:04:42.960290 | PLAY [all] 2026-04-22 13:04:42.971663 | 2026-04-22 13:04:42.971809 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-22 13:04:43.232292 | rockylinux-9 -> localhost | ok 2026-04-22 13:04:43.241311 | 2026-04-22 13:04:43.241455 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-22 13:04:43.262146 | rockylinux-9 | ok 2026-04-22 13:04:43.280281 | rockylinux-9 | included: /var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-22 13:04:43.286718 | 2026-04-22 13:04:43.286865 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-22 13:04:44.566006 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-22 13:04:44.566192 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/work/a422d93088474c1588d26f02836c9bb9_id_rsa 2026-04-22 13:04:44.566226 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/work/a422d93088474c1588d26f02836c9bb9_id_rsa.pub 2026-04-22 13:04:44.566249 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-22 13:04:44.566270 | rockylinux-9 -> localhost | SHA256:skpczzBRhxruqDkO9D1M+n5+KOivElnuYmpyHxQkozs zuul-build-sshkey 2026-04-22 13:04:44.566300 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-22 13:04:44.566321 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-22 13:04:44.566347 | rockylinux-9 -> localhost | | o . ... | 2026-04-22 13:04:44.566368 | rockylinux-9 -> localhost | | . + .... | 2026-04-22 13:04:44.566388 | rockylinux-9 -> localhost | |. ...o | 2026-04-22 13:04:44.566408 | rockylinux-9 -> localhost | | . . .o. | 2026-04-22 13:04:44.566428 | rockylinux-9 -> localhost | |E.+ .+= S | 2026-04-22 13:04:44.566447 | rockylinux-9 -> localhost | |.+.+*..B | 2026-04-22 13:04:44.566466 | rockylinux-9 -> localhost | |. o==+. + | 2026-04-22 13:04:44.566485 | rockylinux-9 -> localhost | |.*=+.+.o . | 2026-04-22 13:04:44.566507 | rockylinux-9 -> localhost | |*o==*++.. | 2026-04-22 13:04:44.566527 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-22 13:04:44.566572 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.860105 2026-04-22 13:04:44.571094 | 2026-04-22 13:04:44.571157 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-22 13:04:44.599231 | rockylinux-9 | ok 2026-04-22 13:04:44.606901 | rockylinux-9 | included: /var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-22 13:04:44.614393 | 2026-04-22 13:04:44.614454 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-22 13:04:44.638393 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:04:44.648221 | 2026-04-22 13:04:44.648328 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-22 13:04:45.187565 | rockylinux-9 | changed 2026-04-22 13:04:45.193251 | 2026-04-22 13:04:45.193374 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-22 13:04:45.435762 | rockylinux-9 | ok 2026-04-22 13:04:45.442029 | 2026-04-22 13:04:45.442135 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-22 13:04:46.339774 | rockylinux-9 | changed 2026-04-22 13:04:46.347620 | 2026-04-22 13:04:46.347755 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-22 13:04:47.227176 | rockylinux-9 | changed 2026-04-22 13:04:47.232267 | 2026-04-22 13:04:47.232336 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-22 13:04:47.266843 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:04:47.275017 | 2026-04-22 13:04:47.275145 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-22 13:04:47.670725 | rockylinux-9 -> localhost | changed 2026-04-22 13:04:47.684596 | 2026-04-22 13:04:47.686160 | TASK [add-build-sshkey : Add back temp key] 2026-04-22 13:04:47.972867 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/work/a422d93088474c1588d26f02836c9bb9_id_rsa (zuul-build-sshkey) 2026-04-22 13:04:47.973144 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013408 2026-04-22 13:04:47.981127 | 2026-04-22 13:04:47.981201 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-22 13:04:48.399582 | rockylinux-9 | ok 2026-04-22 13:04:48.406835 | 2026-04-22 13:04:48.406948 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-22 13:04:48.431979 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:04:48.444513 | 2026-04-22 13:04:48.444598 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-22 13:04:48.855236 | rockylinux-9 | ok 2026-04-22 13:04:48.861129 | 2026-04-22 13:04:48.861208 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-22 13:04:49.787999 | rockylinux-9 | Output suppressed because no_log was given 2026-04-22 13:04:49.796864 | 2026-04-22 13:04:49.796929 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-22 13:04:50.050853 | rockylinux-9 | ok: "logs" 2026-04-22 13:04:50.051092 | rockylinux-9 | ok: All items complete 2026-04-22 13:04:50.051122 | 2026-04-22 13:04:50.280644 | rockylinux-9 | ok: "artifacts" 2026-04-22 13:04:50.500493 | rockylinux-9 | ok: "docs" 2026-04-22 13:04:50.507910 | 2026-04-22 13:04:50.508021 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-22 13:04:50.764068 | rockylinux-9 | changed: "logs" 2026-04-22 13:04:50.979176 | rockylinux-9 | changed: "artifacts" 2026-04-22 13:04:51.201176 | rockylinux-9 | changed: "docs" 2026-04-22 13:04:51.214088 | 2026-04-22 13:04:51.214166 | PLAY RECAP 2026-04-22 13:04:51.214207 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 13:04:51.214238 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-22 13:04:51.214259 | 2026-04-22 13:04:51.341891 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-22 13:04:51.344093 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 13:04:52.057142 | 2026-04-22 13:04:52.057316 | PLAY [all] 2026-04-22 13:04:52.068802 | 2026-04-22 13:04:52.068924 | TASK [setup-uv : Extract archive] 2026-04-22 13:04:55.412004 | rockylinux-9 | changed 2026-04-22 13:04:55.418511 | 2026-04-22 13:04:55.418599 | TASK [setup-uv : Print version] 2026-04-22 13:04:55.439663 | [rockylinux-9] Waiting on logger 2026-04-22 13:04:55.954984 | rockylinux-9 | ok: Runtime: 0:00:00.014897 2026-04-22 13:04:55.961037 | 2026-04-22 13:04:55.961099 | PLAY RECAP 2026-04-22 13:04:55.961141 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 13:04:55.961167 | 2026-04-22 13:04:56.065954 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-22 13:04:56.068114 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 13:04:56.683965 | 2026-04-22 13:04:56.684100 | PLAY [all] 2026-04-22 13:04:56.694308 | 2026-04-22 13:04:56.694389 | TASK [Copy inventory file for Zuul] 2026-04-22 13:04:58.050397 | rockylinux-9 | changed 2026-04-22 13:04:58.056692 | 2026-04-22 13:04:58.056793 | TASK [Switch "ansible_host" to private IP] 2026-04-22 13:04:58.414815 | rockylinux-9 | changed: 1 replacements made 2026-04-22 13:04:58.420762 | 2026-04-22 13:04:58.420844 | TASK [Run Molecule scenario] 2026-04-22 13:04:58.441498 | [rockylinux-9] Waiting on logger 2026-04-22 13:05:06.566280 | [rockylinux-9] Waiting on logger 2026-04-22 13:05:16.742351 | [rockylinux-9] Waiting on logger 2026-04-22 13:05:26.917958 | [rockylinux-9] Waiting on logger 2026-04-22 13:05:37.094187 | [rockylinux-9] Waiting on logger 2026-04-22 13:05:47.270119 | [rockylinux-9] Waiting on logger 2026-04-22 13:05:57.446209 | [rockylinux-9] Waiting on logger 2026-04-22 13:06:06.598119 | [rockylinux-9] Waiting on logger 2026-04-22 13:06:16.778913 | [rockylinux-9] Waiting on logger 2026-04-22 13:06:26.949914 | [rockylinux-9] Waiting on logger 2026-04-22 13:06:36.555045 | [rockylinux-9] Waiting on logger 2026-04-22 13:06:46.725904 | [rockylinux-9] Waiting on logger 2026-04-22 13:06:56.901989 | [rockylinux-9] Waiting on logger 2026-04-22 13:07:07.077927 | [rockylinux-9] Waiting on logger 2026-04-22 13:07:17.253923 | [rockylinux-9] Waiting on logger 2026-04-22 13:07:27.430070 | [rockylinux-9] Waiting on logger 2026-04-22 13:07:37.515295 | [rockylinux-9] Waiting on logger 2026-04-22 13:07:47.686240 | [rockylinux-9] Waiting on logger 2026-04-22 13:07:57.958117 | [rockylinux-9] Waiting on logger 2026-04-22 13:08:08.134131 | [rockylinux-9] Waiting on logger 2026-04-22 13:08:18.310273 | [rockylinux-9] Waiting on logger 2026-04-22 13:08:27.467150 | [rockylinux-9] Waiting on logger 2026-04-22 13:08:37.638111 | [rockylinux-9] Waiting on logger 2026-04-22 13:08:47.973841 | [rockylinux-9] Waiting on logger 2026-04-22 13:08:58.149841 | [rockylinux-9] Waiting on logger 2026-04-22 13:09:08.325961 | [rockylinux-9] Waiting on logger 2026-04-22 13:09:17.483477 | [rockylinux-9] Waiting on logger 2026-04-22 13:09:27.657889 | [rockylinux-9] Waiting on logger 2026-04-22 13:09:37.830010 | [rockylinux-9] Waiting on logger 2026-04-22 13:09:41.197082 | rockylinux-9 | ok: Runtime: 0:04:41.700860 2026-04-22 13:09:41.204684 | 2026-04-22 13:09:41.204741 | PLAY RECAP 2026-04-22 13:09:41.204785 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-22 13:09:41.204808 | 2026-04-22 13:09:41.307041 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-22 13:09:41.308985 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 13:09:41.948175 | 2026-04-22 13:09:41.948388 | PLAY [all] 2026-04-22 13:09:41.961136 | 2026-04-22 13:09:41.961338 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-22 13:09:42.007885 | rockylinux-9 | skipping: Conditional result was False 2026-04-22 13:09:42.016517 | 2026-04-22 13:09:42.016740 | TASK [fetch-output : Set log path for single node] 2026-04-22 13:09:42.060748 | rockylinux-9 | ok 2026-04-22 13:09:42.068305 | 2026-04-22 13:09:42.068475 | LOOP [fetch-output : Ensure local output dirs] 2026-04-22 13:09:42.452712 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/work/logs" 2026-04-22 13:09:42.653129 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/work/artifacts" 2026-04-22 13:09:42.876147 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a422d93088474c1588d26f02836c9bb9/work/docs" 2026-04-22 13:09:42.892083 | 2026-04-22 13:09:42.892279 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-22 13:09:43.629612 | rockylinux-9 | ok 2026-04-22 13:09:43.629901 | rockylinux-9 | changed: All items complete 2026-04-22 13:09:43.629942 | 2026-04-22 13:09:44.143734 | rockylinux-9 | changed: .d..t...... ./ 2026-04-22 13:09:44.700824 | rockylinux-9 | changed: .d..t...... ./ 2026-04-22 13:09:44.724259 | 2026-04-22 13:09:44.724375 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-22 13:09:45.190127 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.009331 2026-04-22 13:09:45.435960 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009494 2026-04-22 13:09:45.456552 | 2026-04-22 13:09:45.456726 | PLAY [all] 2026-04-22 13:09:45.463276 | 2026-04-22 13:09:45.463366 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-22 13:09:45.954426 | rockylinux-9 | changed 2026-04-22 13:09:45.959665 | 2026-04-22 13:09:45.959721 | PLAY RECAP 2026-04-22 13:09:45.959767 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-22 13:09:45.959790 | 2026-04-22 13:09:46.079657 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-22 13:09:46.081574 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-22 13:09:46.663391 | 2026-04-22 13:09:46.663558 | PLAY [localhost] 2026-04-22 13:09:46.675010 | 2026-04-22 13:09:46.675107 | TASK [Generate Zuul manifest] 2026-04-22 13:09:46.697688 | localhost | ok 2026-04-22 13:09:46.713986 | 2026-04-22 13:09:46.714074 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-22 13:09:47.102128 | localhost | changed 2026-04-22 13:09:47.114800 | 2026-04-22 13:09:47.114883 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-22 13:09:47.147803 | localhost | ok 2026-04-22 13:09:47.156255 | 2026-04-22 13:09:47.156398 | TASK [Upload logs] 2026-04-22 13:09:47.179596 | localhost | ok 2026-04-22 13:09:47.247653 | 2026-04-22 13:09:47.247836 | TASK [Set zuul-log-path fact] 2026-04-22 13:09:47.271376 | localhost | ok 2026-04-22 13:09:47.284764 | 2026-04-22 13:09:47.284950 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-22 13:09:47.319587 | localhost | ok 2026-04-22 13:09:47.328119 | 2026-04-22 13:09:47.328292 | TASK [upload-logs : Create log directories] 2026-04-22 13:09:47.776917 | localhost | changed 2026-04-22 13:09:47.782113 | 2026-04-22 13:09:47.782254 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-22 13:09:48.233966 | localhost -> localhost | ok: Runtime: 0:00:00.006308 2026-04-22 13:09:48.238957 | 2026-04-22 13:09:48.239045 | TASK [upload-logs : Upload logs to log server] 2026-04-22 13:09:48.688271 | localhost | Output suppressed because no_log was given 2026-04-22 13:09:48.694060 | 2026-04-22 13:09:48.694593 | LOOP [upload-logs : Compress console log and json output] 2026-04-22 13:09:48.745738 | localhost | skipping: Conditional result was False 2026-04-22 13:09:48.752654 | localhost | skipping: Conditional result was False 2026-04-22 13:09:48.760779 | 2026-04-22 13:09:48.760986 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-22 13:09:48.808142 | localhost | skipping: Conditional result was False 2026-04-22 13:09:48.808589 | 2026-04-22 13:09:48.813463 | localhost | skipping: Conditional result was False 2026-04-22 13:09:48.827154 | 2026-04-22 13:09:48.827273 | LOOP [upload-logs : Upload console log and json output]