2026-03-05 16:19:29.109575 | Job console starting 2026-03-05 16:19:29.136511 | Updating git repos 2026-03-05 16:19:29.292724 | Cloning repos into workspace 2026-03-05 16:19:29.331738 | Restoring repo states 2026-03-05 16:19:29.346927 | Merging changes 2026-03-05 16:19:29.664897 | Checking out repos 2026-03-05 16:19:29.714666 | Preparing playbooks 2026-03-05 16:19:44.423974 | Running Ansible setup 2026-03-05 16:19:49.156498 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 16:19:49.797750 | 2026-03-05 16:19:49.797915 | PLAY [localhost] 2026-03-05 16:19:49.806071 | 2026-03-05 16:19:49.806158 | TASK [Gathering Facts] 2026-03-05 16:19:50.656843 | localhost | ok 2026-03-05 16:19:50.665810 | 2026-03-05 16:19:50.665892 | TASK [Setup log path fact] 2026-03-05 16:19:50.686138 | localhost | ok 2026-03-05 16:19:50.699199 | 2026-03-05 16:19:50.699267 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 16:19:50.729597 | localhost | ok 2026-03-05 16:19:50.737520 | 2026-03-05 16:19:50.737587 | TASK [emit-job-header : Print job information] 2026-03-05 16:19:50.779711 | # Job Information 2026-03-05 16:19:50.779973 | Ansible Version: 2.16.16 2026-03-05 16:19:50.780021 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-03-05 16:19:50.780052 | Pipeline: check 2026-03-05 16:19:50.780080 | Executor: 0a8996d2b663 2026-03-05 16:19:50.780108 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/97 2026-03-05 16:19:50.780138 | Event ID: 12f135d0-18ae-11f1-85c2-fd1165a7d91d 2026-03-05 16:19:50.784084 | 2026-03-05 16:19:50.784177 | LOOP [emit-job-header : Print node information] 2026-03-05 16:19:50.873091 | localhost | ok: 2026-03-05 16:19:50.873364 | localhost | # Node Information 2026-03-05 16:19:50.873414 | localhost | Inventory Hostname: rockylinux-9 2026-03-05 16:19:50.873462 | localhost | Hostname: np0000157909 2026-03-05 16:19:50.873499 | localhost | Username: zuul 2026-03-05 16:19:50.873541 | localhost | Distro: Rocky 9.7 2026-03-05 16:19:50.873577 | localhost | Provider: yul1 2026-03-05 16:19:50.873612 | localhost | Region: ca-ymq-1 2026-03-05 16:19:50.873644 | localhost | Label: rockylinux-9 2026-03-05 16:19:50.873679 | localhost | Product Name: OpenStack Nova 2026-03-05 16:19:50.873712 | localhost | Interface IP: 199.204.45.53 2026-03-05 16:19:50.885181 | 2026-03-05 16:19:50.885357 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-05 16:19:51.301954 | localhost -> localhost | changed 2026-03-05 16:19:51.307730 | 2026-03-05 16:19:51.307807 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-05 16:19:52.158504 | localhost -> localhost | changed 2026-03-05 16:19:52.169736 | 2026-03-05 16:19:52.169791 | PLAY [all] 2026-03-05 16:19:52.177315 | 2026-03-05 16:19:52.177377 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-05 16:19:52.397922 | rockylinux-9 -> localhost | ok 2026-03-05 16:19:52.405275 | 2026-03-05 16:19:52.405370 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-05 16:19:52.439947 | rockylinux-9 | ok 2026-03-05 16:19:52.454941 | rockylinux-9 | included: /var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-05 16:19:52.460036 | 2026-03-05 16:19:52.460098 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-05 16:19:53.903767 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-05 16:19:53.904078 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/work/2bbedf71856046fd8fd94c604bee0e48_id_rsa 2026-03-05 16:19:53.904147 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/work/2bbedf71856046fd8fd94c604bee0e48_id_rsa.pub 2026-03-05 16:19:53.904197 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-05 16:19:53.904244 | rockylinux-9 -> localhost | SHA256:/RAjS+lpkmKDzok5vQnt1Jc2BmSt29CWwqMiVJ1M8tg zuul-build-sshkey 2026-03-05 16:19:53.904306 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-05 16:19:53.904353 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-05 16:19:53.904438 | rockylinux-9 -> localhost | | . . | 2026-03-05 16:19:53.904488 | rockylinux-9 -> localhost | | O.. . | 2026-03-05 16:19:53.904532 | rockylinux-9 -> localhost | | ooE. + o | 2026-03-05 16:19:53.904575 | rockylinux-9 -> localhost | | .= o = = o | 2026-03-05 16:19:53.904618 | rockylinux-9 -> localhost | | .. @ * S o | 2026-03-05 16:19:53.904661 | rockylinux-9 -> localhost | |.B = @ + o | 2026-03-05 16:19:53.904704 | rockylinux-9 -> localhost | |B O o B . | 2026-03-05 16:19:53.904747 | rockylinux-9 -> localhost | |.* o + . | 2026-03-05 16:19:53.904796 | rockylinux-9 -> localhost | | + | 2026-03-05 16:19:53.904841 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-05 16:19:53.904959 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.041688 2026-03-05 16:19:53.914743 | 2026-03-05 16:19:53.914930 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-05 16:19:53.950383 | rockylinux-9 | ok 2026-03-05 16:19:53.958880 | rockylinux-9 | included: /var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-05 16:19:53.968185 | 2026-03-05 16:19:53.968249 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-05 16:19:53.993878 | rockylinux-9 | skipping: Conditional result was False 2026-03-05 16:19:54.001925 | 2026-03-05 16:19:54.002012 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-05 16:19:54.462515 | rockylinux-9 | changed 2026-03-05 16:19:54.470517 | 2026-03-05 16:19:54.470581 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-05 16:19:54.740827 | rockylinux-9 | ok 2026-03-05 16:19:54.747969 | 2026-03-05 16:19:54.748054 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-05 16:19:55.721164 | rockylinux-9 | changed 2026-03-05 16:19:55.727047 | 2026-03-05 16:19:55.727173 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-05 16:19:56.696806 | rockylinux-9 | changed 2026-03-05 16:19:56.702854 | 2026-03-05 16:19:56.702922 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-05 16:19:56.727143 | rockylinux-9 | skipping: Conditional result was False 2026-03-05 16:19:56.737579 | 2026-03-05 16:19:56.737720 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-05 16:19:57.137687 | rockylinux-9 -> localhost | changed 2026-03-05 16:19:57.149473 | 2026-03-05 16:19:57.149538 | TASK [add-build-sshkey : Add back temp key] 2026-03-05 16:19:57.436641 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/work/2bbedf71856046fd8fd94c604bee0e48_id_rsa (zuul-build-sshkey) 2026-03-05 16:19:57.437121 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011403 2026-03-05 16:19:57.446531 | 2026-03-05 16:19:57.446722 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-05 16:19:57.843549 | rockylinux-9 | ok 2026-03-05 16:19:57.849205 | 2026-03-05 16:19:57.849270 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-05 16:19:57.873049 | rockylinux-9 | skipping: Conditional result was False 2026-03-05 16:19:57.886461 | 2026-03-05 16:19:57.886524 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-05 16:19:58.297807 | rockylinux-9 | ok 2026-03-05 16:19:58.304111 | 2026-03-05 16:19:58.304176 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-05 16:19:59.215806 | rockylinux-9 | Output suppressed because no_log was given 2026-03-05 16:19:59.224341 | 2026-03-05 16:19:59.224405 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-05 16:19:59.496166 | rockylinux-9 | ok: "logs" 2026-03-05 16:19:59.496412 | rockylinux-9 | ok: All items complete 2026-03-05 16:19:59.496442 | 2026-03-05 16:19:59.722214 | rockylinux-9 | ok: "artifacts" 2026-03-05 16:19:59.972822 | rockylinux-9 | ok: "docs" 2026-03-05 16:19:59.984390 | 2026-03-05 16:19:59.984558 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-05 16:20:00.262907 | rockylinux-9 | changed: "logs" 2026-03-05 16:20:00.502400 | rockylinux-9 | changed: "artifacts" 2026-03-05 16:20:00.756415 | rockylinux-9 | changed: "docs" 2026-03-05 16:20:00.785407 | 2026-03-05 16:20:00.785546 | PLAY RECAP 2026-03-05 16:20:00.785619 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:20:00.785660 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-05 16:20:00.785682 | 2026-03-05 16:20:00.900101 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-05 16:20:00.902586 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 16:20:01.517773 | 2026-03-05 16:20:01.517995 | PLAY [all] 2026-03-05 16:20:01.532472 | 2026-03-05 16:20:01.532633 | TASK [setup-uv : Extract archive] 2026-03-05 16:20:04.463825 | rockylinux-9 | changed 2026-03-05 16:20:04.470446 | 2026-03-05 16:20:04.470553 | TASK [setup-uv : Print version] 2026-03-05 16:20:04.494022 | [rockylinux-9] Waiting on logger 2026-03-05 16:20:05.008430 | rockylinux-9 | ok: Runtime: 0:00:00.014249 2026-03-05 16:20:05.016345 | 2026-03-05 16:20:05.016410 | PLAY RECAP 2026-03-05 16:20:05.016461 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:20:05.016488 | 2026-03-05 16:20:05.179007 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-05 16:20:05.181416 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 16:20:05.734913 | 2026-03-05 16:20:05.735039 | PLAY [all] 2026-03-05 16:20:05.745594 | 2026-03-05 16:20:05.745804 | TASK [Copy inventory file for Zuul] 2026-03-05 16:20:07.156522 | rockylinux-9 | changed 2026-03-05 16:20:07.163090 | 2026-03-05 16:20:07.163172 | TASK [Switch "ansible_host" to private IP] 2026-03-05 16:20:07.533957 | rockylinux-9 | changed: 1 replacements made 2026-03-05 16:20:07.540097 | 2026-03-05 16:20:07.540164 | TASK [Run Molecule scenario] 2026-03-05 16:20:07.561977 | [rockylinux-9] Waiting on logger 2026-03-05 16:20:15.685970 | [rockylinux-9] Waiting on logger 2026-03-05 16:20:25.865963 | [rockylinux-9] Waiting on logger 2026-03-05 16:20:36.038460 | [rockylinux-9] Waiting on logger 2026-03-05 16:20:46.214106 | [rockylinux-9] Waiting on logger 2026-03-05 16:20:56.390299 | [rockylinux-9] Waiting on logger 2026-03-05 16:21:05.547213 | [rockylinux-9] Waiting on logger 2026-03-05 16:21:15.722123 | [rockylinux-9] Waiting on logger 2026-03-05 16:21:25.897947 | [rockylinux-9] Waiting on logger 2026-03-05 16:21:36.069996 | [rockylinux-9] Waiting on logger 2026-03-05 16:21:46.245889 | [rockylinux-9] Waiting on logger 2026-03-05 16:21:56.421941 | [rockylinux-9] Waiting on logger 2026-03-05 16:22:05.583692 | [rockylinux-9] Waiting on logger 2026-03-05 16:22:15.750206 | [rockylinux-9] Waiting on logger 2026-03-05 16:22:25.926583 | [rockylinux-9] Waiting on logger 2026-03-05 16:22:36.166179 | [rockylinux-9] Waiting on logger 2026-03-05 16:22:46.341819 | [rockylinux-9] Waiting on logger 2026-03-05 16:22:55.531271 | [rockylinux-9] Waiting on logger 2026-03-05 16:23:05.701897 | [rockylinux-9] Waiting on logger 2026-03-05 16:23:15.878573 | [rockylinux-9] Waiting on logger 2026-03-05 16:23:26.053938 | [rockylinux-9] Waiting on logger 2026-03-05 16:23:36.234035 | [rockylinux-9] Waiting on logger 2026-03-05 16:23:46.405888 | [rockylinux-9] Waiting on logger 2026-03-05 16:23:55.563339 | [rockylinux-9] Waiting on logger 2026-03-05 16:24:05.734002 | [rockylinux-9] Waiting on logger 2026-03-05 16:24:16.198560 | [rockylinux-9] Waiting on logger 2026-03-05 16:24:26.374107 | [rockylinux-9] Waiting on logger 2026-03-05 16:24:35.531864 | [rockylinux-9] Waiting on logger 2026-03-05 16:24:45.701999 | [rockylinux-9] Waiting on logger 2026-03-05 16:24:55.877831 | [rockylinux-9] Waiting on logger 2026-03-05 16:25:02.701941 | rockylinux-9 | ok: Runtime: 0:04:54.395489 2026-03-05 16:25:02.708457 | 2026-03-05 16:25:02.708505 | PLAY RECAP 2026-03-05 16:25:02.708547 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-05 16:25:02.708568 | 2026-03-05 16:25:02.802190 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-05 16:25:02.804247 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 16:25:03.346229 | 2026-03-05 16:25:03.346352 | PLAY [all] 2026-03-05 16:25:03.357370 | 2026-03-05 16:25:03.357446 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-05 16:25:03.392254 | rockylinux-9 | skipping: Conditional result was False 2026-03-05 16:25:03.397082 | 2026-03-05 16:25:03.397162 | TASK [fetch-output : Set log path for single node] 2026-03-05 16:25:03.438143 | rockylinux-9 | ok 2026-03-05 16:25:03.444431 | 2026-03-05 16:25:03.444499 | LOOP [fetch-output : Ensure local output dirs] 2026-03-05 16:25:03.961670 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/work/logs" 2026-03-05 16:25:04.191094 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/work/artifacts" 2026-03-05 16:25:04.400826 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/2bbedf71856046fd8fd94c604bee0e48/work/docs" 2026-03-05 16:25:04.421453 | 2026-03-05 16:25:04.421800 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-05 16:25:05.146099 | rockylinux-9 | ok 2026-03-05 16:25:05.146365 | rockylinux-9 | changed: All items complete 2026-03-05 16:25:05.146408 | 2026-03-05 16:25:06.096301 | rockylinux-9 | changed: .d..t...... ./ 2026-03-05 16:25:06.627976 | rockylinux-9 | changed: .d..t...... ./ 2026-03-05 16:25:06.648808 | 2026-03-05 16:25:06.648895 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-05 16:25:07.044373 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008151 2026-03-05 16:25:07.258624 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007140 2026-03-05 16:25:07.279362 | 2026-03-05 16:25:07.279514 | PLAY [all] 2026-03-05 16:25:07.289441 | 2026-03-05 16:25:07.289591 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-05 16:25:07.764213 | rockylinux-9 | changed 2026-03-05 16:25:07.770760 | 2026-03-05 16:25:07.770809 | PLAY RECAP 2026-03-05 16:25:07.770852 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-05 16:25:07.770876 | 2026-03-05 16:25:07.889597 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-05 16:25:07.890891 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-05 16:25:08.462449 | 2026-03-05 16:25:08.462556 | PLAY [localhost] 2026-03-05 16:25:08.472317 | 2026-03-05 16:25:08.472392 | TASK [Generate Zuul manifest] 2026-03-05 16:25:08.491454 | localhost | ok 2026-03-05 16:25:08.509991 | 2026-03-05 16:25:08.542340 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-05 16:25:08.864624 | localhost | changed 2026-03-05 16:25:08.878489 | 2026-03-05 16:25:08.878561 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-05 16:25:08.909686 | localhost | ok 2026-03-05 16:25:08.919752 | 2026-03-05 16:25:08.919818 | TASK [Upload logs] 2026-03-05 16:25:08.939352 | localhost | ok 2026-03-05 16:25:08.996842 | 2026-03-05 16:25:08.996930 | TASK [Set zuul-log-path fact] 2026-03-05 16:25:09.017905 | localhost | ok 2026-03-05 16:25:09.030540 | 2026-03-05 16:25:09.030608 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-05 16:25:09.064368 | localhost | ok 2026-03-05 16:25:09.074200 | 2026-03-05 16:25:09.074261 | TASK [upload-logs : Create log directories] 2026-03-05 16:25:09.479882 | localhost | changed 2026-03-05 16:25:09.484595 | 2026-03-05 16:25:09.484665 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-05 16:25:09.901502 | localhost -> localhost | ok: Runtime: 0:00:00.006016 2026-03-05 16:25:09.908217 | 2026-03-05 16:25:09.908335 | TASK [upload-logs : Upload logs to log server] 2026-03-05 16:25:10.905566 | localhost | Output suppressed because no_log was given 2026-03-05 16:25:10.911529 | 2026-03-05 16:25:10.911622 | LOOP [upload-logs : Compress console log and json output] 2026-03-05 16:25:10.960215 | localhost | skipping: Conditional result was False 2026-03-05 16:25:10.967402 | localhost | skipping: Conditional result was False 2026-03-05 16:25:10.982491 | 2026-03-05 16:25:10.982565 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-05 16:25:11.028746 | localhost | skipping: Conditional result was False 2026-03-05 16:25:11.029178 | 2026-03-05 16:25:11.033287 | localhost | skipping: Conditional result was False 2026-03-05 16:25:11.046287 | 2026-03-05 16:25:11.046424 | LOOP [upload-logs : Upload console log and json output]