2026-05-14 09:59:18.946228 | Job console starting 2026-05-14 09:59:18.974050 | Updating git repos 2026-05-14 09:59:19.001114 | Cloning repos into workspace 2026-05-14 09:59:22.211601 | Restoring repo states 2026-05-14 09:59:22.235505 | Merging changes 2026-05-14 09:59:22.989281 | Checking out repos 2026-05-14 09:59:24.839784 | Preparing playbooks 2026-05-14 09:59:33.946666 | Running Ansible setup 2026-05-14 09:59:38.617322 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-14 09:59:39.242253 | 2026-05-14 09:59:39.242386 | PLAY [localhost] 2026-05-14 09:59:39.250191 | 2026-05-14 09:59:39.250311 | TASK [Gathering Facts] 2026-05-14 09:59:40.385202 | localhost | ok 2026-05-14 09:59:40.394520 | 2026-05-14 09:59:40.394701 | TASK [Setup log path fact] 2026-05-14 09:59:40.414466 | localhost | ok 2026-05-14 09:59:40.433215 | 2026-05-14 09:59:40.433361 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 09:59:40.466010 | localhost | ok 2026-05-14 09:59:40.476700 | 2026-05-14 09:59:40.476849 | TASK [emit-job-header : Print job information] 2026-05-14 09:59:40.518672 | # Job Information 2026-05-14 09:59:40.541080 | Ansible Version: 2.16.18 2026-05-14 09:59:40.541224 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-05-14 09:59:40.541263 | Pipeline: check 2026-05-14 09:59:40.541293 | Executor: 2d72f0692154 2026-05-14 09:59:40.541322 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/129 2026-05-14 09:59:40.541353 | Event ID: e1cdee90-4f7a-11f1-8e86-6e434c085c6c 2026-05-14 09:59:40.550090 | 2026-05-14 09:59:40.550244 | LOOP [emit-job-header : Print node information] 2026-05-14 09:59:40.705658 | localhost | ok: 2026-05-14 09:59:40.705891 | localhost | # Node Information 2026-05-14 09:59:40.705932 | localhost | Inventory Hostname: rockylinux-9 2026-05-14 09:59:40.705957 | localhost | Hostname: np0000174164 2026-05-14 09:59:40.705977 | localhost | Username: zuul 2026-05-14 09:59:40.706005 | localhost | Distro: Rocky 9.7 2026-05-14 09:59:40.706027 | localhost | Provider: yul1 2026-05-14 09:59:40.706046 | localhost | Region: ca-ymq-1 2026-05-14 09:59:40.706065 | localhost | Label: rockylinux-9 2026-05-14 09:59:40.706085 | localhost | Product Name: OpenStack Nova 2026-05-14 09:59:40.706103 | localhost | Interface IP: 199.19.213.21 2026-05-14 09:59:40.723191 | 2026-05-14 09:59:40.723342 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-14 09:59:41.293569 | localhost -> localhost | changed 2026-05-14 09:59:41.304256 | 2026-05-14 09:59:41.304360 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-14 09:59:42.457533 | localhost -> localhost | changed 2026-05-14 09:59:42.466950 | 2026-05-14 09:59:42.467042 | PLAY [all] 2026-05-14 09:59:42.475468 | 2026-05-14 09:59:42.475547 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-14 09:59:42.829978 | rockylinux-9 -> localhost | ok 2026-05-14 09:59:42.842102 | 2026-05-14 09:59:42.842282 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-14 09:59:42.880507 | rockylinux-9 | ok 2026-05-14 09:59:42.905374 | rockylinux-9 | included: /var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-14 09:59:42.912013 | 2026-05-14 09:59:42.912094 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-14 09:59:43.913063 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-14 09:59:43.913381 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/work/4c815b543d6b4a43bdd3e59a1504279d_id_rsa 2026-05-14 09:59:43.913515 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/work/4c815b543d6b4a43bdd3e59a1504279d_id_rsa.pub 2026-05-14 09:59:43.913550 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-14 09:59:43.913581 | rockylinux-9 -> localhost | SHA256:ybz2zzq4Y5aeX/utcMmorEY6W8BU6cLM6+nEBTFpRic zuul-build-sshkey 2026-05-14 09:59:43.913630 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-14 09:59:43.913660 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-14 09:59:43.913695 | rockylinux-9 -> localhost | | .E.o. | 2026-05-14 09:59:43.913726 | rockylinux-9 -> localhost | | +*. | 2026-05-14 09:59:43.913754 | rockylinux-9 -> localhost | | *o. | 2026-05-14 09:59:43.913783 | rockylinux-9 -> localhost | | o=+.. | 2026-05-14 09:59:43.913811 | rockylinux-9 -> localhost | | ooS | 2026-05-14 09:59:43.913839 | rockylinux-9 -> localhost | | ..o.. o . | 2026-05-14 09:59:43.913866 | rockylinux-9 -> localhost | | .o++o + + | 2026-05-14 09:59:43.913893 | rockylinux-9 -> localhost | | .=oB+.+ + . | 2026-05-14 09:59:43.913924 | rockylinux-9 -> localhost | | .oB==*++.o..| 2026-05-14 09:59:43.913953 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-14 09:59:43.914107 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.306559 2026-05-14 09:59:43.922236 | 2026-05-14 09:59:43.922373 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-14 09:59:43.954722 | rockylinux-9 | ok 2026-05-14 09:59:43.963546 | rockylinux-9 | included: /var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-14 09:59:43.971203 | 2026-05-14 09:59:43.971276 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-14 09:59:43.996579 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 09:59:44.020054 | 2026-05-14 09:59:44.020185 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-14 09:59:44.596870 | rockylinux-9 | changed 2026-05-14 09:59:44.613172 | 2026-05-14 09:59:44.613325 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-14 09:59:44.896874 | rockylinux-9 | ok 2026-05-14 09:59:44.901964 | 2026-05-14 09:59:44.902295 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-14 09:59:45.868057 | rockylinux-9 | changed 2026-05-14 09:59:45.872893 | 2026-05-14 09:59:45.873005 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-14 09:59:46.844505 | rockylinux-9 | changed 2026-05-14 09:59:46.853044 | 2026-05-14 09:59:46.853224 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-14 09:59:46.878936 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 09:59:46.888400 | 2026-05-14 09:59:46.888543 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-14 09:59:47.361503 | rockylinux-9 -> localhost | changed 2026-05-14 09:59:47.373266 | 2026-05-14 09:59:47.373371 | TASK [add-build-sshkey : Add back temp key] 2026-05-14 09:59:47.976267 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/work/4c815b543d6b4a43bdd3e59a1504279d_id_rsa (zuul-build-sshkey) 2026-05-14 09:59:47.976608 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011005 2026-05-14 09:59:47.998189 | 2026-05-14 09:59:47.999693 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-14 09:59:48.468164 | rockylinux-9 | ok 2026-05-14 09:59:48.481744 | 2026-05-14 09:59:48.481987 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-14 09:59:48.521343 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 09:59:48.532413 | 2026-05-14 09:59:48.532522 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-14 09:59:48.980245 | rockylinux-9 | ok 2026-05-14 09:59:48.988053 | 2026-05-14 09:59:48.988220 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-14 09:59:51.249315 | rockylinux-9 | Output suppressed because no_log was given 2026-05-14 09:59:51.329436 | 2026-05-14 09:59:51.329586 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-14 09:59:51.581074 | rockylinux-9 | ok: "logs" 2026-05-14 09:59:51.581329 | rockylinux-9 | ok: All items complete 2026-05-14 09:59:51.581358 | 2026-05-14 09:59:51.800510 | rockylinux-9 | ok: "artifacts" 2026-05-14 09:59:52.022426 | rockylinux-9 | ok: "docs" 2026-05-14 09:59:52.038683 | 2026-05-14 09:59:52.038815 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-14 09:59:52.341244 | rockylinux-9 | changed: "logs" 2026-05-14 09:59:52.562421 | rockylinux-9 | changed: "artifacts" 2026-05-14 09:59:52.829573 | rockylinux-9 | changed: "docs" 2026-05-14 09:59:52.895724 | 2026-05-14 09:59:52.895873 | PLAY RECAP 2026-05-14 09:59:52.895933 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 09:59:52.895977 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-14 09:59:52.896009 | 2026-05-14 09:59:53.136875 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-14 09:59:53.138239 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-14 09:59:54.292067 | 2026-05-14 09:59:54.292235 | PLAY [all] 2026-05-14 09:59:54.330704 | 2026-05-14 09:59:54.330841 | TASK [setup-uv : Extract archive] 2026-05-14 09:59:57.348754 | rockylinux-9 | changed 2026-05-14 09:59:57.355406 | 2026-05-14 09:59:57.355547 | TASK [setup-uv : Print version] 2026-05-14 09:59:57.382158 | [rockylinux-9] Waiting on logger 2026-05-14 09:59:59.017936 | rockylinux-9 | ok: Runtime: 0:00:00.009993 2026-05-14 09:59:59.031454 | 2026-05-14 09:59:59.031585 | PLAY RECAP 2026-05-14 09:59:59.031639 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 09:59:59.031664 | 2026-05-14 09:59:59.203316 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-14 09:59:59.205561 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-14 09:59:59.918790 | 2026-05-14 09:59:59.919042 | PLAY [all] 2026-05-14 09:59:59.933821 | 2026-05-14 09:59:59.933923 | TASK [Copy inventory file for Zuul] 2026-05-14 10:00:01.284057 | rockylinux-9 | changed 2026-05-14 10:00:01.290018 | 2026-05-14 10:00:01.290446 | TASK [Switch "ansible_host" to private IP] 2026-05-14 10:00:01.737162 | rockylinux-9 | changed: 1 replacements made 2026-05-14 10:00:01.912911 | 2026-05-14 10:00:01.913070 | TASK [Run Molecule scenario] 2026-05-14 10:00:01.960715 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:10.091778 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:20.265955 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:29.422723 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:39.621937 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:50.022441 | [rockylinux-9] Waiting on logger 2026-05-14 10:00:59.694452 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:09.419273 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:19.590136 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:29.765890 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:39.945886 | [rockylinux-9] Waiting on logger 2026-05-14 10:01:50.117841 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:00.294279 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:09.451469 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:19.622046 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:29.797900 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:39.974188 | [rockylinux-9] Waiting on logger 2026-05-14 10:02:50.150028 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:00.326120 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:09.483685 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:19.653982 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:29.930058 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:40.102086 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:50.282120 | [rockylinux-9] Waiting on logger 2026-05-14 10:03:59.442968 | [rockylinux-9] Waiting on logger 2026-05-14 10:04:09.606157 | [rockylinux-9] Waiting on logger 2026-05-14 10:04:19.849428 | [rockylinux-9] Waiting on logger 2026-05-14 10:04:30.022197 | [rockylinux-9] Waiting on logger 2026-05-14 10:04:40.198050 | [rockylinux-9] Waiting on logger 2026-05-14 10:04:50.374188 | [rockylinux-9] Waiting on logger 2026-05-14 10:04:59.525922 | [rockylinux-9] Waiting on logger 2026-05-14 10:05:01.771171 | rockylinux-9 | ok: Runtime: 0:04:58.229145 2026-05-14 10:05:01.778758 | 2026-05-14 10:05:01.778822 | PLAY RECAP 2026-05-14 10:05:01.778870 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-14 10:05:01.778910 | 2026-05-14 10:05:01.934366 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-14 10:05:01.935643 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 10:05:02.613781 | 2026-05-14 10:05:02.613906 | PLAY [all] 2026-05-14 10:05:02.624765 | 2026-05-14 10:05:02.624846 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-14 10:05:02.670497 | rockylinux-9 | skipping: Conditional result was False 2026-05-14 10:05:02.679117 | 2026-05-14 10:05:02.679192 | TASK [fetch-output : Set log path for single node] 2026-05-14 10:05:02.720486 | rockylinux-9 | ok 2026-05-14 10:05:02.726296 | 2026-05-14 10:05:02.726389 | LOOP [fetch-output : Ensure local output dirs] 2026-05-14 10:05:03.174368 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/work/logs" 2026-05-14 10:05:03.383128 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/work/artifacts" 2026-05-14 10:05:03.588873 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/4c815b543d6b4a43bdd3e59a1504279d/work/docs" 2026-05-14 10:05:03.602550 | 2026-05-14 10:05:03.602680 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-14 10:05:04.315275 | rockylinux-9 | changed: .d..t...... ./ 2026-05-14 10:05:04.315492 | rockylinux-9 | changed: All items complete 2026-05-14 10:05:04.315522 | 2026-05-14 10:05:04.817317 | rockylinux-9 | changed: .d..t...... ./ 2026-05-14 10:05:05.333054 | rockylinux-9 | changed: .d..t...... ./ 2026-05-14 10:05:05.351171 | 2026-05-14 10:05:05.351354 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-14 10:05:05.756898 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007203 2026-05-14 10:05:06.004135 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007200 2026-05-14 10:05:06.027178 | 2026-05-14 10:05:06.027379 | PLAY [all] 2026-05-14 10:05:06.032933 | 2026-05-14 10:05:06.033056 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-14 10:05:06.506277 | rockylinux-9 | changed 2026-05-14 10:05:06.512364 | 2026-05-14 10:05:06.512416 | PLAY RECAP 2026-05-14 10:05:06.512464 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-14 10:05:06.512487 | 2026-05-14 10:05:06.679542 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-14 10:05:06.681439 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-14 10:05:07.358036 | 2026-05-14 10:05:07.358167 | PLAY [localhost] 2026-05-14 10:05:07.368807 | 2026-05-14 10:05:07.368896 | TASK [Generate Zuul manifest] 2026-05-14 10:05:07.390324 | localhost | ok 2026-05-14 10:05:07.408272 | 2026-05-14 10:05:07.408393 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-14 10:05:07.801719 | localhost | changed 2026-05-14 10:05:07.813074 | 2026-05-14 10:05:07.813255 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-14 10:05:07.844493 | localhost | ok 2026-05-14 10:05:07.856169 | 2026-05-14 10:05:07.856378 | TASK [Upload logs] 2026-05-14 10:05:07.879468 | localhost | ok 2026-05-14 10:05:07.944429 | 2026-05-14 10:05:07.944582 | TASK [Set zuul-log-path fact] 2026-05-14 10:05:07.966092 | localhost | ok 2026-05-14 10:05:07.981628 | 2026-05-14 10:05:07.981754 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-14 10:05:08.014673 | localhost | ok 2026-05-14 10:05:08.022466 | 2026-05-14 10:05:08.022547 | TASK [upload-logs : Create log directories] 2026-05-14 10:05:08.446214 | localhost | changed 2026-05-14 10:05:08.453372 | 2026-05-14 10:05:08.453477 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-14 10:05:08.877682 | localhost -> localhost | ok: Runtime: 0:00:00.005406 2026-05-14 10:05:08.884219 | 2026-05-14 10:05:08.884299 | TASK [upload-logs : Upload logs to log server] 2026-05-14 10:05:09.341024 | localhost | Output suppressed because no_log was given 2026-05-14 10:05:09.346047 | 2026-05-14 10:05:09.346140 | LOOP [upload-logs : Compress console log and json output] 2026-05-14 10:05:09.388889 | localhost | skipping: Conditional result was False 2026-05-14 10:05:09.396546 | localhost | skipping: Conditional result was False 2026-05-14 10:05:09.407795 | 2026-05-14 10:05:09.407978 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-14 10:05:09.450223 | localhost | skipping: Conditional result was False 2026-05-14 10:05:09.450630 | 2026-05-14 10:05:09.454017 | localhost | skipping: Conditional result was False 2026-05-14 10:05:09.469882 | 2026-05-14 10:05:09.470035 | LOOP [upload-logs : Upload console log and json output]