2026-03-16 00:54:45.643668 | Job console starting 2026-03-16 00:54:45.662704 | Updating git repos 2026-03-16 00:54:45.713431 | Cloning repos into workspace 2026-03-16 00:54:45.790856 | Restoring repo states 2026-03-16 00:54:45.801199 | Merging changes 2026-03-16 00:54:46.085814 | Checking out repos 2026-03-16 00:54:46.126466 | Preparing playbooks 2026-03-16 00:54:47.843300 | Running Ansible setup 2026-03-16 00:54:51.426291 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-16 00:54:52.022028 | 2026-03-16 00:54:52.022154 | PLAY [localhost] 2026-03-16 00:54:52.029414 | 2026-03-16 00:54:52.029485 | TASK [Gathering Facts] 2026-03-16 00:54:52.908482 | localhost | ok 2026-03-16 00:54:52.918477 | 2026-03-16 00:54:52.918584 | TASK [Setup log path fact] 2026-03-16 00:54:52.939976 | localhost | ok 2026-03-16 00:54:52.954130 | 2026-03-16 00:54:52.954200 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-16 00:54:52.983475 | localhost | ok 2026-03-16 00:54:52.993958 | 2026-03-16 00:54:52.994024 | TASK [emit-job-header : Print job information] 2026-03-16 00:54:53.038261 | # Job Information 2026-03-16 00:54:53.038380 | Ansible Version: 2.16.16 2026-03-16 00:54:53.038412 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-03-16 00:54:53.038434 | Pipeline: check 2026-03-16 00:54:53.038455 | Executor: 0a8996d2b663 2026-03-16 00:54:53.038475 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/101 2026-03-16 00:54:53.038498 | Event ID: 77b9c3c0-20d2-11f1-8fa9-da7cf51ce81d 2026-03-16 00:54:53.042381 | 2026-03-16 00:54:53.042451 | LOOP [emit-job-header : Print node information] 2026-03-16 00:54:53.140165 | localhost | ok: 2026-03-16 00:54:53.140461 | localhost | # Node Information 2026-03-16 00:54:53.140545 | localhost | Inventory Hostname: rockylinux-9 2026-03-16 00:54:53.140602 | localhost | Hostname: np0000158872 2026-03-16 00:54:53.140648 | localhost | Username: zuul 2026-03-16 00:54:53.140697 | localhost | Distro: Rocky 9.7 2026-03-16 00:54:53.140741 | localhost | Provider: yul1 2026-03-16 00:54:53.140783 | localhost | Region: ca-ymq-1 2026-03-16 00:54:53.140824 | localhost | Label: rockylinux-9 2026-03-16 00:54:53.140864 | localhost | Product Name: OpenStack Nova 2026-03-16 00:54:53.140920 | localhost | Interface IP: 162.253.55.221 2026-03-16 00:54:53.148885 | 2026-03-16 00:54:53.149663 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-16 00:54:53.558024 | localhost -> localhost | changed 2026-03-16 00:54:53.563210 | 2026-03-16 00:54:53.563277 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-16 00:54:54.469741 | localhost -> localhost | changed 2026-03-16 00:54:54.478910 | 2026-03-16 00:54:54.478992 | PLAY [all] 2026-03-16 00:54:54.487594 | 2026-03-16 00:54:54.487666 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-16 00:54:54.704314 | rockylinux-9 -> localhost | ok 2026-03-16 00:54:54.713152 | 2026-03-16 00:54:54.713254 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-16 00:54:54.742883 | rockylinux-9 | ok 2026-03-16 00:54:54.758406 | rockylinux-9 | included: /var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-16 00:54:54.763461 | 2026-03-16 00:54:54.763521 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-16 00:54:55.695206 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-16 00:54:55.695479 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/work/56e50106a7cf4a2d94485f2cae77cac9_id_rsa 2026-03-16 00:54:55.695547 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/work/56e50106a7cf4a2d94485f2cae77cac9_id_rsa.pub 2026-03-16 00:54:55.695598 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-16 00:54:55.695646 | rockylinux-9 -> localhost | SHA256:aLZLQ0uTRb2bIdco9VU5+jdUNV7l6hs5kdOat74Wy48 zuul-build-sshkey 2026-03-16 00:54:55.695713 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-16 00:54:55.695761 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-16 00:54:55.695873 | rockylinux-9 -> localhost | | .. oO| 2026-03-16 00:54:55.695932 | rockylinux-9 -> localhost | | . o o++| 2026-03-16 00:54:55.695979 | rockylinux-9 -> localhost | | .. = ...+| 2026-03-16 00:54:55.696024 | rockylinux-9 -> localhost | | +o = o. = | 2026-03-16 00:54:55.696069 | rockylinux-9 -> localhost | | O S+ + B .| 2026-03-16 00:54:55.696101 | rockylinux-9 -> localhost | | = + o . X.| 2026-03-16 00:54:55.696123 | rockylinux-9 -> localhost | | = O *| 2026-03-16 00:54:55.696144 | rockylinux-9 -> localhost | | . o X.| 2026-03-16 00:54:55.696167 | rockylinux-9 -> localhost | | . E++| 2026-03-16 00:54:55.696187 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-16 00:54:55.696237 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.511590 2026-03-16 00:54:55.700844 | 2026-03-16 00:54:55.700909 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-16 00:54:55.732656 | rockylinux-9 | ok 2026-03-16 00:54:55.743859 | rockylinux-9 | included: /var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-16 00:54:55.756578 | 2026-03-16 00:54:55.756643 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-16 00:54:55.781782 | rockylinux-9 | skipping: Conditional result was False 2026-03-16 00:54:55.792160 | 2026-03-16 00:54:55.792235 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-16 00:54:56.262738 | rockylinux-9 | changed 2026-03-16 00:54:56.267120 | 2026-03-16 00:54:56.267188 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-16 00:54:56.533033 | rockylinux-9 | ok 2026-03-16 00:54:56.539239 | 2026-03-16 00:54:56.539326 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-16 00:54:57.445620 | rockylinux-9 | changed 2026-03-16 00:54:57.452093 | 2026-03-16 00:54:57.452164 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-16 00:54:58.363035 | rockylinux-9 | changed 2026-03-16 00:54:58.367908 | 2026-03-16 00:54:58.367991 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-16 00:54:58.392220 | rockylinux-9 | skipping: Conditional result was False 2026-03-16 00:54:58.398320 | 2026-03-16 00:54:58.398398 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-16 00:54:58.748738 | rockylinux-9 -> localhost | changed 2026-03-16 00:54:58.762605 | 2026-03-16 00:54:58.762696 | TASK [add-build-sshkey : Add back temp key] 2026-03-16 00:54:59.054391 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/work/56e50106a7cf4a2d94485f2cae77cac9_id_rsa (zuul-build-sshkey) 2026-03-16 00:54:59.054601 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012979 2026-03-16 00:54:59.060981 | 2026-03-16 00:54:59.061047 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-16 00:54:59.438035 | rockylinux-9 | ok 2026-03-16 00:54:59.446996 | 2026-03-16 00:54:59.447135 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-16 00:54:59.472599 | rockylinux-9 | skipping: Conditional result was False 2026-03-16 00:54:59.485397 | 2026-03-16 00:54:59.485509 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-16 00:54:59.893938 | rockylinux-9 | ok 2026-03-16 00:54:59.910006 | 2026-03-16 00:54:59.910104 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-16 00:55:00.841992 | rockylinux-9 | Output suppressed because no_log was given 2026-03-16 00:55:00.854833 | 2026-03-16 00:55:00.854929 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-16 00:55:01.110161 | rockylinux-9 | ok: "logs" 2026-03-16 00:55:01.111489 | rockylinux-9 | ok: All items complete 2026-03-16 00:55:01.111535 | 2026-03-16 00:55:01.337709 | rockylinux-9 | ok: "artifacts" 2026-03-16 00:55:01.568678 | rockylinux-9 | ok: "docs" 2026-03-16 00:55:01.582235 | 2026-03-16 00:55:01.582939 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-16 00:55:01.834961 | rockylinux-9 | changed: "logs" 2026-03-16 00:55:02.081197 | rockylinux-9 | changed: "artifacts" 2026-03-16 00:55:02.297667 | rockylinux-9 | changed: "docs" 2026-03-16 00:55:02.317068 | 2026-03-16 00:55:02.317268 | PLAY RECAP 2026-03-16 00:55:02.317325 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-16 00:55:02.317357 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-16 00:55:02.317379 | 2026-03-16 00:55:02.444501 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-16 00:55:02.445666 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-16 00:55:03.031953 | 2026-03-16 00:55:03.032128 | PLAY [all] 2026-03-16 00:55:03.043652 | 2026-03-16 00:55:03.043741 | TASK [setup-uv : Extract archive] 2026-03-16 00:55:05.771331 | rockylinux-9 | changed 2026-03-16 00:55:05.778691 | 2026-03-16 00:55:05.778797 | TASK [setup-uv : Print version] 2026-03-16 00:55:05.818188 | [rockylinux-9] Waiting on logger 2026-03-16 00:55:06.230839 | rockylinux-9 | ok: Runtime: 0:00:00.015833 2026-03-16 00:55:06.238824 | 2026-03-16 00:55:06.238890 | PLAY RECAP 2026-03-16 00:55:06.238957 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-16 00:55:06.238994 | 2026-03-16 00:55:06.427714 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-16 00:55:06.430901 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-16 00:55:07.011598 | 2026-03-16 00:55:07.011718 | PLAY [all] 2026-03-16 00:55:07.022449 | 2026-03-16 00:55:07.022524 | TASK [Copy inventory file for Zuul] 2026-03-16 00:55:08.459547 | rockylinux-9 | changed 2026-03-16 00:55:08.465179 | 2026-03-16 00:55:08.465287 | TASK [Switch "ansible_host" to private IP] 2026-03-16 00:55:08.804428 | rockylinux-9 | changed: 1 replacements made 2026-03-16 00:55:08.921929 | 2026-03-16 00:55:08.922114 | TASK [Run Molecule scenario] 2026-03-16 00:55:08.949255 | [rockylinux-9] Waiting on logger 2026-03-16 00:55:16.069907 | [rockylinux-9] Waiting on logger 2026-03-16 00:55:26.252907 | rockylinux-9 | ERROR 2026-03-16 00:55:26.253474 | rockylinux-9 | { 2026-03-16 00:55:26.253528 | rockylinux-9 | "delta": "0:00:15.826152", 2026-03-16 00:55:26.253561 | rockylinux-9 | "end": "2026-03-16 00:55:23.237291", 2026-03-16 00:55:26.253589 | rockylinux-9 | "msg": "non-zero return code", 2026-03-16 00:55:26.253616 | rockylinux-9 | "rc": 4, 2026-03-16 00:55:26.253646 | rockylinux-9 | "start": "2026-03-16 00:55:07.411139" 2026-03-16 00:55:26.253676 | rockylinux-9 | } failure 2026-03-16 00:55:26.256340 | 2026-03-16 00:55:26.256395 | PLAY RECAP 2026-03-16 00:55:26.256444 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0 2026-03-16 00:55:26.256468 | 2026-03-16 00:55:26.405419 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-16 00:55:26.407921 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-16 00:55:26.974383 | 2026-03-16 00:55:26.974504 | PLAY [all] 2026-03-16 00:55:26.984797 | 2026-03-16 00:55:26.984873 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-16 00:55:27.041206 | rockylinux-9 | skipping: Conditional result was False 2026-03-16 00:55:27.049611 | 2026-03-16 00:55:27.049728 | TASK [fetch-output : Set log path for single node] 2026-03-16 00:55:27.094360 | rockylinux-9 | ok 2026-03-16 00:55:27.102211 | 2026-03-16 00:55:27.102306 | LOOP [fetch-output : Ensure local output dirs] 2026-03-16 00:55:27.638775 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/work/logs" 2026-03-16 00:55:27.886390 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/work/artifacts" 2026-03-16 00:55:28.116890 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/56e50106a7cf4a2d94485f2cae77cac9/work/docs" 2026-03-16 00:55:28.128309 | 2026-03-16 00:55:28.128429 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-16 00:55:28.817442 | rockylinux-9 | changed: .d..t...... ./ 2026-03-16 00:55:28.817699 | rockylinux-9 | changed: All items complete 2026-03-16 00:55:28.817741 | 2026-03-16 00:55:29.368978 | rockylinux-9 | changed: .d..t...... ./ 2026-03-16 00:55:29.904329 | rockylinux-9 | changed: .d..t...... ./ 2026-03-16 00:55:29.922827 | 2026-03-16 00:55:29.922912 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-16 00:55:30.814127 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008817 2026-03-16 00:55:31.131358 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009315 2026-03-16 00:55:31.157334 | 2026-03-16 00:55:31.157648 | PLAY [all] 2026-03-16 00:55:31.180411 | 2026-03-16 00:55:31.180502 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-16 00:55:31.676804 | rockylinux-9 | changed 2026-03-16 00:55:31.683514 | 2026-03-16 00:55:31.683582 | PLAY RECAP 2026-03-16 00:55:31.683642 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-16 00:55:31.683675 | 2026-03-16 00:55:31.793316 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-16 00:55:31.794527 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-16 00:55:32.408384 | 2026-03-16 00:55:32.408735 | PLAY [localhost] 2026-03-16 00:55:32.418856 | 2026-03-16 00:55:32.418930 | TASK [Generate Zuul manifest] 2026-03-16 00:55:32.451512 | localhost | ok 2026-03-16 00:55:32.470573 | 2026-03-16 00:55:32.470750 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-16 00:55:32.785670 | localhost | changed 2026-03-16 00:55:32.799303 | 2026-03-16 00:55:32.799390 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-16 00:55:32.830647 | localhost | ok 2026-03-16 00:55:32.845657 | 2026-03-16 00:55:32.845728 | TASK [Upload logs] 2026-03-16 00:55:32.863982 | localhost | ok 2026-03-16 00:55:32.921936 | 2026-03-16 00:55:32.922020 | TASK [Set zuul-log-path fact] 2026-03-16 00:55:32.942774 | localhost | ok 2026-03-16 00:55:32.954266 | 2026-03-16 00:55:32.954332 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-16 00:55:32.985196 | localhost | ok 2026-03-16 00:55:32.994429 | 2026-03-16 00:55:32.994491 | TASK [upload-logs : Create log directories] 2026-03-16 00:55:33.402199 | localhost | changed 2026-03-16 00:55:33.406768 | 2026-03-16 00:55:33.406833 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-16 00:55:33.811412 | localhost -> localhost | ok: Runtime: 0:00:00.005824 2026-03-16 00:55:33.818437 | 2026-03-16 00:55:33.818524 | TASK [upload-logs : Upload logs to log server] 2026-03-16 00:55:34.274749 | localhost | Output suppressed because no_log was given 2026-03-16 00:55:34.280022 | 2026-03-16 00:55:34.280104 | LOOP [upload-logs : Compress console log and json output] 2026-03-16 00:55:34.329641 | localhost | skipping: Conditional result was False 2026-03-16 00:55:34.337243 | localhost | skipping: Conditional result was False 2026-03-16 00:55:34.353546 | 2026-03-16 00:55:34.353718 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-16 00:55:34.398934 | localhost | skipping: Conditional result was False 2026-03-16 00:55:34.399405 | 2026-03-16 00:55:34.403023 | localhost | skipping: Conditional result was False 2026-03-16 00:55:34.418965 | 2026-03-16 00:55:34.419191 | LOOP [upload-logs : Upload console log and json output]