2026-04-19 01:01:39.365410 | Job console starting 2026-04-19 01:01:39.374669 | Updating git repos 2026-04-19 01:01:39.620666 | Cloning repos into workspace 2026-04-19 01:01:42.401972 | Restoring repo states 2026-04-19 01:01:42.408365 | Merging changes 2026-04-19 01:01:42.947071 | Checking out repos 2026-04-19 01:01:43.546131 | Preparing playbooks 2026-04-19 01:01:57.512791 | Running Ansible setup 2026-04-19 01:02:01.538885 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-19 01:02:02.095410 | 2026-04-19 01:02:02.095540 | PLAY [localhost] 2026-04-19 01:02:02.103160 | 2026-04-19 01:02:02.103248 | TASK [Gathering Facts] 2026-04-19 01:02:03.512153 | localhost | ok 2026-04-19 01:02:03.521823 | 2026-04-19 01:02:03.521908 | TASK [Setup log path fact] 2026-04-19 01:02:03.547524 | localhost | ok 2026-04-19 01:02:03.562024 | 2026-04-19 01:02:03.562168 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 01:02:03.592716 | localhost | ok 2026-04-19 01:02:03.601507 | 2026-04-19 01:02:03.601633 | TASK [emit-job-header : Print job information] 2026-04-19 01:02:03.642375 | # Job Information 2026-04-19 01:02:03.642576 | Ansible Version: 2.16.16 2026-04-19 01:02:03.642623 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-19 01:02:03.642656 | Pipeline: check 2026-04-19 01:02:03.642686 | Executor: 0a8996d2b663 2026-04-19 01:02:03.642715 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/102 2026-04-19 01:02:03.642746 | Event ID: 175c64a0-3b8b-11f1-9cee-b51e243a86c4 2026-04-19 01:02:03.646640 | 2026-04-19 01:02:03.646730 | LOOP [emit-job-header : Print node information] 2026-04-19 01:02:03.741027 | localhost | ok: 2026-04-19 01:02:03.741245 | localhost | # Node Information 2026-04-19 01:02:03.741279 | localhost | Inventory Hostname: rockylinux-9 2026-04-19 01:02:03.741313 | localhost | Hostname: np0000167707 2026-04-19 01:02:03.741335 | localhost | Username: zuul 2026-04-19 01:02:03.741358 | localhost | Distro: Rocky 9.7 2026-04-19 01:02:03.741378 | localhost | Provider: yul1 2026-04-19 01:02:03.741398 | localhost | Region: ca-ymq-1 2026-04-19 01:02:03.741416 | localhost | Label: rockylinux-9 2026-04-19 01:02:03.741435 | localhost | Product Name: OpenStack Nova 2026-04-19 01:02:03.741453 | localhost | Interface IP: 199.204.45.138 2026-04-19 01:02:03.753496 | 2026-04-19 01:02:03.753615 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-19 01:02:04.156389 | localhost -> localhost | changed 2026-04-19 01:02:04.164354 | 2026-04-19 01:02:04.164508 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-19 01:02:05.021334 | localhost -> localhost | changed 2026-04-19 01:02:05.032712 | 2026-04-19 01:02:05.032784 | PLAY [all] 2026-04-19 01:02:05.040423 | 2026-04-19 01:02:05.040507 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-19 01:02:05.259438 | rockylinux-9 -> localhost | ok 2026-04-19 01:02:05.269842 | 2026-04-19 01:02:05.269908 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-19 01:02:05.302176 | rockylinux-9 | ok 2026-04-19 01:02:05.319025 | rockylinux-9 | included: /var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-19 01:02:05.323960 | 2026-04-19 01:02:05.324018 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-19 01:02:06.467956 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-19 01:02:06.468184 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/work/8f262b5f1e3f4fb1b733cbb77b44a645_id_rsa 2026-04-19 01:02:06.468231 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/work/8f262b5f1e3f4fb1b733cbb77b44a645_id_rsa.pub 2026-04-19 01:02:06.468264 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-19 01:02:06.468296 | rockylinux-9 -> localhost | SHA256:6eCQOujiuByXG/jzkEyq8i1usFTSha7IoZhy8roeqbI zuul-build-sshkey 2026-04-19 01:02:06.468345 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-19 01:02:06.468376 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-19 01:02:06.468412 | rockylinux-9 -> localhost | | . | 2026-04-19 01:02:06.468443 | rockylinux-9 -> localhost | | . . | 2026-04-19 01:02:06.468473 | rockylinux-9 -> localhost | | o . | 2026-04-19 01:02:06.468503 | rockylinux-9 -> localhost | | o + . . | 2026-04-19 01:02:06.468533 | rockylinux-9 -> localhost | |=.=.o . S | 2026-04-19 01:02:06.468562 | rockylinux-9 -> localhost | |O=B.oo o | 2026-04-19 01:02:06.468590 | rockylinux-9 -> localhost | |+%oB . . | 2026-04-19 01:02:06.468620 | rockylinux-9 -> localhost | |@ O++ | 2026-04-19 01:02:06.468652 | rockylinux-9 -> localhost | |E&o++. | 2026-04-19 01:02:06.468682 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-19 01:02:06.468754 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.743591 2026-04-19 01:02:06.474992 | 2026-04-19 01:02:06.475103 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-19 01:02:06.507416 | rockylinux-9 | ok 2026-04-19 01:02:06.517997 | rockylinux-9 | included: /var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-19 01:02:06.529344 | 2026-04-19 01:02:06.529670 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-19 01:02:06.554651 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:02:06.565927 | 2026-04-19 01:02:06.566076 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-19 01:02:07.024599 | rockylinux-9 | changed 2026-04-19 01:02:07.030427 | 2026-04-19 01:02:07.030487 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-19 01:02:07.275074 | rockylinux-9 | ok 2026-04-19 01:02:07.281199 | 2026-04-19 01:02:07.281279 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-19 01:02:08.240374 | rockylinux-9 | changed 2026-04-19 01:02:08.245966 | 2026-04-19 01:02:08.246027 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-19 01:02:09.159928 | rockylinux-9 | changed 2026-04-19 01:02:09.164696 | 2026-04-19 01:02:09.164757 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-19 01:02:09.189311 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:02:09.196897 | 2026-04-19 01:02:09.196989 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-19 01:02:09.567185 | rockylinux-9 -> localhost | changed 2026-04-19 01:02:09.576430 | 2026-04-19 01:02:09.576516 | TASK [add-build-sshkey : Add back temp key] 2026-04-19 01:02:09.869097 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/work/8f262b5f1e3f4fb1b733cbb77b44a645_id_rsa (zuul-build-sshkey) 2026-04-19 01:02:09.869309 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014714 2026-04-19 01:02:09.876209 | 2026-04-19 01:02:09.876274 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-19 01:02:10.275333 | rockylinux-9 | ok 2026-04-19 01:02:10.281526 | 2026-04-19 01:02:10.281595 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-19 01:02:10.306630 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:02:10.320572 | 2026-04-19 01:02:10.320654 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-19 01:02:10.718515 | rockylinux-9 | ok 2026-04-19 01:02:10.725399 | 2026-04-19 01:02:10.725464 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-19 01:02:11.633189 | rockylinux-9 | Output suppressed because no_log was given 2026-04-19 01:02:11.641335 | 2026-04-19 01:02:11.641400 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-19 01:02:11.899325 | rockylinux-9 | ok: "logs" 2026-04-19 01:02:11.899770 | rockylinux-9 | ok: All items complete 2026-04-19 01:02:11.899812 | 2026-04-19 01:02:12.114743 | rockylinux-9 | ok: "artifacts" 2026-04-19 01:02:12.347771 | rockylinux-9 | ok: "docs" 2026-04-19 01:02:12.358717 | 2026-04-19 01:02:12.358879 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-19 01:02:12.596381 | rockylinux-9 | changed: "logs" 2026-04-19 01:02:12.819301 | rockylinux-9 | changed: "artifacts" 2026-04-19 01:02:13.036954 | rockylinux-9 | changed: "docs" 2026-04-19 01:02:13.057008 | 2026-04-19 01:02:13.057143 | PLAY RECAP 2026-04-19 01:02:13.057192 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 01:02:13.057224 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-19 01:02:13.057247 | 2026-04-19 01:02:13.167307 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-19 01:02:13.168380 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-19 01:02:13.765396 | 2026-04-19 01:02:13.765510 | PLAY [all] 2026-04-19 01:02:13.775967 | 2026-04-19 01:02:13.776043 | TASK [setup-uv : Extract archive] 2026-04-19 01:02:16.515189 | rockylinux-9 | changed 2026-04-19 01:02:16.520570 | 2026-04-19 01:02:16.520654 | TASK [setup-uv : Print version] 2026-04-19 01:02:16.542059 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:17.058107 | rockylinux-9 | ok: Runtime: 0:00:00.013393 2026-04-19 01:02:17.065665 | 2026-04-19 01:02:17.065779 | PLAY RECAP 2026-04-19 01:02:17.065845 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 01:02:17.065878 | 2026-04-19 01:02:17.163677 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-19 01:02:17.165283 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-19 01:02:17.770857 | 2026-04-19 01:02:17.771051 | PLAY [all] 2026-04-19 01:02:17.783138 | 2026-04-19 01:02:17.783268 | TASK [Copy inventory file for Zuul] 2026-04-19 01:02:19.107611 | rockylinux-9 | changed 2026-04-19 01:02:19.113275 | 2026-04-19 01:02:19.113355 | TASK [Switch "ansible_host" to private IP] 2026-04-19 01:02:19.472389 | rockylinux-9 | changed: 1 replacements made 2026-04-19 01:02:19.478180 | 2026-04-19 01:02:19.478245 | TASK [Run Molecule scenario] 2026-04-19 01:02:19.498996 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:28.229865 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:38.534035 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:47.685989 | [rockylinux-9] Waiting on logger 2026-04-19 01:02:57.865895 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:08.037965 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:18.214232 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:28.389975 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:37.550833 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:47.718314 | [rockylinux-9] Waiting on logger 2026-04-19 01:03:57.893944 | [rockylinux-9] Waiting on logger 2026-04-19 01:04:08.070088 | [rockylinux-9] Waiting on logger 2026-04-19 01:04:18.245857 | [rockylinux-9] Waiting on logger 2026-04-19 01:04:28.425827 | [rockylinux-9] Waiting on logger 2026-04-19 01:04:37.582890 | [rockylinux-9] Waiting on logger 2026-04-19 01:04:47.942111 | [rockylinux-9] Waiting on logger 2026-04-19 01:04:58.117784 | [rockylinux-9] Waiting on logger 2026-04-19 01:05:08.294172 | [rockylinux-9] Waiting on logger 2026-04-19 01:05:18.469956 | [rockylinux-9] Waiting on logger 2026-04-19 01:05:27.627024 | [rockylinux-9] Waiting on logger 2026-04-19 01:05:37.797899 | [rockylinux-9] Waiting on logger 2026-04-19 01:05:47.973843 | [rockylinux-9] Waiting on logger 2026-04-19 01:05:58.149900 | [rockylinux-9] Waiting on logger 2026-04-19 01:06:08.490046 | [rockylinux-9] Waiting on logger 2026-04-19 01:06:17.643171 | [rockylinux-9] Waiting on logger 2026-04-19 01:06:27.814148 | [rockylinux-9] Waiting on logger 2026-04-19 01:06:38.025974 | [rockylinux-9] Waiting on logger 2026-04-19 01:06:47.085222 | rockylinux-9 | ok: Runtime: 0:04:26.342723 2026-04-19 01:06:47.092379 | 2026-04-19 01:06:47.092437 | PLAY RECAP 2026-04-19 01:06:47.092482 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-19 01:06:47.092504 | 2026-04-19 01:06:47.195140 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-19 01:06:47.197022 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 01:06:47.743390 | 2026-04-19 01:06:47.743930 | PLAY [all] 2026-04-19 01:06:47.755152 | 2026-04-19 01:06:47.755225 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-19 01:06:47.800414 | rockylinux-9 | skipping: Conditional result was False 2026-04-19 01:06:47.810017 | 2026-04-19 01:06:47.810179 | TASK [fetch-output : Set log path for single node] 2026-04-19 01:06:47.852677 | rockylinux-9 | ok 2026-04-19 01:06:47.860253 | 2026-04-19 01:06:47.860350 | LOOP [fetch-output : Ensure local output dirs] 2026-04-19 01:06:48.290650 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/work/logs" 2026-04-19 01:06:48.501658 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/work/artifacts" 2026-04-19 01:06:48.714621 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/8f262b5f1e3f4fb1b733cbb77b44a645/work/docs" 2026-04-19 01:06:48.730951 | 2026-04-19 01:06:48.731070 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-19 01:06:49.443968 | rockylinux-9 | changed: .d..t...... ./ 2026-04-19 01:06:49.444273 | rockylinux-9 | changed: All items complete 2026-04-19 01:06:49.444316 | 2026-04-19 01:06:49.991214 | rockylinux-9 | changed: .d..t...... ./ 2026-04-19 01:06:50.526276 | rockylinux-9 | changed: .d..t...... ./ 2026-04-19 01:06:50.545000 | 2026-04-19 01:06:50.545074 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-19 01:06:51.004674 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.010186 2026-04-19 01:06:51.351375 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008254 2026-04-19 01:06:51.366913 | 2026-04-19 01:06:51.367025 | PLAY [all] 2026-04-19 01:06:51.372645 | 2026-04-19 01:06:51.372706 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-19 01:06:51.819815 | rockylinux-9 | changed 2026-04-19 01:06:51.828211 | 2026-04-19 01:06:51.828277 | PLAY RECAP 2026-04-19 01:06:51.828322 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-19 01:06:51.828344 | 2026-04-19 01:06:51.920849 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-19 01:06:51.921948 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-19 01:06:52.520556 | 2026-04-19 01:06:52.520714 | PLAY [localhost] 2026-04-19 01:06:52.531267 | 2026-04-19 01:06:52.531357 | TASK [Generate Zuul manifest] 2026-04-19 01:06:52.554395 | localhost | ok 2026-04-19 01:06:52.571760 | 2026-04-19 01:06:52.571850 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-19 01:06:52.879768 | localhost | changed 2026-04-19 01:06:52.892718 | 2026-04-19 01:06:52.892826 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-19 01:06:52.924743 | localhost | ok 2026-04-19 01:06:52.933978 | 2026-04-19 01:06:52.934072 | TASK [Upload logs] 2026-04-19 01:06:52.954057 | localhost | ok 2026-04-19 01:06:53.017524 | 2026-04-19 01:06:53.017699 | TASK [Set zuul-log-path fact] 2026-04-19 01:06:53.038088 | localhost | ok 2026-04-19 01:06:53.051672 | 2026-04-19 01:06:53.051749 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-19 01:06:53.084421 | localhost | ok 2026-04-19 01:06:53.093647 | 2026-04-19 01:06:53.093715 | TASK [upload-logs : Create log directories] 2026-04-19 01:06:53.510539 | localhost | changed 2026-04-19 01:06:53.517717 | 2026-04-19 01:06:53.517842 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-19 01:06:53.930819 | localhost -> localhost | ok: Runtime: 0:00:00.005223 2026-04-19 01:06:53.939354 | 2026-04-19 01:06:53.939495 | TASK [upload-logs : Upload logs to log server] 2026-04-19 01:06:54.410772 | localhost | Output suppressed because no_log was given 2026-04-19 01:06:54.415967 | 2026-04-19 01:06:54.416032 | LOOP [upload-logs : Compress console log and json output] 2026-04-19 01:06:54.465778 | localhost | skipping: Conditional result was False 2026-04-19 01:06:54.473947 | localhost | skipping: Conditional result was False 2026-04-19 01:06:54.491504 | 2026-04-19 01:06:54.491703 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-19 01:06:54.536301 | localhost | skipping: Conditional result was False 2026-04-19 01:06:54.536629 | 2026-04-19 01:06:54.540590 | localhost | skipping: Conditional result was False 2026-04-19 01:06:54.556668 | 2026-04-19 01:06:54.556835 | LOOP [upload-logs : Upload console log and json output]