[INFO] cloning repository https://github.com/kusl/poolsim
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/kusl/poolsim" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkusl%2Fpoolsim", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkusl%2Fpoolsim'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 2d8690974b706af7f0ff71be29d227238f0c324a
[INFO] testing kusl/poolsim against 1.99.0-beta.8+cargoflags=--release for beta-release-1.100-2
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fkusl%2Fpoolsim" "/workspace/builds/worker-4-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-4-tc1/source'...
[INFO] [stderr] done.
[INFO] removed /workspace/builds/worker-4-tc1/source/rust-toolchain.toml
[INFO] started tweaking git repo https://github.com/kusl/poolsim
[INFO] finished tweaking git repo https://github.com/kusl/poolsim
[INFO] tweaked toml for git repo https://github.com/kusl/poolsim written to /workspace/builds/worker-4-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/kusl/poolsim 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/kusl/poolsim 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] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-4-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:77db811e55d90add9212f6832c23229e3de5f1c1f9905e8cb07319ea15df73ac" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf
[INFO] running `Command { std: "docker" "start" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf", 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" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf" "/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" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf", 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" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling poolsim v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 3.48s
[INFO] running `Command { std: "docker" "inspect" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf", 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" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--no-run" "--message-format=json" "--release", kill_on_drop: false }`
[INFO] [stderr]    Compiling poolsim v0.2.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 5.54s
[INFO] running `Command { std: "docker" "inspect" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf", 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" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen" "--release", kill_on_drop: false }`
[INFO] [stderr]     Finished `release` profile [optimized] target(s) in 0.02s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/release/deps/poolsim-4c85e01c9331e289)
[INFO] [stdout] 
[INFO] [stdout] running 73 tests
[INFO] [stdout] test dispatch::tests::baseline_mode_caps_occupancy_at_one ... ok
[INFO] [stdout] test fleet::tests::default_costs_are_exact_dyadic_values ... ok
[INFO] [stdout] test fleet::tests::expansion_numbers_vehicles_in_spec_order ... ok
[INFO] [stdout] test dispatch::tests::relocation_moves_a_request_to_a_strictly_cheaper_vehicle ... ok
[INFO] [stdout] test dispatch::tests::deadline_violations_are_infeasible_not_errors ... ok
[INFO] [stdout] test dispatch::tests::evaluate_plan_walks_times_and_path ... ok
[INFO] [stdout] test dispatch::tests::cheapest_cost_wins_across_vehicles ... ok
[INFO] [stdout] test dispatch::tests::relocation_is_a_no_op_without_strict_improvement ... ok
[INFO] [stdout] test fleet::tests::parses_spec_with_default_and_explicit_costs ... ok
[INFO] [stdout] test geometry::tests::manhattan_distance_is_symmetric ... ok
[INFO] [stdout] test dispatch::tests::onboard_dropoffs_are_never_movable ... ok
[INFO] [stdout] test fleet::tests::rejects_malformed_specs ... ok
[INFO] [stdout] test metrics::tests::a_run_that_serves_nothing_reports_nulls_not_fake_zeros ... ok
[INFO] [stdout] test geometry::tests::manhattan_distance_is_zero_for_identical_points ... ok
[INFO] [stdout] test geometry::tests::manhattan_distance_between_origin_and_3_4_is_7 ... ok
[INFO] [stdout] test dispatch::tests::onboard_passengers_need_only_their_dropoff ... ok
[INFO] [stdout] test fleet::tests::rejects_fleets_above_the_vehicle_ceiling ... ok
[INFO] [stdout] test metrics::tests::double_outcomes_are_rejected_by_validation ... ok
[INFO] [stdout] test fleet::tests::canonical_rendering_round_trips ... ok
[INFO] [stdout] test metrics::tests::empty_value_list_is_rejected ... ok
[INFO] [stdout] test metrics::tests::median_of_an_even_count_is_the_mean_of_the_middle_values ... ok
[INFO] [stdout] test dispatch::tests::structural_violations_are_loud_errors ... ok
[INFO] [stdout] test dispatch::tests::capacity_breaks_exact_cost_ties_before_vehicle_id ... ok
[INFO] [stdout] test metrics::tests::metrics_of_a_hand_built_run_match_hand_arithmetic ... ok
[INFO] [stdout] test dispatch::tests::unplaceable_requests_are_left_pending ... ok
[INFO] [stdout] test dispatch::tests::vehicle_id_breaks_full_ties ... ok
[INFO] [stdout] test metrics::tests::broken_service_promises_are_rejected_by_validation ... ok
[INFO] [stdout] test metrics::tests::median_of_an_odd_count_is_the_middle_value ... ok
[INFO] [stdout] test metrics::tests::requests_without_an_outcome_are_rejected_by_validation ... ok
[INFO] [stdout] test output::tests::undefined_ratios_render_as_null_in_json ... ok
[INFO] [stdout] test parameters::tests::missing_value_is_rejected ... ok
[INFO] [stdout] test parameters::tests::negative_max_wait_is_rejected ... ok
[INFO] [stdout] test output::tests::fractional_floats_keep_their_shortest_round_trip_form ... ok
[INFO] [stdout] test parameters::tests::empty_output_directory_is_rejected ... ok
[INFO] [stdout] test parameters::tests::environment_overrides_defaults ... ok
[INFO] [stdout] test output::tests::undefined_ratios_render_as_not_available_in_the_summary ... ok
[INFO] [stdout] test parameters::tests::equals_form_is_accepted ... ok
[INFO] [stdout] test parameters::tests::non_finite_depot_is_rejected ... ok
[INFO] [stdout] test parameters::tests::help_flag_short_circuits ... ok
[INFO] [stdout] test parameters::tests::non_positive_batch_interval_is_rejected ... ok
[INFO] [stdout] test parameters::tests::onboard_factor_below_one_is_rejected ... ok
[INFO] [stdout] test parameters::tests::command_line_overrides_environment ... ok
[INFO] [stdout] test parameters::tests::removed_v1_flags_are_rejected ... ok
[INFO] [stdout] test parameters::tests::defaults_apply_when_nothing_is_set ... ok
[INFO] [stdout] test parameters::tests::malformed_values_are_rejected ... ok
[INFO] [stdout] test parameters::tests::directly_constructed_fleet_is_validated ... ok
[INFO] [stdout] test parameters::tests::duplicate_argument_is_rejected ... ok
[INFO] [stdout] test request::tests::deadlines_follow_the_promise_formulas ... ok
[INFO] [stdout] test parameters::tests::unrecognized_argument_is_rejected ... ok
[INFO] [stdout] test request::tests::canonical_order_is_request_time_then_id ... ok
[INFO] [stdout] test route::tests::path_distance_counts_the_approach_leg ... ok
[INFO] [stdout] test route::tests::path_distance_of_an_empty_plan_is_zero ... ok
[INFO] [stdout] test route::tests::path_distance_sums_consecutive_legs_in_order ... ok
[INFO] [stdout] test request::tests::empty_request_set_is_rejected ... ok
[INFO] [stdout] test request::tests::fixed_request_set_content_hash_is_stable ... ok
[INFO] [stdout] test simulation::tests::arrival_exactly_at_a_boundary_is_replannable ... ok
[INFO] [stdout] test simulation::tests::invalid_parameters_are_rejected_before_planning ... ok
[INFO] [stdout] test simulation::tests::invalid_requests_are_rejected_before_planning ... ok
[INFO] [stdout] test simulation::tests::unreachable_requests_are_rejected_at_the_documented_boundary ... ok
[INFO] [stdout] test output::tests::json_strings_are_escaped ... ok
[INFO] [stdout] test simulation::tests::deadlines_end_the_starvation_oscillation_scenario ... ok
[INFO] [stdout] test output::tests::non_finite_numbers_are_rejected ... ok
[INFO] [stdout] test parameters::tests::non_positive_or_non_finite_speed_is_rejected ... ok
[INFO] [stdout] test simulation::tests::input_order_does_not_change_the_result ... ok
[INFO] [stdout] test request::tests::fixed_request_set_has_the_hand_authored_values ... ok
[INFO] [stdout] test request::tests::dropoff_deadline_scales_with_speed ... ok
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/release/deps/poolsim-3f581a55f1b949df)
[INFO] [stdout] test request::tests::duplicate_request_ids_are_rejected ... ok
[INFO] [stdout] test request::tests::negative_request_times_are_rejected ... ok
[INFO] [stdout] test output::tests::integer_valued_floats_gain_a_decimal_point ... ok
[INFO] [stdout] test request::tests::non_finite_coordinates_are_rejected ... ok
[INFO] [stdout] test request::tests::zero_distance_requests_are_rejected ... ok
[INFO] [stdout] test simulation::tests::approach_legs_are_counted_and_attributed_as_deadhead ... ok
[INFO] [stdout] test simulation::tests::committed_legs_are_never_diverted ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 73 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration.rs (/opt/rustwide/target/release/deps/integration-84558a2adddb7201)
[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] [stdout] 
[INFO] [stdout] running 9 tests
[INFO] [stdout] test capacity_sweep_needs_no_vehicle_size_branching ... ok
[INFO] [stdout] test deadlines_end_starvation_end_to_end ... ok
[INFO] [stdout] test golden_baseline_run_matches_the_hand_derivation ... ok
[INFO] [stdout] test results_json_pins_the_golden_values ... ok
[INFO] [stdout] test golden_comparison_metrics_match_the_hand_arithmetic ... ok
[INFO] [stderr]    Doc-tests poolsim
[INFO] [stdout] test golden_pooled_run_matches_the_hand_derivation ... ok
[INFO] [stdout] test rendering_is_byte_deterministic_across_runs_and_input_orders ... ok
[INFO] [stdout] test artifacts_are_written_and_byte_exact ... ok
[INFO] [stdout] test an_unservable_set_reports_nulls_end_to_end ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 9 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[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] running `Command { std: "docker" "inspect" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf", kill_on_drop: false }`
[INFO] [stdout] 7d8ab25eba444a088d2bba1bb75410c5a88971f8cc41c5d4c7a254d1fd1d6ecf
