2026-04-14 02:23:57.534027 | Job console starting 2026-04-14 02:23:57.544841 | Updating git repos 2026-04-14 02:23:57.577011 | Cloning repos into workspace 2026-04-14 02:23:57.632542 | Restoring repo states 2026-04-14 02:23:57.645562 | Merging changes 2026-04-14 02:23:58.107279 | Checking out repos 2026-04-14 02:23:58.137242 | Preparing playbooks 2026-04-14 02:23:59.944746 | Running Ansible setup 2026-04-14 02:24:06.015171 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:24:06.691793 | 2026-04-14 02:24:06.691954 | PLAY [localhost] 2026-04-14 02:24:06.702098 | 2026-04-14 02:24:06.702215 | TASK [Gathering Facts] 2026-04-14 02:24:07.983482 | localhost | ok 2026-04-14 02:24:08.006252 | 2026-04-14 02:24:08.006375 | TASK [Setup log path fact] 2026-04-14 02:24:08.037569 | localhost | ok 2026-04-14 02:24:08.053244 | 2026-04-14 02:24:08.053417 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:24:08.084846 | localhost | ok 2026-04-14 02:24:08.093149 | 2026-04-14 02:24:08.093310 | TASK [emit-job-header : Print job information] 2026-04-14 02:24:08.147111 | # Job Information 2026-04-14 02:24:08.147333 | Ansible Version: 2.16.16 2026-04-14 02:24:08.147384 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-14 02:24:08.147419 | Pipeline: check 2026-04-14 02:24:08.147451 | Executor: 0a8996d2b663 2026-04-14 02:24:08.147724 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/108 2026-04-14 02:24:08.147784 | Event ID: ccc5bb20-37a8-11f1-82ba-71f59466e919 2026-04-14 02:24:08.156623 | 2026-04-14 02:24:08.156857 | LOOP [emit-job-header : Print node information] 2026-04-14 02:24:08.270722 | localhost | ok: 2026-04-14 02:24:08.271170 | localhost | # Node Information 2026-04-14 02:24:08.271232 | localhost | Inventory Hostname: rockylinux-9 2026-04-14 02:24:08.271288 | localhost | Hostname: np0000165913 2026-04-14 02:24:08.271327 | localhost | Username: zuul 2026-04-14 02:24:08.271372 | localhost | Distro: Rocky 9.7 2026-04-14 02:24:08.271413 | localhost | Provider: yul1 2026-04-14 02:24:08.271454 | localhost | Region: ca-ymq-1 2026-04-14 02:24:08.271484 | localhost | Label: rockylinux-9 2026-04-14 02:24:08.271512 | localhost | Product Name: OpenStack Nova 2026-04-14 02:24:08.271540 | localhost | Interface IP: 199.19.213.143 2026-04-14 02:24:08.289178 | 2026-04-14 02:24:08.289379 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-14 02:24:09.039504 | localhost -> localhost | changed 2026-04-14 02:24:09.047329 | 2026-04-14 02:24:09.047503 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-14 02:24:10.393527 | localhost -> localhost | changed 2026-04-14 02:24:10.405044 | 2026-04-14 02:24:10.405258 | PLAY [all] 2026-04-14 02:24:10.417457 | 2026-04-14 02:24:10.417577 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-14 02:24:10.647858 | rockylinux-9 -> localhost | ok 2026-04-14 02:24:10.655714 | 2026-04-14 02:24:10.655808 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-14 02:24:10.698982 | rockylinux-9 | ok 2026-04-14 02:24:10.714213 | rockylinux-9 | included: /var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-14 02:24:10.719592 | 2026-04-14 02:24:10.719657 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-14 02:24:12.430683 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-14 02:24:12.431072 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/work/cf1948a5993b4b46a34d5292518702f6_id_rsa 2026-04-14 02:24:12.431131 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/work/cf1948a5993b4b46a34d5292518702f6_id_rsa.pub 2026-04-14 02:24:12.431173 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-14 02:24:12.431211 | rockylinux-9 -> localhost | SHA256:HlWqxAv/Fq9EBHyR/QHV6Kh6xAtZh3//Xp7JXEDY/c8 zuul-build-sshkey 2026-04-14 02:24:12.431279 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-14 02:24:12.431314 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-14 02:24:12.431358 | rockylinux-9 -> localhost | | .. .+oo.o | 2026-04-14 02:24:12.431392 | rockylinux-9 -> localhost | | ...oo.oo..| 2026-04-14 02:24:12.431426 | rockylinux-9 -> localhost | | . o.+..+o..| 2026-04-14 02:24:12.431461 | rockylinux-9 -> localhost | | + =o o.o .| 2026-04-14 02:24:12.431498 | rockylinux-9 -> localhost | | S+o+ . .| 2026-04-14 02:24:12.431533 | rockylinux-9 -> localhost | | .o++o. .o.| 2026-04-14 02:24:12.431568 | rockylinux-9 -> localhost | | .++... .E| 2026-04-14 02:24:12.431603 | rockylinux-9 -> localhost | | .oo. o.*| 2026-04-14 02:24:12.431639 | rockylinux-9 -> localhost | | .. *=| 2026-04-14 02:24:12.431671 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-14 02:24:12.431876 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.270291 2026-04-14 02:24:12.441441 | 2026-04-14 02:24:12.441593 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-14 02:24:12.475879 | rockylinux-9 | ok 2026-04-14 02:24:12.489664 | rockylinux-9 | included: /var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-14 02:24:12.502440 | 2026-04-14 02:24:12.502566 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-14 02:24:12.527789 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:24:12.538700 | 2026-04-14 02:24:12.538906 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-14 02:24:13.098854 | rockylinux-9 | changed 2026-04-14 02:24:13.103494 | 2026-04-14 02:24:13.103560 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-14 02:24:13.366021 | rockylinux-9 | ok 2026-04-14 02:24:13.371108 | 2026-04-14 02:24:13.371191 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-14 02:24:16.775340 | rockylinux-9 | changed 2026-04-14 02:24:16.783777 | 2026-04-14 02:24:16.783862 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-14 02:24:17.753801 | rockylinux-9 | changed 2026-04-14 02:24:17.758714 | 2026-04-14 02:24:17.758787 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-14 02:24:17.783522 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:24:17.791750 | 2026-04-14 02:24:17.791821 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-14 02:24:18.191416 | rockylinux-9 -> localhost | changed 2026-04-14 02:24:18.202022 | 2026-04-14 02:24:18.202109 | TASK [add-build-sshkey : Add back temp key] 2026-04-14 02:24:19.959628 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/work/cf1948a5993b4b46a34d5292518702f6_id_rsa (zuul-build-sshkey) 2026-04-14 02:24:19.959839 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013459 2026-04-14 02:24:19.968441 | 2026-04-14 02:24:19.968591 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-14 02:24:20.378498 | rockylinux-9 | ok 2026-04-14 02:24:20.387654 | 2026-04-14 02:24:20.387841 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-14 02:24:20.414414 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:24:20.427757 | 2026-04-14 02:24:20.427857 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-14 02:24:20.921456 | rockylinux-9 | ok 2026-04-14 02:24:20.936751 | 2026-04-14 02:24:20.937013 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-14 02:24:21.865982 | rockylinux-9 | Output suppressed because no_log was given 2026-04-14 02:24:21.873741 | 2026-04-14 02:24:21.873803 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-14 02:24:22.144030 | rockylinux-9 | ok: "logs" 2026-04-14 02:24:22.144327 | rockylinux-9 | ok: All items complete 2026-04-14 02:24:22.144369 | 2026-04-14 02:24:22.370964 | rockylinux-9 | ok: "artifacts" 2026-04-14 02:24:22.593552 | rockylinux-9 | ok: "docs" 2026-04-14 02:24:22.602452 | 2026-04-14 02:24:22.602525 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-14 02:24:22.873327 | rockylinux-9 | changed: "logs" 2026-04-14 02:24:23.120017 | rockylinux-9 | changed: "artifacts" 2026-04-14 02:24:23.356911 | rockylinux-9 | changed: "docs" 2026-04-14 02:24:23.377948 | 2026-04-14 02:24:23.378016 | PLAY RECAP 2026-04-14 02:24:23.378077 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:24:23.378120 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-14 02:24:23.378149 | 2026-04-14 02:24:23.525219 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-14 02:24:23.527453 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:24:24.210501 | 2026-04-14 02:24:24.210691 | PLAY [all] 2026-04-14 02:24:24.223810 | 2026-04-14 02:24:24.223987 | TASK [setup-uv : Extract archive] 2026-04-14 02:24:27.027717 | rockylinux-9 | changed 2026-04-14 02:24:27.033522 | 2026-04-14 02:24:27.033607 | TASK [setup-uv : Print version] 2026-04-14 02:24:27.060057 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:27.572701 | rockylinux-9 | ok: Runtime: 0:00:00.015840 2026-04-14 02:24:27.579856 | 2026-04-14 02:24:27.579912 | PLAY RECAP 2026-04-14 02:24:27.579961 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:24:27.579986 | 2026-04-14 02:24:27.728762 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-14 02:24:27.731765 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:24:28.417520 | 2026-04-14 02:24:28.417650 | PLAY [all] 2026-04-14 02:24:28.428537 | 2026-04-14 02:24:28.428613 | TASK [Copy inventory file for Zuul] 2026-04-14 02:24:29.897233 | rockylinux-9 | changed 2026-04-14 02:24:29.902159 | 2026-04-14 02:24:29.902241 | TASK [Switch "ansible_host" to private IP] 2026-04-14 02:24:30.264052 | rockylinux-9 | changed: 1 replacements made 2026-04-14 02:24:30.938583 | 2026-04-14 02:24:30.941847 | TASK [Run Molecule scenario] 2026-04-14 02:24:30.970680 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:38.091049 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:48.262030 | [rockylinux-9] Waiting on logger 2026-04-14 02:24:58.698049 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:08.201975 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:18.378122 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:28.554170 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:38.728947 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:48.901837 | [rockylinux-9] Waiting on logger 2026-04-14 02:25:58.059453 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:08.421905 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:18.597953 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:28.774266 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:38.982051 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:48.230070 | [rockylinux-9] Waiting on logger 2026-04-14 02:26:58.406156 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:08.582458 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:18.757922 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:28.933931 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:38.095432 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:48.265915 | [rockylinux-9] Waiting on logger 2026-04-14 02:27:58.438116 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:08.614119 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:18.790098 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:28.966176 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:38.123955 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:48.294181 | [rockylinux-9] Waiting on logger 2026-04-14 02:28:55.122782 | rockylinux-9 | ok: Runtime: 0:04:23.638186 2026-04-14 02:28:55.129704 | 2026-04-14 02:28:55.129807 | PLAY RECAP 2026-04-14 02:28:55.129867 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-14 02:28:55.129898 | 2026-04-14 02:28:55.269282 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-14 02:28:55.271446 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:28:55.893669 | 2026-04-14 02:28:55.894198 | PLAY [all] 2026-04-14 02:28:55.905291 | 2026-04-14 02:28:55.905380 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-14 02:28:55.952100 | rockylinux-9 | skipping: Conditional result was False 2026-04-14 02:28:55.960404 | 2026-04-14 02:28:55.960529 | TASK [fetch-output : Set log path for single node] 2026-04-14 02:28:56.005700 | rockylinux-9 | ok 2026-04-14 02:28:56.013720 | 2026-04-14 02:28:56.013820 | LOOP [fetch-output : Ensure local output dirs] 2026-04-14 02:28:56.410527 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/work/logs" 2026-04-14 02:28:56.632336 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/work/artifacts" 2026-04-14 02:28:56.866701 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/cf1948a5993b4b46a34d5292518702f6/work/docs" 2026-04-14 02:28:56.898220 | 2026-04-14 02:28:56.898437 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-14 02:28:57.625111 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:57.625395 | rockylinux-9 | changed: All items complete 2026-04-14 02:28:57.625436 | 2026-04-14 02:28:58.142234 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:58.650002 | rockylinux-9 | changed: .d..t...... ./ 2026-04-14 02:28:58.676563 | 2026-04-14 02:28:58.676735 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-14 02:28:59.094205 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008742 2026-04-14 02:28:59.319881 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008359 2026-04-14 02:28:59.339287 | 2026-04-14 02:28:59.339431 | PLAY [all] 2026-04-14 02:28:59.345795 | 2026-04-14 02:28:59.345924 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-14 02:28:59.836594 | rockylinux-9 | changed 2026-04-14 02:28:59.842185 | 2026-04-14 02:28:59.842242 | PLAY RECAP 2026-04-14 02:28:59.842289 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-14 02:28:59.842323 | 2026-04-14 02:28:59.961546 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-14 02:28:59.964616 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-14 02:29:00.553894 | 2026-04-14 02:29:00.554025 | PLAY [localhost] 2026-04-14 02:29:00.563888 | 2026-04-14 02:29:00.563968 | TASK [Generate Zuul manifest] 2026-04-14 02:29:00.587415 | localhost | ok 2026-04-14 02:29:00.605135 | 2026-04-14 02:29:00.605245 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-14 02:29:00.943069 | localhost | changed 2026-04-14 02:29:00.955132 | 2026-04-14 02:29:00.955225 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-14 02:29:00.986608 | localhost | ok 2026-04-14 02:29:00.995339 | 2026-04-14 02:29:00.995438 | TASK [Upload logs] 2026-04-14 02:29:01.015397 | localhost | ok 2026-04-14 02:29:01.081725 | 2026-04-14 02:29:01.081894 | TASK [Set zuul-log-path fact] 2026-04-14 02:29:01.104827 | localhost | ok 2026-04-14 02:29:01.116378 | 2026-04-14 02:29:01.116450 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-14 02:29:01.147842 | localhost | ok 2026-04-14 02:29:01.157601 | 2026-04-14 02:29:01.157665 | TASK [upload-logs : Create log directories] 2026-04-14 02:29:01.576780 | localhost | changed 2026-04-14 02:29:01.587481 | 2026-04-14 02:29:01.587618 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-14 02:29:02.045838 | localhost -> localhost | ok: Runtime: 0:00:00.052690 2026-04-14 02:29:02.053763 | 2026-04-14 02:29:02.053836 | TASK [upload-logs : Upload logs to log server] 2026-04-14 02:29:02.538715 | localhost | Output suppressed because no_log was given 2026-04-14 02:29:02.544640 | 2026-04-14 02:29:02.544717 | LOOP [upload-logs : Compress console log and json output] 2026-04-14 02:29:02.594086 | localhost | skipping: Conditional result was False 2026-04-14 02:29:02.600919 | localhost | skipping: Conditional result was False 2026-04-14 02:29:02.613928 | 2026-04-14 02:29:02.614107 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-14 02:29:02.661674 | localhost | skipping: Conditional result was False 2026-04-14 02:29:02.662310 | 2026-04-14 02:29:02.665920 | localhost | skipping: Conditional result was False 2026-04-14 02:29:02.680996 | 2026-04-14 02:29:02.681172 | LOOP [upload-logs : Upload console log and json output]