2026-01-22 21:57:19.394421 | Job console starting 2026-01-22 21:57:19.402239 | Updating git repos 2026-01-22 21:57:19.430019 | Cloning repos into workspace 2026-01-22 21:57:19.482517 | Restoring repo states 2026-01-22 21:57:19.489901 | Merging changes 2026-01-22 21:57:19.742288 | Checking out repos 2026-01-22 21:57:19.786722 | Preparing playbooks 2026-01-22 21:57:21.678688 | Running Ansible setup 2026-01-22 21:57:25.522996 | PRE-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-22 21:57:26.132832 | 2026-01-22 21:57:26.133033 | PLAY [localhost] 2026-01-22 21:57:26.141837 | 2026-01-22 21:57:26.141970 | TASK [Gathering Facts] 2026-01-22 21:57:27.029693 | localhost | ok 2026-01-22 21:57:27.036711 | 2026-01-22 21:57:27.036789 | TASK [Setup log path fact] 2026-01-22 21:57:27.052894 | localhost | ok 2026-01-22 21:57:27.062613 | 2026-01-22 21:57:27.062681 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-22 21:57:27.091465 | localhost | ok 2026-01-22 21:57:27.099088 | 2026-01-22 21:57:27.099178 | TASK [emit-job-header : Print job information] 2026-01-22 21:57:27.139782 | # Job Information 2026-01-22 21:57:27.139993 | Ansible Version: 2.16.15 2026-01-22 21:57:27.140064 | Job: ansible-collection-containers-molecule-nerdctl-rockylinux-9 2026-01-22 21:57:27.140114 | Pipeline: check 2026-01-22 21:57:27.140159 | Executor: 3a2793d2bd32 2026-01-22 21:57:27.140203 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/95 2026-01-22 21:57:27.140252 | Event ID: 1a8e4590-f7dd-11f0-8607-4007cb371271 2026-01-22 21:57:27.144934 | 2026-01-22 21:57:27.145053 | LOOP [emit-job-header : Print node information] 2026-01-22 21:57:27.249939 | localhost | ok: 2026-01-22 21:57:27.250227 | localhost | # Node Information 2026-01-22 21:57:27.250257 | localhost | Inventory Hostname: rockylinux-9 2026-01-22 21:57:27.250279 | localhost | Hostname: np0000154438 2026-01-22 21:57:27.250299 | localhost | Username: zuul 2026-01-22 21:57:27.250332 | localhost | Distro: Rocky 9.7 2026-01-22 21:57:27.250352 | localhost | Provider: yul1 2026-01-22 21:57:27.250370 | localhost | Region: ca-ymq-1 2026-01-22 21:57:27.250388 | localhost | Label: rockylinux-9 2026-01-22 21:57:27.250407 | localhost | Product Name: OpenStack Nova 2026-01-22 21:57:27.250426 | localhost | Interface IP: 199.19.213.221 2026-01-22 21:57:27.262701 | 2026-01-22 21:57:27.262869 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-01-22 21:57:27.671526 | localhost -> localhost | changed 2026-01-22 21:57:27.676748 | 2026-01-22 21:57:27.676823 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-01-22 21:57:28.522533 | localhost -> localhost | changed 2026-01-22 21:57:28.533655 | 2026-01-22 21:57:28.533770 | PLAY [all] 2026-01-22 21:57:28.542427 | 2026-01-22 21:57:28.542494 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-01-22 21:57:28.789683 | rockylinux-9 -> localhost | ok 2026-01-22 21:57:28.798503 | 2026-01-22 21:57:28.798644 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-01-22 21:57:28.830528 | rockylinux-9 | ok 2026-01-22 21:57:28.845607 | rockylinux-9 | included: /var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-01-22 21:57:28.851586 | 2026-01-22 21:57:28.851651 | TASK [add-build-sshkey : Create Temp SSH key] 2026-01-22 21:57:30.159161 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-01-22 21:57:30.159387 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/work/7ec9a3415cf24593b83630d197dc9008_id_rsa 2026-01-22 21:57:30.159429 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/work/7ec9a3415cf24593b83630d197dc9008_id_rsa.pub 2026-01-22 21:57:30.159461 | rockylinux-9 -> localhost | The key fingerprint is: 2026-01-22 21:57:30.159489 | rockylinux-9 -> localhost | SHA256:lCLE73Ouvwuq5pmtgQtTfOYT+IZK2kWODuinOR4MM7U zuul-build-sshkey 2026-01-22 21:57:30.159533 | rockylinux-9 -> localhost | The key's randomart image is: 2026-01-22 21:57:30.159560 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-01-22 21:57:30.159594 | rockylinux-9 -> localhost | | .. | 2026-01-22 21:57:30.159624 | rockylinux-9 -> localhost | | .. . | 2026-01-22 21:57:30.159649 | rockylinux-9 -> localhost | | . ... o | 2026-01-22 21:57:30.159675 | rockylinux-9 -> localhost | | o o ..o | 2026-01-22 21:57:30.159703 | rockylinux-9 -> localhost | |+ E =. S | 2026-01-22 21:57:30.159730 | rockylinux-9 -> localhost | |+= X .o . | 2026-01-22 21:57:30.159757 | rockylinux-9 -> localhost | |*++ B .+ | 2026-01-22 21:57:30.159785 | rockylinux-9 -> localhost | |=O+O o .. | 2026-01-22 21:57:30.159814 | rockylinux-9 -> localhost | |=B%oo .o+o | 2026-01-22 21:57:30.159860 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-01-22 21:57:30.159930 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.864339 2026-01-22 21:57:30.166005 | 2026-01-22 21:57:30.166093 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-01-22 21:57:30.200115 | rockylinux-9 | ok 2026-01-22 21:57:30.211788 | rockylinux-9 | included: /var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-01-22 21:57:30.220920 | 2026-01-22 21:57:30.221005 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-01-22 21:57:30.246303 | rockylinux-9 | skipping: Conditional result was False 2026-01-22 21:57:30.258103 | 2026-01-22 21:57:30.258175 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-01-22 21:57:30.721051 | rockylinux-9 | changed 2026-01-22 21:57:30.726114 | 2026-01-22 21:57:30.726188 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-01-22 21:57:30.984596 | rockylinux-9 | ok 2026-01-22 21:57:30.989921 | 2026-01-22 21:57:30.989986 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-01-22 21:57:31.926256 | rockylinux-9 | changed 2026-01-22 21:57:31.931199 | 2026-01-22 21:57:31.931265 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-01-22 21:57:32.747268 | rockylinux-9 | changed 2026-01-22 21:57:32.752990 | 2026-01-22 21:57:32.753053 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-01-22 21:57:32.777548 | rockylinux-9 | skipping: Conditional result was False 2026-01-22 21:57:32.786533 | 2026-01-22 21:57:32.786623 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-01-22 21:57:33.177795 | rockylinux-9 -> localhost | changed 2026-01-22 21:57:33.191625 | 2026-01-22 21:57:33.191704 | TASK [add-build-sshkey : Add back temp key] 2026-01-22 21:57:33.509462 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/work/7ec9a3415cf24593b83630d197dc9008_id_rsa (zuul-build-sshkey) 2026-01-22 21:57:33.509974 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014928 2026-01-22 21:57:33.515324 | 2026-01-22 21:57:33.515389 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-01-22 21:57:33.902727 | rockylinux-9 | ok 2026-01-22 21:57:33.909910 | 2026-01-22 21:57:33.909981 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-01-22 21:57:33.934624 | rockylinux-9 | skipping: Conditional result was False 2026-01-22 21:57:33.950437 | 2026-01-22 21:57:33.950522 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-01-22 21:57:34.329138 | rockylinux-9 | ok 2026-01-22 21:57:34.337852 | 2026-01-22 21:57:34.337918 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-01-22 21:57:35.220336 | rockylinux-9 | Output suppressed because no_log was given 2026-01-22 21:57:35.233403 | 2026-01-22 21:57:35.233608 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-01-22 21:57:35.505500 | rockylinux-9 | ok: "logs" 2026-01-22 21:57:35.505875 | rockylinux-9 | ok: All items complete 2026-01-22 21:57:35.505921 | 2026-01-22 21:57:35.752494 | rockylinux-9 | ok: "artifacts" 2026-01-22 21:57:36.023723 | rockylinux-9 | ok: "docs" 2026-01-22 21:57:36.041593 | 2026-01-22 21:57:36.041764 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-01-22 21:57:36.307254 | rockylinux-9 | changed: "logs" 2026-01-22 21:57:36.543318 | rockylinux-9 | changed: "artifacts" 2026-01-22 21:57:36.803768 | rockylinux-9 | changed: "docs" 2026-01-22 21:57:36.824581 | 2026-01-22 21:57:36.824737 | PLAY RECAP 2026-01-22 21:57:36.824803 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-22 21:57:36.824846 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-01-22 21:57:36.824878 | 2026-01-22 21:57:36.961452 | PRE-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/pre.yaml@main] 2026-01-22 21:57:36.963995 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-22 21:57:37.504533 | 2026-01-22 21:57:37.504663 | PLAY [all] 2026-01-22 21:57:37.515942 | 2026-01-22 21:57:37.516020 | TASK [setup-uv : Extract archive] 2026-01-22 21:57:40.765719 | rockylinux-9 | changed 2026-01-22 21:57:40.773081 | 2026-01-22 21:57:40.773184 | TASK [setup-uv : Print version] 2026-01-22 21:57:40.796220 | [rockylinux-9] Waiting on logger 2026-01-22 21:57:41.311642 | rockylinux-9 | ok: Runtime: 0:00:00.015336 2026-01-22 21:57:41.318775 | 2026-01-22 21:57:41.318826 | PLAY RECAP 2026-01-22 21:57:41.318875 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-22 21:57:41.318899 | 2026-01-22 21:57:41.444259 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-01-22 21:57:41.446484 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-22 21:57:42.019811 | 2026-01-22 21:57:42.019986 | PLAY [all] 2026-01-22 21:57:42.032857 | 2026-01-22 21:57:42.033015 | TASK [Copy inventory file for Zuul] 2026-01-22 21:57:43.312775 | rockylinux-9 | changed 2026-01-22 21:57:43.319510 | 2026-01-22 21:57:43.319619 | TASK [Switch "ansible_host" to private IP] 2026-01-22 21:57:43.658527 | rockylinux-9 | changed: 1 replacements made 2026-01-22 21:57:43.665316 | 2026-01-22 21:57:43.665388 | TASK [Run Molecule scenario] 2026-01-22 21:57:43.688149 | [rockylinux-9] Waiting on logger 2026-01-22 21:57:51.819108 | [rockylinux-9] Waiting on logger 2026-01-22 21:58:02.494333 | [rockylinux-9] Waiting on logger 2026-01-22 21:58:12.677860 | [rockylinux-9] Waiting on logger 2026-01-22 21:58:21.835145 | [rockylinux-9] Waiting on logger 2026-01-22 21:58:32.006031 | [rockylinux-9] Waiting on logger 2026-01-22 21:58:42.181912 | [rockylinux-9] Waiting on logger 2026-01-22 21:58:52.357831 | [rockylinux-9] Waiting on logger 2026-01-22 21:59:02.533964 | [rockylinux-9] Waiting on logger 2026-01-22 21:59:12.713674 | [rockylinux-9] Waiting on logger 2026-01-22 21:59:21.866997 | [rockylinux-9] Waiting on logger 2026-01-22 21:59:32.042111 | [rockylinux-9] Waiting on logger 2026-01-22 21:59:42.214050 | [rockylinux-9] Waiting on logger 2026-01-22 21:59:45.580679 | rockylinux-9 | ok: Runtime: 0:02:00.809830 2026-01-22 21:59:45.586658 | 2026-01-22 21:59:45.586725 | PLAY RECAP 2026-01-22 21:59:45.586773 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-01-22 21:59:45.586796 | 2026-01-22 21:59:45.690969 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-01-22 21:59:45.693225 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-22 21:59:46.277248 | 2026-01-22 21:59:46.277381 | PLAY [all] 2026-01-22 21:59:46.291281 | 2026-01-22 21:59:46.291375 | TASK [fetch-output : Set log path for multiple nodes] 2026-01-22 21:59:46.328371 | rockylinux-9 | skipping: Conditional result was False 2026-01-22 21:59:46.336945 | 2026-01-22 21:59:46.337072 | TASK [fetch-output : Set log path for single node] 2026-01-22 21:59:46.369883 | rockylinux-9 | ok 2026-01-22 21:59:46.375411 | 2026-01-22 21:59:46.375478 | LOOP [fetch-output : Ensure local output dirs] 2026-01-22 21:59:46.782214 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/work/logs" 2026-01-22 21:59:46.988498 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/work/artifacts" 2026-01-22 21:59:47.204847 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/7ec9a3415cf24593b83630d197dc9008/work/docs" 2026-01-22 21:59:47.226056 | 2026-01-22 21:59:47.226176 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-01-22 21:59:47.896825 | rockylinux-9 | changed: .d..t...... ./ 2026-01-22 21:59:47.897126 | rockylinux-9 | changed: All items complete 2026-01-22 21:59:47.897165 | 2026-01-22 21:59:48.413297 | rockylinux-9 | changed: .d..t...... ./ 2026-01-22 21:59:48.911723 | rockylinux-9 | changed: .d..t...... ./ 2026-01-22 21:59:48.932948 | 2026-01-22 21:59:48.933140 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-01-22 21:59:49.366864 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006606 2026-01-22 21:59:49.609704 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007757 2026-01-22 21:59:49.628737 | 2026-01-22 21:59:49.629708 | PLAY [all] 2026-01-22 21:59:49.636539 | 2026-01-22 21:59:49.636612 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-01-22 21:59:50.174829 | rockylinux-9 | changed 2026-01-22 21:59:50.181114 | 2026-01-22 21:59:50.181165 | PLAY RECAP 2026-01-22 21:59:50.181209 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-01-22 21:59:50.181231 | 2026-01-22 21:59:50.292364 | POST-RUN END RESULT_NORMAL: [trusted : vexxhost.dev/zuul-config/playbooks/base/post.yaml@main] 2026-01-22 21:59:50.294742 | POST-RUN START: [trusted : vexxhost.dev/zuul-config/playbooks/base/post-logs.yaml@main] 2026-01-22 21:59:50.890843 | 2026-01-22 21:59:50.890980 | PLAY [localhost] 2026-01-22 21:59:50.902668 | 2026-01-22 21:59:50.902745 | TASK [Generate Zuul manifest] 2026-01-22 21:59:50.927233 | localhost | ok 2026-01-22 21:59:50.942165 | 2026-01-22 21:59:50.942253 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-01-22 21:59:51.298801 | localhost | changed 2026-01-22 21:59:51.310929 | 2026-01-22 21:59:51.311009 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-01-22 21:59:51.342797 | localhost | ok 2026-01-22 21:59:51.351272 | 2026-01-22 21:59:51.351334 | TASK [Upload logs] 2026-01-22 21:59:51.371460 | localhost | ok 2026-01-22 21:59:51.430009 | 2026-01-22 21:59:51.430097 | TASK [Set zuul-log-path fact] 2026-01-22 21:59:51.451540 | localhost | ok 2026-01-22 21:59:51.464504 | 2026-01-22 21:59:51.464572 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-01-22 21:59:51.496064 | localhost | ok 2026-01-22 21:59:51.504634 | 2026-01-22 21:59:51.504699 | TASK [upload-logs : Create log directories] 2026-01-22 21:59:51.951154 | localhost | changed 2026-01-22 21:59:51.957652 | 2026-01-22 21:59:51.957741 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-01-22 21:59:52.365380 | localhost -> localhost | ok: Runtime: 0:00:00.005594 2026-01-22 21:59:52.371490 | 2026-01-22 21:59:52.371626 | TASK [upload-logs : Upload logs to log server] 2026-01-22 21:59:52.848357 | localhost | Output suppressed because no_log was given 2026-01-22 21:59:52.852271 | 2026-01-22 21:59:52.852335 | LOOP [upload-logs : Compress console log and json output] 2026-01-22 21:59:52.903308 | localhost | skipping: Conditional result was False 2026-01-22 21:59:52.910453 | localhost | skipping: Conditional result was False 2026-01-22 21:59:52.926603 | 2026-01-22 21:59:52.926767 | LOOP [upload-logs : Upload compressed console log and json output] 2026-01-22 21:59:52.970313 | localhost | skipping: Conditional result was False 2026-01-22 21:59:52.970681 | 2026-01-22 21:59:52.973832 | localhost | skipping: Conditional result was False 2026-01-22 21:59:52.991539 | 2026-01-22 21:59:52.991692 | LOOP [upload-logs : Upload console log and json output]