2026-04-16 01:00:23.547340 | Job console starting 2026-04-16 01:00:23.559884 | Updating git repos 2026-04-16 01:00:23.608529 | Cloning repos into workspace 2026-04-16 01:00:23.851463 | Restoring repo states 2026-04-16 01:00:23.859213 | Merging changes 2026-04-16 01:00:24.149961 | Checking out repos 2026-04-16 01:00:24.180256 | Preparing playbooks 2026-04-16 01:00:26.154780 | Running Ansible setup 2026-04-16 01:00:30.205944 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-16 01:00:30.812579 | 2026-04-16 01:00:30.812722 | PLAY [localhost] 2026-04-16 01:00:30.820924 | 2026-04-16 01:00:30.821047 | TASK [Gathering Facts] 2026-04-16 01:00:32.351409 | localhost | ok 2026-04-16 01:00:32.358056 | 2026-04-16 01:00:32.358130 | TASK [Setup log path fact] 2026-04-16 01:00:32.375789 | localhost | ok 2026-04-16 01:00:32.388698 | 2026-04-16 01:00:32.388766 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 01:00:32.416288 | localhost | ok 2026-04-16 01:00:32.427106 | 2026-04-16 01:00:32.427179 | TASK [emit-job-header : Print job information] 2026-04-16 01:00:32.465919 | # Job Information 2026-04-16 01:00:32.466072 | Ansible Version: 2.16.16 2026-04-16 01:00:32.466106 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-16 01:00:32.466129 | Pipeline: check 2026-04-16 01:00:32.466149 | Executor: 0a8996d2b663 2026-04-16 01:00:32.466169 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109 2026-04-16 01:00:32.466192 | Event ID: 8a718e60-392f-11f1-8507-97aae718a6d9 2026-04-16 01:00:32.469037 | 2026-04-16 01:00:32.469105 | LOOP [emit-job-header : Print node information] 2026-04-16 01:00:32.560926 | localhost | ok: 2026-04-16 01:00:32.561131 | localhost | # Node Information 2026-04-16 01:00:32.561172 | localhost | Inventory Hostname: rockylinux-9 2026-04-16 01:00:32.561208 | localhost | Hostname: np0000166520 2026-04-16 01:00:32.561238 | localhost | Username: zuul 2026-04-16 01:00:32.561270 | localhost | Distro: Rocky 9.7 2026-04-16 01:00:32.561297 | localhost | Provider: yul1 2026-04-16 01:00:32.561323 | localhost | Region: ca-ymq-1 2026-04-16 01:00:32.561349 | localhost | Label: rockylinux-9 2026-04-16 01:00:32.561374 | localhost | Product Name: OpenStack Nova 2026-04-16 01:00:32.561400 | localhost | Interface IP: 199.204.45.69 2026-04-16 01:00:32.571974 | 2026-04-16 01:00:32.572067 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-16 01:00:33.271421 | localhost -> localhost | changed 2026-04-16 01:00:33.281118 | 2026-04-16 01:00:33.281211 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-16 01:00:34.399835 | localhost -> localhost | changed 2026-04-16 01:00:34.406701 | 2026-04-16 01:00:34.406763 | PLAY [all] 2026-04-16 01:00:34.417622 | 2026-04-16 01:00:34.417693 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-16 01:00:34.684825 | rockylinux-9 -> localhost | ok 2026-04-16 01:00:34.695373 | 2026-04-16 01:00:34.695513 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-16 01:00:34.737352 | rockylinux-9 | ok 2026-04-16 01:00:34.839181 | rockylinux-9 | included: /var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-16 01:00:34.844458 | 2026-04-16 01:00:34.844521 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-16 01:00:35.794477 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-16 01:00:35.794677 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/work/5308b54bfa6d4f129b3ef800ba7b29d7_id_rsa 2026-04-16 01:00:35.794712 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/work/5308b54bfa6d4f129b3ef800ba7b29d7_id_rsa.pub 2026-04-16 01:00:35.794736 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-16 01:00:35.794758 | rockylinux-9 -> localhost | SHA256:2RWLxKYYuF/kK+CIVS0SdgNM0w7PPjIme4gFWL3/sbk zuul-build-sshkey 2026-04-16 01:00:35.794790 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-16 01:00:35.794812 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-16 01:00:35.794838 | rockylinux-9 -> localhost | | o*=oo .. . | 2026-04-16 01:00:35.794860 | rockylinux-9 -> localhost | | .=o*.o ..o. o | 2026-04-16 01:00:35.794880 | rockylinux-9 -> localhost | |.. B + = o. o | 2026-04-16 01:00:35.794901 | rockylinux-9 -> localhost | |o . B . +o . | 2026-04-16 01:00:35.794921 | rockylinux-9 -> localhost | | + + + .S.. | 2026-04-16 01:00:35.794940 | rockylinux-9 -> localhost | |o * + + o | 2026-04-16 01:00:35.794960 | rockylinux-9 -> localhost | |.* o . o + | 2026-04-16 01:00:35.794980 | rockylinux-9 -> localhost | |+ . + | 2026-04-16 01:00:35.795003 | rockylinux-9 -> localhost | | . E. | 2026-04-16 01:00:35.795024 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-16 01:00:35.795076 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.502813 2026-04-16 01:00:35.804131 | 2026-04-16 01:00:35.804272 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-16 01:00:35.849004 | rockylinux-9 | ok 2026-04-16 01:00:35.863147 | rockylinux-9 | included: /var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-16 01:00:35.873757 | 2026-04-16 01:00:35.874160 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-16 01:00:35.889953 | rockylinux-9 | skipping: Conditional result was False 2026-04-16 01:00:35.898831 | 2026-04-16 01:00:35.898926 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-16 01:00:36.363290 | rockylinux-9 | changed 2026-04-16 01:00:36.371016 | 2026-04-16 01:00:36.371085 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-16 01:00:36.632208 | rockylinux-9 | ok 2026-04-16 01:00:36.692562 | 2026-04-16 01:00:36.692705 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-16 01:00:37.586391 | rockylinux-9 | changed 2026-04-16 01:00:37.592296 | 2026-04-16 01:00:37.592369 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-16 01:00:38.495877 | rockylinux-9 | changed 2026-04-16 01:00:38.500489 | 2026-04-16 01:00:38.500552 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-16 01:00:38.524386 | rockylinux-9 | skipping: Conditional result was False 2026-04-16 01:00:38.533456 | 2026-04-16 01:00:38.533543 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-16 01:00:38.914060 | rockylinux-9 -> localhost | changed 2026-04-16 01:00:38.924317 | 2026-04-16 01:00:38.924383 | TASK [add-build-sshkey : Add back temp key] 2026-04-16 01:00:39.209174 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/work/5308b54bfa6d4f129b3ef800ba7b29d7_id_rsa (zuul-build-sshkey) 2026-04-16 01:00:39.209394 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.010697 2026-04-16 01:00:39.215801 | 2026-04-16 01:00:39.215868 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-16 01:00:39.617181 | rockylinux-9 | ok 2026-04-16 01:00:39.621847 | 2026-04-16 01:00:39.621917 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-16 01:00:39.645574 | rockylinux-9 | skipping: Conditional result was False 2026-04-16 01:00:39.658455 | 2026-04-16 01:00:39.658630 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-16 01:00:40.094574 | rockylinux-9 | ok 2026-04-16 01:00:40.099947 | 2026-04-16 01:00:40.100010 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-16 01:00:41.041819 | rockylinux-9 | Output suppressed because no_log was given 2026-04-16 01:00:41.053299 | 2026-04-16 01:00:41.053400 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-16 01:00:41.319812 | rockylinux-9 | ok: "logs" 2026-04-16 01:00:41.320118 | rockylinux-9 | ok: All items complete 2026-04-16 01:00:41.320147 | 2026-04-16 01:00:41.558530 | rockylinux-9 | ok: "artifacts" 2026-04-16 01:00:41.773835 | rockylinux-9 | ok: "docs" 2026-04-16 01:00:41.791593 | 2026-04-16 01:00:41.791785 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-16 01:00:42.040977 | rockylinux-9 | changed: "logs" 2026-04-16 01:00:42.248374 | rockylinux-9 | changed: "artifacts" 2026-04-16 01:00:42.466593 | rockylinux-9 | changed: "docs" 2026-04-16 01:00:42.478145 | 2026-04-16 01:00:42.478258 | PLAY RECAP 2026-04-16 01:00:42.478419 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-16 01:00:42.478459 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-16 01:00:42.478481 | 2026-04-16 01:00:42.624932 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-16 01:00:42.626066 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-16 01:00:43.244196 | 2026-04-16 01:00:43.244310 | PLAY [all] 2026-04-16 01:00:43.254978 | 2026-04-16 01:00:43.255053 | TASK [setup-uv : Extract archive] 2026-04-16 01:00:45.857038 | rockylinux-9 | changed 2026-04-16 01:00:45.862480 | 2026-04-16 01:00:45.862687 | TASK [setup-uv : Print version] 2026-04-16 01:00:45.896861 | [rockylinux-9] Waiting on logger 2026-04-16 01:00:46.412760 | rockylinux-9 | ok: Runtime: 0:00:00.013263 2026-04-16 01:00:46.429643 | 2026-04-16 01:00:46.429818 | PLAY RECAP 2026-04-16 01:00:46.429918 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-16 01:00:46.429972 | 2026-04-16 01:00:46.560385 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-16 01:00:46.561519 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-16 01:00:47.236942 | 2026-04-16 01:00:47.237113 | PLAY [all] 2026-04-16 01:00:47.256008 | 2026-04-16 01:00:47.256142 | TASK [Copy inventory file for Zuul] 2026-04-16 01:00:48.527630 | rockylinux-9 | changed 2026-04-16 01:00:48.535828 | 2026-04-16 01:00:48.535993 | TASK [Switch "ansible_host" to private IP] 2026-04-16 01:00:48.913937 | rockylinux-9 | changed: 1 replacements made 2026-04-16 01:00:48.923378 | 2026-04-16 01:00:48.923453 | TASK [Run Molecule scenario] 2026-04-16 01:00:48.945420 | [rockylinux-9] Waiting on logger 2026-04-16 01:00:57.094154 | [rockylinux-9] Waiting on logger 2026-04-16 01:01:07.270062 | [rockylinux-9] Waiting on logger 2026-04-16 01:01:17.446294 | [rockylinux-9] Waiting on logger 2026-04-16 01:01:27.622148 | [rockylinux-9] Waiting on logger 2026-04-16 01:01:37.802084 | [rockylinux-9] Waiting on logger 2026-04-16 01:01:46.955536 | [rockylinux-9] Waiting on logger 2026-04-16 01:01:57.126118 | [rockylinux-9] Waiting on logger 2026-04-16 01:02:07.302060 | [rockylinux-9] Waiting on logger 2026-04-16 01:02:18.377972 | [rockylinux-9] Waiting on logger 2026-04-16 01:02:28.549910 | [rockylinux-9] Waiting on logger 2026-04-16 01:02:38.726122 | [rockylinux-9] Waiting on logger 2026-04-16 01:02:48.901970 | [rockylinux-9] Waiting on logger 2026-04-16 01:02:58.054467 | [rockylinux-9] Waiting on logger 2026-04-16 01:03:08.230097 | [rockylinux-9] Waiting on logger 2026-04-16 01:03:18.406306 | [rockylinux-9] Waiting on logger 2026-04-16 01:03:28.582397 | [rockylinux-9] Waiting on logger 2026-04-16 01:03:38.758499 | [rockylinux-9] Waiting on logger 2026-04-16 01:03:47.915372 | [rockylinux-9] Waiting on logger 2026-04-16 01:03:58.086422 | [rockylinux-9] Waiting on logger 2026-04-16 01:04:08.262207 | [rockylinux-9] Waiting on logger 2026-04-16 01:04:18.438155 | [rockylinux-9] Waiting on logger 2026-04-16 01:04:28.614201 | [rockylinux-9] Waiting on logger 2026-04-16 01:04:38.790008 | [rockylinux-9] Waiting on logger 2026-04-16 01:04:47.947313 | [rockylinux-9] Waiting on logger 2026-04-16 01:04:58.118211 | [rockylinux-9] Waiting on logger 2026-04-16 01:05:08.298139 | [rockylinux-9] Waiting on logger 2026-04-16 01:05:18.474221 | [rockylinux-9] Waiting on logger 2026-04-16 01:05:24.173877 | rockylinux-9 | ok: Runtime: 0:04:33.929241 2026-04-16 01:05:24.181615 | 2026-04-16 01:05:24.181674 | PLAY RECAP 2026-04-16 01:05:24.181739 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-16 01:05:24.181764 | 2026-04-16 01:05:24.313788 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-16 01:05:24.316078 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 01:05:24.877701 | 2026-04-16 01:05:24.878161 | PLAY [all] 2026-04-16 01:05:24.889076 | 2026-04-16 01:05:24.889170 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-16 01:05:24.934317 | rockylinux-9 | skipping: Conditional result was False 2026-04-16 01:05:24.943265 | 2026-04-16 01:05:24.943371 | TASK [fetch-output : Set log path for single node] 2026-04-16 01:05:24.986159 | rockylinux-9 | ok 2026-04-16 01:05:24.991646 | 2026-04-16 01:05:24.991714 | LOOP [fetch-output : Ensure local output dirs] 2026-04-16 01:05:25.347488 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/work/logs" 2026-04-16 01:05:25.543295 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/work/artifacts" 2026-04-16 01:05:25.736727 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/5308b54bfa6d4f129b3ef800ba7b29d7/work/docs" 2026-04-16 01:05:25.750003 | 2026-04-16 01:05:25.750146 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-16 01:05:28.046375 | rockylinux-9 | changed: .d..t...... ./ 2026-04-16 01:05:28.046631 | rockylinux-9 | changed: All items complete 2026-04-16 01:05:28.046678 | 2026-04-16 01:05:28.562373 | rockylinux-9 | changed: .d..t...... ./ 2026-04-16 01:05:29.057888 | rockylinux-9 | changed: .d..t...... ./ 2026-04-16 01:05:29.080774 | 2026-04-16 01:05:29.080907 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-16 01:05:29.469246 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007182 2026-04-16 01:05:29.687512 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007204 2026-04-16 01:05:29.705321 | 2026-04-16 01:05:29.705375 | PLAY [all] 2026-04-16 01:05:29.710771 | 2026-04-16 01:05:29.710873 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-16 01:05:30.148628 | rockylinux-9 | changed 2026-04-16 01:05:30.153781 | 2026-04-16 01:05:30.153833 | PLAY RECAP 2026-04-16 01:05:30.153875 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-16 01:05:30.153898 | 2026-04-16 01:05:30.259367 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-16 01:05:30.261627 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-16 01:05:30.819922 | 2026-04-16 01:05:30.820283 | PLAY [localhost] 2026-04-16 01:05:30.830042 | 2026-04-16 01:05:30.830112 | TASK [Generate Zuul manifest] 2026-04-16 01:05:30.849725 | localhost | ok 2026-04-16 01:05:30.867646 | 2026-04-16 01:05:30.867845 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-16 01:05:31.176849 | localhost | changed 2026-04-16 01:05:31.188635 | 2026-04-16 01:05:31.188731 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-16 01:05:31.218478 | localhost | ok 2026-04-16 01:05:31.227279 | 2026-04-16 01:05:31.227351 | TASK [Upload logs] 2026-04-16 01:05:31.247100 | localhost | ok 2026-04-16 01:05:31.316102 | 2026-04-16 01:05:31.316252 | TASK [Set zuul-log-path fact] 2026-04-16 01:05:31.350827 | localhost | ok 2026-04-16 01:05:31.362487 | 2026-04-16 01:05:31.362569 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-16 01:05:31.394146 | localhost | ok 2026-04-16 01:05:31.403685 | 2026-04-16 01:05:31.403759 | TASK [upload-logs : Create log directories] 2026-04-16 01:05:31.787847 | localhost | changed 2026-04-16 01:05:31.793445 | 2026-04-16 01:05:31.793524 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-16 01:05:32.186352 | localhost -> localhost | ok: Runtime: 0:00:00.004920 2026-04-16 01:05:32.191198 | 2026-04-16 01:05:32.191261 | TASK [upload-logs : Upload logs to log server] 2026-04-16 01:05:32.625531 | localhost | Output suppressed because no_log was given 2026-04-16 01:05:32.630589 | 2026-04-16 01:05:32.630682 | LOOP [upload-logs : Compress console log and json output] 2026-04-16 01:05:32.675132 | localhost | skipping: Conditional result was False 2026-04-16 01:05:32.682273 | localhost | skipping: Conditional result was False 2026-04-16 01:05:32.694583 | 2026-04-16 01:05:32.694738 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-16 01:05:32.734436 | localhost | skipping: Conditional result was False 2026-04-16 01:05:32.734793 | 2026-04-16 01:05:32.738702 | localhost | skipping: Conditional result was False 2026-04-16 01:05:32.758517 | 2026-04-16 01:05:32.758619 | LOOP [upload-logs : Upload console log and json output]