2026-04-29 20:48:39.230846 | Job console starting
2026-04-29 20:48:39.245155 | Updating git repos
2026-04-29 20:48:39.279924 | Cloning repos into workspace
2026-04-29 20:48:39.499435 | Restoring repo states
2026-04-29 20:48:39.505137 | Merging changes
2026-04-29 20:48:39.972845 | Checking out repos
2026-04-29 20:48:40.086203 | Preparing playbooks
2026-04-29 20:48:42.452173 | Running Ansible setup
2026-04-29 20:48:46.335366 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-29 20:48:46.999553 | 
2026-04-29 20:48:46.999692 | PLAY [localhost]
2026-04-29 20:48:47.007299 | 
2026-04-29 20:48:47.007369 | TASK [Gathering Facts]
2026-04-29 20:48:47.979062 | localhost | ok
2026-04-29 20:48:47.986238 | 
2026-04-29 20:48:47.986315 | TASK [Setup log path fact]
2026-04-29 20:48:48.004321 | localhost | ok
2026-04-29 20:48:48.015354 | 
2026-04-29 20:48:48.015453 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-29 20:48:48.043754 | localhost | ok
2026-04-29 20:48:48.050793 | 
2026-04-29 20:48:48.050903 | TASK [emit-job-header : Print job information]
2026-04-29 20:48:48.091891 | # Job Information
2026-04-29 20:48:48.092119 | Ansible Version: 2.16.18
2026-04-29 20:48:48.092168 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-04-29 20:48:48.092203 | Pipeline: check
2026-04-29 20:48:48.092234 | Executor: 2d72f0692154
2026-04-29 20:48:48.092264 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126
2026-04-29 20:48:48.092298 | Event ID: 9b4e1cc0-440c-11f1-90ca-0e5250fcd001
2026-04-29 20:48:48.097185 | 
2026-04-29 20:48:48.097290 | LOOP [emit-job-header : Print node information]
2026-04-29 20:48:48.194554 | localhost | ok:
2026-04-29 20:48:48.194733 | localhost | # Node Information
2026-04-29 20:48:48.194763 | localhost | Inventory Hostname: rockylinux-9
2026-04-29 20:48:48.194786 | localhost | Hostname: np0000171278
2026-04-29 20:48:48.194806 | localhost | Username: zuul
2026-04-29 20:48:48.194830 | localhost | Distro: Rocky 9.7
2026-04-29 20:48:48.194850 | localhost | Provider: yul1
2026-04-29 20:48:48.194870 | localhost | Region: ca-ymq-1
2026-04-29 20:48:48.194889 | localhost | Label: rockylinux-9
2026-04-29 20:48:48.194908 | localhost | Product Name: OpenStack Nova
2026-04-29 20:48:48.194927 | localhost | Interface IP: 199.204.45.240
2026-04-29 20:48:48.208489 | 
2026-04-29 20:48:48.208623 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-29 20:48:48.708013 | localhost -> localhost | changed
2026-04-29 20:48:48.715307 | 
2026-04-29 20:48:48.715395 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-29 20:48:49.684321 | localhost -> localhost | changed
2026-04-29 20:48:49.694120 | 
2026-04-29 20:48:49.694259 | PLAY [all]
2026-04-29 20:48:49.702457 | 
2026-04-29 20:48:49.702622 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-29 20:48:49.985882 | rockylinux-9 -> localhost | ok
2026-04-29 20:48:49.995118 | 
2026-04-29 20:48:49.995194 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-29 20:48:50.029491 | rockylinux-9 | ok
2026-04-29 20:48:50.043540 | rockylinux-9 | included: /var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-29 20:48:50.050308 | 
2026-04-29 20:48:50.050379 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-29 20:48:51.578152 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-04-29 20:48:51.578332 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/work/9d44a69876594532a63b91a0f26b5603_id_rsa
2026-04-29 20:48:51.578363 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/work/9d44a69876594532a63b91a0f26b5603_id_rsa.pub
2026-04-29 20:48:51.578385 | rockylinux-9 -> localhost | The key fingerprint is:
2026-04-29 20:48:51.578407 | rockylinux-9 -> localhost | SHA256:TWcS/LV0Ul0RMAS6dwduTRjMeVZKvIqJjiGdJcWlEOM zuul-build-sshkey
2026-04-29 20:48:51.578442 | rockylinux-9 -> localhost | The key's randomart image is:
2026-04-29 20:48:51.578464 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-04-29 20:48:51.578491 | rockylinux-9 -> localhost | |      +o oo.=*++X|
2026-04-29 20:48:51.578513 | rockylinux-9 -> localhost | |     . oo.o. =O+o|
2026-04-29 20:48:51.578545 | rockylinux-9 -> localhost | |      E...o.o=+* |
2026-04-29 20:48:51.578567 | rockylinux-9 -> localhost | |      . .o.+o *  |
2026-04-29 20:48:51.578588 | rockylinux-9 -> localhost | |     . +So.+ = o |
2026-04-29 20:48:51.578609 | rockylinux-9 -> localhost | |    . + . + + .  |
2026-04-29 20:48:51.578629 | rockylinux-9 -> localhost | |     . +         |
2026-04-29 20:48:51.578650 | rockylinux-9 -> localhost | |      . .        |
2026-04-29 20:48:51.578673 | rockylinux-9 -> localhost | |                 |
2026-04-29 20:48:51.578694 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-04-29 20:48:51.578759 | rockylinux-9 -> localhost | ok: Runtime: 0:00:01.091732
2026-04-29 20:48:51.584497 | 
2026-04-29 20:48:51.584561 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-29 20:48:51.615247 | rockylinux-9 | ok
2026-04-29 20:48:51.626016 | rockylinux-9 | included: /var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-29 20:48:51.635789 | 
2026-04-29 20:48:51.635869 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-29 20:48:51.661626 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:48:51.671423 | 
2026-04-29 20:48:51.671524 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-29 20:48:52.214050 | rockylinux-9 | changed
2026-04-29 20:48:52.219575 | 
2026-04-29 20:48:52.219649 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-29 20:48:52.498419 | rockylinux-9 | ok
2026-04-29 20:48:52.634591 | 
2026-04-29 20:48:52.634707 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-29 20:48:53.567753 | rockylinux-9 | changed
2026-04-29 20:48:53.573964 | 
2026-04-29 20:48:53.574036 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-29 20:48:54.543676 | rockylinux-9 | changed
2026-04-29 20:48:54.548111 | 
2026-04-29 20:48:54.548172 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-29 20:48:54.573713 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:48:54.587568 | 
2026-04-29 20:48:54.587641 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-29 20:48:54.947434 | rockylinux-9 -> localhost | changed
2026-04-29 20:48:54.959938 | 
2026-04-29 20:48:54.960006 | TASK [add-build-sshkey : Add back temp key]
2026-04-29 20:48:55.225911 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/work/9d44a69876594532a63b91a0f26b5603_id_rsa (zuul-build-sshkey)
2026-04-29 20:48:55.226179 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.014726
2026-04-29 20:48:55.232806 | 
2026-04-29 20:48:55.232939 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-29 20:48:55.614451 | rockylinux-9 | ok
2026-04-29 20:48:55.618638 | 
2026-04-29 20:48:55.618704 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-29 20:48:55.643588 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:48:55.659753 | 
2026-04-29 20:48:55.659923 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-29 20:48:56.071620 | rockylinux-9 | ok
2026-04-29 20:48:56.077072 | 
2026-04-29 20:48:56.077136 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-29 20:48:57.067830 | rockylinux-9 | Output suppressed because no_log was given
2026-04-29 20:48:57.077207 | 
2026-04-29 20:48:57.077276 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-29 20:48:57.338960 | rockylinux-9 | ok: "logs"
2026-04-29 20:48:57.339219 | rockylinux-9 | ok: All items complete
2026-04-29 20:48:57.339248 | 
2026-04-29 20:48:57.568341 | rockylinux-9 | ok: "artifacts"
2026-04-29 20:48:57.799930 | rockylinux-9 | ok: "docs"
2026-04-29 20:48:57.812621 | 
2026-04-29 20:48:57.812780 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-29 20:48:58.077775 | rockylinux-9 | changed: "logs"
2026-04-29 20:48:58.360835 | rockylinux-9 | changed: "artifacts"
2026-04-29 20:48:58.558217 | rockylinux-9 | changed: "docs"
2026-04-29 20:48:58.568810 | 
2026-04-29 20:48:58.568940 | PLAY RECAP
2026-04-29 20:48:58.569026 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-29 20:48:58.569060 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-29 20:48:58.569083 | 
2026-04-29 20:48:58.710936 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-29 20:48:58.713332 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-29 20:48:59.309023 | 
2026-04-29 20:48:59.309162 | PLAY [all]
2026-04-29 20:48:59.320330 | 
2026-04-29 20:48:59.320415 | TASK [setup-uv : Extract archive]
2026-04-29 20:49:02.167379 | rockylinux-9 | changed
2026-04-29 20:49:02.175042 | 
2026-04-29 20:49:02.175158 | TASK [setup-uv : Print version]
2026-04-29 20:49:02.198137 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:02.710962 | rockylinux-9 | ok: Runtime: 0:00:00.014437
2026-04-29 20:49:02.717782 | 
2026-04-29 20:49:02.717832 | PLAY RECAP
2026-04-29 20:49:02.717882 | rockylinux-9 | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-29 20:49:02.717905 | 
2026-04-29 20:49:02.894880 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-29 20:49:02.898344 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-29 20:49:03.479442 | 
2026-04-29 20:49:03.686705 | PLAY [all]
2026-04-29 20:49:03.704324 | 
2026-04-29 20:49:03.704458 | TASK [Copy inventory file for Zuul]
2026-04-29 20:49:05.124018 | rockylinux-9 | changed
2026-04-29 20:49:05.131967 | 
2026-04-29 20:49:05.132220 | TASK [Switch "ansible_host" to private IP]
2026-04-29 20:49:05.540843 | rockylinux-9 | changed: 1 replacements made
2026-04-29 20:49:05.548375 | 
2026-04-29 20:49:05.548474 | TASK [Run Molecule scenario]
2026-04-29 20:49:05.573377 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:13.701871 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:23.877839 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:34.053655 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:43.210999 | [rockylinux-9] Waiting on logger
2026-04-29 20:49:53.382006 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:03.558124 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:13.733872 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:23.909776 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:34.090180 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:43.243378 | [rockylinux-9] Waiting on logger
2026-04-29 20:50:53.414340 | [rockylinux-9] Waiting on logger
2026-04-29 20:51:03.590018 | [rockylinux-9] Waiting on logger
2026-04-29 20:51:13.765971 | [rockylinux-9] Waiting on logger
2026-04-29 20:51:23.941930 | [rockylinux-9] Waiting on logger
2026-04-29 20:51:34.118144 | [rockylinux-9] Waiting on logger
2026-04-29 20:51:43.275676 | [rockylinux-9] Waiting on logger
2026-04-29 20:51:44.398687 | rockylinux-9 | ok: Runtime: 0:02:38.174393
2026-04-29 20:51:44.409033 | 
2026-04-29 20:51:44.409088 | PLAY RECAP
2026-04-29 20:51:44.409135 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-29 20:51:44.409157 | 
2026-04-29 20:51:44.589836 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-29 20:51:44.591665 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-29 20:51:46.411973 | 
2026-04-29 20:51:46.412147 | PLAY [all]
2026-04-29 20:51:46.423800 | 
2026-04-29 20:51:46.423883 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-29 20:51:46.469957 | rockylinux-9 | skipping: Conditional result was False
2026-04-29 20:51:46.479536 | 
2026-04-29 20:51:46.479657 | TASK [fetch-output : Set log path for single node]
2026-04-29 20:51:46.523489 | rockylinux-9 | ok
2026-04-29 20:51:46.529463 | 
2026-04-29 20:51:46.529563 | LOOP [fetch-output : Ensure local output dirs]
2026-04-29 20:51:46.941359 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/work/logs"
2026-04-29 20:51:47.153669 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/work/artifacts"
2026-04-29 20:51:47.373901 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/9d44a69876594532a63b91a0f26b5603/work/docs"
2026-04-29 20:51:47.389257 | 
2026-04-29 20:51:47.389399 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-29 20:51:48.121268 | rockylinux-9 | ok
2026-04-29 20:51:48.121476 | rockylinux-9 | changed: All items complete
2026-04-29 20:51:48.121505 | 
2026-04-29 20:51:48.636739 | rockylinux-9 | changed: .d..t...... ./
2026-04-29 20:51:49.160904 | rockylinux-9 | changed: .d..t...... ./
2026-04-29 20:51:49.178841 | 
2026-04-29 20:51:49.179003 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-29 20:51:49.624781 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.007282
2026-04-29 20:51:49.864549 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007438
2026-04-29 20:51:49.874909 | 
2026-04-29 20:51:49.875029 | PLAY [all]
2026-04-29 20:51:49.880671 | 
2026-04-29 20:51:49.880741 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-29 20:51:50.345694 | rockylinux-9 | changed
2026-04-29 20:51:50.351408 | 
2026-04-29 20:51:50.351462 | PLAY RECAP
2026-04-29 20:51:50.351507 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-29 20:51:50.351531 | 
2026-04-29 20:51:50.476591 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-29 20:51:50.478572 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-29 20:51:51.043666 | 
2026-04-29 20:51:51.044093 | PLAY [localhost]
2026-04-29 20:51:51.054355 | 
2026-04-29 20:51:51.054426 | TASK [Generate Zuul manifest]
2026-04-29 20:51:51.075780 | localhost | ok
2026-04-29 20:51:51.091288 | 
2026-04-29 20:51:51.091363 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-29 20:51:51.483453 | localhost | changed
2026-04-29 20:51:51.495362 | 
2026-04-29 20:51:51.495480 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-29 20:51:51.528303 | localhost | ok
2026-04-29 20:51:51.537328 | 
2026-04-29 20:51:51.537398 | TASK [Upload logs]
2026-04-29 20:51:51.555755 | localhost | ok
2026-04-29 20:51:51.616364 | 
2026-04-29 20:51:51.616508 | TASK [Set zuul-log-path fact]
2026-04-29 20:51:51.640057 | localhost | ok
2026-04-29 20:51:51.654915 | 
2026-04-29 20:51:51.655041 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-29 20:51:51.689366 | localhost | ok
2026-04-29 20:51:51.699628 | 
2026-04-29 20:51:51.699706 | TASK [upload-logs : Create log directories]
2026-04-29 20:51:52.095769 | localhost | changed
2026-04-29 20:51:52.106260 | 
2026-04-29 20:51:52.106368 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-29 20:51:52.533518 | localhost -> localhost | ok: Runtime: 0:00:00.006063
2026-04-29 20:51:52.540004 | 
2026-04-29 20:51:52.540069 | TASK [upload-logs : Upload logs to log server]
2026-04-29 20:51:53.019431 | localhost | Output suppressed because no_log was given
2026-04-29 20:51:53.024399 | 
2026-04-29 20:51:53.024486 | LOOP [upload-logs : Compress console log and json output]
2026-04-29 20:51:53.071932 | localhost | skipping: Conditional result was False
2026-04-29 20:51:53.079379 | localhost | skipping: Conditional result was False
2026-04-29 20:51:53.089554 | 
2026-04-29 20:51:53.089714 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-29 20:51:53.132694 | localhost | skipping: Conditional result was False
2026-04-29 20:51:53.133079 | 
2026-04-29 20:51:53.143402 | localhost | skipping: Conditional result was False
2026-04-29 20:51:53.293583 | 
2026-04-29 20:51:53.293784 | LOOP [upload-logs : Upload console log and json output]