2026-04-18 16:35:24.538702 | Job console starting 2026-04-18 16:35:24.553013 | Updating git repos 2026-04-18 16:35:24.569991 | Cloning repos into workspace 2026-04-18 16:35:24.662250 | Restoring repo states 2026-04-18 16:35:24.669212 | Merging changes 2026-04-18 16:35:25.077050 | Checking out repos 2026-04-18 16:35:25.129523 | Preparing playbooks 2026-04-18 16:35:26.799190 | Running Ansible setup 2026-04-18 16:35:33.218641 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:35:33.860416 | 2026-04-18 16:35:33.860539 | PLAY [localhost] 2026-04-18 16:35:33.874559 | 2026-04-18 16:35:33.874802 | TASK [Gathering Facts] 2026-04-18 16:35:38.518415 | localhost | ok 2026-04-18 16:35:38.527465 | 2026-04-18 16:35:38.527774 | TASK [Setup log path fact] 2026-04-18 16:35:38.559466 | localhost | ok 2026-04-18 16:35:38.573240 | 2026-04-18 16:35:38.573389 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:35:38.614819 | localhost | ok 2026-04-18 16:35:38.626011 | 2026-04-18 16:35:38.626164 | TASK [emit-job-header : Print job information] 2026-04-18 16:35:38.671499 | # Job Information 2026-04-18 16:35:38.671741 | Ansible Version: 2.16.16 2026-04-18 16:35:38.672116 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-04-18 16:35:38.672149 | Pipeline: check 2026-04-18 16:35:38.672171 | Executor: 0a8996d2b663 2026-04-18 16:35:38.672190 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/107 2026-04-18 16:35:38.672212 | Event ID: 3989c0a0-3b44-11f1-8b72-089614b0c768 2026-04-18 16:35:38.678523 | 2026-04-18 16:35:38.678759 | LOOP [emit-job-header : Print node information] 2026-04-18 16:35:38.772619 | localhost | ok: 2026-04-18 16:35:38.772792 | localhost | # Node Information 2026-04-18 16:35:38.772828 | localhost | Inventory Hostname: rockylinux-9 2026-04-18 16:35:38.772857 | localhost | Hostname: np0000167356 2026-04-18 16:35:38.772883 | localhost | Username: zuul 2026-04-18 16:35:38.772940 | localhost | Distro: Rocky 9.7 2026-04-18 16:35:38.772965 | localhost | Provider: yul1 2026-04-18 16:35:38.773007 | localhost | Region: ca-ymq-1 2026-04-18 16:35:38.773029 | localhost | Label: rockylinux-9 2026-04-18 16:35:38.773048 | localhost | Product Name: OpenStack Nova 2026-04-18 16:35:38.773067 | localhost | Interface IP: 199.19.213.146 2026-04-18 16:35:38.783998 | 2026-04-18 16:35:38.784113 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-04-18 16:35:39.212706 | localhost -> localhost | changed 2026-04-18 16:35:39.219356 | 2026-04-18 16:35:39.219448 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-04-18 16:35:40.691427 | localhost -> localhost | changed 2026-04-18 16:35:40.697857 | 2026-04-18 16:35:40.697919 | PLAY [all] 2026-04-18 16:35:40.705646 | 2026-04-18 16:35:40.705720 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-04-18 16:35:40.924755 | rockylinux-9 -> localhost | ok 2026-04-18 16:35:40.934314 | 2026-04-18 16:35:40.934408 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-04-18 16:35:40.959073 | rockylinux-9 | ok 2026-04-18 16:35:40.974686 | rockylinux-9 | included: /var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-04-18 16:35:40.980231 | 2026-04-18 16:35:40.980317 | TASK [add-build-sshkey : Create Temp SSH key] 2026-04-18 16:35:42.181566 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-04-18 16:35:42.181768 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/work/6f8d0f4c7a2d4b37a19ba25e6a7912ac_id_rsa 2026-04-18 16:35:42.181801 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/work/6f8d0f4c7a2d4b37a19ba25e6a7912ac_id_rsa.pub 2026-04-18 16:35:42.181824 | rockylinux-9 -> localhost | The key fingerprint is: 2026-04-18 16:35:42.181845 | rockylinux-9 -> localhost | SHA256:J7gGj5FDPDtWjMDW/MzR1q6mBZiwPi0J7BJd/a+nrT4 zuul-build-sshkey 2026-04-18 16:35:42.181878 | rockylinux-9 -> localhost | The key's randomart image is: 2026-04-18 16:35:42.181899 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-04-18 16:35:42.181924 | rockylinux-9 -> localhost | | ..o . . . | 2026-04-18 16:35:42.181945 | rockylinux-9 -> localhost | | +o+oo o . | 2026-04-18 16:35:42.181966 | rockylinux-9 -> localhost | |.o +=*o+ . | 2026-04-18 16:35:42.181986 | rockylinux-9 -> localhost | |o.o.o==.. . | 2026-04-18 16:35:42.182006 | rockylinux-9 -> localhost | |.+ oO ..So. | 2026-04-18 16:35:42.182025 | rockylinux-9 -> localhost | |..=..B .+o. | 2026-04-18 16:35:42.182044 | rockylinux-9 -> localhost | |. o. ++ . | 2026-04-18 16:35:42.182063 | rockylinux-9 -> localhost | | .. E.. | 2026-04-18 16:35:42.182086 | rockylinux-9 -> localhost | | .+=. | 2026-04-18 16:35:42.182106 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-04-18 16:35:42.182165 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.692866 2026-04-18 16:35:42.187676 | 2026-04-18 16:35:42.187764 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-04-18 16:35:42.230201 | rockylinux-9 | ok 2026-04-18 16:35:42.239594 | rockylinux-9 | included: /var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-04-18 16:35:42.247586 | 2026-04-18 16:35:42.247659 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-04-18 16:35:42.272188 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:42.284953 | 2026-04-18 16:35:42.285441 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-04-18 16:35:42.810175 | rockylinux-9 | changed 2026-04-18 16:35:43.446779 | 2026-04-18 16:35:43.447035 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-04-18 16:35:43.705235 | rockylinux-9 | ok 2026-04-18 16:35:43.935991 | 2026-04-18 16:35:43.936136 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-04-18 16:35:44.776626 | rockylinux-9 | changed 2026-04-18 16:35:44.782457 | 2026-04-18 16:35:44.782562 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-04-18 16:35:45.630371 | rockylinux-9 | changed 2026-04-18 16:35:45.635075 | 2026-04-18 16:35:45.635141 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-04-18 16:35:45.658324 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:45.663920 | 2026-04-18 16:35:45.664002 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-04-18 16:35:46.035660 | rockylinux-9 -> localhost | changed 2026-04-18 16:35:46.046790 | 2026-04-18 16:35:46.046926 | TASK [add-build-sshkey : Add back temp key] 2026-04-18 16:35:46.353491 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/work/6f8d0f4c7a2d4b37a19ba25e6a7912ac_id_rsa (zuul-build-sshkey) 2026-04-18 16:35:46.353712 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013338 2026-04-18 16:35:46.361355 | 2026-04-18 16:35:46.361449 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-04-18 16:35:46.784281 | rockylinux-9 | ok 2026-04-18 16:35:46.793983 | 2026-04-18 16:35:46.794054 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-04-18 16:35:46.822971 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:35:46.836110 | 2026-04-18 16:35:46.836264 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-04-18 16:35:47.255860 | rockylinux-9 | ok 2026-04-18 16:35:47.263010 | 2026-04-18 16:35:47.263071 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-04-18 16:35:48.165382 | rockylinux-9 | Output suppressed because no_log was given 2026-04-18 16:35:48.663727 | 2026-04-18 16:35:48.663857 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-04-18 16:35:48.906394 | rockylinux-9 | ok: "logs" 2026-04-18 16:35:48.906806 | rockylinux-9 | ok: All items complete 2026-04-18 16:35:48.906845 | 2026-04-18 16:35:49.121420 | rockylinux-9 | ok: "artifacts" 2026-04-18 16:35:49.347693 | rockylinux-9 | ok: "docs" 2026-04-18 16:35:49.357633 | 2026-04-18 16:35:49.357774 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-04-18 16:35:49.617445 | rockylinux-9 | changed: "logs" 2026-04-18 16:35:50.164488 | rockylinux-9 | changed: "artifacts" 2026-04-18 16:35:50.170668 | rockylinux-9 | changed: "docs" 2026-04-18 16:35:50.186244 | 2026-04-18 16:35:50.186709 | PLAY RECAP 2026-04-18 16:35:50.186803 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:35:50.186851 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-04-18 16:35:50.186887 | 2026-04-18 16:35:50.303944 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-04-18 16:35:50.306220 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:35:50.872086 | 2026-04-18 16:35:50.872196 | PLAY [all] 2026-04-18 16:35:50.883135 | 2026-04-18 16:35:50.883214 | TASK [setup-uv : Extract archive] 2026-04-18 16:35:53.499175 | rockylinux-9 | changed 2026-04-18 16:35:53.950642 | 2026-04-18 16:35:53.950760 | TASK [setup-uv : Print version] 2026-04-18 16:35:53.988044 | [rockylinux-9] Waiting on logger 2026-04-18 16:35:54.501865 | rockylinux-9 | ok: Runtime: 0:00:00.013455 2026-04-18 16:35:54.508854 | 2026-04-18 16:35:54.508917 | PLAY RECAP 2026-04-18 16:35:54.509061 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:35:54.509099 | 2026-04-18 16:35:54.602646 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-04-18 16:35:54.604683 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:35:55.174353 | 2026-04-18 16:35:55.174492 | PLAY [all] 2026-04-18 16:35:55.185279 | 2026-04-18 16:35:55.185380 | TASK [Copy inventory file for Zuul] 2026-04-18 16:35:56.464755 | rockylinux-9 | changed 2026-04-18 16:35:56.472609 | 2026-04-18 16:35:56.472736 | TASK [Switch "ansible_host" to private IP] 2026-04-18 16:35:56.833494 | rockylinux-9 | changed: 1 replacements made 2026-04-18 16:35:57.202050 | 2026-04-18 16:35:57.202208 | TASK [Run Molecule scenario] 2026-04-18 16:35:57.225645 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:05.353951 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:15.530030 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:25.702115 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:35.877916 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:45.035367 | [rockylinux-9] Waiting on logger 2026-04-18 16:36:55.466001 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:05.641951 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:15.814050 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:25.994140 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:35.142031 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:45.322086 | [rockylinux-9] Waiting on logger 2026-04-18 16:37:55.494174 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:05.740421 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:15.909880 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:25.125933 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:35.302137 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:45.478076 | [rockylinux-9] Waiting on logger 2026-04-18 16:38:55.654011 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:05.834001 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:14.991276 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:25.157993 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:35.370511 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:45.545947 | [rockylinux-9] Waiting on logger 2026-04-18 16:39:55.717922 | [rockylinux-9] Waiting on logger 2026-04-18 16:40:05.893942 | [rockylinux-9] Waiting on logger 2026-04-18 16:40:15.051618 | [rockylinux-9] Waiting on logger 2026-04-18 16:40:20.657737 | rockylinux-9 | ok: Runtime: 0:04:22.287399 2026-04-18 16:40:20.664830 | 2026-04-18 16:40:20.664881 | PLAY RECAP 2026-04-18 16:40:20.664923 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-04-18 16:40:20.664944 | 2026-04-18 16:40:20.786946 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-04-18 16:40:20.789880 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:40:21.333472 | 2026-04-18 16:40:21.333584 | PLAY [all] 2026-04-18 16:40:21.343796 | 2026-04-18 16:40:21.343872 | TASK [fetch-output : Set log path for multiple nodes] 2026-04-18 16:40:21.377822 | rockylinux-9 | skipping: Conditional result was False 2026-04-18 16:40:21.389720 | 2026-04-18 16:40:21.389846 | TASK [fetch-output : Set log path for single node] 2026-04-18 16:40:21.424077 | rockylinux-9 | ok 2026-04-18 16:40:21.430687 | 2026-04-18 16:40:21.430760 | LOOP [fetch-output : Ensure local output dirs] 2026-04-18 16:40:21.788755 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/work/logs" 2026-04-18 16:40:22.014490 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/work/artifacts" 2026-04-18 16:40:22.228826 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/6f8d0f4c7a2d4b37a19ba25e6a7912ac/work/docs" 2026-04-18 16:40:22.247229 | 2026-04-18 16:40:22.247378 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-04-18 16:40:22.917113 | rockylinux-9 | ok 2026-04-18 16:40:23.065263 | rockylinux-9 | changed: All items complete 2026-04-18 16:40:23.065438 | 2026-04-18 16:40:23.403302 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:40:23.912792 | rockylinux-9 | changed: .d..t...... ./ 2026-04-18 16:40:23.930838 | 2026-04-18 16:40:23.930930 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-04-18 16:40:24.343236 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007418 2026-04-18 16:40:24.590422 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006883 2026-04-18 16:40:24.608941 | 2026-04-18 16:40:24.609056 | PLAY [all] 2026-04-18 16:40:24.614642 | 2026-04-18 16:40:24.614707 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-04-18 16:40:25.060633 | rockylinux-9 | changed 2026-04-18 16:40:25.069298 | 2026-04-18 16:40:25.069353 | PLAY RECAP 2026-04-18 16:40:25.069399 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-04-18 16:40:25.069422 | 2026-04-18 16:40:25.167933 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-04-18 16:40:25.170251 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-04-18 16:40:25.731458 | 2026-04-18 16:40:25.731573 | PLAY [localhost] 2026-04-18 16:40:25.741175 | 2026-04-18 16:40:25.741246 | TASK [Generate Zuul manifest] 2026-04-18 16:40:25.758967 | localhost | ok 2026-04-18 16:40:25.776660 | 2026-04-18 16:40:25.776740 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-04-18 16:40:26.081541 | localhost | changed 2026-04-18 16:40:26.096439 | 2026-04-18 16:40:26.096550 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-04-18 16:40:26.128316 | localhost | ok 2026-04-18 16:40:26.138850 | 2026-04-18 16:40:26.138925 | TASK [Upload logs] 2026-04-18 16:40:26.157648 | localhost | ok 2026-04-18 16:40:26.229908 | 2026-04-18 16:40:26.230049 | TASK [Set zuul-log-path fact] 2026-04-18 16:40:26.248127 | localhost | ok 2026-04-18 16:40:26.265200 | 2026-04-18 16:40:26.265268 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-04-18 16:40:26.295711 | localhost | ok 2026-04-18 16:40:26.304787 | 2026-04-18 16:40:26.304848 | TASK [upload-logs : Create log directories] 2026-04-18 16:40:26.686992 | localhost | changed 2026-04-18 16:40:26.691409 | 2026-04-18 16:40:26.691474 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-04-18 16:40:27.081973 | localhost -> localhost | ok: Runtime: 0:00:00.005880 2026-04-18 16:40:27.086633 | 2026-04-18 16:40:27.086695 | TASK [upload-logs : Upload logs to log server] 2026-04-18 16:40:27.549470 | localhost | Output suppressed because no_log was given 2026-04-18 16:40:27.554140 | 2026-04-18 16:40:27.554227 | LOOP [upload-logs : Compress console log and json output] 2026-04-18 16:40:27.603239 | localhost | skipping: Conditional result was False 2026-04-18 16:40:27.610741 | localhost | skipping: Conditional result was False 2026-04-18 16:40:27.626214 | 2026-04-18 16:40:27.626337 | LOOP [upload-logs : Upload compressed console log and json output] 2026-04-18 16:40:27.674190 | localhost | skipping: Conditional result was False 2026-04-18 16:40:27.674600 | 2026-04-18 16:40:27.678403 | localhost | skipping: Conditional result was False 2026-04-18 16:40:27.690669 | 2026-04-18 16:40:27.690841 | LOOP [upload-logs : Upload console log and json output]