2026-03-09 15:22:43.667846 | Job console starting 2026-03-09 15:22:43.675027 | Updating git repos 2026-03-09 15:22:43.697951 | Cloning repos into workspace 2026-03-09 15:22:43.741084 | Restoring repo states 2026-03-09 15:22:43.754379 | Merging changes 2026-03-09 15:22:44.031268 | Checking out repos 2026-03-09 15:22:44.063548 | Preparing playbooks 2026-03-09 15:22:44.994184 | Running Ansible setup 2026-03-09 15:22:48.564880 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-09 15:22:49.149914 | 2026-03-09 15:22:49.153481 | PLAY [localhost] 2026-03-09 15:22:49.161668 | 2026-03-09 15:22:49.161762 | TASK [Gathering Facts] 2026-03-09 15:22:50.103271 | localhost | ok 2026-03-09 15:22:50.112609 | 2026-03-09 15:22:50.112708 | TASK [Setup log path fact] 2026-03-09 15:22:50.134861 | localhost | ok 2026-03-09 15:22:50.166907 | 2026-03-09 15:22:50.167683 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 15:22:50.198739 | localhost | ok 2026-03-09 15:22:50.208695 | 2026-03-09 15:22:50.208824 | TASK [emit-job-header : Print job information] 2026-03-09 15:22:50.252259 | # Job Information 2026-03-09 15:22:50.252489 | Ansible Version: 2.16.16 2026-03-09 15:22:50.252538 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9 2026-03-09 15:22:50.252573 | Pipeline: check 2026-03-09 15:22:50.252605 | Executor: 0a8996d2b663 2026-03-09 15:22:50.252635 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/98 2026-03-09 15:22:50.252701 | Event ID: b83b96a0-1bcb-11f1-9a32-4521dd0a5981 2026-03-09 15:22:50.257522 | 2026-03-09 15:22:50.265765 | LOOP [emit-job-header : Print node information] 2026-03-09 15:22:50.381087 | localhost | ok: 2026-03-09 15:22:50.381335 | localhost | # Node Information 2026-03-09 15:22:50.381364 | localhost | Inventory Hostname: rockylinux-9 2026-03-09 15:22:50.381389 | localhost | Hostname: np0000158182 2026-03-09 15:22:50.381409 | localhost | Username: zuul 2026-03-09 15:22:50.381431 | localhost | Distro: Rocky 9.7 2026-03-09 15:22:50.381451 | localhost | Provider: yul1 2026-03-09 15:22:50.381470 | localhost | Region: ca-ymq-1 2026-03-09 15:22:50.381489 | localhost | Label: rockylinux-9 2026-03-09 15:22:50.381508 | localhost | Product Name: OpenStack Nova 2026-03-09 15:22:50.381527 | localhost | Interface IP: 162.253.55.62 2026-03-09 15:22:50.396704 | 2026-03-09 15:22:50.396942 | TASK [log-inventory : Ensure Zuul Ansible directory exists] 2026-03-09 15:22:50.935959 | localhost -> localhost | changed 2026-03-09 15:22:50.944453 | 2026-03-09 15:22:50.944562 | TASK [log-inventory : Copy ansible inventory to logs dir] 2026-03-09 15:22:51.904714 | localhost -> localhost | changed 2026-03-09 15:22:51.915244 | 2026-03-09 15:22:51.915341 | PLAY [all] 2026-03-09 15:22:51.937761 | 2026-03-09 15:22:51.943148 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build] 2026-03-09 15:22:52.236412 | rockylinux-9 -> localhost | ok 2026-03-09 15:22:52.243926 | 2026-03-09 15:22:52.244014 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID] 2026-03-09 15:22:52.276538 | rockylinux-9 | ok 2026-03-09 15:22:52.297976 | rockylinux-9 | included: /var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml 2026-03-09 15:22:52.304394 | 2026-03-09 15:22:52.304900 | TASK [add-build-sshkey : Create Temp SSH key] 2026-03-09 15:22:53.164612 | rockylinux-9 -> localhost | Generating public/private rsa key pair. 2026-03-09 15:22:53.164785 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/work/f155c2b03a1c4449a013dce34accdc3c_id_rsa 2026-03-09 15:22:53.164815 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/work/f155c2b03a1c4449a013dce34accdc3c_id_rsa.pub 2026-03-09 15:22:53.164838 | rockylinux-9 -> localhost | The key fingerprint is: 2026-03-09 15:22:53.164860 | rockylinux-9 -> localhost | SHA256:b9YpZJhttkZZL1UGFJzLmT6MTSGC7xbmIsX0FA0Y7e8 zuul-build-sshkey 2026-03-09 15:22:53.164889 | rockylinux-9 -> localhost | The key's randomart image is: 2026-03-09 15:22:53.164910 | rockylinux-9 -> localhost | +---[RSA 3072]----+ 2026-03-09 15:22:53.164936 | rockylinux-9 -> localhost | | .=o+ o++o| 2026-03-09 15:22:53.164962 | rockylinux-9 -> localhost | | + + o +o | 2026-03-09 15:22:53.164999 | rockylinux-9 -> localhost | | o = ..o.= | 2026-03-09 15:22:53.165020 | rockylinux-9 -> localhost | | o+*o o* | 2026-03-09 15:22:53.165040 | rockylinux-9 -> localhost | | .S+Oo.*. | 2026-03-09 15:22:53.165060 | rockylinux-9 -> localhost | | . .B+ooo= | 2026-03-09 15:22:53.165079 | rockylinux-9 -> localhost | | . oB.o . | 2026-03-09 15:22:53.165099 | rockylinux-9 -> localhost | | + .E | 2026-03-09 15:22:53.165121 | rockylinux-9 -> localhost | | | 2026-03-09 15:22:53.165141 | rockylinux-9 -> localhost | +----[SHA256]-----+ 2026-03-09 15:22:53.165187 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.413141 2026-03-09 15:22:53.169800 | 2026-03-09 15:22:53.169863 | TASK [add-build-sshkey : Remote setup ssh keys (linux)] 2026-03-09 15:22:53.199712 | rockylinux-9 | ok 2026-03-09 15:22:53.207642 | rockylinux-9 | included: /var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml 2026-03-09 15:22:53.222425 | 2026-03-09 15:22:53.229619 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey] 2026-03-09 15:22:53.255071 | rockylinux-9 | skipping: Conditional result was False 2026-03-09 15:22:53.268634 | 2026-03-09 15:22:53.309933 | TASK [add-build-sshkey : Enable access via build key on all nodes] 2026-03-09 15:22:53.840150 | rockylinux-9 | changed 2026-03-09 15:22:53.846766 | 2026-03-09 15:22:53.846837 | TASK [add-build-sshkey : Make sure user has a .ssh] 2026-03-09 15:22:54.120339 | rockylinux-9 | ok 2026-03-09 15:22:54.125038 | 2026-03-09 15:22:54.125110 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes] 2026-03-09 15:22:55.055261 | rockylinux-9 | changed 2026-03-09 15:22:55.061698 | 2026-03-09 15:22:55.061773 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes] 2026-03-09 15:22:55.973251 | rockylinux-9 | changed 2026-03-09 15:22:55.978031 | 2026-03-09 15:22:55.978098 | TASK [add-build-sshkey : Remote setup ssh keys (windows)] 2026-03-09 15:22:55.998262 | rockylinux-9 | skipping: Conditional result was False 2026-03-09 15:22:56.007049 | 2026-03-09 15:22:56.007138 | TASK [remove-zuul-sshkey : Remove master key from local agent] 2026-03-09 15:22:56.419461 | rockylinux-9 -> localhost | changed 2026-03-09 15:22:56.429758 | 2026-03-09 15:22:56.429829 | TASK [add-build-sshkey : Add back temp key] 2026-03-09 15:22:56.741658 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/work/f155c2b03a1c4449a013dce34accdc3c_id_rsa (zuul-build-sshkey) 2026-03-09 15:22:56.741912 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.011641 2026-03-09 15:22:56.748257 | 2026-03-09 15:22:56.748321 | TASK [add-build-sshkey : Verify we can still SSH to all nodes] 2026-03-09 15:22:57.125645 | rockylinux-9 | ok 2026-03-09 15:22:57.130057 | 2026-03-09 15:22:57.130122 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)] 2026-03-09 15:22:57.165525 | rockylinux-9 | skipping: Conditional result was False 2026-03-09 15:22:57.175805 | 2026-03-09 15:22:57.175875 | TASK [prepare-workspace : Start zuul_console daemon.] 2026-03-09 15:22:57.617793 | rockylinux-9 | ok 2026-03-09 15:22:57.626160 | 2026-03-09 15:22:57.626266 | TASK [prepare-workspace : Synchronize src repos to workspace directory.] 2026-03-09 15:22:58.623621 | rockylinux-9 | Output suppressed because no_log was given 2026-03-09 15:22:58.633971 | 2026-03-09 15:22:58.634036 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them] 2026-03-09 15:22:58.879747 | rockylinux-9 | ok: "logs" 2026-03-09 15:22:58.880041 | rockylinux-9 | ok: All items complete 2026-03-09 15:22:58.880080 | 2026-03-09 15:22:59.105519 | rockylinux-9 | ok: "artifacts" 2026-03-09 15:22:59.328328 | rockylinux-9 | ok: "docs" 2026-03-09 15:22:59.389350 | 2026-03-09 15:22:59.389533 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist] 2026-03-09 15:22:59.654231 | rockylinux-9 | changed: "logs" 2026-03-09 15:22:59.875593 | rockylinux-9 | changed: "artifacts" 2026-03-09 15:23:00.097797 | rockylinux-9 | changed: "docs" 2026-03-09 15:23:00.109721 | 2026-03-09 15:23:00.109864 | PLAY RECAP 2026-03-09 15:23:00.109915 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 15:23:00.109948 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0 2026-03-09 15:23:00.109970 | 2026-03-09 15:23:00.333606 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main] 2026-03-09 15:23:00.339198 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-09 15:23:01.058162 | 2026-03-09 15:23:01.058312 | PLAY [all] 2026-03-09 15:23:01.069727 | 2026-03-09 15:23:01.069808 | TASK [setup-uv : Extract archive] 2026-03-09 15:23:03.896854 | rockylinux-9 | changed 2026-03-09 15:23:03.904016 | 2026-03-09 15:23:03.904100 | TASK [setup-uv : Print version] 2026-03-09 15:23:04.004497 | [rockylinux-9] Waiting on logger 2026-03-09 15:23:04.519112 | rockylinux-9 | ok: Runtime: 0:00:00.015642 2026-03-09 15:23:04.532940 | 2026-03-09 15:23:04.533027 | PLAY RECAP 2026-03-09 15:23:04.533084 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 15:23:04.533110 | 2026-03-09 15:23:04.693158 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main] 2026-03-09 15:23:04.706022 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-09 15:23:05.365781 | 2026-03-09 15:23:05.366206 | PLAY [all] 2026-03-09 15:23:05.389687 | 2026-03-09 15:23:05.389854 | TASK [Copy inventory file for Zuul] 2026-03-09 15:23:06.856103 | rockylinux-9 | changed 2026-03-09 15:23:06.860830 | 2026-03-09 15:23:06.860902 | TASK [Switch "ansible_host" to private IP] 2026-03-09 15:23:07.201481 | rockylinux-9 | changed: 1 replacements made 2026-03-09 15:23:07.208875 | 2026-03-09 15:23:07.208945 | TASK [Run Molecule scenario] 2026-03-09 15:23:07.276911 | [rockylinux-9] Waiting on logger 2026-03-09 15:23:15.433964 | [rockylinux-9] Waiting on logger 2026-03-09 15:23:25.605803 | [rockylinux-9] Waiting on logger 2026-03-09 15:23:35.813902 | [rockylinux-9] Waiting on logger 2026-03-09 15:23:45.989951 | [rockylinux-9] Waiting on logger 2026-03-09 15:23:55.142087 | [rockylinux-9] Waiting on logger 2026-03-09 15:24:05.317826 | [rockylinux-9] Waiting on logger 2026-03-09 15:24:15.494069 | [rockylinux-9] Waiting on logger 2026-03-09 15:24:25.670153 | [rockylinux-9] Waiting on logger 2026-03-09 15:24:35.019377 | [rockylinux-9] Waiting on logger 2026-03-09 15:24:45.193974 | [rockylinux-9] Waiting on logger 2026-03-09 15:24:55.366122 | [rockylinux-9] Waiting on logger 2026-03-09 15:25:05.542208 | [rockylinux-9] Waiting on logger 2026-03-09 15:25:15.718008 | [rockylinux-9] Waiting on logger 2026-03-09 15:25:25.894173 | [rockylinux-9] Waiting on logger 2026-03-09 15:25:35.051619 | [rockylinux-9] Waiting on logger 2026-03-09 15:25:45.222038 | [rockylinux-9] Waiting on logger 2026-03-09 15:25:55.398072 | [rockylinux-9] Waiting on logger 2026-03-09 15:26:05.574265 | [rockylinux-9] Waiting on logger 2026-03-09 15:26:15.749866 | [rockylinux-9] Waiting on logger 2026-03-09 15:26:25.925947 | [rockylinux-9] Waiting on logger 2026-03-09 15:26:35.087975 | [rockylinux-9] Waiting on logger 2026-03-09 15:26:45.254354 | [rockylinux-9] Waiting on logger 2026-03-09 15:26:55.430003 | [rockylinux-9] Waiting on logger 2026-03-09 15:27:05.606118 | [rockylinux-9] Waiting on logger 2026-03-09 15:27:15.781887 | [rockylinux-9] Waiting on logger 2026-03-09 15:27:24.850807 | rockylinux-9 | ok: Runtime: 0:04:17.069096 2026-03-09 15:27:24.856710 | 2026-03-09 15:27:24.856766 | PLAY RECAP 2026-03-09 15:27:24.856811 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0 2026-03-09 15:27:24.856833 | 2026-03-09 15:27:24.969238 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main] 2026-03-09 15:27:24.971577 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-09 15:27:25.557917 | 2026-03-09 15:27:25.558078 | PLAY [all] 2026-03-09 15:27:25.570157 | 2026-03-09 15:27:25.570250 | TASK [fetch-output : Set log path for multiple nodes] 2026-03-09 15:27:25.615862 | rockylinux-9 | skipping: Conditional result was False 2026-03-09 15:27:25.625012 | 2026-03-09 15:27:25.625094 | TASK [fetch-output : Set log path for single node] 2026-03-09 15:27:25.668097 | rockylinux-9 | ok 2026-03-09 15:27:25.673862 | 2026-03-09 15:27:25.673932 | LOOP [fetch-output : Ensure local output dirs] 2026-03-09 15:27:26.060713 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/work/logs" 2026-03-09 15:27:26.280400 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/work/artifacts" 2026-03-09 15:27:26.507608 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/f155c2b03a1c4449a013dce34accdc3c/work/docs" 2026-03-09 15:27:26.522176 | 2026-03-09 15:27:26.522337 | LOOP [fetch-output : Collect logs, artifacts and docs] 2026-03-09 15:27:27.227669 | rockylinux-9 | changed: .d..t...... ./ 2026-03-09 15:27:27.227961 | rockylinux-9 | changed: All items complete 2026-03-09 15:27:27.228006 | 2026-03-09 15:27:27.752926 | rockylinux-9 | changed: .d..t...... ./ 2026-03-09 15:27:28.260830 | rockylinux-9 | changed: .d..t...... ./ 2026-03-09 15:27:28.288337 | 2026-03-09 15:27:28.288500 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir] 2026-03-09 15:27:28.714125 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006483 2026-03-09 15:27:28.938428 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.006518 2026-03-09 15:27:28.959999 | 2026-03-09 15:27:28.962578 | PLAY [all] 2026-03-09 15:27:28.969329 | 2026-03-09 15:27:28.969414 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes] 2026-03-09 15:27:29.436449 | rockylinux-9 | changed 2026-03-09 15:27:29.443917 | 2026-03-09 15:27:29.443978 | PLAY RECAP 2026-03-09 15:27:29.444024 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0 2026-03-09 15:27:29.444048 | 2026-03-09 15:27:29.555798 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main] 2026-03-09 15:27:29.558225 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main] 2026-03-09 15:27:30.109151 | 2026-03-09 15:27:30.109277 | PLAY [localhost] 2026-03-09 15:27:30.118733 | 2026-03-09 15:27:30.118803 | TASK [Generate Zuul manifest] 2026-03-09 15:27:30.147299 | localhost | ok 2026-03-09 15:27:30.164450 | 2026-03-09 15:27:30.164525 | TASK [generate-zuul-manifest : Generate Zuul manifest] 2026-03-09 15:27:30.465562 | localhost | changed 2026-03-09 15:27:30.477325 | 2026-03-09 15:27:30.477403 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul] 2026-03-09 15:27:30.508235 | localhost | ok 2026-03-09 15:27:30.515685 | 2026-03-09 15:27:30.515748 | TASK [Upload logs] 2026-03-09 15:27:30.536173 | localhost | ok 2026-03-09 15:27:30.595352 | 2026-03-09 15:27:30.595459 | TASK [Set zuul-log-path fact] 2026-03-09 15:27:30.615848 | localhost | ok 2026-03-09 15:27:30.627823 | 2026-03-09 15:27:30.627889 | TASK [set-zuul-log-path-fact : Set log path for a build] 2026-03-09 15:27:30.659284 | localhost | ok 2026-03-09 15:27:30.671188 | 2026-03-09 15:27:30.671254 | TASK [upload-logs : Create log directories] 2026-03-09 15:27:31.100785 | localhost | changed 2026-03-09 15:27:31.107013 | 2026-03-09 15:27:31.109729 | TASK [upload-logs : Ensure logs are readable before uploading] 2026-03-09 15:27:31.530968 | localhost -> localhost | ok: Runtime: 0:00:00.004435 2026-03-09 15:27:31.536271 | 2026-03-09 15:27:31.536335 | TASK [upload-logs : Upload logs to log server] 2026-03-09 15:27:31.993999 | localhost | Output suppressed because no_log was given 2026-03-09 15:27:31.999633 | 2026-03-09 15:27:31.999726 | LOOP [upload-logs : Compress console log and json output] 2026-03-09 15:27:32.044568 | localhost | skipping: Conditional result was False 2026-03-09 15:27:32.051454 | localhost | skipping: Conditional result was False 2026-03-09 15:27:32.062969 | 2026-03-09 15:27:32.063131 | LOOP [upload-logs : Upload compressed console log and json output] 2026-03-09 15:27:32.105255 | localhost | skipping: Conditional result was False 2026-03-09 15:27:32.105652 | 2026-03-09 15:27:32.109453 | localhost | skipping: Conditional result was False 2026-03-09 15:27:32.128646 | 2026-03-09 15:27:32.128804 | LOOP [upload-logs : Upload console log and json output]