[INFO] cloning repository https://github.com/lmontigny/inference-sim
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/lmontigny/inference-sim" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flmontigny%2Finference-sim", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flmontigny%2Finference-sim'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 6b21473af1d76e5c7b460161a07aa1e5f2b0b45c
[INFO] testing lmontigny/inference-sim against 1.99.0-beta.1+cargoflags=--release for beta-release-1.99-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Flmontigny%2Finference-sim" "/workspace/builds/worker-1-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-1-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/lmontigny/inference-sim
[INFO] finished tweaking git repo https://github.com/lmontigny/inference-sim
[INFO] tweaked toml for git repo https://github.com/lmontigny/inference-sim written to /workspace/builds/worker-1-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/lmontigny/inference-sim on toolchain 1.99.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.99.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/lmontigny/inference-sim 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.1" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-1-tc2/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:8683fc1fc2eb5c9ac98e0d076ab094b2ffac7f99da555d2b6a2e27f346de2ec7" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227
[INFO] running `Command { std: "docker" "start" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227", 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" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227", 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" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.1" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling quote v1.0.45
[INFO] [stderr]    Compiling zerocopy v0.8.50
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling libm v0.2.16
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling memchr v2.8.1
[INFO] [stderr]    Compiling autocfg v1.5.1
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.2
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling anstyle-query v1.1.5
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling simd-adler32 v0.3.9
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling itoa v1.0.18
[INFO] [stderr]    Compiling serde_json v1.0.150
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling minimal-lexical v0.2.1
[INFO] [stderr]    Compiling crossbeam-channel v0.5.15
[INFO] [stderr]    Compiling ryu v1.0.23
[INFO] [stderr]    Compiling either v1.16.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling syn v2.0.117
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling miniz_oxide v0.8.9
[INFO] [stderr]    Compiling rayon v1.12.0
[INFO] [stderr]    Compiling rustc-hash v2.1.2
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling csv-core v0.1.13
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling flate2 v1.1.9
[INFO] [stderr]    Compiling hdrhistogram v7.5.4
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling clap_derive v4.6.1
[INFO] [stderr]    Compiling csv v1.4.0
[INFO] [stderr]    Compiling clap v4.6.1
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling rand v0.9.4
[INFO] [stderr]    Compiling rand_distr v0.5.1
[INFO] [stderr]    Compiling tokenmill v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 29.70s
[INFO] running `Command { std: "docker" "inspect" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227", 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" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling linux-raw-sys v0.12.1
[INFO] [stderr]    Compiling bitflags v2.11.1
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling tokenmill v0.1.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 7.96s
[INFO] running `Command { std: "docker" "inspect" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227", 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" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.1" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.10s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/tokenmill-b7e2e973a4e5f253)
[INFO] [stdout] running 17 tests
[INFO] [stdout] test hardware::cost::tests::cluster_hourly_scales_with_chip_count ... ok
[INFO] [stdout] test hardware::cost::tests::per_million_tokens_units_are_correct ... ok
[INFO] [stdout] test hardware::power::tests::fully_loaded_prefill_uses_prefill_coef ... ok
[INFO] [stdout] test hardware::power::tests::all_idle_run_uses_only_idle_power ... ok
[INFO] [stdout] test hardware::kernel_table::tests::exact_lookup ... ok
[INFO] [stdout] test hardware::kernel_table::tests::missing_returns_none ... ok
[INFO] [stdout] test hardware::power::tests::per_token_energy_is_nonzero ... ok
[INFO] [stdout] test hardware::power::tests::zero_tdp_disables_reporting ... ok
[INFO] [stdout] test model::kv_cache::tests::alloc_and_free ... ok
[INFO] [stdout] test model::kv_cache::tests::grow_allocates_extra_blocks ... ok
[INFO] [stdout] test model::kv_cache::tests::oom_returns_error ... ok
[INFO] [stdout] test workload::trace_replay::tests::azure_format_parses_datetime ... ok
[INFO] [stdout] test workload::trace_replay::tests::azure_multiday_timestamps_are_monotonic ... ok
[INFO] [stdout] test hardware::cost::tests::ten_min_h100_costs_pro_rated_dollars ... ok
[INFO] [stdout] test workload::trace_replay::tests::native_format_loads_sorted ... ok
[INFO] [stdout] test hardware::cost::tests::zero_price_disables_reporting ... ok
[INFO] [stdout] test hardware::kernel_table::tests::interpolated_lookup ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/tokenmill-e464831167d3c31e)
[INFO] [stdout] test result: ok. 17 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[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]      Running tests/cost.rs (/opt/rustwide/target/release/deps/cost-86cd46e8c793d6f4)
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test cost_scales_with_chip_count ... ok
[INFO] [stdout] test disaggregated_doubles_chip_count_for_cost ... ok
[INFO] [stdout] test h100_single_chip_one_hour_costs_three_fifty ... ok
[INFO] [stdout] test ep_greater_than_tp_counts_max_chips ... ok
[INFO] [stdout] test ep_equal_tp_doesnt_double_count ... ok
[INFO] [stdout] test zero_price_disables_cost_reporting ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/moe.rs (/opt/rustwide/target/release/deps/moe-25a9c6dc5b8fe959)
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test dense_models_have_full_active_param_fraction ... ok
[INFO] [stdout] test ep_all_to_all_scales_with_token_count ... ok
[INFO] [stdout] test behemoth_active_params_dominate_decode_cost ... ok
[INFO] [stdout] test ep_all_to_all_uses_scale_out_when_experts_cross_nodes ... ok
[INFO] [stdout] test ep_all_to_all_zero_when_ep_eq_one ... ok
[INFO] [stdout] test ep_shards_expert_weights ... ok
[INFO] [stdout] test expert_weight_bytes_active_is_subset_of_total_active ... ok
[INFO] [stdout] test kimi_k2_uses_mla_kv ... ok
[INFO] [stdout] test mixtral_active_params_match_published_value ... ok
[INFO] [stdout] test deepseek_v3_uses_mla_kv ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/power.rs (/opt/rustwide/target/release/deps/power-3ed7dac83c45d99e)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test coefficients_are_monotonic ... ok
[INFO] [stdout] test summary_zero_energy_when_tdp_is_zero ... ok
[INFO] [stdout] test disaggregated_doubles_chip_count_for_idle_accounting ... ok
[INFO] [stdout] test summary_carries_energy_when_tdp_is_set ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/quantization_validation.rs (/opt/rustwide/target/release/deps/quantization_validation-ef2b975f92ae416e)
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test b200_fp4_llama70b_matches_public_trtllm_throughput_regime ... ok
[INFO] [stderr]      Running tests/report_cli.rs (/opt/rustwide/target/release/deps/report_cli-d1a88a498950ba77)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.10s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test study_mode_writes_json_and_html_report ... ok
[INFO] [stderr]      Running tests/roofline.rs (/opt/rustwide/target/release/deps/roofline-43ee7aad1ed9de0d)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 30 tests
[INFO] [stdout] test a100_has_no_fp8_tensor_cores ... ok
[INFO] [stdout] test all_reduce_scales_with_message_size ... ok
[INFO] [stdout] test b200_is_faster_than_h100_for_fp8 ... ok
[INFO] [stdout] test fp4_prefill_uses_fp4_flops_when_available ... ok
[INFO] [stdout] test fp8_prefill_uses_fp8_flops_on_h100 ... ok
[INFO] [stdout] test gpu_sram_only_discounts_decode_kv_traffic ... ok
[INFO] [stdout] test groq_chip_is_sram_only_with_tiny_capacity ... ok
[INFO] [stdout] test h100_llama8b_bf16_decode_matches_hand_calculation ... ok
[INFO] [stdout] test h200_has_h100_compute_with_larger_faster_hbm3e ... ok
[INFO] [stdout] test groq_needs_high_tp_for_large_models ... ok
[INFO] [stdout] test h200_improves_memory_bound_decode_but_not_compute_bound_prefill ... ok
[INFO] [stdout] test mi300x_beats_h100_on_decode_despite_lower_mfu ... ok
[INFO] [stdout] test mi355x_has_fp8_tensor_cores ... ok
[INFO] [stdout] test pipeline_parallelism_uses_per_stage_kv_for_sram_fit ... ok
[INFO] [stdout] test pipeline_transfer_uses_scale_out_for_cross_node_boundary ... ok
[INFO] [stdout] test cerebras_cs3_is_sram_only_with_system_io_boundary ... ok
[INFO] [stdout] test fp8_decode_is_half_of_bf16_for_same_param_count ... ok
[INFO] [stdout] test rubin_has_largest_hbm_in_nvidia_lineup ... ok
[INFO] [stdout] test rubin_decode_beats_b200_proportional_to_hbm ... ok
[INFO] [stdout] test scale_out_all_reduce_is_slower_than_single_node_scale_up ... ok
[INFO] [stdout] test sparse_flag_is_ignored_without_hardware_support ... ok
[INFO] [stdout] test sparse_nvfp4_decode_gets_extra_two_x_on_supported_hardware ... ok
[INFO] [stdout] test sram_no_benefit_when_kv_doesnt_fit ... ok
[INFO] [stdout] test tp_scales_decode_inversely_with_degree ... ok
[INFO] [stdout] test tpu_8i_decode_close_to_b200 ... ok
[INFO] [stdout] test tpu_8i_has_larger_hbm_than_b200 ... ok
[INFO] [stdout] test tpu_ironwood_has_fp8_and_torus_ici ... ok
[INFO] [stdout] test w4a16_keeps_bf16_activation_traffic ... ok
[INFO] [stdout] test w4a8kv4_halves_kv_vs_fp8 ... ok
[INFO] [stdout] test tpu_8i_sram_helps_small_batch_kv ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 30 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stderr]      Running tests/scale_out_cli.rs (/opt/rustwide/target/release/deps/scale_out_cli-8555bad42eb4e578)
[INFO] [stdout] running 3 tests
[INFO] [stdout] test dgx_h200_system_preset_resolves_to_h200_gpu ... ok
[INFO] [stdout] test quantum_x800_preset_matches_manual_800g_values ... ok
[INFO] [stdout] test dgx_b200_system_preset_matches_manual_b200_ndr_node_defaults ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/spec_mtp.rs (/opt/rustwide/target/release/deps/spec_mtp-daebbaecc0fb754a)
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test spec_high_acceptance_approaches_k_plus_one ... ok
[INFO] [stdout] test mtp_overhead_fraction ... ok
[INFO] [stdout] test spec_tokens_at_zero_acceptance_is_one ... ok
[INFO] [stdout] test mtp_tokens_per_step_formula ... ok
[INFO] [stdout] test spec_tokens_per_step_formula ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.03s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/trace.rs (/opt/rustwide/target/release/deps/trace-8675dafba519f835)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test azure_csv_loads_and_normalises ... ok
[INFO] [stdout] test empty_trace_loads ... ok
[INFO] [stdout] test native_csv_loads ... ok
[INFO] [stdout] test trace_skips_comment_lines ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests tokenmill
[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" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227", kill_on_drop: false }`
[INFO] [stdout] 4a5e017430f5d3c5537e042a742fbe34b803e6fe921a99514ca7cb58ba0ca227
