2026-05-21 02:32:23.683388 | Job console starting 2026-05-21 02:32:23.700382 | Updating git repos 2026-05-21 02:32:23.741755 | Cloning repos into workspace 2026-05-21 02:32:23.799794 | Restoring repo states 2026-05-21 02:32:23.807859 | Merging changes 2026-05-21 02:32:24.059574 | Checking out repos 2026-05-21 02:32:24.093111 | Preparing playbooks 2026-05-21 02:32:25.137966 | Running Ansible setup 2026-05-21 02:32:28.795852 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 02:32:29.496752 | 2026-05-21 02:32:29.496912 | PLAY [localhost] 2026-05-21 02:32:29.504659 | 2026-05-21 02:32:29.504728 | TASK [Gathering Facts] 2026-05-21 02:32:30.385509 | localhost | ok 2026-05-21 02:32:30.396920 | 2026-05-21 02:32:30.397017 | TASK [Setup log path fact] 2026-05-21 02:32:30.416687 | localhost | ok 2026-05-21 02:32:30.429575 | 2026-05-21 02:32:30.429653 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 02:32:30.460664 | localhost | ok 2026-05-21 02:32:30.468620 | 2026-05-21 02:32:30.468685 | TASK [emit-job-header : Print job information] 2026-05-21 02:32:30.508484 | # Job Information 2026-05-21 02:32:30.508717 | Ansible Version: 2.16.18 2026-05-21 02:32:30.508785 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-05-21 02:32:30.508824 | Pipeline: check 2026-05-21 02:32:30.508862 | Executor: 2d72f0692154 2026-05-21 02:32:30.508893 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/124 2026-05-21 02:32:30.508931 | Event ID: f64d4540-54bc-11f1-8f3a-f73e0c2800c8 2026-05-21 02:32:30.513932 | 2026-05-21 02:32:30.514027 | LOOP [emit-job-header : Print node information] 2026-05-21 02:32:30.639195 | localhost | ok: 2026-05-21 02:32:30.639458 | localhost | # Node Information 2026-05-21 02:32:30.639501 | localhost | Inventory Hostname: rockylinux-9 2026-05-21 02:32:30.639532 | localhost | Hostname: np0000175680 2026-05-21 02:32:30.639560 | localhost | Username: zuul 2026-05-21 02:32:30.639590 | localhost | Distro: Rocky 9.7 2026-05-21 02:32:30.639617 | localhost | Provider: yul1 2026-05-21 02:32:30.639644 | localhost | Region: ca-ymq-1 2026-05-21 02:32:30.639669 | localhost | Label: rockylinux-9 2026-05-21 02:32:30.639694 | localhost | Product Name: OpenStack Nova 2026-05-21 02:32:30.639719 | localhost | Interface IP: 199.19.213.138 2026-05-21 02:32:30.656648 | 2026-05-21 02:32:30.656742 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-05-21 02:32:31.102576 | localhost -> localhost | changed 2026-05-21 02:32:31.111176 | 2026-05-21 02:32:31.111247 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-05-21 02:32:32.169471 | localhost -> localhost | changed 2026-05-21 02:32:32.176630 | 2026-05-21 02:32:32.176692 | PLAY [all] 2026-05-21 02:32:32.184688 | 2026-05-21 02:32:32.184777 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-05-21 02:32:32.443039 | rockylinux-9 -> localhost | ok 2026-05-21 02:32:32.453046 | 2026-05-21 02:32:32.453161 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-05-21 02:32:32.489248 | rockylinux-9 | ok 2026-05-21 02:32:32.520122 | rockylinux-9 | included: /var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-05-21 02:32:32.526647 | 2026-05-21 02:32:32.526709 | TASK [add-build-sshkey : Create Temp SSH key] 2026-05-21 02:32:33.998693 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-05-21 02:32:33.998842 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/work/e60a0702eb9b4d2ba466222dfbcacb54_id_rsa 2026-05-21 02:32:33.998871 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/work/e60a0702eb9b4d2ba466222dfbcacb54_id_rsa.pub 2026-05-21 02:32:33.998895 | rockylinux-9 -> localhost | The key fingerprint is: 2026-05-21 02:32:33.998916 | rockylinux-9 -> localhost | SHA256:W9STP/+jlCw7XmFV0CRSvIZk+TNPfUT5vduZzNyyCRk zuul-build-sshkey 2026-05-21 02:32:33.998950 | rockylinux-9 -> localhost | The key's randomart image is: 2026-05-21 02:32:33.998971 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-05-21 02:32:33.998993 | rockylinux-9 -> localhost | | .++==| 2026-05-21 02:32:33.999014 | rockylinux-9 -> localhost | | .+o.o+| 2026-05-21 02:32:33.999035 | rockylinux-9 -> localhost | | .o+o ++| 2026-05-21 02:32:33.999055 | rockylinux-9 -> localhost | | . .oB *| 2026-05-21 02:32:33.999075 | rockylinux-9 -> localhost | | S . E+o=o| 2026-05-21 02:32:33.999102 | rockylinux-9 -> localhost | | o ooo+.| 2026-05-21 02:32:33.999131 | rockylinux-9 -> localhost | | . .o=+ B| 2026-05-21 02:32:33.999159 | rockylinux-9 -> localhost | | .=..X+| 2026-05-21 02:32:33.999186 | rockylinux-9 -> localhost | | .o..+oo| 2026-05-21 02:32:33.999211 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-05-21 02:32:33.999271 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.972319 2026-05-21 02:32:34.005953 | 2026-05-21 02:32:34.006038 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-05-21 02:32:34.036462 | rockylinux-9 | ok 2026-05-21 02:32:34.047282 | rockylinux-9 | included: /var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-05-21 02:32:34.057750 | 2026-05-21 02:32:34.057892 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-05-21 02:32:34.083580 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:32:34.089427 | 2026-05-21 02:32:34.089506 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-05-21 02:32:35.325942 | rockylinux-9 | changed 2026-05-21 02:32:35.330660 | 2026-05-21 02:32:35.330740 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-05-21 02:32:35.579576 | rockylinux-9 | ok 2026-05-21 02:32:35.588451 | 2026-05-21 02:32:35.588607 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-05-21 02:32:36.456274 | rockylinux-9 | changed 2026-05-21 02:32:36.461003 | 2026-05-21 02:32:36.461082 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-05-21 02:32:37.388930 | rockylinux-9 | changed 2026-05-21 02:32:37.394469 | 2026-05-21 02:32:37.394578 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-05-21 02:32:37.421522 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:32:37.427738 | 2026-05-21 02:32:37.427821 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-05-21 02:32:37.850425 | rockylinux-9 -> localhost | changed 2026-05-21 02:32:37.860118 | 2026-05-21 02:32:37.860206 | TASK [add-build-sshkey : Add back temp key] 2026-05-21 02:32:38.150587 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/work/e60a0702eb9b4d2ba466222dfbcacb54_id_rsa (zuul-build-sshkey) 2026-05-21 02:32:38.151097 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.012421 2026-05-21 02:32:38.155654 | 2026-05-21 02:32:38.155719 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-05-21 02:32:38.562108 | rockylinux-9 | ok 2026-05-21 02:32:38.566393 | 2026-05-21 02:32:38.566463 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-05-21 02:32:38.591244 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:32:38.600648 | 2026-05-21 02:32:38.600790 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-05-21 02:32:39.040859 | rockylinux-9 | ok 2026-05-21 02:32:40.549219 | 2026-05-21 02:32:40.549324 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-05-21 02:32:42.388047 | rockylinux-9 | Output suppressed because no_log was given 2026-05-21 02:32:42.398069 | 2026-05-21 02:32:42.398195 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-05-21 02:32:42.678290 | rockylinux-9 | ok: "logs" 2026-05-21 02:32:42.678612 | rockylinux-9 | ok: All items complete 2026-05-21 02:32:42.678645 | 2026-05-21 02:32:42.870389 | rockylinux-9 | ok: "artifacts" 2026-05-21 02:32:43.106867 | rockylinux-9 | ok: "docs" 2026-05-21 02:32:43.125100 | 2026-05-21 02:32:43.125255 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-05-21 02:32:43.421533 | rockylinux-9 | changed: "logs" 2026-05-21 02:32:43.722569 | rockylinux-9 | changed: "artifacts" 2026-05-21 02:32:43.925798 | rockylinux-9 | changed: "docs" 2026-05-21 02:32:44.934314 | 2026-05-21 02:32:44.934397 | PLAY RECAP 2026-05-21 02:32:44.934439 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:32:44.934468 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-05-21 02:32:44.934488 | 2026-05-21 02:32:45.113621 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-05-21 02:32:45.116096 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 02:32:45.743691 | 2026-05-21 02:32:45.856454 | PLAY [all] 2026-05-21 02:32:45.878347 | 2026-05-21 02:32:45.878503 | TASK [setup-uv : Extract archive] 2026-05-21 02:32:48.673922 | rockylinux-9 | changed 2026-05-21 02:32:48.943139 | 2026-05-21 02:32:48.943299 | TASK [setup-uv : Print version] 2026-05-21 02:32:48.966383 | [rockylinux-9] Waiting on logger 2026-05-21 02:32:49.479053 | rockylinux-9 | ok: Runtime: 0:00:00.013812 2026-05-21 02:32:49.490456 | 2026-05-21 02:32:49.490547 | TASK [Install Ansible collection siblings] 2026-05-21 02:32:49.960769 | rockylinux-9 | ok: 2026-05-21 02:32:49.961111 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-05-21 02:32:49.961157 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-05-21 02:32:49.963495 | 2026-05-21 02:32:49.963710 | PLAY RECAP 2026-05-21 02:32:49.963903 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:32:49.963943 | 2026-05-21 02:32:50.145905 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-05-21 02:32:50.148714 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 02:32:50.822244 | 2026-05-21 02:32:50.976188 | PLAY [all] 2026-05-21 02:32:50.997334 | 2026-05-21 02:32:50.997489 | TASK [Copy inventory file for Zuul] 2026-05-21 02:32:52.354533 | rockylinux-9 | changed 2026-05-21 02:32:52.571363 | 2026-05-21 02:32:52.571717 | TASK [Switch "ansible_host" to private IP] 2026-05-21 02:32:52.995043 | rockylinux-9 | changed: 1 replacements made 2026-05-21 02:32:53.000131 | 2026-05-21 02:32:53.000200 | TASK [Run Molecule scenario] 2026-05-21 02:32:53.022205 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:00.166047 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:10.341851 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:20.522070 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:30.697999 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:40.869742 | [rockylinux-9] Waiting on logger 2026-05-21 02:33:50.598154 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:00.774402 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:10.950000 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:20.102013 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:30.277929 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:40.454029 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:50.630705 | [rockylinux-9] Waiting on logger 2026-05-21 02:34:54.092274 | rockylinux-9 | ok: Runtime: 0:02:00.167839 2026-05-21 02:34:54.098902 | 2026-05-21 02:34:54.098976 | PLAY RECAP 2026-05-21 02:34:54.099037 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-05-21 02:34:54.099065 | 2026-05-21 02:34:54.274502 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-05-21 02:34:54.276902 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 02:34:54.867112 | 2026-05-21 02:34:54.867249 | PLAY [all] 2026-05-21 02:34:54.877819 | 2026-05-21 02:34:54.877924 | TASK [fetch-output : Set log path for multiple nodes] 2026-05-21 02:34:54.922893 | rockylinux-9 | skipping: Conditional result was False 2026-05-21 02:34:54.931901 | 2026-05-21 02:34:54.932032 | TASK [fetch-output : Set log path for single node] 2026-05-21 02:34:54.973978 | rockylinux-9 | ok 2026-05-21 02:34:54.980289 | 2026-05-21 02:34:54.980357 | LOOP [fetch-output : Ensure local output dirs] 2026-05-21 02:34:55.350027 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/work/logs" 2026-05-21 02:34:55.562003 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/work/artifacts" 2026-05-21 02:34:56.223051 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/e60a0702eb9b4d2ba466222dfbcacb54/work/docs" 2026-05-21 02:34:56.243804 | 2026-05-21 02:34:56.244173 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-05-21 02:34:56.965934 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:34:56.966306 | rockylinux-9 | changed: All items complete 2026-05-21 02:34:56.966373 | 2026-05-21 02:34:57.465288 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:34:58.043139 | rockylinux-9 | changed: .d..t...... ./ 2026-05-21 02:34:58.067876 | 2026-05-21 02:34:58.068009 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-05-21 02:34:58.506987 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007387 2026-05-21 02:34:58.725194 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007766 2026-05-21 02:34:58.744917 | 2026-05-21 02:34:58.745105 | PLAY [all] 2026-05-21 02:34:58.752404 | 2026-05-21 02:34:58.752493 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-05-21 02:34:59.199973 | rockylinux-9 | changed 2026-05-21 02:34:59.205822 | 2026-05-21 02:34:59.206004 | PLAY RECAP 2026-05-21 02:34:59.206064 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-05-21 02:34:59.206086 | 2026-05-21 02:34:59.354450 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-05-21 02:34:59.357042 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-05-21 02:34:59.988968 | 2026-05-21 02:34:59.989102 | PLAY [localhost] 2026-05-21 02:35:00.000215 | 2026-05-21 02:35:00.000307 | TASK [Generate Zuul manifest] 2026-05-21 02:35:00.023035 | localhost | ok 2026-05-21 02:35:00.043021 | 2026-05-21 02:35:00.043199 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-05-21 02:35:00.391424 | localhost | changed 2026-05-21 02:35:00.409023 | 2026-05-21 02:35:00.409162 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-05-21 02:35:00.442049 | localhost | ok 2026-05-21 02:35:00.455580 | 2026-05-21 02:35:00.455802 | TASK [Upload logs] 2026-05-21 02:35:00.480369 | localhost | ok 2026-05-21 02:35:00.544797 | 2026-05-21 02:35:00.544992 | TASK [Set zuul-log-path fact] 2026-05-21 02:35:00.568156 | localhost | ok 2026-05-21 02:35:00.582284 | 2026-05-21 02:35:00.582354 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-05-21 02:35:00.617249 | localhost | ok 2026-05-21 02:35:00.626806 | 2026-05-21 02:35:00.626901 | TASK [upload-logs : Create log directories] 2026-05-21 02:35:01.026753 | localhost | changed 2026-05-21 02:35:01.039284 | 2026-05-21 02:35:01.039361 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-05-21 02:35:01.474564 | localhost -> localhost | ok: Runtime: 0:00:00.006646 2026-05-21 02:35:01.479612 | 2026-05-21 02:35:01.479680 | TASK [upload-logs : Upload logs to log server] 2026-05-21 02:35:01.977750 | localhost | Output suppressed because no_log was given 2026-05-21 02:35:01.982894 | 2026-05-21 02:35:01.982962 | LOOP [upload-logs : Compress console log and json output] 2026-05-21 02:35:02.032055 | localhost | skipping: Conditional result was False 2026-05-21 02:35:02.038785 | localhost | skipping: Conditional result was False 2026-05-21 02:35:02.047708 | 2026-05-21 02:35:02.047859 | LOOP [upload-logs : Upload compressed console log and json output] 2026-05-21 02:35:02.091046 | localhost | skipping: Conditional result was False 2026-05-21 02:35:02.091485 | 2026-05-21 02:35:02.094990 | localhost | skipping: Conditional result was False 2026-05-21 02:35:02.110537 | 2026-05-21 02:35:02.110712 | LOOP [upload-logs : Upload console log and json output]