[INFO] fetching crate spark-serve 0.1.0...
[INFO] testing spark-serve-0.1.0 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate spark-serve 0.1.0 into /workspace/builds/worker-1-tc1/source
[INFO] started tweaking crates.io crate spark-serve 0.1.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate spark-serve 0.1.0
[INFO] tweaked toml for crates.io crate spark-serve 0.1.0 written to /workspace/builds/worker-1-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate spark-serve 0.1.0 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 crates.io crate spark-serve 0.1.0 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]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded spark-core v0.1.0
[INFO] [stderr]   Downloaded spark-sched v0.1.0
[INFO] [stderr]   Downloaded spark-text v0.1.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-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] 16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920
[INFO] running `Command { std: "docker" "start" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920", 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" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920" "/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" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920", 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" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling libc v0.2.189
[INFO] [stderr]    Compiling serde_core v1.0.229
[INFO] [stderr]    Compiling thiserror v2.0.19
[INFO] [stderr]    Compiling futures-core v0.3.33
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling smallvec v1.15.2
[INFO] [stderr]    Compiling futures-channel v0.3.33
[INFO] [stderr]    Compiling http-body-util v0.1.4
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling socket2 v0.6.5
[INFO] [stderr]    Compiling mio v1.2.2
[INFO] [stderr]    Compiling tokio v1.53.1
[INFO] [stderr]    Compiling thiserror-impl v2.0.19
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling spark-core v0.1.0
[INFO] [stderr]    Compiling spark-sched v0.1.0
[INFO] [stderr]    Compiling spark-text v0.1.0
[INFO] [stderr]    Compiling hyper v1.11.0
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling spark-serve v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 12.70s
[INFO] running `Command { std: "docker" "inspect" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920", 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" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920" "/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 spark-serve v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.44s
[INFO] running `Command { std: "docker" "inspect" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920", 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" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920" "/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.04s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/spark_serve-05b1de39c799f38f)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test http_gateway::tests::routes_match_c_surface ... ok
[INFO] [stdout] test http_gateway::tests::service_health_matches_c_fixture ... ok
[INFO] [stdout] test http_gateway::tests::event_names_and_terminal_match_c ... ok
[INFO] [stdout] test http_gateway::tests::authorization_matches_c_rules ... ok
[INFO] [stdout] test http_gateway::tests::token_frame_escapes_like_c ... ok
[INFO] [stdout] test http_gateway::tests::token_frame_is_byte_exact ... ok
[INFO] [stdout] test http_gateway::tests::stream_flag_scan_matches_c ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/http_gateway.rs (/opt/rustwide/target/debug/deps/http_gateway-2d72d83b13ec3902)
[INFO] [stdout] 
[INFO] [stdout] running 14 tests
[INFO] [stdout] test cors_preflight_matches_c ... ok
[INFO] [stdout] test backend_unavailable_json_and_sse ... ok
[INFO] [stdout] test health_full_body_is_byte_exact ... ok
[INFO] [stdout] test nonstream_submit_accepted_is_byte_exact ... ok
[INFO] [stdout] test sse_stream_frames_are_byte_exact ... ok
[INFO] [stdout] test oversized_request_is_400_byte_exact ... ok
[INFO] [stdout] test sse_token_text_is_json_escaped_like_c ... ok
[INFO] [stdout] test demo_ui_serves_html ... ok
[INFO] [stdout] test busy_submit_maps_to_504_json_and_sse ... ok
[INFO] [stdout] test auth_enforced_on_completion_routes_only ... ok
[INFO] [stdout] test keep_alive_serves_multiple_requests_on_one_connection ... ok
[INFO] [stdout] test health_reports_unattached_backend_as_zeros ... ok
[INFO] [stdout] test unknown_route_is_404_byte_exact ... ok
[INFO] [stdout] test sse_client_disconnect_cancels_request ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 14 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/request_api.rs (/opt/rustwide/target/debug/deps/request_api-8af46ba1803c85a8)
[INFO] [stdout] 
[INFO] [stdout] running 49 tests
[INFO] [stdout] test accepts_mtp_force_enable_configuration ... ok
[INFO] [stdout] test adaptive_prefill_chooses_full_resident_bucket_over_older_singleton ... ok
[INFO] [stdout] test adaptive_width_backfills_without_growing_priority_class ... ok
[INFO] [stdout] test async_jit_prefetch_overlaps_resident_work ... ok
[INFO] [stdout] test admits_thirteen_thousand_with_free_list ... ok
[INFO] [stdout] test batches_decode_after_batch_critical_prefetch ... ok
[INFO] [stdout] test chooses_wider_shared_prefix_family ... ok
[INFO] [stdout] test batches_ready_decode_requests_and_consumes_budgets ... ok
[INFO] [stdout] test batches_variable_one_block_suffixes ... ok
[INFO] [stdout] test batches_prefill_after_batch_critical_prefetch ... ok
[INFO] [stdout] test caps_decode_batch_by_active_kv_blocks ... ok
[INFO] [stdout] test does_not_greenlight_missing_jit_kv ... ok
[INFO] [stdout] test dspark_disabled_per_request_falls_back_to_decode ... ok
[INFO] [stdout] test describes_and_copies_full_prefill_token_windows ... ok
[INFO] [stdout] test cohorts_arbitrary_shared_prefix_with_suffixes ... ok
[INFO] [stdout] test dspark_captures_taps_and_runs_speculative_verify ... ok
[INFO] [stdout] test mtp_budget_leaves_verifier_fallback_headroom ... ok
[INFO] [stdout] test mtp_tree_uses_compact_alternate_storage ... ok
[INFO] [stdout] test fills_decode_batch_before_equal_priority_decode ... ok
[INFO] [stdout] test late_high_priority_uses_next_available_pipeline_slot ... ok
[INFO] [stdout] test evicts_cold_resident_blocks_but_keeps_lookahead_hotset ... ok
[INFO] [stdout] test mtp_tree_resolves_every_path ... ok
[INFO] [stdout] test cohorts_same_prompt_requests_and_shares_blocks ... ok
[INFO] [stdout] test jit_prefetches_cached_prefix_for_priority_request ... ok
[INFO] [stdout] test mtp_draft_requires_speculative_verify ... ok
[INFO] [stdout] test dspark_batches_equal_length_drafts ... ok
[INFO] [stdout] test mtp_verify_captures_dspark_batch_tap ... ok
[INFO] [stdout] test refreshes_queue_aware_prefix_protection ... ok
[INFO] [stdout] test opportunistic_lookahead_does_not_block_ready_priority_prefill ... ok
[INFO] [stdout] test holds_prefill_at_global_resident_kv_limit ... ok
[INFO] [stdout] test decode_batch_uses_measured_b64_for_seventeen_ready_requests ... ok
[INFO] [stdout] test realtime_prefill_bypasses_full_bulk_batch ... ok
[INFO] [stdout] test uses_adaptive_pipeline_batch_width ... ok
[INFO] [stdout] test mtp_verify_caps_packed_execution_rows ... ok
[INFO] [stdout] test decode_batch_backfills_by_descending_priority ... ok
[INFO] [stdout] test prefill_wave_spans_multiple_kv_blocks ... ok
[INFO] [stdout] test prefetches_live_nonresident_decode_blocks ... ok
[INFO] [stdout] test mtp_draft_budget_remains_transactional ... ok
[INFO] [stdout] test wide_prefix_family_cannot_beat_higher_priority ... ok
[INFO] [stdout] test uses_built_in_async_memory_prefetch_backend ... ok
[INFO] [stdout] test reuse_scored_eviction_keeps_shared_prefix_family ... ok
[INFO] [stdout] test submits_c_text_prompt_to_prefill_schedule ... ok
[INFO] [stdout] test trims_resident_kv_without_evicting_near_future_prefix ... ok
[INFO] [stdout] test mtp_rejected_draft_stays_outside_next_context ... ok
[INFO] [stdout] test uses_higher_yield_mtp_before_equal_priority_decode ... ok
[INFO] [stdout] test reserves_mtp_draft_kv_blocks ... ok
[INFO] [stdout] test skips_unprofitable_wide_mtp ... ok
[INFO] [stdout] test prefill_waves_pipeline_through_ring ... ok
[INFO] [stdout] test mtp_adaptive_floor_suppresses_and_recovers ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 49 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/serving_engine.rs (/opt/rustwide/target/debug/deps/serving_engine-5f4ada4e4e1ed3ea)
[INFO] [stdout] 
[INFO] [stdout] running 19 tests
[INFO] [stdout] test compat_anthropic_messages ... ok
[INFO] [stdout] test compat_openai_chat_with_files ... ok
[INFO] [stdout] test compat_openai_chat ... ok
[INFO] [stdout] test compat_openai_prompt ... ok
[INFO] [stdout] test compat_openai_thinking_budgets ... ok
[INFO] [stdout] test compat_rejects_small_buffer ... ok
[INFO] [stdout] test compat_rejects_unknown_chat_role ... ok
[INFO] [stdout] test rejects_tail_window_runtime_contract ... ok
[INFO] [stdout] test dynamic_token_storage_grows_and_recycles ... ok
[INFO] [stdout] test fail_request_by_request_id_emits_terminal_event ... ok
[INFO] [stdout] test prefill_batching_is_internal ... ok
[INFO] [stdout] test prefill_failure_emits_terminal_event ... ok
[INFO] [stdout] test preserves_accepted_pending_decode ... ok
[INFO] [stdout] test fire_and_forget_pump_runs_full_prompt_to_decode ... ok
[INFO] [stdout] test mtp_commit_streams_multi_token_lanes ... ok
[INFO] [stdout] test retries_unaccepted_busy_decode ... ok
[INFO] [stdout] test pumps_multiple_accepted_pending_decodes ... ok
[INFO] [stdout] test service_token_frame_submit_works ... ok
[INFO] [stdout] test service_clients_use_internal_queueing ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 19 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests spark_serve
[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" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920", kill_on_drop: false }`
[INFO] [stdout] 16e01807fdb5bcac6bade22ba9107ab1002d4779d5648f3a126afbf34ec99920
