2026-04-18 00:53:30.710672 | Job console starting 2026-04-18 00:53:30.722608 | Updating git repos 2026-04-18 00:53:30.750650 | Cloning repos into workspace 2026-04-18 00:53:30.810615 | Restoring repo states 2026-04-18 00:53:30.822209 | Merging changes 2026-04-18 00:53:31.168416 | Checking out repos 2026-04-18 00:53:31.211527 | Preparing playbooks 2026-04-18 00:53:32.782438 | Running Ansible setup 2026-04-18 00:53:36.881409 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 00:53:37.652898 | 2026-04-18 00:53:37.653065 | PLAY [localhost] 2026-04-18 00:53:37.661202 | 2026-04-18 00:53:37.661282 | TASK [Gathering Facts] 2026-04-18 00:53:38.688355 | localhost | ok 2026-04-18 00:53:38.694897 | 2026-04-18 00:53:38.694969 | TASK [Setup log path fact] 2026-04-18 00:53:38.712125 | localhost | ok 2026-04-18 00:53:38.722652 | 2026-04-18 00:53:38.722720 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 00:53:38.750287 | localhost | ok 2026-04-18 00:53:38.756894 | 2026-04-18 00:53:38.757050 | TASK [emit-job-header : Print job information] 2026-04-18 00:53:38.793214 | # Job Information 2026-04-18 00:53:38.793377 | Ansible Version: 2.16.16 2026-04-18 00:53:38.793409 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-18 00:53:38.793432 | Pipeline: check 2026-04-18 00:53:38.793453 | Executor: 0a8996d2b663 2026-04-18 00:53:38.793473 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/109 2026-04-18 00:53:38.793495 | Event ID: e69d5b00-3ac0-11f1-8e84-863288ff99e4 2026-04-18 00:53:38.796244 | 2026-04-18 00:53:38.796304 | LOOP [emit-job-header : Print node information] 2026-04-18 00:53:38.880427 | localhost | ok: 2026-04-18 00:53:38.880661 | localhost | # Node Information 2026-04-18 00:53:38.880697 | localhost | Inventory Hostname: rockylinux-9 2026-04-18 00:53:38.880729 | localhost | Hostname: np0000167181 2026-04-18 00:53:38.880755 | localhost | Username: zuul 2026-04-18 00:53:38.880783 | localhost | Distro: Rocky 9.7 2026-04-18 00:53:38.880807 | localhost | Provider: yul1 2026-04-18 00:53:38.880831 | localhost | Region: ca-ymq-1 2026-04-18 00:53:38.880854 | localhost | Label: rockylinux-9 2026-04-18 00:53:38.880878 | localhost | Product Name: OpenStack Nova 2026-04-18 00:53:38.880902 | localhost | Interface IP: 199.204.45.233 2026-04-18 00:53:38.890682 | 2026-04-18 00:53:38.890837 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 00:53:39.831149 | localhost -> localhost | changed 2026-04-18 00:53:39.836196 | 2026-04-18 00:53:39.836294 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 00:53:41.052610 | localhost -> localhost | changed 2026-04-18 00:53:41.058346 | 2026-04-18 00:53:41.058401 | PLAY [all] 2026-04-18 00:53:41.070990 | 2026-04-18 00:53:41.071108 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 00:53:41.343394 | rockylinux-9 -> localhost | ok 2026-04-18 00:53:41.349154 | 2026-04-18 00:53:41.349235 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 00:53:41.378567 | rockylinux-9 | ok 2026-04-18 00:53:41.404191 | rockylinux-9 | included: /var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 00:53:41.409894 | 2026-04-18 00:53:41.409962 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 00:53:42.302253 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-18 00:53:42.302598 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/work/7d2bece1a5964aa19acb0790a77051b1_id_rsa 2026-04-18 00:53:42.302807 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/work/7d2bece1a5964aa19acb0790a77051b1_id_rsa.pub 2026-04-18 00:53:42.302845 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-18 00:53:42.302878 | rockylinux-9 -> localhost | SHA256:LOVA0kX5jlGI2N5FEaSBIKB5UEG+urraNVABrF2QxvQ zuul-build-sshkey 2026-04-18 00:53:42.302931 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-18 00:53:42.302963 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-18 00:53:42.302997 | rockylinux-9 -> localhost | |oBO=+=.=+==o | 2026-04-18 00:53:42.303029 | rockylinux-9 -> localhost | |.+=oo+= ooo | 2026-04-18 00:53:42.303058 | rockylinux-9 -> localhost | |o+o.E...o+ | 2026-04-18 00:53:42.303088 | rockylinux-9 -> localhost | |...o .=o . | 2026-04-18 00:53:42.303117 | rockylinux-9 -> localhost | | o . S+ | 2026-04-18 00:53:42.303146 | rockylinux-9 -> localhost | | . . .. . | 2026-04-18 00:53:42.303175 | rockylinux-9 -> localhost | |. o | 2026-04-18 00:53:42.303204 | rockylinux-9 -> localhost | | o . . | 2026-04-18 00:53:42.303237 | rockylinux-9 -> localhost | |B.. | 2026-04-18 00:53:42.303266 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-18 00:53:42.303342 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.430635 2026-04-18 00:53:42.310382 | 2026-04-18 00:53:42.310521 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 00:53:42.340168 | rockylinux-9 | ok 2026-04-18 00:53:42.348534 | rockylinux-9 | included: /var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 00:53:42.356670 | 2026-04-18 00:53:42.356733 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 00:53:42.370978 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:53:42.380676 | 2026-04-18 00:53:42.380762 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 00:53:42.891676 | rockylinux-9 | changed 2026-04-18 00:53:42.898982 | 2026-04-18 00:53:42.899108 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 00:53:43.137077 | rockylinux-9 | ok 2026-04-18 00:53:43.142526 | 2026-04-18 00:53:43.142630 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 00:53:44.059483 | rockylinux-9 | changed 2026-04-18 00:53:44.065654 | 2026-04-18 00:53:44.065721 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 00:53:44.982505 | rockylinux-9 | changed 2026-04-18 00:53:44.988804 | 2026-04-18 00:53:44.988865 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 00:53:45.012669 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:53:45.017913 | 2026-04-18 00:53:45.017984 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 00:53:45.554897 | rockylinux-9 -> localhost | changed 2026-04-18 00:53:45.565002 | 2026-04-18 00:53:45.565080 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 00:53:45.861870 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/work/7d2bece1a5964aa19acb0790a77051b1_id_rsa (zuul-build-sshkey) 2026-04-18 00:53:45.862072 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013907 2026-04-18 00:53:45.867944 | 2026-04-18 00:53:45.868019 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 00:53:46.263443 | rockylinux-9 | ok 2026-04-18 00:53:46.269727 | 2026-04-18 00:53:46.269815 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 00:53:46.294359 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:53:46.306315 | 2026-04-18 00:53:46.306408 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 00:53:46.733880 | rockylinux-9 | ok 2026-04-18 00:53:46.739061 | 2026-04-18 00:53:46.739127 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 00:53:47.678129 | rockylinux-9 | Output suppressed because no_log was given 2026-04-18 00:53:47.685624 | 2026-04-18 00:53:47.685693 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 00:53:47.940075 | rockylinux-9 | ok: "logs" 2026-04-18 00:53:47.940387 | rockylinux-9 | ok: All items complete 2026-04-18 00:53:47.940427 | 2026-04-18 00:53:48.164363 | rockylinux-9 | ok: "artifacts" 2026-04-18 00:53:48.432608 | rockylinux-9 | ok: "docs" 2026-04-18 00:53:48.452490 | 2026-04-18 00:53:48.452642 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 00:53:48.719166 | rockylinux-9 | changed: "logs" 2026-04-18 00:53:48.952283 | rockylinux-9 | changed: "artifacts" 2026-04-18 00:53:49.192413 | rockylinux-9 | changed: "docs" 2026-04-18 00:53:49.212944 | 2026-04-18 00:53:49.213107 | PLAY RECAP 2026-04-18 00:53:49.213160 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 00:53:49.213191 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 00:53:49.213211 | 2026-04-18 00:53:49.428469 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 00:53:49.430644 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 00:53:50.052039 | 2026-04-18 00:53:50.052194 | PLAY [all] 2026-04-18 00:53:50.066810 | 2026-04-18 00:53:50.066952 | TASK [setup-uv : Extract archive] 2026-04-18 00:53:52.815732 | rockylinux-9 | changed 2026-04-18 00:53:52.823837 | 2026-04-18 00:53:52.823933 | TASK [setup-uv : Print version] 2026-04-18 00:53:52.852602 | [rockylinux-9] Waiting on logger 2026-04-18 00:53:53.362672 | rockylinux-9 | ok: Runtime: 0:00:00.012305 2026-04-18 00:53:53.368564 | 2026-04-18 00:53:53.368615 | PLAY RECAP 2026-04-18 00:53:53.368658 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 00:53:53.368682 | 2026-04-18 00:53:53.478752 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 00:53:53.479971 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 00:53:54.018663 | 2026-04-18 00:53:54.018815 | PLAY [all] 2026-04-18 00:53:54.029700 | 2026-04-18 00:53:54.029777 | TASK [Copy inventory file for Zuul] 2026-04-18 00:53:55.411435 | rockylinux-9 | changed 2026-04-18 00:53:55.416778 | 2026-04-18 00:53:55.416850 | TASK [Switch "ansible_host" to private IP] 2026-04-18 00:53:55.769440 | rockylinux-9 | changed: 1 replacements made 2026-04-18 00:53:55.778096 | 2026-04-18 00:53:55.778191 | TASK [Run Molecule scenario] 2026-04-18 00:53:55.807024 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:03.947485 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:14.118134 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:24.294159 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:34.470228 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:44.646177 | [rockylinux-9] Waiting on logger 2026-04-18 00:54:54.822185 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:03.973785 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:14.150198 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:24.326027 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:34.502099 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:44.870318 | [rockylinux-9] Waiting on logger 2026-04-18 00:55:54.022157 | [rockylinux-9] Waiting on logger 2026-04-18 00:56:04.198556 | [rockylinux-9] Waiting on logger 2026-04-18 00:56:14.374300 | [rockylinux-9] Waiting on logger 2026-04-18 00:56:24.550335 | [rockylinux-9] Waiting on logger 2026-04-18 00:56:34.726319 | [rockylinux-9] Waiting on logger 2026-04-18 00:56:44.234380 | [rockylinux-9] Waiting on logger 2026-04-18 00:56:54.410266 | [rockylinux-9] Waiting on logger 2026-04-18 00:57:04.582230 | [rockylinux-9] Waiting on logger 2026-04-18 00:57:14.758212 | [rockylinux-9] Waiting on logger 2026-04-18 00:57:23.916015 | [rockylinux-9] Waiting on logger 2026-04-18 00:57:34.086225 | [rockylinux-9] Waiting on logger 2026-04-18 00:57:44.262238 | [rockylinux-9] Waiting on logger 2026-04-18 00:57:54.183446 | [rockylinux-9] Waiting on logger 2026-04-18 00:58:04.358248 | [rockylinux-9] Waiting on logger 2026-04-18 00:58:14.534584 | [rockylinux-9] Waiting on logger 2026-04-18 00:58:22.478060 | rockylinux-9 | ok: Runtime: 0:04:25.701323 2026-04-18 00:58:22.487204 | 2026-04-18 00:58:22.487256 | PLAY RECAP 2026-04-18 00:58:22.487298 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 00:58:22.487319 | 2026-04-18 00:58:22.604055 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 00:58:22.606529 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 00:58:23.188340 | 2026-04-18 00:58:23.188484 | PLAY [all] 2026-04-18 00:58:23.199449 | 2026-04-18 00:58:23.199538 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 00:58:23.245313 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 00:58:23.254622 | 2026-04-18 00:58:23.254706 | TASK [fetch-output : Set log path for single node] 2026-04-18 00:58:23.297745 | rockylinux-9 | ok 2026-04-18 00:58:23.305994 | 2026-04-18 00:58:23.306093 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 00:58:23.670204 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/work/logs" 2026-04-18 00:58:23.898807 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/work/artifacts" 2026-04-18 00:58:24.506986 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7d2bece1a5964aa19acb0790a77051b1/work/docs" 2026-04-18 00:58:24.521422 | 2026-04-18 00:58:24.521573 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 00:58:25.218635 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 00:58:25.218907 | rockylinux-9 | changed: All items complete 2026-04-18 00:58:25.218945 | 2026-04-18 00:58:25.719071 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 00:58:26.218524 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 00:58:26.242467 | 2026-04-18 00:58:26.242613 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 00:58:26.640971 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006876 2026-04-18 00:58:26.866265 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007059 2026-04-18 00:58:26.887145 | 2026-04-18 00:58:26.887280 | PLAY [all] 2026-04-18 00:58:26.893117 | 2026-04-18 00:58:26.893181 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 00:58:27.352252 | rockylinux-9 | changed 2026-04-18 00:58:27.359700 | 2026-04-18 00:58:27.359828 | PLAY RECAP 2026-04-18 00:58:27.359896 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 00:58:27.359925 | 2026-04-18 00:58:27.509880 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 00:58:27.512261 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 00:58:28.055741 | 2026-04-18 00:58:28.055864 | PLAY [localhost] 2026-04-18 00:58:28.065553 | 2026-04-18 00:58:28.065622 | TASK [Generate Zuul manifest] 2026-04-18 00:58:28.087178 | localhost | ok 2026-04-18 00:58:28.103356 | 2026-04-18 00:58:28.103436 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 00:58:28.407797 | localhost | changed 2026-04-18 00:58:28.421116 | 2026-04-18 00:58:28.421292 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 00:58:28.452216 | localhost | ok 2026-04-18 00:58:28.460792 | 2026-04-18 00:58:28.460876 | TASK [Upload logs] 2026-04-18 00:58:28.482182 | localhost | ok 2026-04-18 00:58:28.539918 | 2026-04-18 00:58:28.540025 | TASK [Set zuul-log-path fact] 2026-04-18 00:58:28.558074 | localhost | ok 2026-04-18 00:58:28.568408 | 2026-04-18 00:58:28.568476 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 00:58:28.600311 | localhost | ok 2026-04-18 00:58:28.610410 | 2026-04-18 00:58:28.610493 | TASK [upload-logs : Create log directories] 2026-04-18 00:58:29.018368 | localhost | changed 2026-04-18 00:58:29.025389 | 2026-04-18 00:58:29.025482 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 00:58:29.413286 | localhost -> localhost | ok: Runtime: 0:00:00.005430 2026-04-18 00:58:29.418116 | 2026-04-18 00:58:29.418179 | TASK [upload-logs : Upload logs to log server] 2026-04-18 00:58:29.861963 | localhost | Output suppressed because no_log was given 2026-04-18 00:58:29.866869 | 2026-04-18 00:58:29.866955 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 00:58:29.917890 | localhost | skipping: Conditional result was False 2026-04-18 00:58:29.925249 | localhost | skipping: Conditional result was False 2026-04-18 00:58:29.942151 | 2026-04-18 00:58:29.942320 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 00:58:29.986430 | localhost | skipping: Conditional result was False 2026-04-18 00:58:29.986838 | 2026-04-18 00:58:29.990655 | localhost | skipping: Conditional result was False 2026-04-18 00:58:30.002473 | 2026-04-18 00:58:30.002605 | LOOP [upload-logs : Upload console log and json output]