[INFO] cloning repository https://github.com/dshakes/clickllm
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/dshakes/clickllm" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdshakes%2Fclickllm", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdshakes%2Fclickllm'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 4e84045e311dd2b8b71b5ae82cddaa0b9b44d4e9
[INFO] testing dshakes/clickllm against 1.99.0-beta.8 for beta-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fdshakes%2Fclickllm" "/workspace/builds/worker-5-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-5-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/dshakes/clickllm
[INFO] finished tweaking git repo https://github.com/dshakes/clickllm
[INFO] tweaked toml for git repo https://github.com/dshakes/clickllm written to /workspace/builds/worker-5-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/dshakes/clickllm on toolchain 1.99.0-beta.8
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/dshakes/clickllm already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.8" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded pyo3-build-config v0.29.0
[INFO] [stderr]   Downloaded tokio-macros v2.7.1
[INFO] [stderr]   Downloaded pyo3-macros v0.29.0
[INFO] [stderr]   Downloaded pyo3-ffi v0.29.0
[INFO] [stderr]   Downloaded pyo3-macros-backend v0.29.0
[INFO] [stderr]   Downloaded pyo3 v0.29.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-5-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-m" "1610612736" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129
[INFO] running `Command { std: "docker" "start" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-w" "/opt/rustwide/workdir" "--user" "0:0" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling bytes v1.12.1
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling syn v2.0.119
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling zeroize v1.9.0
[INFO] [stderr]    Compiling cc v1.4.0
[INFO] [stderr]    Compiling memchr v2.8.3
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling try-lock v0.2.5
[INFO] [stderr]    Compiling futures-channel v0.3.33
[INFO] [stderr]    Compiling rustls v0.23.42
[INFO] [stderr]    Compiling pyo3-build-config v0.29.0
[INFO] [stderr]    Compiling ipnet v2.12.0
[INFO] [stderr]    Compiling want v0.3.1
[INFO] [stderr]    Compiling inout v0.1.4
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling rustls-pki-types v1.15.1
[INFO] [stderr]    Compiling bitflags v2.13.1
[INFO] [stderr]    Compiling opaque-debug v0.3.1
[INFO] [stderr]    Compiling thread_local v1.1.10
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling webpki-roots v1.0.9
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling http v1.4.2
[INFO] [stderr]    Compiling pyo3-ffi v0.29.0
[INFO] [stderr]    Compiling pyo3 v0.29.0
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling rand_core v0.6.4
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling universal-hash v0.5.1
[INFO] [stderr]    Compiling cipher v0.4.4
[INFO] [stderr]    Compiling aead v0.5.2
[INFO] [stderr]    Compiling poly1305 v0.8.0
[INFO] [stderr]    Compiling chacha20 v0.9.1
[INFO] [stderr]    Compiling chacha20poly1305 v0.10.1
[INFO] [stderr]    Compiling http-body v1.1.0
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling http-body-util v0.1.4
[INFO] [stderr]    Compiling regex-automata v0.4.16
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling thiserror-impl v2.0.19
[INFO] [stderr]    Compiling synstructure v0.13.2
[INFO] [stderr]    Compiling pyo3-macros-backend v0.29.0
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling displaydoc v0.2.6
[INFO] [stderr]    Compiling tokio-macros v2.7.1
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling futures-macro v0.3.33
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling futures-util v0.3.33
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling zerofrom v0.1.8
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling yoke v0.8.3
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling tracing-serde v0.2.0
[INFO] [stderr]    Compiling onpar-core v1.3.4 (/opt/rustwide/workdir/onpar-core)
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.13.1
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling pyo3-macros v0.29.0
[INFO] [stderr]    Compiling idna_adapter v1.2.2
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling hyper v1.11.0
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-util v0.7.19
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling tower-http v0.6.11
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-rustls v0.27.9
[INFO] [stderr]    Compiling axum v0.8.9
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling onpar-gateway v1.3.4 (/opt/rustwide/workdir/onpar-gateway)
[INFO] [stderr]    Compiling onpar-py v1.3.4 (/opt/rustwide/workdir/onpar-py)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 00s
[INFO] running `Command { std: "docker" "inspect" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling onpar-core v1.3.4 (/opt/rustwide/workdir/onpar-core)
[INFO] [stderr]    Compiling getrandom v0.4.3
[INFO] [stderr]    Compiling tower-http v0.6.11
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling onpar-gateway v1.3.4 (/opt/rustwide/workdir/onpar-gateway)
[INFO] [stderr]    Compiling onpar-py v1.3.4 (/opt/rustwide/workdir/onpar-py)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 20.89s
[INFO] running `Command { std: "docker" "inspect" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "exec" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=warn" "-e" "RUSTDOCFLAGS=--cap-lints=warn" "-w" "/opt/rustwide/workdir" "--user" "0:0" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.31s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/onpar_core-9628227ac0d545fa)
[INFO] [stdout] 
[INFO] [stdout] running 88 tests
[INFO] [stdout] test licence::tests::classify_is_insensitive_to_casing_and_padding ... ok
[INFO] [stdout] test licence::tests::default_policy_is_the_strict_one ... ok
[INFO] [stdout] test licence::tests::catalogue_ids_are_canonical_and_unique ... ok
[INFO] [stdout] test licence::tests::unknown_licence_fails_closed ... ok
[INFO] [stdout] test licence::tests::non_commercial_is_allowed_for_non_commercial_intent_with_the_terms_shown ... ok
[INFO] [stdout] test licence::tests::acknowledgement_is_case_insensitive_but_not_cross_licence ... ok
[INFO] [stdout] test licence::tests::unknown_licence_can_be_permitted_only_explicitly ... ok
[INFO] [stdout] test licence::tests::acknowledgement_unblocks_but_still_returns_the_obligation ... ok
[INFO] [stdout] test licence::tests::empty_licence_field_is_not_permission ... ok
[INFO] [stdout] test licence::tests::every_conditional_and_non_commercial_entry_states_an_obligation ... ok
[INFO] [stdout] test error::tests::messages_name_the_offending_value ... ok
[INFO] [stdout] test model_ref::tests::bare_locator_defaults_to_hugging_face ... ok
[INFO] [stdout] test model_ref::tests::display_round_trips_through_parse ... ok
[INFO] [stdout] test licence::tests::non_commercial_is_denied_for_commercial_intent ... ok
[INFO] [stdout] test model_ref::tests::every_scheme_parses ... ok
[INFO] [stdout] test model_ref::tests::cache_key_cannot_be_confused_by_field_boundaries ... ok
[INFO] [stdout] test error::tests::checksum_error_shows_both_digests ... ok
[INFO] [stdout] test error::tests::io_errors_keep_path_and_source ... ok
[INFO] [stdout] test licence::tests::conditional_licence_needs_acknowledgement ... ok
[INFO] [stdout] test model_ref::tests::quant_and_revision_parse_in_either_notation ... ok
[INFO] [stdout] test licence::tests::permissive_licences_pass_with_the_default_policy ... ok
[INFO] [stdout] test model_ref::tests::only_local_sources_avoid_the_network ... ok
[INFO] [stdout] test pack::tests::a_box_that_does_not_fit_gives_something_up_rather_than_nothing ... ok
[INFO] [stdout] test pack::tests::a_re_solve_without_a_bench_does_not_claim_one_was_invalidated ... ok
[INFO] [stdout] test pack::tests::accepts_is_directional ... ok
[INFO] [stdout] test pack::tests::a_manifest_round_trips_through_json ... ok
[INFO] [stdout] test pack::tests::an_unpinned_reference_cannot_be_packed ... ok
[INFO] [stdout] test model_ref::tests::malformed_refs_are_rejected_with_a_reason ... ok
[INFO] [stdout] test model_ref::tests::cache_key_separates_every_field ... ok
[INFO] [stdout] test model_ref::tests::quant_labels_canonicalise_to_one_cache_entry ... ok
[INFO] [stdout] test pack::tests::a_different_device_count_forces_a_re_solve ... ok
[INFO] [stdout] test pack::tests::degradation_stops_at_a_useful_floor_rather_than_serving_a_toy_window ... ok
[INFO] [stdout] test pack::tests::hardware_that_cannot_run_it_says_so_rather_than_approximating ... ok
[INFO] [stdout] test model_ref::tests::oci_tag_colon_is_not_mistaken_for_a_scheme ... ok
[INFO] [stdout] test pack::tests::more_memory_than_packed_for_is_still_as_packed ... ok
[INFO] [stdout] test pack::tests::no_runtimes_offered_is_reported_not_silently_empty ... ok
[INFO] [stdout] test pack::tests::provenance_states_whether_it_was_measured ... ok
[INFO] [stdout] test runtime::llmd::tests::accepts_multi_device_and_explains_the_topology ... ok
[INFO] [stdout] test runtime::llmd::tests::refuses_single_device_and_points_at_the_simpler_option ... ok
[INFO] [stdout] test runtime::sglang::tests::apple_and_cpu_are_refused_with_different_reasons ... ok
[INFO] [stdout] test runtime::sglang::tests::every_target_renders_a_runnable_artifact_with_provenance ... ok
[INFO] [stdout] test pack::tests::landing_on_a_smaller_box_re_solves_rather_than_ooming ... ok
[INFO] [stdout] test runtime::sglang::tests::sizing_matches_vllm_because_the_arithmetic_is_shared ... ok
[INFO] [stdout] test pack::tests::identical_hardware_uses_the_shipped_plan ... ok
[INFO] [stdout] test runtime::sglang::tests::speculative_decoding_is_dropped_and_says_so_rather_than_guessing ... ok
[INFO] [stdout] test pack::tests::provenance_carries_the_rationale_so_the_box_explains_itself ... ok
[INFO] [stdout] test runtime::sglang::tests::tensor_parallel_uses_tp_size_and_only_when_sharding ... ok
[INFO] [stdout] test runtime::llmd::tests::prefix_caching_is_forced_on_because_routing_depends_on_it ... ok
[INFO] [stdout] test runtime::tests::feasibility_usability_and_reasons ... ok
[INFO] [stdout] test runtime::sglang::tests::the_kubernetes_name_is_a_valid_dns_label ... ok
[INFO] [stdout] test runtime::llmd::tests::renders_an_inferencepool_with_provenance ... ok
[INFO] [stdout] test runtime::sglang::tests::a_model_too_big_for_the_card_is_refused_with_the_numbers ... ok
[INFO] [stdout] test runtime::tests::provenance_names_the_tool_and_carries_the_rationale ... ok
[INFO] [stdout] test runtime::sglang::tests::wanting_prefix_reuse_emits_nothing_rather_than_the_opposite ... ok
[INFO] [stdout] test runtime::tests::draft_length_shrinks_as_concurrency_rises ... ok
[INFO] [stdout] test runtime::vllm::tests::cpu_is_degraded_not_refused_and_says_why ... ok
[INFO] [stdout] test runtime::vllm::tests::context_never_exceeds_what_the_weights_support ... ok
[INFO] [stdout] test runtime::sglang::tests::the_flags_are_sglangs_own_not_vllms_renamed ... ok
[INFO] [stdout] test runtime::tests::spec_decode_is_disabled_above_the_concurrency_cliff ... ok
[INFO] [stdout] test runtime::llmd::tests::refuses_non_cuda ... ok
[INFO] [stdout] test runtime::tests::spec_decode_is_enabled_at_and_below_the_cliff ... ok
[INFO] [stdout] test runtime::llmd::tests::refuses_non_kubernetes_targets_rather_than_emitting_nonsense ... ok
[INFO] [stdout] test runtime::vllm::tests::every_plan_explains_itself ... ok
[INFO] [stdout] test runtime::tests::systemd_exec_start_is_an_absolute_path ... ok
[INFO] [stdout] test runtime::vllm::tests::context_is_capped_to_observed_p95_not_the_model_maximum ... ok
[INFO] [stdout] test runtime::vllm::tests::every_target_renders_something_runnable ... ok
[INFO] [stdout] test runtime::vllm::tests::refuses_when_weights_alone_cannot_fit ... ok
[INFO] [stdout] test runtime::vllm::tests::renders_every_target_with_provenance ... ok
[INFO] [stdout] test runtime::vllm::tests::serve_args_carry_every_derived_knob ... ok
[INFO] [stdout] test runtime::tests::systemd_quotes_arguments_with_bare_quotes_or_backslashes ... ok
[INFO] [stdout] test runtime::vllm::tests::tensor_parallel_matches_device_count ... ok
[INFO] [stdout] test runtime::vllm::tests::spec_decode_is_dropped_above_the_cliff_and_explained ... ok
[INFO] [stdout] test runtime::vllm::tests::the_systemd_unit_survives_a_slow_start_and_restarts ... ok
[INFO] [stdout] test spec::tests::mla_kv_is_far_smaller_than_gqa_would_suggest ... ok
[INFO] [stdout] test spec::tests::mla_without_a_rank_falls_back_conservatively_rather_than_guessing_low ... ok
[INFO] [stdout] test spec::tests::moe_is_detected_from_the_active_fraction ... ok
[INFO] [stdout] test spec::tests::sparse_models_read_far_fewer_bytes_per_token_than_a_dense_model_of_equal_total_size ... ok
[INFO] [stdout] test tests::version_is_populated ... ok
[INFO] [stdout] test runtime::vllm::tests::kubernetes_names_are_dns_1123_labels ... ok
[INFO] [stdout] test runtime::vllm::tests::local_script_is_a_runnable_native_invocation ... ok
[INFO] [stdout] test runtime::vllm::tests::prefix_caching_follows_measured_prefix_sharing ... ok
[INFO] [stdout] test runtime::vllm::tests::refuses_apple_silicon_with_an_actionable_reason ... ok
[INFO] [stdout] test spec::tests::default_workload_is_the_conservative_one ... ok
[INFO] [stdout] test spec::tests::moe_weights_size_on_total_not_active_params ... ok
[INFO] [stdout] test spec::tests::degenerate_sizes_floor_to_zero_rather_than_wrapping ... ok
[INFO] [stdout] test spec::tests::hardware_describe_mentions_device_count_only_when_plural ... ok
[INFO] [stdout] test spec::tests::gqa_kv_matches_the_hand_computation ... ok
[INFO] [stdout] test spec::tests::kv_scales_linearly_in_dtype_width ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 88 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/onpar_gateway-16dc175b4e846ba2)
[INFO] [stdout] 
[INFO] [stdout] running 109 tests
[INFO] [stdout] test capture::redact::tests::luhn_rejects_what_it_should ... ok
[INFO] [stdout] test capture::redact::tests::oversized_text_is_refused_so_a_partial_scan_never_passes_as_complete ... ok
[INFO] [stdout] test capture::store::tests::a_key_is_generated_once_and_reused ... ok
[INFO] [stdout] test capture::store::tests::a_record_written_before_the_shape_fields_existed_still_reads ... ok
[INFO] [stdout] test capture::store::tests::an_absent_log_reads_as_empty_rather_than_failing ... ok
[INFO] [stdout] test capture::redact::tests::patterns_all_compile ... ok
[INFO] [stdout] test capture::store::tests::every_record_gets_its_own_nonce ... ok
[INFO] [stdout] test capture::redact::tests::an_order_id_that_is_not_luhn_valid_survives ... ok
[INFO] [stdout] test capture::redact::tests::emails_and_secrets_are_removed_and_counted ... ok
[INFO] [stdout] test capture::redact::tests::ordinary_prose_is_left_completely_alone ... ok
[INFO] [stdout] test capture::redact::tests::private_keys_go_whole_not_in_fragments ... ok
[INFO] [stdout] test capture::store::tests::an_unredactable_capture_is_dropped_not_stored ... ok
[INFO] [stdout] test capture::redact::tests::a_luhn_valid_card_is_redacted ... ok
[INFO] [stdout] test capture::redact::tests::card_ordering_beats_the_phone_pattern ... ok
[INFO] [stdout] test capture::redact::tests::multiple_occurrences_are_all_replaced_and_counted ... ok
[INFO] [stdout] test capture::redact::tests::code_and_hashes_survive ... ok
[INFO] [stdout] test capture::redact::tests::the_report_makes_redaction_observable ... ok
[INFO] [stdout] test control::tests::a_bearer_token_is_required_only_when_one_is_configured ... ok
[INFO] [stdout] test control::tests::an_empty_env_token_is_treated_as_unset_rather_than_as_the_password ... ok
[INFO] [stdout] test capture::redact::tests::a_version_string_is_not_mistaken_for_an_ip ... ok
[INFO] [stdout] test capture::store::tests::a_crash_mid_append_costs_one_record_not_the_corpus ... ok
[INFO] [stdout] test capture::store::tests::a_capture_round_trips ... ok
[INFO] [stdout] test capture::store::tests::the_key_file_is_owner_only ... ok
[INFO] [stdout] test capture::store::tests::json_structure_survives_redaction ... ok
[INFO] [stdout] test capture::store::tests::nothing_reaches_disk_unredacted ... ok
[INFO] [stdout] test capture::store::tests::opening_a_store_to_read_it_touches_nothing ... ok
[INFO] [stdout] test capture::redact::tests::placeholders_preserve_shape_so_clustering_still_works ... ok
[INFO] [stdout] test capture::store::tests::tampering_is_detected_rather_than_silently_accepted ... ok
[INFO] [stdout] test capture::store::tests::the_log_is_unreadable_with_the_wrong_key ... ok
[INFO] [stdout] test host::tests::an_unknown_on_either_side_reports_nothing_rather_than_guessing ... ok
[INFO] [stdout] test control::tests::escalation_is_computed_from_share_not_phase_name ... ok
[INFO] [stdout] test capture::store::tests::redaction_reaches_nested_json_not_just_the_top_level ... ok
[INFO] [stdout] test host::tests::a_busy_host_with_a_busy_gpu_is_not_starvation ... ok
[INFO] [stdout] test host::tests::unavailable_is_not_an_empty_device_list ... ok
[INFO] [stdout] test host::tests::a_busy_host_with_an_idle_gpu_is_named_as_host_starvation ... ok
[INFO] [stdout] test meter::tests::a_null_usage_field_does_not_read_as_zeros ... ok
[INFO] [stdout] test host::tests::a_card_full_because_of_our_own_kv_cache_is_not_reported_as_foreign ... ok
[INFO] [stdout] test host::tests::a_field_the_card_does_not_expose_stays_none_rather_than_zero ... ok
[INFO] [stdout] test host::tests::real_shaped_output_parses_every_column ... ok
[INFO] [stdout] test meter::tests::cost_is_integer_micros_and_does_not_drift ... ok
[INFO] [stdout] test meter::tests::cost_and_total_saturate_rather_than_wrapping ... ok
[INFO] [stdout] test meter::tests::empty_content_deltas_are_not_counted ... ok
[INFO] [stdout] test host::tests::foreign_memory_is_the_thing_engine_telemetry_cannot_see ... ok
[INFO] [stdout] test host::tests::memory_used_is_computed_from_available_not_free ... ok
[INFO] [stdout] test host::tests::an_unmeasurable_host_is_unknown_rather_than_healthy ... ok
[INFO] [stdout] test host::tests::peak_is_the_worst_device_not_the_first ... ok
[INFO] [stdout] test host::tests::system_stats_are_partial_rather_than_all_or_nothing ... ok
[INFO] [stdout] test host::tests::an_unparseable_row_is_dropped_not_defaulted ... ok
[INFO] [stdout] test host::tests::starvation_is_silent_when_either_side_is_unknown ... ok
[INFO] [stdout] test capture::redact::tests::ssn_phone_ip_and_jwt_are_all_caught ... ok
[INFO] [stdout] test meter::tests::a_partial_usage_object_is_rejected_rather_than_half_counted ... ok
[INFO] [stdout] test host::tests::probing_this_machine_never_panics_and_always_explains_itself ... ok
[INFO] [stdout] test router::tests::a_canary_falls_back_only_for_the_bucket_actually_on_the_candidate ... ok
[INFO] [stdout] test router::tests::a_local_plus_cloud_split_routes_each_cluster_to_its_own_backend ... ok
[INFO] [stdout] test router::tests::a_regret_cluster_stays_on_the_incumbent_while_the_rest_cuts_over ... ok
[INFO] [stdout] test router::tests::bucketing_is_deterministic_so_a_retry_lands_the_same_way ... ok
[INFO] [stdout] test router::tests::canary_still_mirrors_the_requests_it_does_not_serve ... ok
[INFO] [stdout] test router::tests::cut_serves_only_the_candidate ... ok
[INFO] [stdout] test router::tests::every_decision_carries_a_reason ... ok
[INFO] [stdout] test router::tests::failover_is_off_unless_asked_for ... ok
[INFO] [stdout] test router::tests::failover_rescues_the_candidate_and_only_the_candidate ... ok
[INFO] [stdout] test router::tests::off_serves_the_incumbent_and_mirrors_nothing ... ok
[INFO] [stdout] test router::tests::percentages_above_100_are_clamped_not_wrapped ... ok
[INFO] [stdout] test meter::tests::later_usage_wins_so_a_final_frame_corrects_an_earlier_estimate ... ok
[INFO] [stdout] test router::tests::unknown_clusters_fall_back_to_the_default_route ... ok
[INFO] [stdout] test meter::tests::unary_bodies_are_metered_too ... ok
[INFO] [stdout] test router::tests::split_percentages_read_as_all_or_nothing ... ok
[INFO] [stdout] test router::tests::shadow_never_serves_the_candidate ... ok
[INFO] [stdout] test router::tests::shadow_never_serves_the_candidate_even_when_the_incumbent_is_down ... ok
[INFO] [stdout] test router::tests::split_assigns_a_cluster_permanently_and_never_mirrors ... ok
[INFO] [stdout] test sse::tests::a_partial_trailing_frame_is_retained_not_emitted ... ok
[INFO] [stdout] test router::tests::split_is_a_destination_not_a_rollout_step ... ok
[INFO] [stdout] test sse::tests::comments_and_other_fields_are_ignored ... ok
[INFO] [stdout] test sse::tests::crlf_terminators_are_accepted ... ok
[INFO] [stdout] test router::tests::zero_percent_canary_sends_nothing_to_the_candidate ... ok
[INFO] [stdout] test meter::tests::missing_usage_is_reported_as_missing_never_as_zero ... ok
[INFO] [stdout] test meter::tests::reads_usage_from_a_streamed_final_frame ... ok
[INFO] [stdout] test meter::tests::the_done_sentinel_is_not_counted_as_content ... ok
[INFO] [stdout] test sse::tests::byte_at_a_time_delivery_still_decodes ... ok
[INFO] [stdout] test sse::tests::invalid_utf8_drops_the_frame_rather_than_panicking ... ok
[INFO] [stdout] test sse::tests::decodes_a_simple_stream ... ok
[INFO] [stdout] test sse::tests::encode_round_trips_through_the_decoder ... ok
[INFO] [stdout] test telemetry::tests::a_multi_model_endpoint_reports_the_fleet_not_one_label_set ... ok
[INFO] [stdout] test sse::tests::multi_line_data_fields_concatenate ... ok
[INFO] [stdout] test sse::tests::frames_split_across_chunk_boundaries_survive ... ok
[INFO] [stdout] test sse::tests::optional_space_after_the_colon_is_stripped_once ... ok
[INFO] [stdout] test telemetry::tests::a_plan_that_predicted_correctly_is_not_second_guessed ... ok
[INFO] [stdout] test telemetry::tests::a_prefix_name_does_not_match_a_longer_metric ... ok
[INFO] [stdout] test telemetry::tests::an_absent_series_is_none_and_never_zero ... ok
[INFO] [stdout] test telemetry::tests::a_real_looking_scrape_parses_every_series_we_claim ... ok
[INFO] [stdout] test telemetry::tests::an_unlabelled_series_still_parses ... ok
[INFO] [stdout] test meter::tests::unparseable_frames_do_not_break_metering ... ok
[INFO] [stdout] test host::tests::a_missing_binary_is_a_reason_not_a_crash ... ok
[INFO] [stdout] test telemetry::tests::a_histogram_with_no_observations_is_unknown_rather_than_zero_latency ... ok
[INFO] [stdout] test telemetry::tests::comments_and_blank_lines_are_ignored ... ok
[INFO] [stdout] test telemetry::tests::contradictions_say_nothing_when_nothing_was_measured ... ok
[INFO] [stdout] test telemetry::tests::kv_pressure_is_only_true_near_the_ceiling ... ok
[INFO] [stdout] test telemetry::tests::garbage_parses_to_nothing_rather_than_to_wrong_numbers ... ok
[INFO] [stdout] test telemetry::tests::finish_reasons_are_split_because_collapsed_they_all_look_like_success ... ok
[INFO] [stdout] test telemetry::tests::queue_pressure_is_only_reported_when_it_is_actually_the_cap ... ok
[INFO] [stdout] test telemetry::tests::the_prefill_decode_split_is_computed_from_both_halves ... ok
[INFO] [stdout] test telemetry::tests::the_full_verified_series_set_is_parsed_not_a_subset ... ok
[INFO] [stdout] test telemetry::tests::telemetry_contradicts_a_plan_that_predicted_wrongly ... ok
[INFO] [stdout] test sse::tests::the_decoder_resynchronises_after_discarding_an_oversized_frame ... ok
[INFO] [stdout] test sse::tests::a_discard_never_emits_a_truncated_payload_as_if_it_were_whole ... ok
[INFO] [stdout] test router::tests::bucket_is_uniform_enough_and_within_range ... ok
[INFO] [stdout] test router::tests::canary_splits_close_to_the_requested_percentage ... ok
[INFO] [stdout] test capture::store::tests::many_appends_all_read_back_in_order ... ok
[INFO] [stdout] test sse::tests::an_upstream_that_never_terminates_a_frame_cannot_grow_the_buffer ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 109 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 3.34s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/onpar_gateway-c2d6ef16127ed45d)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test tests::an_unknown_flag_is_refused_rather_than_ignored ... ok
[INFO] [stdout] test tests::capture_is_on_unless_declined ... ok
[INFO] [stdout] test tests::no_candidate_means_off_not_shadow ... ok
[INFO] [stdout] test tests::shadow_is_still_allowed_at_startup ... ok
[INFO] [stdout] test tests::a_percent_over_one_hundred_is_refused_rather_than_truncated ... ok
[INFO] [stdout] test tests::every_phase_describes_itself ... ok
[INFO] [stdout] test tests::a_share_with_nowhere_to_send_it_is_refused ... ok
[INFO] [stdout] test tests::an_upstream_is_required_and_never_guessed ... ok
[INFO] [stdout] test tests::tracing_init_installs_a_dispatcher ... ok
[INFO] [stdout] test tests::tracing_init_is_idempotent ... ok
[INFO] [stdout] test tests::startup_cannot_move_traffic ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/capture.rs (/opt/rustwide/target/debug/deps/capture-42212fe0d3738007)
[INFO] [stdout] 
[INFO] [stdout] running 11 tests
[INFO] [stdout] test ready_fails_on_a_log_path_that_cannot_be_written ... ok
[INFO] [stdout] test ready_succeeds_and_does_not_write_a_record ... ok
[INFO] [stdout] test a_gateway_without_a_store_writes_nothing_and_still_serves ... ok
[INFO] [stdout] test a_tool_using_exchange_records_the_three_fields_it_clusters_on ... ok
[INFO] [stdout] test a_non_streamed_request_is_captured_with_both_sides_redacted ... ok
[INFO] [stdout] test a_request_offering_no_tools_records_none_rather_than_guessing ... ok
[INFO] [stdout] test a_streamed_response_is_reassembled_from_its_deltas ... ok
[INFO] [stdout] test tool_call_arguments_never_reach_the_log ... ok
[INFO] [stdout] test a_streamed_tool_call_is_recorded_once_from_its_opening_fragment ... ok
[INFO] [stdout] test the_file_on_disk_contains_no_plaintext_at_all ... ok
[INFO] [stderr]      Running tests/control.rs (/opt/rustwide/target/debug/deps/control-6e1e8c415aee65ee)
[INFO] [stdout] test many_concurrent_requests_all_land_without_corrupting_each_other ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 11 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.22s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test the_current_phase_is_readable ... ok
[INFO] [stdout] test a_rollback_needs_no_human_confirmation ... ok
[INFO] [stdout] test pinning_one_cluster_leaves_every_other_where_it_was ... ok
[INFO] [stdout] test an_unconfirmed_escalation_is_refused_and_changes_nothing ... ok
[INFO] [stdout] test a_confirmed_escalation_is_allowed ... ok
[INFO] [stdout] test every_applied_change_is_recorded_with_its_reason ... ok
[INFO] [stdout] test a_rollback_reroutes_the_very_next_request ... ok
[INFO] [stdout] test a_change_without_a_reason_is_refused ... ok
[INFO] [stdout] test traffic_keeps_flowing_while_the_phase_changes_underneath_it ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.13s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/latency.rs (/opt/rustwide/target/debug/deps/latency-b5dc78de9a380356)
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test the_measurement_can_detect_overhead_that_is_really_there ... ok
[INFO] [stdout] test added_latency_stays_inside_the_nfr1_budget ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.31s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/startup.rs (/opt/rustwide/target/debug/deps/startup-be82508828826e4b)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test it_refuses_to_move_traffic_at_startup ... ok
[INFO] [stdout] test it_refuses_an_upstream_it_would_have_to_invent ... ok
[INFO] [stdout] test help_is_help_and_not_an_error ... ok
[INFO] [stderr]      Running tests/streaming.rs (/opt/rustwide/target/debug/deps/streaming-75078f5f2059ce0e)
[INFO] [stdout] test it_refuses_to_serve_when_the_capture_log_cannot_be_written ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 17 tests
[INFO] [stdout] test healthz_and_metrics_endpoints_answer ... ok
[INFO] [stdout] test an_unreachable_upstream_returns_502_in_the_openai_error_shape ... ok
[INFO] [stdout] test a_dead_candidate_is_recorded_rather_than_silently_dropped ... ok
[INFO] [stdout] test a_slow_candidate_does_not_delay_the_client ... ok
[INFO] [stdout] test a_malformed_body_is_a_400_not_a_panic ... ok
[INFO] [stdout] test failover_reaches_the_cloud_when_local_is_down ... ok
[INFO] [stdout] test unary_requests_are_metered_too ... ok
[INFO] [stdout] test without_failover_a_dead_primary_is_an_error_not_a_surprise_bill ... ok
[INFO] [stdout] test the_console_is_served_and_reflects_real_recorded_traffic ... ok
[INFO] [stdout] test a_healthy_primary_is_not_marked_as_failed_over ... ok
[INFO] [stdout] test the_console_can_read_the_mirror_endpoint_it_renders ... ok
[INFO] [stdout] test shadow_actually_sends_the_request_to_the_candidate_backend ... ok
[INFO] [stdout] test shadow_phase_never_returns_the_candidate_to_the_client ... ok
[INFO] [stdout] test a_split_serves_local_and_never_mirrors_to_the_cloud ... ok
[INFO] [stdout] test a_stalled_frame_past_the_cap_does_not_break_the_stream ... ok
[INFO] [stdout] test streaming_response_reaches_the_client_incrementally ... ok
[INFO] [stdout] test streamed_usage_is_metered_without_buffering ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.40s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/_onpar_core-b7865f474331ea54)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests onpar_core
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests onpar_gateway
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests _onpar_core
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129", kill_on_drop: false }`
[INFO] [stdout] 512490f9639c0b5bc9019e13083857eea5490a7b5659dca7e6823704b480d129
