2026-05-21 02:31:35.560426 | Job console starting
2026-05-21 02:31:35.572029 | Updating git repos
2026-05-21 02:31:35.615327 | Cloning repos into workspace
2026-05-21 02:31:36.231246 | Restoring repo states
2026-05-21 02:31:36.239474 | Merging changes
2026-05-21 02:31:37.223467 | Checking out repos
2026-05-21 02:31:37.291153 | Preparing playbooks
2026-05-21 02:31:40.683953 | Running Ansible setup
2026-05-21 02:31:45.511856 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:31:46.197002 | 
2026-05-21 02:31:46.197195 | PLAY [localhost]
2026-05-21 02:31:46.211458 | 
2026-05-21 02:31:46.211667 | TASK [Gathering Facts]
2026-05-21 02:31:47.232081 | localhost | ok
2026-05-21 02:31:47.251524 | 
2026-05-21 02:31:47.251692 | TASK [Setup log path fact]
2026-05-21 02:31:47.273368 | localhost | ok
2026-05-21 02:31:47.286107 | 
2026-05-21 02:31:47.286222 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:31:47.314443 | localhost | ok
2026-05-21 02:31:47.331298 | 
2026-05-21 02:31:47.331496 | TASK [emit-job-header : Print job information]
2026-05-21 02:31:47.396539 | # Job Information
2026-05-21 02:31:47.397255 | Ansible Version: 2.16.18
2026-05-21 02:31:47.397321 | Job: ansible-collection-containers-molecule-docker-rockylinux-9
2026-05-21 02:31:47.397359 | Pipeline: check
2026-05-21 02:31:47.397390 | Executor: 2d72f0692154
2026-05-21 02:31:47.397420 | Triggered by: https://github.com/vexxhost/ansible-collection-containers/pull/126
2026-05-21 02:31:47.397453 | Event ID: fae3e460-54bc-11f1-8133-32d0a9e16fea
2026-05-21 02:31:47.404085 | 
2026-05-21 02:31:47.404177 | LOOP [emit-job-header : Print node information]
2026-05-21 02:31:47.515115 | localhost | ok:
2026-05-21 02:31:47.515374 | localhost | # Node Information
2026-05-21 02:31:47.515419 | localhost | Inventory Hostname: rockylinux-9
2026-05-21 02:31:47.515448 | localhost | Hostname: np0000175693
2026-05-21 02:31:47.515473 | localhost | Username: zuul
2026-05-21 02:31:47.515504 | localhost | Distro: Rocky 9.7
2026-05-21 02:31:47.515530 | localhost | Provider: yul1
2026-05-21 02:31:47.515554 | localhost | Region: ca-ymq-1
2026-05-21 02:31:47.515578 | localhost | Label: rockylinux-9
2026-05-21 02:31:47.515601 | localhost | Product Name: OpenStack Nova
2026-05-21 02:31:47.515624 | localhost | Interface IP: 199.19.213.185
2026-05-21 02:31:47.522815 | 
2026-05-21 02:31:47.522962 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-05-21 02:31:47.995182 | localhost -> localhost | changed
2026-05-21 02:31:48.003598 | 
2026-05-21 02:31:48.003752 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-05-21 02:31:48.958900 | localhost -> localhost | changed
2026-05-21 02:31:48.970123 | 
2026-05-21 02:31:48.970221 | PLAY [all]
2026-05-21 02:31:48.982712 | 
2026-05-21 02:31:48.982862 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-05-21 02:31:49.235226 | rockylinux-9 -> localhost | ok
2026-05-21 02:31:49.241403 | 
2026-05-21 02:31:49.241476 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-05-21 02:31:49.260133 | rockylinux-9 | ok
2026-05-21 02:31:49.274368 | rockylinux-9 | included: /var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-05-21 02:31:49.282019 | 
2026-05-21 02:31:49.282108 | TASK [add-build-sshkey : Create Temp SSH key]
2026-05-21 02:31:49.953660 | rockylinux-9 -> localhost | Generating public/private rsa key pair.
2026-05-21 02:31:49.953816 | rockylinux-9 -> localhost | Your identification has been saved in /var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/work/a797985517ab41a8b3dbf1f9d79dc661_id_rsa
2026-05-21 02:31:49.953848 | rockylinux-9 -> localhost | Your public key has been saved in /var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/work/a797985517ab41a8b3dbf1f9d79dc661_id_rsa.pub
2026-05-21 02:31:49.953872 | rockylinux-9 -> localhost | The key fingerprint is:
2026-05-21 02:31:49.953894 | rockylinux-9 -> localhost | SHA256:2RIzSlXasCj4n8tySX0/vdy4t2RxhEQuurCGNalPRNI zuul-build-sshkey
2026-05-21 02:31:49.953930 | rockylinux-9 -> localhost | The key's randomart image is:
2026-05-21 02:31:49.953952 | rockylinux-9 -> localhost | +---[RSA 3072]----+
2026-05-21 02:31:49.953978 | rockylinux-9 -> localhost | |        o..  .o  |
2026-05-21 02:31:49.954000 | rockylinux-9 -> localhost | |   .   + =   o . |
2026-05-21 02:31:49.954021 | rockylinux-9 -> localhost | |  . . + E . . o .|
2026-05-21 02:31:49.954042 | rockylinux-9 -> localhost | |   . o + B . . . |
2026-05-21 02:31:49.954062 | rockylinux-9 -> localhost | |    . ..S o    ..|
2026-05-21 02:31:49.954083 | rockylinux-9 -> localhost | |     ..*.=..    o|
2026-05-21 02:31:49.954103 | rockylinux-9 -> localhost | |     .=.+... . o |
2026-05-21 02:31:49.954123 | rockylinux-9 -> localhost | |    ..o=    o.+o.|
2026-05-21 02:31:49.954146 | rockylinux-9 -> localhost | |     oo .    .==o|
2026-05-21 02:31:49.954167 | rockylinux-9 -> localhost | +----[SHA256]-----+
2026-05-21 02:31:49.954212 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.288791
2026-05-21 02:31:49.958906 | 
2026-05-21 02:31:49.958968 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-05-21 02:31:49.987644 | rockylinux-9 | ok
2026-05-21 02:31:49.996044 | rockylinux-9 | included: /var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-05-21 02:31:50.003378 | 
2026-05-21 02:31:50.003446 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-05-21 02:31:50.027184 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:50.046271 | 
2026-05-21 02:31:50.046615 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-05-21 02:31:50.524099 | rockylinux-9 | changed
2026-05-21 02:31:50.529110 | 
2026-05-21 02:31:50.529188 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-05-21 02:31:50.791612 | rockylinux-9 | ok
2026-05-21 02:31:50.806718 | 
2026-05-21 02:31:50.806873 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-05-21 02:31:51.713430 | rockylinux-9 | changed
2026-05-21 02:31:51.718978 | 
2026-05-21 02:31:51.719082 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-05-21 02:31:52.663579 | rockylinux-9 | changed
2026-05-21 02:31:52.669059 | 
2026-05-21 02:31:52.669143 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-05-21 02:31:52.701490 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:52.709467 | 
2026-05-21 02:31:52.709649 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-05-21 02:31:53.149696 | rockylinux-9 -> localhost | changed
2026-05-21 02:31:53.163469 | 
2026-05-21 02:31:53.163578 | TASK [add-build-sshkey : Add back temp key]
2026-05-21 02:31:53.456745 | rockylinux-9 -> localhost | Identity added: /var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/work/a797985517ab41a8b3dbf1f9d79dc661_id_rsa (zuul-build-sshkey)
2026-05-21 02:31:53.456989 | rockylinux-9 -> localhost | ok: Runtime: 0:00:00.017414
2026-05-21 02:31:53.462422 | 
2026-05-21 02:31:53.462489 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-05-21 02:31:53.862074 | rockylinux-9 | ok
2026-05-21 02:31:53.869252 | 
2026-05-21 02:31:53.869350 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-05-21 02:31:53.901744 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:31:53.914062 | 
2026-05-21 02:31:53.914137 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-05-21 02:31:54.351647 | rockylinux-9 | ok
2026-05-21 02:31:54.357035 | 
2026-05-21 02:31:54.357095 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-05-21 02:31:55.332198 | rockylinux-9 | Output suppressed because no_log was given
2026-05-21 02:31:55.340609 | 
2026-05-21 02:31:55.340689 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-05-21 02:31:55.602317 | rockylinux-9 | ok: "logs"
2026-05-21 02:31:55.603134 | rockylinux-9 | ok: All items complete
2026-05-21 02:31:55.603189 | 
2026-05-21 02:31:55.827679 | rockylinux-9 | ok: "artifacts"
2026-05-21 02:31:56.054545 | rockylinux-9 | ok: "docs"
2026-05-21 02:31:56.064164 | 
2026-05-21 02:31:56.064323 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-05-21 02:31:56.331594 | rockylinux-9 | changed: "logs"
2026-05-21 02:31:56.637654 | rockylinux-9 | changed: "artifacts"
2026-05-21 02:31:56.811156 | rockylinux-9 | changed: "docs"
2026-05-21 02:31:56.827753 | 
2026-05-21 02:31:56.827905 | PLAY RECAP
2026-05-21 02:31:56.827956 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:31:56.827988 | rockylinux-9 | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-05-21 02:31:56.828010 | 
2026-05-21 02:31:56.988304 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-05-21 02:31:56.990999 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:31:57.647872 | 
2026-05-21 02:31:57.676987 | PLAY [all]
2026-05-21 02:31:57.693411 | 
2026-05-21 02:31:57.693487 | TASK [setup-uv : Extract archive]
2026-05-21 02:32:00.541535 | rockylinux-9 | changed
2026-05-21 02:32:00.547319 | 
2026-05-21 02:32:00.547408 | TASK [setup-uv : Print version]
2026-05-21 02:32:00.569920 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:01.084362 | rockylinux-9 | ok: Runtime: 0:00:00.017578
2026-05-21 02:32:01.095967 | 
2026-05-21 02:32:01.096038 | TASK [Install Ansible collection siblings]
2026-05-21 02:32:01.560064 | rockylinux-9 | ok:
2026-05-21 02:32:01.560262 | rockylinux-9 | Skipping ansible.posix: no checked-out sibling
2026-05-21 02:32:01.560302 | rockylinux-9 | Skipping community.general: no checked-out sibling
2026-05-21 02:32:01.562507 | 
2026-05-21 02:32:01.562585 | PLAY RECAP
2026-05-21 02:32:01.562645 | rockylinux-9 | ok: 3 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:32:01.562698 | 
2026-05-21 02:32:01.675900 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-05-21 02:32:01.677975 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:32:02.240727 | 
2026-05-21 02:32:02.240838 | PLAY [all]
2026-05-21 02:32:02.251228 | 
2026-05-21 02:32:02.251304 | TASK [Copy inventory file for Zuul]
2026-05-21 02:32:04.256969 | rockylinux-9 | changed
2026-05-21 02:32:04.261803 | 
2026-05-21 02:32:04.261879 | TASK [Switch "ansible_host" to private IP]
2026-05-21 02:32:04.769800 | rockylinux-9 | changed: 1 replacements made
2026-05-21 02:32:04.774920 | 
2026-05-21 02:32:04.774994 | TASK [Run Molecule scenario]
2026-05-21 02:32:04.804393 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:11.914133 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:22.086016 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:32.265890 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:42.441852 | [rockylinux-9] Waiting on logger
2026-05-21 02:32:51.595385 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:01.802020 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:12.198283 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:22.373949 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:32.554783 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:41.739741 | [rockylinux-9] Waiting on logger
2026-05-21 02:33:51.909952 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:02.086217 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:12.261952 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:22.438156 | [rockylinux-9] Waiting on logger
2026-05-21 02:34:23.562180 | rockylinux-9 | ok: Runtime: 0:02:17.813930
2026-05-21 02:34:23.568698 | 
2026-05-21 02:34:23.568765 | PLAY RECAP
2026-05-21 02:34:23.568812 | rockylinux-9 | ok: 3 changed: 3 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-05-21 02:34:23.568836 | 
2026-05-21 02:34:23.726960 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-05-21 02:34:23.729774 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:34:24.436935 | 
2026-05-21 02:34:24.437102 | PLAY [all]
2026-05-21 02:34:24.448431 | 
2026-05-21 02:34:24.448553 | TASK [fetch-output : Set log path for multiple nodes]
2026-05-21 02:34:24.493826 | rockylinux-9 | skipping: Conditional result was False
2026-05-21 02:34:24.502816 | 
2026-05-21 02:34:24.502985 | TASK [fetch-output : Set log path for single node]
2026-05-21 02:34:24.538692 | rockylinux-9 | ok
2026-05-21 02:34:24.545144 | 
2026-05-21 02:34:24.545226 | LOOP [fetch-output : Ensure local output dirs]
2026-05-21 02:34:24.967024 | rockylinux-9 -> localhost | ok: "/var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/work/logs"
2026-05-21 02:34:25.255795 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/work/artifacts"
2026-05-21 02:34:25.518055 | rockylinux-9 -> localhost | changed: "/var/lib/zuul/builds/a797985517ab41a8b3dbf1f9d79dc661/work/docs"
2026-05-21 02:34:25.537193 | 
2026-05-21 02:34:25.537364 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-05-21 02:34:26.224669 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:26.224987 | rockylinux-9 | changed: All items complete
2026-05-21 02:34:26.225046 | 
2026-05-21 02:34:26.748987 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:27.281388 | rockylinux-9 | changed: .d..t...... ./
2026-05-21 02:34:27.304336 | 
2026-05-21 02:34:27.304494 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-05-21 02:34:27.700432 | rockylinux-9 -> localhost | ok: Item: artifacts Runtime: 0:00:00.006982
2026-05-21 02:34:27.907446 | rockylinux-9 -> localhost | ok: Item: docs Runtime: 0:00:00.007580
2026-05-21 02:34:27.919931 | 
2026-05-21 02:34:27.920051 | PLAY [all]
2026-05-21 02:34:27.926374 | 
2026-05-21 02:34:27.926442 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-05-21 02:34:28.429886 | rockylinux-9 | changed
2026-05-21 02:34:28.435358 | 
2026-05-21 02:34:28.435410 | PLAY RECAP
2026-05-21 02:34:28.435458 | rockylinux-9 | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-05-21 02:34:28.435482 | 
2026-05-21 02:34:28.703761 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-05-21 02:34:28.706173 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-05-21 02:34:29.298393 | 
2026-05-21 02:34:29.298550 | PLAY [localhost]
2026-05-21 02:34:29.309614 | 
2026-05-21 02:34:29.309688 | TASK [Generate Zuul manifest]
2026-05-21 02:34:29.331760 | localhost | ok
2026-05-21 02:34:29.347200 | 
2026-05-21 02:34:29.347280 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-05-21 02:34:29.745443 | localhost | changed
2026-05-21 02:34:29.758315 | 
2026-05-21 02:34:29.758415 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-05-21 02:34:29.793157 | localhost | ok
2026-05-21 02:34:29.802232 | 
2026-05-21 02:34:29.802324 | TASK [Upload logs]
2026-05-21 02:34:29.841091 | localhost | ok
2026-05-21 02:34:29.900610 | 
2026-05-21 02:34:29.900732 | TASK [Set zuul-log-path fact]
2026-05-21 02:34:29.922554 | localhost | ok
2026-05-21 02:34:29.933643 | 
2026-05-21 02:34:29.933708 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-05-21 02:34:29.967579 | localhost | ok
2026-05-21 02:34:29.977696 | 
2026-05-21 02:34:29.977783 | TASK [upload-logs : Create log directories]
2026-05-21 02:34:30.374960 | localhost | changed
2026-05-21 02:34:30.383116 | 
2026-05-21 02:34:30.383224 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-05-21 02:34:30.771465 | localhost -> localhost | ok: Runtime: 0:00:00.005051
2026-05-21 02:34:30.781921 | 
2026-05-21 02:34:30.782004 | TASK [upload-logs : Upload logs to log server]
2026-05-21 02:34:31.211878 | localhost | Output suppressed because no_log was given
2026-05-21 02:34:31.222560 | 
2026-05-21 02:34:31.222682 | LOOP [upload-logs : Compress console log and json output]
2026-05-21 02:34:31.279871 | localhost | skipping: Conditional result was False
2026-05-21 02:34:31.286928 | localhost | skipping: Conditional result was False
2026-05-21 02:34:31.304856 | 
2026-05-21 02:34:31.306066 | LOOP [upload-logs : Upload compressed console log and json output]
2026-05-21 02:34:31.356904 | localhost | skipping: Conditional result was False
2026-05-21 02:34:31.357373 | 
2026-05-21 02:34:31.362568 | localhost | skipping: Conditional result was False
2026-05-21 02:34:31.373204 | 
2026-05-21 02:34:31.373401 | LOOP [upload-logs : Upload console log and json output]