2026-06-16 00:50:45.574120 | Job console starting 2026-06-16 00:50:45.581524 | Updating git repos 2026-06-16 00:50:45.751797 | Cloning repos into workspace 2026-06-16 00:50:46.132850 | Restoring repo states 2026-06-16 00:50:46.141049 | Merging changes 2026-06-16 00:50:46.577078 | Checking out repos 2026-06-16 00:50:46.642591 | Preparing playbooks 2026-06-16 00:50:48.877835 | Running Ansible setup 2026-06-16 00:50:55.989092 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-16 00:50:56.743676 | 2026-06-16 00:50:56.743840 | PLAY [localhost] 2026-06-16 00:50:57.114893 | 2026-06-16 00:50:57.115231 | TASK [Gathering Facts] 2026-06-16 00:50:58.010012 | localhost | ok 2026-06-16 00:50:58.028773 | 2026-06-16 00:50:58.029084 | TASK [Setup log path fact] 2026-06-16 00:50:58.062552 | localhost | ok 2026-06-16 00:50:58.081946 | 2026-06-16 00:50:58.082063 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-16 00:50:58.116842 | localhost | ok 2026-06-16 00:50:58.125857 | 2026-06-16 00:50:58.125969 | TASK [emit-job-header : Print job information] 2026-06-16 00:50:58.165935 | # Job Information 2026-06-16 00:50:58.166115 | Ansible Version: 2.16.18 2026-06-16 00:50:58.166150 | Job: ansible-collection-containers-molecule-docker-rockylinux-9 2026-06-16 00:50:58.166174 | Pipeline: check 2026-06-16 00:50:58.166196 | Executor: 2d72f0692154 2026-06-16 00:50:58.166216 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/122 2026-06-16 00:50:58.166239 | Event ID: 4ebe2140-691c-11f1-91ea-de5b29192145 2026-06-16 00:50:58.169518 | 2026-06-16 00:50:58.169592 | LOOP [emit-job-header : Print node information] 2026-06-16 00:50:58.267284 | localhost | ok: 2026-06-16 00:50:58.267660 | localhost | # Node Information 2026-06-16 00:50:58.267736 | localhost | Inventory Hostname: rockylinux-9 2026-06-16 00:50:58.267785 | localhost | Hostname: np0000180549 2026-06-16 00:50:58.267829 | localhost | Username: zuul 2026-06-16 00:50:58.267879 | localhost | Distro: Rocky 9.7 2026-06-16 00:50:58.267931 | localhost | Provider: yul1 2026-06-16 00:50:58.267969 | localhost | Region: ca-ymq-1 2026-06-16 00:50:58.267992 | localhost | Label: rockylinux-9 2026-06-16 00:50:58.268014 | localhost | Product Name: OpenStack Nova 2026-06-16 00:50:58.268037 | localhost | Interface IP: 199.19.213.213 2026-06-16 00:50:58.286249 | 2026-06-16 00:50:58.286512 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-06-16 00:50:58.878295 | localhost -> localhost | changed 2026-06-16 00:50:58.891455 | 2026-06-16 00:50:58.891650 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-06-16 00:51:00.433716 | localhost -> localhost | changed 2026-06-16 00:51:00.444210 | 2026-06-16 00:51:00.444351 | PLAY [all] 2026-06-16 00:51:00.468012 | 2026-06-16 00:51:00.468186 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-06-16 00:51:00.803431 | rockylinux-9 -> localhost | ok 2026-06-16 00:51:00.812361 | 2026-06-16 00:51:00.812473 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-06-16 00:51:00.856882 | rockylinux-9 | ok 2026-06-16 00:51:00.901727 | rockylinux-9 | included: /var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-06-16 00:51:00.917769 | 2026-06-16 00:51:00.917898 | TASK [add-build-sshkey : Create Temp SSH key] 2026-06-16 00:51:02.837249 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-06-16 00:51:02.837505 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/work/d2327bb6607a4dad83a7fca0a4c13c5b_id_rsa 2026-06-16 00:51:02.837540 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/work/d2327bb6607a4dad83a7fca0a4c13c5b_id_rsa.pub 2026-06-16 00:51:02.837565 | rockylinux-9 -> localhost | The key fingerprint is: 2026-06-16 00:51:02.837587 | rockylinux-9 -> localhost | SHA256:usk0aJz5Bn+UYYN2Z1acguX2nbsb7/fSnr6Zr6dYXV8 zuul-build-sshkey 2026-06-16 00:51:02.837627 | rockylinux-9 -> localhost | The key's randomart image is: 2026-06-16 00:51:02.837650 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-06-16 00:51:02.837678 | rockylinux-9 -> localhost | | o.. . | 2026-06-16 00:51:02.837702 | rockylinux-9 -> localhost | | ... + | 2026-06-16 00:51:02.837723 | rockylinux-9 -> localhost | | . oo | 2026-06-16 00:51:02.837744 | rockylinux-9 -> localhost | | o =.+. . . | 2026-06-16 00:51:02.837765 | rockylinux-9 -> localhost | | . oSB . o E| 2026-06-16 00:51:02.837784 | rockylinux-9 -> localhost | | ..+ .o o+| 2026-06-16 00:51:02.837804 | rockylinux-9 -> localhost | | *o+. +.o| 2026-06-16 00:51:02.837824 | rockylinux-9 -> localhost | | . +o+. o.+B| 2026-06-16 00:51:02.837847 | rockylinux-9 -> localhost | | .=. . *#@| 2026-06-16 00:51:02.837868 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-06-16 00:51:02.837979 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.061981 2026-06-16 00:51:02.843061 | 2026-06-16 00:51:02.843133 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-06-16 00:51:02.893937 | rockylinux-9 | ok 2026-06-16 00:51:02.904169 | rockylinux-9 | included: /var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-06-16 00:51:02.914287 | 2026-06-16 00:51:02.914369 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-06-16 00:51:02.956982 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:51:02.978923 | 2026-06-16 00:51:02.979161 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-06-16 00:51:03.519949 | rockylinux-9 | changed 2026-06-16 00:51:03.524861 | 2026-06-16 00:51:03.524928 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-06-16 00:51:03.806842 | rockylinux-9 | ok 2026-06-16 00:51:03.824661 | 2026-06-16 00:51:03.824825 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-06-16 00:51:04.805940 | rockylinux-9 | changed 2026-06-16 00:51:04.816095 | 2026-06-16 00:51:04.816310 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-06-16 00:51:05.833884 | rockylinux-9 | changed 2026-06-16 00:51:05.839535 | 2026-06-16 00:51:05.839605 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-06-16 00:51:05.879039 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:51:05.885578 | 2026-06-16 00:51:05.885662 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-06-16 00:51:06.645043 | rockylinux-9 -> localhost | changed 2026-06-16 00:51:06.675292 | 2026-06-16 00:51:06.675511 | TASK [add-build-sshkey : Add back temp key] 2026-06-16 00:51:07.017036 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/work/d2327bb6607a4dad83a7fca0a4c13c5b_id_rsa (zuul-build-sshkey) 2026-06-16 00:51:07.017261 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.013950 2026-06-16 00:51:07.022623 | 2026-06-16 00:51:07.022685 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-06-16 00:51:07.407823 | rockylinux-9 | ok 2026-06-16 00:51:07.412532 | 2026-06-16 00:51:07.412606 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-06-16 00:51:07.439011 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:51:07.453312 | 2026-06-16 00:51:07.453470 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-06-16 00:51:07.892843 | rockylinux-9 | ok 2026-06-16 00:51:07.898443 | 2026-06-16 00:51:07.898523 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-06-16 00:51:14.018368 | rockylinux-9 | Output suppressed because no_log was given 2026-06-16 00:51:14.033444 | 2026-06-16 00:51:14.033664 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-06-16 00:51:14.293384 | rockylinux-9 | ok: "logs" 2026-06-16 00:51:14.293985 | rockylinux-9 | ok: All items complete 2026-06-16 00:51:14.294016 | 2026-06-16 00:51:14.529126 | rockylinux-9 | ok: "artifacts" 2026-06-16 00:51:14.767666 | rockylinux-9 | ok: "docs" 2026-06-16 00:51:14.783124 | 2026-06-16 00:51:14.783306 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-06-16 00:51:15.062840 | rockylinux-9 | changed: "logs" 2026-06-16 00:51:15.344443 | rockylinux-9 | changed: "artifacts" 2026-06-16 00:51:15.809744 | rockylinux-9 | changed: "docs" 2026-06-16 00:51:15.826504 | 2026-06-16 00:51:15.826770 | PLAY RECAP 2026-06-16 00:51:15.826879 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-16 00:51:15.826947 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-06-16 00:51:15.826995 | 2026-06-16 00:51:16.008213 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-06-16 00:51:16.009497 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-16 00:51:16.749509 | 2026-06-16 00:51:16.749670 | PLAY [all] 2026-06-16 00:51:16.762474 | 2026-06-16 00:51:16.762648 | TASK [setup-uv : Extract archive] 2026-06-16 00:51:19.749226 | rockylinux-9 | changed 2026-06-16 00:51:19.953138 | 2026-06-16 00:51:19.953320 | TASK [setup-uv : Print version] 2026-06-16 00:51:19.976455 | [rockylinux-9] Waiting on logger 2026-06-16 00:51:20.490837 | rockylinux-9 | ok: Runtime: 0:00:00.010890 2026-06-16 00:51:20.507974 | 2026-06-16 00:51:20.508257 | TASK [Install Ansible collection siblings] 2026-06-16 00:51:20.937295 | rockylinux-9 | ok: 2026-06-16 00:51:20.937538 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling 2026-06-16 00:51:20.937581 | rockylinux-9 | Skipping community.general: no checked-out sibling 2026-06-16 00:51:20.939974 | 2026-06-16 00:51:20.940104 | PLAY RECAP 2026-06-16 00:51:20.943162 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-16 00:51:20.943217 | 2026-06-16 00:51:21.120344 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-06-16 00:51:21.178117 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-16 00:51:21.799613 | 2026-06-16 00:51:21.799733 | PLAY [all] 2026-06-16 00:51:21.811642 | 2026-06-16 00:51:21.811768 | TASK [Copy inventory file for Zuul] 2026-06-16 00:51:23.450044 | rockylinux-9 | changed 2026-06-16 00:51:23.455308 | 2026-06-16 00:51:23.456749 | TASK [Switch "ansible_host" to private IP] 2026-06-16 00:51:23.910280 | rockylinux-9 | changed: 1 replacements made 2026-06-16 00:51:23.919939 | 2026-06-16 00:51:23.920085 | TASK [Run Molecule scenario] 2026-06-16 00:51:23.991051 | [rockylinux-9] Waiting on logger 2026-06-16 00:51:31.113906 | [rockylinux-9] Waiting on logger 2026-06-16 00:51:41.510330 | [rockylinux-9] Waiting on logger 2026-06-16 00:51:51.749950 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:01.930131 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:11.086587 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:21.254405 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:31.434541 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:41.625033 | [rockylinux-9] Waiting on logger 2026-06-16 00:52:51.813963 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:01.003650 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:11.173942 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:21.350064 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:31.558192 | [rockylinux-9] Waiting on logger 2026-06-16 00:53:39.505459 | rockylinux-9 | ok: Runtime: 0:02:14.130632 2026-06-16 00:53:39.513163 | 2026-06-16 00:53:39.513282 | PLAY RECAP 2026-06-16 00:53:39.513550 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-06-16 00:53:39.513598 | 2026-06-16 00:53:39.793286 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-06-16 00:53:39.794544 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-16 00:53:41.189196 | 2026-06-16 00:53:41.189377 | PLAY [all] 2026-06-16 00:53:41.201873 | 2026-06-16 00:53:41.201963 | TASK [fetch-output : Set log path for multiple nodes] 2026-06-16 00:53:41.259090 | rockylinux-9 | skipping: Conditional result was False 2026-06-16 00:53:41.267353 | 2026-06-16 00:53:41.267496 | TASK [fetch-output : Set log path for single node] 2026-06-16 00:53:41.311991 | rockylinux-9 | ok 2026-06-16 00:53:41.319778 | 2026-06-16 00:53:41.319887 | LOOP [fetch-output : Ensure local output dirs] 2026-06-16 00:53:41.999456 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/work/logs" 2026-06-16 00:53:42.222264 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/work/artifacts" 2026-06-16 00:53:42.429459 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/d2327bb6607a4dad83a7fca0a4c13c5b/work/docs" 2026-06-16 00:53:42.453499 | 2026-06-16 00:53:42.453638 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-06-16 00:53:43.161191 | rockylinux-9 | ok 2026-06-16 00:53:43.161383 | rockylinux-9 | changed: All items complete 2026-06-16 00:53:43.161410 | 2026-06-16 00:53:44.119528 | rockylinux-9 | changed: .d..t...... ./ 2026-06-16 00:53:44.642553 | rockylinux-9 | changed: .d..t...... ./ 2026-06-16 00:53:44.667603 | 2026-06-16 00:53:44.667757 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-06-16 00:53:45.229802 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.107114 2026-06-16 00:53:45.582706 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.009582 2026-06-16 00:53:45.603681 | 2026-06-16 00:53:45.603830 | PLAY [all] 2026-06-16 00:53:45.611289 | 2026-06-16 00:53:45.611376 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-06-16 00:53:46.097199 | rockylinux-9 | changed 2026-06-16 00:53:46.105011 | 2026-06-16 00:53:46.105107 | PLAY RECAP 2026-06-16 00:53:46.105155 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-06-16 00:53:46.105177 | 2026-06-16 00:53:46.293990 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-06-16 00:53:46.297103 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-06-16 00:53:47.078958 | 2026-06-16 00:53:48.328383 | PLAY [localhost] 2026-06-16 00:53:48.350604 | 2026-06-16 00:53:48.350804 | TASK [Generate Zuul manifest] 2026-06-16 00:53:48.373312 | localhost | ok 2026-06-16 00:53:48.513124 | 2026-06-16 00:53:48.513301 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-06-16 00:53:49.099319 | localhost | changed 2026-06-16 00:53:49.782888 | 2026-06-16 00:53:49.783002 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-06-16 00:53:49.956707 | localhost | ok 2026-06-16 00:53:49.966548 | 2026-06-16 00:53:49.966617 | TASK [Upload logs] 2026-06-16 00:53:49.987486 | localhost | ok 2026-06-16 00:53:50.068926 | 2026-06-16 00:53:50.069087 | TASK [Set zuul-log-path fact] 2026-06-16 00:53:50.093123 | localhost | ok 2026-06-16 00:53:50.110012 | 2026-06-16 00:53:50.110159 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-06-16 00:53:50.140558 | localhost | ok 2026-06-16 00:53:50.151527 | 2026-06-16 00:53:50.151664 | TASK [upload-logs : Create log directories] 2026-06-16 00:53:50.595621 | localhost | changed 2026-06-16 00:53:50.604714 | 2026-06-16 00:53:50.604805 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-06-16 00:53:51.060114 | localhost -> localhost | ok: Runtime: 0:00:00.004593 2026-06-16 00:53:51.066148 | 2026-06-16 00:53:51.066242 | TASK [upload-logs : Upload logs to log server] 2026-06-16 00:53:51.590994 | localhost | Output suppressed because no_log was given 2026-06-16 00:53:51.596228 | 2026-06-16 00:53:51.596320 | LOOP [upload-logs : Compress console log and json output] 2026-06-16 00:53:51.649988 | localhost | skipping: Conditional result was False 2026-06-16 00:53:51.656913 | localhost | skipping: Conditional result was False 2026-06-16 00:53:51.670410 | 2026-06-16 00:53:51.670589 | LOOP [upload-logs : Upload compressed console log and json output] 2026-06-16 00:53:51.720792 | localhost | skipping: Conditional result was False 2026-06-16 00:53:51.721127 | 2026-06-16 00:53:51.726294 | localhost | skipping: Conditional result was False 2026-06-16 00:53:51.745975 | 2026-06-16 00:53:51.746148 | LOOP [upload-logs : Upload console log and json output]