2026-04-24 01:01:43.523836 | Job console starting
2026-04-24 01:01:43.534095 | Updating git repos
2026-04-24 01:01:43.565658 | Cloning repos into workspace
2026-04-24 01:01:43.699337 | Restoring repo states
2026-04-24 01:01:43.706621 | Merging changes
2026-04-24 01:01:44.204900 | Checking out repos
2026-04-24 01:01:44.261350 | Preparing playbooks
2026-04-24 01:01:46.544884 | Running Ansible setup
2026-04-24 01:01:50.228032 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-24 01:01:50.791630 | 
2026-04-24 01:01:50.791769 | PLAY [localhost]
2026-04-24 01:01:50.799681 | 
2026-04-24 01:01:50.799752 | TASK [Gathering Facts]
2026-04-24 01:01:51.753524 | localhost | ok
2026-04-24 01:01:51.763645 | 
2026-04-24 01:01:51.763794 | TASK [Setup log path fact]
2026-04-24 01:01:51.786140 | localhost | ok
2026-04-24 01:01:51.799332 | 
2026-04-24 01:01:51.799470 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-24 01:01:51.830823 | localhost | ok
2026-04-24 01:01:51.839456 | 
2026-04-24 01:01:51.839543 | TASK [emit-job-header : Print job information]
2026-04-24 01:01:51.882137 | # Job Information
2026-04-24 01:01:51.882437 | Ansible Version: 2.16.16
2026-04-24 01:01:51.882505 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-04-24 01:01:51.882554 | Pipeline: check
2026-04-24 01:01:51.882597 | Executor: 0a8996d2b663
2026-04-24 01:01:51.882641 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/120
2026-04-24 01:01:51.882689 | Event ID: f893b090-3f78-11f1-873d-35d7e9189637
2026-04-24 01:01:51.887864 | 
2026-04-24 01:01:51.887937 | LOOP [emit-job-header : Print node information]
2026-04-24 01:01:51.972506 | localhost | ok:
2026-04-24 01:01:51.972712 | localhost | # Node Information
2026-04-24 01:01:51.972740 | localhost | Inventory Hostname: rockylinux-9
2026-04-24 01:01:51.972765 | localhost | Hostname: np0000169885
2026-04-24 01:01:51.972784 | localhost | Username: zuul
2026-04-24 01:01:51.972808 | localhost | Distro: Rocky 9.7
2026-04-24 01:01:51.972827 | localhost | Provider: yul1
2026-04-24 01:01:51.972845 | localhost | Region: ca-ymq-1
2026-04-24 01:01:51.972863 | localhost | Label: rockylinux-9
2026-04-24 01:01:51.972884 | localhost | Product Name: OpenStack Nova
2026-04-24 01:01:51.972903 | localhost | Interface IP: 199.204.45.115
2026-04-24 01:01:51.984884 | 
2026-04-24 01:01:51.985037 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-24 01:01:52.340582 | localhost -> localhost | changed
2026-04-24 01:01:52.346500 | 
2026-04-24 01:01:52.346579 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-24 01:01:53.189341 | localhost -> localhost | changed
2026-04-24 01:01:53.196409 | 
2026-04-24 01:01:53.196472 | PLAY [all]
2026-04-24 01:01:53.203934 | 
2026-04-24 01:01:53.203999 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-24 01:01:53.410685 | rockylinux-9 -> localhost | ok
2026-04-24 01:01:53.420930 | 
2026-04-24 01:01:53.421023 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-24 01:01:53.454960 | rockylinux-9 | ok
2026-04-24 01:01:53.470178 | rockylinux-9 | included: /var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-24 01:01:53.475590 | 
2026-04-24 01:01:53.475654 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-24 01:01:54.462939 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-24 01:01:54.463227 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/work/44d93139c28e423aac680824b8b15fbc_id_rsa
2026-04-24 01:01:54.463272 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/work/44d93139c28e423aac680824b8b15fbc_id_rsa.pub
2026-04-24 01:01:54.463304 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-24 01:01:54.463334 | rockylinux-9 -> localhost | SHA256:AVB7kctC5M9aL0hwvxW0wSeJlLbjmyIPzB9FnF2wEg8 zuul-build-sshkey
2026-04-24 01:01:54.463390 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-24 01:01:54.463420 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-24 01:01:54.463458 | rockylinux-9 -> localhost | |    .++.E=+o.    |
2026-04-24 01:01:54.463489 | rockylinux-9 -> localhost | |     ..+=O+=.    |
2026-04-24 01:01:54.463516 | rockylinux-9 -> localhost | |    ..+oBo*o     |
2026-04-24 01:01:54.463543 | rockylinux-9 -> localhost | |     o.*=o .     |
2026-04-24 01:01:54.463571 | rockylinux-9 -> localhost | |      .oS..      |
2026-04-24 01:01:54.463598 | rockylinux-9 -> localhost | |   o . =.+       |
2026-04-24 01:01:54.463625 | rockylinux-9 -> localhost | |    + + oo.      |
2026-04-24 01:01:54.463652 | rockylinux-9 -> localhost | |    .o..o.       |
2026-04-24 01:01:54.463682 | rockylinux-9 -> localhost | |     oo.         |
2026-04-24 01:01:54.463709 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-24 01:01:54.463791 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.583501
2026-04-24 01:01:54.470286 | 
2026-04-24 01:01:54.470371 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-24 01:01:54.504009 | rockylinux-9 | ok
2026-04-24 01:01:54.513969 | rockylinux-9 | included: /var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-24 01:01:54.522199 | 
2026-04-24 01:01:54.522267 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-24 01:01:54.547778 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:01:54.559385 | 
2026-04-24 01:01:54.559459 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-24 01:01:55.072823 | rockylinux-9 | changed
2026-04-24 01:01:55.078768 | 
2026-04-24 01:01:55.078841 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-24 01:01:55.360000 | rockylinux-9 | ok
2026-04-24 01:01:55.366089 | 
2026-04-24 01:01:55.366157 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-24 01:01:56.323100 | rockylinux-9 | changed
2026-04-24 01:01:56.329301 | 
2026-04-24 01:01:56.329372 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-24 01:01:57.279352 | rockylinux-9 | changed
2026-04-24 01:01:57.284786 | 
2026-04-24 01:01:57.284855 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-24 01:01:57.320137 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:01:57.336561 | 
2026-04-24 01:01:57.336810 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-24 01:01:57.713427 | rockylinux-9 -> localhost | changed
2026-04-24 01:01:57.723630 | 
2026-04-24 01:01:57.723718 | TASK [add-build-sshkey : Add back temp key]
2026-04-24 01:01:58.001885 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/work/44d93139c28e423aac680824b8b15fbc_id_rsa (zuul-build-sshkey)
2026-04-24 01:01:58.002082 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014638
2026-04-24 01:01:58.008374 | 
2026-04-24 01:01:58.008439 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-24 01:01:58.421808 | rockylinux-9 | ok
2026-04-24 01:01:58.426132 | 
2026-04-24 01:01:58.426198 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-24 01:01:58.450495 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:01:58.462972 | 
2026-04-24 01:01:58.463040 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-24 01:01:58.900108 | rockylinux-9 | ok
2026-04-24 01:01:58.906314 | 
2026-04-24 01:01:58.906378 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-24 01:01:59.869661 | rockylinux-9 | Output suppressed because no_log was given
2026-04-24 01:01:59.878015 | 
2026-04-24 01:01:59.878093 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-24 01:02:00.154107 | rockylinux-9 | ok: "logs"
2026-04-24 01:02:00.154511 | rockylinux-9 | ok: All items complete
2026-04-24 01:02:00.154559 | 
2026-04-24 01:02:00.400368 | rockylinux-9 | ok: "artifacts"
2026-04-24 01:02:00.643855 | rockylinux-9 | ok: "docs"
2026-04-24 01:02:00.659090 | 
2026-04-24 01:02:00.659267 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-24 01:02:00.934623 | rockylinux-9 | changed: "logs"
2026-04-24 01:02:01.163828 | rockylinux-9 | changed: "artifacts"
2026-04-24 01:02:01.393666 | rockylinux-9 | changed: "docs"
2026-04-24 01:02:01.414312 | 
2026-04-24 01:02:01.414450 | PLAY RECAP
2026-04-24 01:02:01.414497 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-24 01:02:01.414526 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-24 01:02:01.414549 | 
2026-04-24 01:02:01.538222 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-24 01:02:01.540604 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-24 01:02:02.177317 | 
2026-04-24 01:02:02.177879 | PLAY [all]
2026-04-24 01:02:02.189153 | 
2026-04-24 01:02:02.190427 | TASK [setup-uv : Extract archive]
2026-04-24 01:02:05.103174 | rockylinux-9 | changed
2026-04-24 01:02:05.109319 | 
2026-04-24 01:02:05.109402 | TASK [setup-uv : Print version]
2026-04-24 01:02:05.132787 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:05.648161 | rockylinux-9 | ok: Runtime: 0:00:00.012323
2026-04-24 01:02:05.687071 | 
2026-04-24 01:02:05.687163 | PLAY RECAP
2026-04-24 01:02:05.687230 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-24 01:02:05.687265 | 
2026-04-24 01:02:05.843860 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-24 01:02:05.845427 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-24 01:02:06.433615 | 
2026-04-24 01:02:06.481242 | PLAY [all]
2026-04-24 01:02:06.496540 | 
2026-04-24 01:02:06.496621 | TASK [Copy inventory file for Zuul]
2026-04-24 01:02:07.874101 | rockylinux-9 | changed
2026-04-24 01:02:07.882122 | 
2026-04-24 01:02:07.882350 | TASK [Switch "ansible_host" to private IP]
2026-04-24 01:02:08.260742 | rockylinux-9 | changed: 1 replacements made
2026-04-24 01:02:08.270011 | 
2026-04-24 01:02:08.270110 | TASK [Run Molecule scenario]
2026-04-24 01:02:08.293166 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:16.421991 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:26.602136 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:36.778016 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:46.949977 | [rockylinux-9] Waiting on logger
2026-04-24 01:02:48.082917 | rockylinux-9 | ERROR
2026-04-24 01:02:48.083124 | rockylinux-9 | {
2026-04-24 01:02:48.083155 | rockylinux-9 |   "delta": "0:00:39.275071",
2026-04-24 01:02:48.083176 | rockylinux-9 |   "end": "2026-04-24 01:02:49.072991",
2026-04-24 01:02:48.083198 | rockylinux-9 |   "msg": "non-zero return code",
2026-04-24 01:02:48.083217 | rockylinux-9 |   "rc": 2,
2026-04-24 01:02:48.083238 | rockylinux-9 |   "start": "2026-04-24 01:02:09.797920"
2026-04-24 01:02:48.083256 | rockylinux-9 | }
failure
2026-04-24 01:02:48.086778 | 
2026-04-24 01:02:48.086829 | PLAY RECAP
2026-04-24 01:02:48.087141 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-24 01:02:48.087180 | 
2026-04-24 01:02:48.233774 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-24 01:02:48.235139 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-24 01:02:48.825817 | 
2026-04-24 01:02:48.825962 | PLAY [all]
2026-04-24 01:02:48.836728 | 
2026-04-24 01:02:48.836810 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-24 01:02:48.882316 | rockylinux-9 | skipping: Conditional result was False
2026-04-24 01:02:48.888703 | 
2026-04-24 01:02:48.888827 | TASK [fetch-output : Set log path for single node]
2026-04-24 01:02:48.932875 | rockylinux-9 | ok
2026-04-24 01:02:48.940320 | 
2026-04-24 01:02:48.940389 | LOOP [fetch-output : Ensure local output dirs]
2026-04-24 01:02:49.367986 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/work/logs"
2026-04-24 01:02:49.577780 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/work/artifacts"
2026-04-24 01:02:49.803543 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/44d93139c28e423aac680824b8b15fbc/work/docs"
2026-04-24 01:02:49.818176 | 
2026-04-24 01:02:49.818311 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-24 01:02:50.539533 | rockylinux-9 | ok
2026-04-24 01:02:50.539839 | rockylinux-9 | changed: All items complete
2026-04-24 01:02:50.539907 | 
2026-04-24 01:02:51.079183 | rockylinux-9 | changed: .d..t...... ./
2026-04-24 01:02:51.590565 | rockylinux-9 | changed: .d..t...... ./
2026-04-24 01:02:51.613878 | 
2026-04-24 01:02:51.614011 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-24 01:02:52.027394 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007100
2026-04-24 01:02:52.250354 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007946
2026-04-24 01:02:52.258673 | 
2026-04-24 01:02:52.259344 | PLAY [all]
2026-04-24 01:02:52.265015 | 
2026-04-24 01:02:52.265080 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-24 01:02:52.723210 | rockylinux-9 | changed
2026-04-24 01:02:52.729593 | 
2026-04-24 01:02:52.729643 | PLAY RECAP
2026-04-24 01:02:52.729685 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-24 01:02:52.729707 | 
2026-04-24 01:02:52.840564 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-24 01:02:52.842624 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-24 01:02:53.382085 | 
2026-04-24 01:02:53.382217 | PLAY [localhost]
2026-04-24 01:02:53.392356 | 
2026-04-24 01:02:53.392428 | TASK [Generate Zuul manifest]
2026-04-24 01:02:53.412729 | localhost | ok
2026-04-24 01:02:53.430291 | 
2026-04-24 01:02:53.430369 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-24 01:02:53.755272 | localhost | changed
2026-04-24 01:02:53.768119 | 
2026-04-24 01:02:53.768186 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-24 01:02:53.800193 | localhost | ok
2026-04-24 01:02:53.808295 | 
2026-04-24 01:02:53.808358 | TASK [Upload logs]
2026-04-24 01:02:53.829169 | localhost | ok
2026-04-24 01:02:53.887113 | 
2026-04-24 01:02:53.887244 | TASK [Set zuul-log-path fact]
2026-04-24 01:02:53.908418 | localhost | ok
2026-04-24 01:02:53.920409 | 
2026-04-24 01:02:53.920475 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-24 01:02:53.952089 | localhost | ok
2026-04-24 01:02:53.961876 | 
2026-04-24 01:02:53.961953 | TASK [upload-logs : Create log directories]
2026-04-24 01:02:54.371789 | localhost | changed
2026-04-24 01:02:54.378690 | 
2026-04-24 01:02:54.378826 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-24 01:02:54.786370 | localhost -> localhost | ok: Runtime: 0:00:00.005728
2026-04-24 01:02:54.792360 | 
2026-04-24 01:02:54.792427 | TASK [upload-logs : Upload logs to log server]
2026-04-24 01:02:55.237933 | localhost | Output suppressed because no_log was given
2026-04-24 01:02:55.241925 | 
2026-04-24 01:02:55.241993 | LOOP [upload-logs : Compress console log and json output]
2026-04-24 01:02:55.291002 | localhost | skipping: Conditional result was False
2026-04-24 01:02:55.297620 | localhost | skipping: Conditional result was False
2026-04-24 01:02:55.313503 | 
2026-04-24 01:02:55.313679 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-24 01:02:55.360482 | localhost | skipping: Conditional result was False
2026-04-24 01:02:55.360935 | 
2026-04-24 01:02:55.364538 | localhost | skipping: Conditional result was False
2026-04-24 01:02:55.379191 | 
2026-04-24 01:02:55.379347 | LOOP [upload-logs : Upload console log and json output]