2026-03-12 00:41:13.479386 | Job console starting
2026-03-12 00:41:13.489081 | Updating git repos
2026-03-12 00:41:13.514886 | Cloning repos into workspace
2026-03-12 00:41:13.591607 | Restoring repo states
2026-03-12 00:41:13.600346 | Merging changes
2026-03-12 00:41:13.867556 | Checking out repos
2026-03-12 00:41:13.905929 | Preparing playbooks
2026-03-12 00:41:14.892587 | Running Ansible setup
2026-03-12 00:41:18.516879 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-12 00:41:19.121338 | 
2026-03-12 00:41:19.121523 | PLAY [localhost]
2026-03-12 00:41:19.129838 | 
2026-03-12 00:41:19.129913 | TASK [Gathering Facts]
2026-03-12 00:41:20.001818 | localhost | ok
2026-03-12 00:41:20.012230 | 
2026-03-12 00:41:20.012310 | TASK [Setup log path fact]
2026-03-12 00:41:20.031726 | localhost | ok
2026-03-12 00:41:20.045377 | 
2026-03-12 00:41:20.045445 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-12 00:41:20.089973 | localhost | ok
2026-03-12 00:41:20.214639 | 
2026-03-12 00:41:20.214768 | TASK [emit-job-header : Print job information]
2026-03-12 00:41:20.257548 | # Job Information
2026-03-12 00:41:20.257806 | Ansible Version: 2.16.16
2026-03-12 00:41:20.257877 | Job: ansible-collection-containers-molecule-containerd-rockylinux-9
2026-03-12 00:41:20.257913 | Pipeline: check
2026-03-12 00:41:20.257942 | Executor: 0a8996d2b663
2026-03-12 00:41:20.257969 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/92
2026-03-12 00:41:20.257999 | Event ID: 139b20b0-1dac-11f1-81b9-c96c44ae2f23
2026-03-12 00:41:20.261779 | 
2026-03-12 00:41:20.261874 | LOOP [emit-job-header : Print node information]
2026-03-12 00:41:20.350990 | localhost | ok:
2026-03-12 00:41:20.351563 | localhost | # Node Information
2026-03-12 00:41:20.351624 | localhost | Inventory Hostname: rockylinux-9
2026-03-12 00:41:20.351666 | localhost | Hostname: np0000158559
2026-03-12 00:41:20.351701 | localhost | Username: zuul
2026-03-12 00:41:20.351741 | localhost | Distro: Rocky 9.7
2026-03-12 00:41:20.351774 | localhost | Provider: yul1
2026-03-12 00:41:20.351808 | localhost | Region: ca-ymq-1
2026-03-12 00:41:20.351840 | localhost | Label: rockylinux-9
2026-03-12 00:41:20.351873 | localhost | Product Name: OpenStack Nova
2026-03-12 00:41:20.351915 | localhost | Interface IP: 162.253.55.49
2026-03-12 00:41:20.363488 | 
2026-03-12 00:41:20.363663 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-03-12 00:41:20.724361 | localhost -> localhost | changed
2026-03-12 00:41:20.729661 | 
2026-03-12 00:41:20.729734 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-03-12 00:41:21.546131 | localhost -> localhost | changed
2026-03-12 00:41:21.553136 | 
2026-03-12 00:41:21.553221 | PLAY [all]
2026-03-12 00:41:21.563574 | 
2026-03-12 00:41:21.563641 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-03-12 00:41:21.802747 | rockylinux-9 -> localhost | ok
2026-03-12 00:41:21.811926 | 
2026-03-12 00:41:21.812014 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-03-12 00:41:21.843876 | rockylinux-9 | ok
2026-03-12 00:41:21.859207 | rockylinux-9 | included: /var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-03-12 00:41:21.864206 | 
2026-03-12 00:41:21.864267 | TASK [add-build-sshkey : Create Temp SSH key]
2026-03-12 00:41:22.504012 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-03-12 00:41:22.504254 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/work/61ea5747cc01400fac10c09b35635737_id_rsa
2026-03-12 00:41:22.504299 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/work/61ea5747cc01400fac10c09b35635737_id_rsa.pub
2026-03-12 00:41:22.504332 | rockylinux-9 -> localhost | The key fingerprint is:
2026-03-12 00:41:22.504363 | rockylinux-9 -> localhost | SHA256:zZJMMp9MAtujtXnbeD74pccKlOG2xecaiWkI8PoZ5qc zuul-build-sshkey
2026-03-12 00:41:22.504409 | rockylinux-9 -> localhost | The key's randomart image is:
2026-03-12 00:41:22.504439 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-03-12 00:41:22.504474 | rockylinux-9 -> localhost | |    .            |
2026-03-12 00:41:22.504504 | rockylinux-9 -> localhost | |     +           |
2026-03-12 00:41:22.504532 | rockylinux-9 -> localhost | |  . . B +        |
2026-03-12 00:41:22.504560 | rockylinux-9 -> localhost | |   o o # O       |
2026-03-12 00:41:22.504589 | rockylinux-9 -> localhost | |    + o S = .    |
2026-03-12 00:41:22.504806 | rockylinux-9 -> localhost | |   . . = X +     |
2026-03-12 00:41:22.504880 | rockylinux-9 -> localhost | |  . o . O.=.o    |
2026-03-12 00:41:22.504916 | rockylinux-9 -> localhost | |   + o...+.=o    |
2026-03-12 00:41:22.504949 | rockylinux-9 -> localhost | |    Eo   .*+     |
2026-03-12 00:41:22.505048 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-03-12 00:41:22.505209 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.217736
2026-03-12 00:41:22.511810 | 
2026-03-12 00:41:22.511875 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-03-12 00:41:22.543316 | rockylinux-9 | ok
2026-03-12 00:41:22.552887 | rockylinux-9 | included: /var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-03-12 00:41:22.560550 | 
2026-03-12 00:41:22.560610 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-03-12 00:41:22.584464 | rockylinux-9 | skipping: Conditional result was False
2026-03-12 00:41:22.594265 | 
2026-03-12 00:41:22.594330 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-03-12 00:41:23.083984 | rockylinux-9 | changed
2026-03-12 00:41:23.089319 | 
2026-03-12 00:41:23.089391 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-03-12 00:41:23.357136 | rockylinux-9 | ok
2026-03-12 00:41:23.362782 | 
2026-03-12 00:41:23.362850 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-03-12 00:41:24.278560 | rockylinux-9 | changed
2026-03-12 00:41:24.286337 | 
2026-03-12 00:41:24.286408 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-03-12 00:41:25.165845 | rockylinux-9 | changed
2026-03-12 00:41:25.170649 | 
2026-03-12 00:41:25.170714 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-03-12 00:41:25.196041 | rockylinux-9 | skipping: Conditional result was False
2026-03-12 00:41:25.201494 | 
2026-03-12 00:41:25.201564 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-03-12 00:41:25.579893 | rockylinux-9 -> localhost | changed
2026-03-12 00:41:25.588769 | 
2026-03-12 00:41:25.588844 | TASK [add-build-sshkey : Add back temp key]
2026-03-12 00:41:25.862566 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/work/61ea5747cc01400fac10c09b35635737_id_rsa (zuul-build-sshkey)
2026-03-12 00:41:25.862792 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014512
2026-03-12 00:41:25.869765 | 
2026-03-12 00:41:25.869832 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-03-12 00:41:26.246054 | rockylinux-9 | ok
2026-03-12 00:41:26.251836 | 
2026-03-12 00:41:26.251962 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-03-12 00:41:26.277132 | rockylinux-9 | skipping: Conditional result was False
2026-03-12 00:41:26.289552 | 
2026-03-12 00:41:26.289615 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-03-12 00:41:26.675232 | rockylinux-9 | ok
2026-03-12 00:41:26.727327 | 
2026-03-12 00:41:26.727416 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-03-12 00:41:27.642326 | rockylinux-9 | Output suppressed because no_log was given
2026-03-12 00:41:27.650213 | 
2026-03-12 00:41:27.650282 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-03-12 00:41:27.903628 | rockylinux-9 | ok: "logs"
2026-03-12 00:41:27.967005 | rockylinux-9 | ok: All items complete
2026-03-12 00:41:27.967107 | 
2026-03-12 00:41:28.116611 | rockylinux-9 | ok: "artifacts"
2026-03-12 00:41:28.337558 | rockylinux-9 | ok: "docs"
2026-03-12 00:41:28.424050 | 
2026-03-12 00:41:28.424369 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-03-12 00:41:28.693097 | rockylinux-9 | changed: "logs"
2026-03-12 00:41:28.913226 | rockylinux-9 | changed: "artifacts"
2026-03-12 00:41:29.127599 | rockylinux-9 | changed: "docs"
2026-03-12 00:41:29.148129 | 
2026-03-12 00:41:29.148283 | PLAY RECAP
2026-03-12 00:41:29.148347 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-12 00:41:29.148388 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-03-12 00:41:29.148420 | 
2026-03-12 00:41:29.269813 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-03-12 00:41:29.271620 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-12 00:41:29.913729 | 
2026-03-12 00:41:29.913937 | PLAY [all]
2026-03-12 00:41:29.926977 | 
2026-03-12 00:41:29.927173 | TASK [setup-uv : Extract archive]
2026-03-12 00:41:32.693409 | rockylinux-9 | changed
2026-03-12 00:41:32.699770 | 
2026-03-12 00:41:32.699844 | TASK [setup-uv : Print version]
2026-03-12 00:41:32.719287 | [rockylinux-9] Waiting on logger
2026-03-12 00:41:33.134433 | rockylinux-9 | ok: Runtime: 0:00:00.014464
2026-03-12 00:41:33.146945 | 
2026-03-12 00:41:33.146992 | PLAY RECAP
2026-03-12 00:41:33.147038 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-12 00:41:33.147062 | 
2026-03-12 00:41:33.279059 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-03-12 00:41:33.281821 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-12 00:41:33.887366 | 
2026-03-12 00:41:33.887532 | PLAY [all]
2026-03-12 00:41:33.898256 | 
2026-03-12 00:41:33.961356 | TASK [Copy inventory file for Zuul]
2026-03-12 00:41:35.274661 | rockylinux-9 | changed
2026-03-12 00:41:35.279376 | 
2026-03-12 00:41:35.281581 | TASK [Switch "ansible_host" to private IP]
2026-03-12 00:41:35.623979 | rockylinux-9 | changed: 1 replacements made
2026-03-12 00:41:35.629766 | 
2026-03-12 00:41:35.629830 | TASK [Run Molecule scenario]
2026-03-12 00:41:35.650731 | [rockylinux-9] Waiting on logger
2026-03-12 00:41:42.763113 | [rockylinux-9] Waiting on logger
2026-03-12 00:41:52.966184 | [rockylinux-9] Waiting on logger
2026-03-12 00:42:03.141804 | [rockylinux-9] Waiting on logger
2026-03-12 00:42:13.317765 | [rockylinux-9] Waiting on logger
2026-03-12 00:42:23.494059 | [rockylinux-9] Waiting on logger
2026-03-12 00:42:33.669871 | [rockylinux-9] Waiting on logger
2026-03-12 00:42:42.827213 | [rockylinux-9] Waiting on logger
2026-03-12 00:42:53.001966 | [rockylinux-9] Waiting on logger
2026-03-12 00:43:03.173937 | [rockylinux-9] Waiting on logger
2026-03-12 00:43:13.350138 | [rockylinux-9] Waiting on logger
2026-03-12 00:43:23.558146 | [rockylinux-9] Waiting on logger
2026-03-12 00:43:33.734021 | [rockylinux-9] Waiting on logger
2026-03-12 00:43:42.886017 | [rockylinux-9] Waiting on logger
2026-03-12 00:43:53.062027 | [rockylinux-9] Waiting on logger
2026-03-12 00:44:03.238065 | [rockylinux-9] Waiting on logger
2026-03-12 00:44:13.573949 | [rockylinux-9] Waiting on logger
2026-03-12 00:44:23.046014 | [rockylinux-9] Waiting on logger
2026-03-12 00:44:33.222100 | [rockylinux-9] Waiting on logger
2026-03-12 00:44:43.397920 | [rockylinux-9] Waiting on logger
2026-03-12 00:44:53.574191 | [rockylinux-9] Waiting on logger
2026-03-12 00:45:02.731334 | [rockylinux-9] Waiting on logger
2026-03-12 00:45:12.905937 | [rockylinux-9] Waiting on logger
2026-03-12 00:45:23.078050 | [rockylinux-9] Waiting on logger
2026-03-12 00:45:33.258022 | [rockylinux-9] Waiting on logger
2026-03-12 00:45:43.434276 | [rockylinux-9] Waiting on logger
2026-03-12 00:45:53.606184 | [rockylinux-9] Waiting on logger
2026-03-12 00:45:58.189708 | rockylinux-9 | ok: Runtime: 0:04:21.684091
2026-03-12 00:45:58.197197 | 
2026-03-12 00:45:58.197246 | PLAY RECAP
2026-03-12 00:45:58.197291 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-03-12 00:45:58.197313 | 
2026-03-12 00:45:58.297076 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-03-12 00:45:58.298541 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-12 00:45:58.844453 | 
2026-03-12 00:45:58.844579 | PLAY [all]
2026-03-12 00:45:58.854858 | 
2026-03-12 00:45:58.854933 | TASK [fetch-output : Set log path for multiple nodes]
2026-03-12 00:45:58.899368 | rockylinux-9 | skipping: Conditional result was False
2026-03-12 00:45:58.904002 | 
2026-03-12 00:45:58.904082 | TASK [fetch-output : Set log path for single node]
2026-03-12 00:45:58.942347 | rockylinux-9 | ok
2026-03-12 00:45:58.948363 | 
2026-03-12 00:45:58.948456 | LOOP [fetch-output : Ensure local output dirs]
2026-03-12 00:45:59.307502 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/work/logs"
2026-03-12 00:45:59.509910 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/work/artifacts"
2026-03-12 00:45:59.704714 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/61ea5747cc01400fac10c09b35635737/work/docs"
2026-03-12 00:45:59.719196 | 
2026-03-12 00:45:59.719344 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-03-12 00:46:00.422677 | rockylinux-9 | changed: .d..t...... ./
2026-03-12 00:46:00.422931 | rockylinux-9 | changed: All items complete
2026-03-12 00:46:00.422986 | 
2026-03-12 00:46:00.951792 | rockylinux-9 | changed: .d..t...... ./
2026-03-12 00:46:01.462886 | rockylinux-9 | changed: .d..t...... ./
2026-03-12 00:46:01.481019 | 
2026-03-12 00:46:01.481159 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-03-12 00:46:01.955010 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.008648
2026-03-12 00:46:02.200823 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.008062
2026-03-12 00:46:02.221042 | 
2026-03-12 00:46:02.221202 | PLAY [all]
2026-03-12 00:46:02.227314 | 
2026-03-12 00:46:02.227383 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-03-12 00:46:02.731214 | rockylinux-9 | changed
2026-03-12 00:46:02.736502 | 
2026-03-12 00:46:02.736551 | PLAY RECAP
2026-03-12 00:46:02.736597 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-03-12 00:46:02.736621 | 
2026-03-12 00:46:02.880963 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-03-12 00:46:02.883021 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-03-12 00:46:03.432248 | 
2026-03-12 00:46:03.432380 | PLAY [localhost]
2026-03-12 00:46:03.442227 | 
2026-03-12 00:46:03.442300 | TASK [Generate Zuul manifest]
2026-03-12 00:46:03.465649 | localhost | ok
2026-03-12 00:46:03.481657 | 
2026-03-12 00:46:03.481737 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-03-12 00:46:03.800257 | localhost | changed
2026-03-12 00:46:03.813181 | 
2026-03-12 00:46:03.813263 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-03-12 00:46:03.843755 | localhost | ok
2026-03-12 00:46:03.851537 | 
2026-03-12 00:46:03.851599 | TASK [Upload logs]
2026-03-12 00:46:03.871059 | localhost | ok
2026-03-12 00:46:03.944938 | 
2026-03-12 00:46:03.945115 | TASK [Set zuul-log-path fact]
2026-03-12 00:46:03.966978 | localhost | ok
2026-03-12 00:46:03.980052 | 
2026-03-12 00:46:03.980118 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-03-12 00:46:04.011069 | localhost | ok
2026-03-12 00:46:04.020901 | 
2026-03-12 00:46:04.020990 | TASK [upload-logs : Create log directories]
2026-03-12 00:46:04.492821 | localhost | changed
2026-03-12 00:46:04.498865 | 
2026-03-12 00:46:04.498934 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-03-12 00:46:04.887528 | localhost -> localhost | ok: Runtime: 0:00:00.005176
2026-03-12 00:46:04.893277 | 
2026-03-12 00:46:04.893343 | TASK [upload-logs : Upload logs to log server]
2026-03-12 00:46:05.382273 | localhost | Output suppressed because no_log was given
2026-03-12 00:46:05.387186 | 
2026-03-12 00:46:05.387274 | LOOP [upload-logs : Compress console log and json output]
2026-03-12 00:46:05.432662 | localhost | skipping: Conditional result was False
2026-03-12 00:46:05.439516 | localhost | skipping: Conditional result was False
2026-03-12 00:46:05.450302 | 
2026-03-12 00:46:05.450436 | LOOP [upload-logs : Upload compressed console log and json output]
2026-03-12 00:46:05.495659 | localhost | skipping: Conditional result was False
2026-03-12 00:46:05.496024 | 
2026-03-12 00:46:05.499957 | localhost | skipping: Conditional result was False
2026-03-12 00:46:05.515514 | 
2026-03-12 00:46:05.515697 | LOOP [upload-logs : Upload console log and json output]