2026-04-18 16:15:40.821418 | Job console starting
2026-04-18 16:15:40.828576 | Updating git repos
2026-04-18 16:15:41.068830 | Cloning repos into workspace
2026-04-18 16:15:44.757847 | Restoring repo states
2026-04-18 16:15:44.766423 | Merging changes
2026-04-18 16:15:45.232288 | Checking out repos
2026-04-18 16:15:45.861368 | Preparing playbooks
2026-04-18 16:15:52.861134 | Running Ansible setup
2026-04-18 16:15:56.294450 | PRE-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 16:15:56.864473 | 
2026-04-18 16:15:56.865000 | PLAY [localhost]
2026-04-18 16:15:56.873472 | 
2026-04-18 16:15:56.873563 | TASK [Gathering Facts]
2026-04-18 16:15:57.753535 | localhost | ok
2026-04-18 16:15:57.763986 | 
2026-04-18 16:15:57.764090 | TASK [Setup log path fact]
2026-04-18 16:15:57.782848 | localhost | ok
2026-04-18 16:15:57.798534 | 
2026-04-18 16:15:57.798669 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 16:15:57.831028 | localhost | ok
2026-04-18 16:15:57.840466 | 
2026-04-18 16:15:57.840553 | TASK [emit-job-header : Print job information]
2026-04-18 16:15:57.882098 | # Job Information
2026-04-18 16:15:57.882536 | Ansible Version: 2.16.16
2026-04-18 16:15:57.882603 | Job: ansible-collection-ceph-molecule-aio-ubuntu-jammy
2026-04-18 16:15:57.882664 | Pipeline: check
2026-04-18 16:15:57.882697 | Executor: 0a8996d2b663
2026-04-18 16:15:57.882727 | Triggered by: https://github.com/vexxhost/ansible-collection-ceph/pull/103
2026-04-18 16:15:57.882761 | Event ID: bcf0b280-3b41-11f1-9349-0bdc5a4d9f14
2026-04-18 16:15:57.887148 | 
2026-04-18 16:15:57.887278 | LOOP [emit-job-header : Print node information]
2026-04-18 16:15:57.976643 | localhost | ok:
2026-04-18 16:15:57.976819 | localhost | # Node Information
2026-04-18 16:15:57.976849 | localhost | Inventory Hostname: aio
2026-04-18 16:15:57.976872 | localhost | Hostname: np0000167278
2026-04-18 16:15:57.976892 | localhost | Username: zuul
2026-04-18 16:15:57.976915 | localhost | Distro: Ubuntu 22.04
2026-04-18 16:15:57.976936 | localhost | Provider: yul1
2026-04-18 16:15:57.976975 | localhost | Region: ca-ymq-1
2026-04-18 16:15:57.976997 | localhost | Label: ubuntu-jammy
2026-04-18 16:15:57.977016 | localhost | Product Name: OpenStack Nova
2026-04-18 16:15:57.977036 | localhost | Interface IP: 199.204.45.169
2026-04-18 16:15:57.997476 | 
2026-04-18 16:15:57.997803 | TASK [log-inventory : Ensure Zuul Ansible directory exists]
2026-04-18 16:15:58.356202 | localhost -> localhost | changed
2026-04-18 16:15:58.362626 | 
2026-04-18 16:15:58.362718 | TASK [log-inventory : Copy ansible inventory to logs dir]
2026-04-18 16:15:59.624170 | localhost -> localhost | changed
2026-04-18 16:15:59.633641 | 
2026-04-18 16:15:59.633945 | PLAY [all]
2026-04-18 16:15:59.653449 | 
2026-04-18 16:15:59.653670 | TASK [add-build-sshkey : Check to see if ssh key was already created for this build]
2026-04-18 16:16:00.064991 | aio -> localhost | ok
2026-04-18 16:16:00.075482 | 
2026-04-18 16:16:00.075598 | TASK [add-build-sshkey : Create a new key in workspace based on build UUID]
2026-04-18 16:16:00.104152 | aio | ok
2026-04-18 16:16:00.118635 | aio | included: /var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/create-key-and-replace.yaml
2026-04-18 16:16:00.124071 | 
2026-04-18 16:16:00.124132 | TASK [add-build-sshkey : Create Temp SSH key]
2026-04-18 16:16:01.296946 | aio -> localhost | Generating public/private rsa key pair.
2026-04-18 16:16:01.297181 | aio -> localhost | Your identification has been saved in /var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/work/e7a1eae6bac94766ac4eff3674ba43b3_id_rsa
2026-04-18 16:16:01.297222 | aio -> localhost | Your public key has been saved in /var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/work/e7a1eae6bac94766ac4eff3674ba43b3_id_rsa.pub
2026-04-18 16:16:01.297253 | aio -> localhost | The key fingerprint is:
2026-04-18 16:16:01.297283 | aio -> localhost | SHA256:OnsbwAQfZS+fscFrgzrdzrOlP4Utb37dzkWdQbmmVGY zuul-build-sshkey
2026-04-18 16:16:01.297326 | aio -> localhost | The key's randomart image is:
2026-04-18 16:16:01.297356 | aio -> localhost | +---[RSA 3072]----+
2026-04-18 16:16:01.297391 | aio -> localhost | |    . ..o      ..|
2026-04-18 16:16:01.297420 | aio -> localhost | |     o o o    .E |
2026-04-18 16:16:01.297448 | aio -> localhost | |      o . =   +..|
2026-04-18 16:16:01.297475 | aio -> localhost | |     o   + * . o+|
2026-04-18 16:16:01.297502 | aio -> localhost | |      o S B .oo.o|
2026-04-18 16:16:01.297529 | aio -> localhost | |       = o .o.o. |
2026-04-18 16:16:01.297556 | aio -> localhost | |      = o . .+  +|
2026-04-18 16:16:01.297584 | aio -> localhost | |       +.+.o. o.=|
2026-04-18 16:16:01.297616 | aio -> localhost | |      ....=+.+.oo|
2026-04-18 16:16:01.297643 | aio -> localhost | +----[SHA256]-----+
2026-04-18 16:16:01.297711 | aio -> localhost | ok: Runtime: 0:00:00.787919
2026-04-18 16:16:01.304252 | 
2026-04-18 16:16:01.304318 | TASK [add-build-sshkey : Remote setup ssh keys (linux)]
2026-04-18 16:16:01.336935 | aio | ok
2026-04-18 16:16:01.346130 | aio | included: /var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/trusted/project_1/opendev.org/zuul/zuul-jobs/roles/add-build-sshkey/tasks/remote-linux.yaml
2026-04-18 16:16:01.353459 | 
2026-04-18 16:16:01.353527 | TASK [add-build-sshkey : Remove previously added zuul-build-sshkey]
2026-04-18 16:16:01.377230 | aio | skipping: Conditional result was False
2026-04-18 16:16:01.388489 | 
2026-04-18 16:16:01.388584 | TASK [add-build-sshkey : Enable access via build key on all nodes]
2026-04-18 16:16:01.884093 | aio | changed
2026-04-18 16:16:01.890216 | 
2026-04-18 16:16:01.890278 | TASK [add-build-sshkey : Make sure user has a .ssh]
2026-04-18 16:16:02.075033 | aio | ok
2026-04-18 16:16:03.171037 | 
2026-04-18 16:16:03.171175 | TASK [add-build-sshkey : Install build private key as SSH key on all nodes]
2026-04-18 16:16:03.629617 | aio | changed
2026-04-18 16:16:03.636317 | 
2026-04-18 16:16:03.636390 | TASK [add-build-sshkey : Install build public key as SSH key on all nodes]
2026-04-18 16:16:04.094394 | aio | changed
2026-04-18 16:16:04.101437 | 
2026-04-18 16:16:04.101521 | TASK [add-build-sshkey : Remote setup ssh keys (windows)]
2026-04-18 16:16:04.125662 | aio | skipping: Conditional result was False
2026-04-18 16:16:04.137743 | 
2026-04-18 16:16:04.137813 | TASK [remove-zuul-sshkey : Remove master key from local agent]
2026-04-18 16:16:04.516649 | aio -> localhost | changed
2026-04-18 16:16:04.530457 | 
2026-04-18 16:16:04.530531 | TASK [add-build-sshkey : Add back temp key]
2026-04-18 16:16:04.797421 | aio -> localhost | Identity added: /var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/work/e7a1eae6bac94766ac4eff3674ba43b3_id_rsa (zuul-build-sshkey)
2026-04-18 16:16:04.797863 | aio -> localhost | ok: Runtime: 0:00:00.010927
2026-04-18 16:16:04.805263 | 
2026-04-18 16:16:04.805347 | TASK [add-build-sshkey : Verify we can still SSH to all nodes]
2026-04-18 16:16:05.104497 | aio | ok
2026-04-18 16:16:05.111006 | 
2026-04-18 16:16:05.111068 | TASK [add-build-sshkey : Verify we can still SSH to all nodes (windows)]
2026-04-18 16:16:05.134747 | aio | skipping: Conditional result was False
2026-04-18 16:16:05.149190 | 
2026-04-18 16:16:05.149253 | TASK [prepare-workspace : Start zuul_console daemon.]
2026-04-18 16:16:05.465981 | aio | ok
2026-04-18 16:16:05.471933 | 
2026-04-18 16:16:05.472008 | TASK [prepare-workspace : Synchronize src repos to workspace directory.]
2026-04-18 16:16:08.250779 | aio | Output suppressed because no_log was given
2026-04-18 16:16:08.260454 | 
2026-04-18 16:16:08.260518 | LOOP [ensure-output-dirs : Empty Zuul Output directories by removing them]
2026-04-18 16:16:08.442371 | aio | ok: "logs"
2026-04-18 16:16:08.442626 | aio | ok: All items complete
2026-04-18 16:16:08.442655 | 
2026-04-18 16:16:08.598576 | aio | ok: "artifacts"
2026-04-18 16:16:08.764020 | aio | ok: "docs"
2026-04-18 16:16:08.780914 | 
2026-04-18 16:16:08.781082 | LOOP [ensure-output-dirs : Ensure Zuul Output directories exist]
2026-04-18 16:16:09.005100 | aio | changed: "logs"
2026-04-18 16:16:09.118586 | aio | changed: "artifacts"
2026-04-18 16:16:09.283318 | aio | changed: "docs"
2026-04-18 16:16:09.303524 | 
2026-04-18 16:16:09.303659 | PLAY RECAP
2026-04-18 16:16:09.303718 | aio | ok: 15 changed: 8 unreachable: 0 failed: 0 skipped: 3 rescued: 0 ignored: 0
2026-04-18 16:16:09.303758 | localhost | ok: 6 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:16:09.303787 | 
2026-04-18 16:16:09.429252 | PRE-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/pre.yaml@main]
2026-04-18 16:16:09.433228 | PRE-RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-18 16:16:10.016605 | 
2026-04-18 16:16:10.016757 | PLAY [all]
2026-04-18 16:16:10.029052 | 
2026-04-18 16:16:10.029171 | TASK [setup-uv : Extract archive]
2026-04-18 16:16:16.715853 | aio | changed
2026-04-18 16:16:17.143448 | 
2026-04-18 16:16:17.143611 | TASK [setup-uv : Print version]
2026-04-18 16:16:17.479920 | aio | uv 0.8.13
2026-04-18 16:16:17.681351 | aio | ok: Runtime: 0:00:00.013345
2026-04-18 16:16:17.688524 | 
2026-04-18 16:16:17.688569 | PLAY RECAP
2026-04-18 16:16:17.688616 | aio | ok: 2 changed: 2 unreachable: 0 failed: 0 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:16:17.688640 | 
2026-04-18 16:16:17.802016 | PRE-RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/pre.yaml@main]
2026-04-18 16:16:18.048622 | RUN START: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-18 16:16:18.589756 | 
2026-04-18 16:16:18.589889 | PLAY [all]
2026-04-18 16:16:18.600348 | 
2026-04-18 16:16:18.600424 | TASK [Copy inventory file for Zuul]
2026-04-18 16:16:19.440931 | aio | changed
2026-04-18 16:16:19.448117 | 
2026-04-18 16:16:19.448216 | TASK [Switch "ansible_host" to private IP]
2026-04-18 16:16:19.716394 | aio | changed: 1 replacements made
2026-04-18 16:16:19.933034 | 
2026-04-18 16:16:19.933227 | TASK [Run Molecule scenario]
2026-04-18 16:16:34.666720 | aio | error: Request failed after 3 retries
2026-04-18 16:16:34.666866 | aio |   Caused by: Failed to download https://github.com/astral-sh/python-build-standalone/releases/download/20250818/cpython-3.13.7%2B20250818-x86_64-unknown-linux-gnu-install_only_stripped.tar.gz
2026-04-18 16:16:34.666889 | aio |   Caused by: HTTP status server error (504 Gateway Timeout) for url (https://github.com/astral-sh/python-build-standalone/releases/download/20250818/cpython-3.13.7%2B20250818-x86_64-unknown-linux-gnu-install_only_stripped.tar.gz)
2026-04-18 16:16:35.021390 | aio | ERROR
2026-04-18 16:16:35.021713 | aio | {
2026-04-18 16:16:35.021761 | aio |   "delta": "0:00:14.403741",
2026-04-18 16:16:35.021791 | aio |   "end": "2026-04-18 16:16:34.669808",
2026-04-18 16:16:35.021818 | aio |   "msg": "non-zero return code",
2026-04-18 16:16:35.021843 | aio |   "rc": 2,
2026-04-18 16:16:35.021877 | aio |   "start": "2026-04-18 16:16:20.266067"
2026-04-18 16:16:35.021904 | aio | }
failure
2026-04-18 16:16:35.023553 | 
2026-04-18 16:16:35.023616 | PLAY RECAP
2026-04-18 16:16:35.023671 | aio | ok: 2 changed: 2 unreachable: 0 failed: 1 skipped: 0 rescued: 0 ignored: 0
2026-04-18 16:16:35.023700 | 
2026-04-18 16:16:35.126353 | RUN END RESULT_NORMAL: [untrusted : github.com/vexxhost/zuul-jobs/playbooks/molecule/run.yaml@main]
2026-04-18 16:16:35.128311 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 16:16:35.702907 | 
2026-04-18 16:16:35.703024 | PLAY [all]
2026-04-18 16:16:35.714089 | 
2026-04-18 16:16:35.714318 | TASK [fetch-output : Set log path for multiple nodes]
2026-04-18 16:16:35.758757 | aio | skipping: Conditional result was False
2026-04-18 16:16:35.766667 | 
2026-04-18 16:16:35.766797 | TASK [fetch-output : Set log path for single node]
2026-04-18 16:16:35.807164 | aio | ok
2026-04-18 16:16:35.812127 | 
2026-04-18 16:16:35.812197 | LOOP [fetch-output : Ensure local output dirs]
2026-04-18 16:16:36.179993 | aio -> localhost | ok: "/var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/work/logs"
2026-04-18 16:16:36.407751 | aio -> localhost | changed: "/var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/work/artifacts"
2026-04-18 16:16:36.622675 | aio -> localhost | changed: "/var/lib/zuul/builds/e7a1eae6bac94766ac4eff3674ba43b3/work/docs"
2026-04-18 16:16:36.644005 | 
2026-04-18 16:16:36.644211 | LOOP [fetch-output : Collect logs, artifacts and docs]
2026-04-18 16:16:37.263858 | aio | changed: .d..t...... ./
2026-04-18 16:16:37.264124 | aio | changed: All items complete
2026-04-18 16:16:37.264162 | 
2026-04-18 16:16:37.713964 | aio | changed: .d..t...... ./
2026-04-18 16:16:38.136723 | aio | changed: .d..t...... ./
2026-04-18 16:16:38.152466 | 
2026-04-18 16:16:38.152957 | LOOP [merge-output-to-logs : Move artifacts and docs to logs dir]
2026-04-18 16:16:38.561889 | aio -> localhost | ok: Item: artifacts Runtime: 0:00:00.010806
2026-04-18 16:16:38.838347 | aio -> localhost | ok: Item: docs Runtime: 0:00:00.046420
2026-04-18 16:16:38.859212 | 
2026-04-18 16:16:38.859347 | PLAY [all]
2026-04-18 16:16:38.865193 | 
2026-04-18 16:16:38.865258 | TASK [remove-build-sshkey : Remove the build SSH key from all nodes]
2026-04-18 16:16:39.273352 | aio | changed
2026-04-18 16:16:39.280364 | 
2026-04-18 16:16:39.280414 | PLAY RECAP
2026-04-18 16:16:39.280460 | aio | ok: 5 changed: 4 unreachable: 0 failed: 0 skipped: 1 rescued: 0 ignored: 0
2026-04-18 16:16:39.280483 | 
2026-04-18 16:16:39.397195 | POST-RUN END RESULT_NORMAL: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post.yaml@main]
2026-04-18 16:16:39.399124 | POST-RUN START: [trusted : github.com/vexxhost/zuul-config/playbooks/base/post-logs.yaml@main]
2026-04-18 16:16:39.967356 | 
2026-04-18 16:16:39.967906 | PLAY [localhost]
2026-04-18 16:16:39.977793 | 
2026-04-18 16:16:39.977863 | TASK [Generate Zuul manifest]
2026-04-18 16:16:40.000094 | localhost | ok
2026-04-18 16:16:40.017600 | 
2026-04-18 16:16:40.017676 | TASK [generate-zuul-manifest : Generate Zuul manifest]
2026-04-18 16:16:40.353471 | localhost | changed
2026-04-18 16:16:40.370690 | 
2026-04-18 16:16:40.370891 | TASK [generate-zuul-manifest : Return Zuul manifest URL to Zuul]
2026-04-18 16:16:40.403721 | localhost | ok
2026-04-18 16:16:40.410749 | 
2026-04-18 16:16:40.410813 | TASK [Upload logs]
2026-04-18 16:16:40.430975 | localhost | ok
2026-04-18 16:16:40.495055 | 
2026-04-18 16:16:40.495222 | TASK [Set zuul-log-path fact]
2026-04-18 16:16:40.514878 | localhost | ok
2026-04-18 16:16:40.526366 | 
2026-04-18 16:16:40.526474 | TASK [set-zuul-log-path-fact : Set log path for a build]
2026-04-18 16:16:40.558605 | localhost | ok
2026-04-18 16:16:40.566200 | 
2026-04-18 16:16:40.566275 | TASK [upload-logs : Create log directories]
2026-04-18 16:16:40.979540 | localhost | changed
2026-04-18 16:16:40.984706 | 
2026-04-18 16:16:40.984779 | TASK [upload-logs : Ensure logs are readable before uploading]
2026-04-18 16:16:41.379551 | localhost -> localhost | ok: Runtime: 0:00:00.004349
2026-04-18 16:16:41.384950 | 
2026-04-18 16:16:41.385072 | TASK [upload-logs : Upload logs to log server]
2026-04-18 16:16:41.840943 | localhost | Output suppressed because no_log was given
2026-04-18 16:16:41.847022 | 
2026-04-18 16:16:41.847088 | LOOP [upload-logs : Compress console log and json output]
2026-04-18 16:16:41.901202 | localhost | skipping: Conditional result was False
2026-04-18 16:16:41.908751 | localhost | skipping: Conditional result was False
2026-04-18 16:16:41.921021 | 
2026-04-18 16:16:41.921160 | LOOP [upload-logs : Upload compressed console log and json output]
2026-04-18 16:16:41.966030 | localhost | skipping: Conditional result was False
2026-04-18 16:16:41.966417 | 
2026-04-18 16:16:41.969758 | localhost | skipping: Conditional result was False
2026-04-18 16:16:41.987903 | 
2026-04-18 16:16:41.988040 | LOOP [upload-logs : Upload console log and json output]