[INFO] fetching crate sashite-sanki-arbiter 0.12.1...
[INFO] testing sashite-sanki-arbiter-0.12.1 against 1.99.0-beta.8 for beta-1.100-2
[INFO] extracting crate sashite-sanki-arbiter 0.12.1 into /workspace/builds/worker-2-tc1/source
[INFO] removed /workspace/builds/worker-2-tc1/source/rust-toolchain.toml
[INFO] started tweaking crates.io crate sashite-sanki-arbiter 0.12.1
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sashite-sanki-arbiter 0.12.1
[INFO] tweaked toml for crates.io crate sashite-sanki-arbiter 0.12.1 written to /workspace/builds/worker-2-tc1/source/Cargo.toml
[INFO] validating manifest of crates.io crate sashite-sanki-arbiter 0.12.1 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 sashite-sanki-arbiter 0.12.1 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]     Blocking waiting for file lock on package cache
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded sashite-qi v0.2.1
[INFO] [stderr]   Downloaded sashite-sin v1.1.0
[INFO] [stderr]   Downloaded sashite-feen v0.2.0
[INFO] [stderr]   Downloaded sashite-epin v1.1.0
[INFO] [stderr]   Downloaded sashite-sanki-engine v0.9.0
[INFO] [stderr]   Downloaded sashite-pin v1.1.0
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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] 476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b
[INFO] running `Command { std: "docker" "start" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b", 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" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b" "/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" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b", 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" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling sashite-pin v1.1.0
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling proc-macro2 v1.0.107
[INFO] [stderr]    Compiling sashite-sin v1.1.0
[INFO] [stderr]    Compiling sashite-qi v0.2.1
[INFO] [stderr]    Compiling serde_json v1.0.151
[INFO] [stderr]    Compiling sashite-epin v1.1.0
[INFO] [stderr]    Compiling sashite-feen v0.2.0
[INFO] [stderr]    Compiling quote v1.0.47
[INFO] [stderr]    Compiling syn v3.0.3
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling sashite-sanki-engine v0.9.0
[INFO] [stderr]    Compiling sashite-sanki-arbiter v0.12.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.83s
[INFO] running `Command { std: "docker" "inspect" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b", 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" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b" "/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 sashite-sanki-arbiter v0.12.1 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3.03s
[INFO] running `Command { std: "docker" "inspect" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b", 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" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b" "/opt/rustwide/cargo-home/bin/cargo" "+1.99.0-beta.8" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.05s
[INFO] [stdout] running 119 tests
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/sashite_sanki_arbiter-c366bbf8c0fc8fa2)
[INFO] [stdout] test event::tests::event_id_ordered_by_bytes ... ok
[INFO] [stdout] test event::tests::public_key_equality ... ok
[INFO] [stdout] test event::tests::event_id_hex_round_trip ... ok
[INFO] [stdout] test event::tests::ply_exposes_its_fields ... ok
[INFO] [stdout] test event::tests::event_id_order_is_the_raw_byte_order ... ok
[INFO] [stdout] test event::tests::parse_hex_rejects_invalid_inputs ... ok
[INFO] [stdout] test event::tests::adjudication_request_links_session_and_arbiter ... ok
[INFO] [stdout] test event::tests::parse_measures_bytes_and_decodes_ascii_only ... ok
[INFO] [stdout] test event::tests::attestation_carries_the_canonical_timing ... ok
[INFO] [stdout] test implicit::tests::non_player_invoker_does_not_accept ... ok
[INFO] [stdout] test implicit::tests::a_reply_the_selection_skipped_does_not_decline_the_offer ... ok
[INFO] [stdout] test implicit::tests::an_offer_on_a_ply_that_lost_its_slot_never_stood ... ok
[INFO] [stdout] test implicit::tests::an_offer_two_half_moves_back_is_no_longer_standing ... ok
[INFO] [stdout] test implicit::tests::no_acceptance_without_a_draw_flag ... ok
[INFO] [stdout] test implicit::tests::a_reply_after_the_cutoff_does_not_decline_the_offer ... ok
[INFO] [stdout] test implicit::tests::an_anterior_premove_may_carry_the_standing_offer ... ok
[INFO] [stdout] test implicit::tests::agreement_when_opponent_accepts_the_draw ... ok
[INFO] [stdout] test implicit::tests::offer_extended_past_by_play_is_declined ... ok
[INFO] [stdout] test natural_state::tests::deep_premove_activates_by_chain_progression ... ok
[INFO] [stdout] test implicit::tests::empty_chain_has_no_offer ... ok
[INFO] [stdout] test implicit::tests::offerer_cannot_accept_their_own_offer ... ok
[INFO] [stdout] test natural_state::tests::is_legal_matches_the_kernel_step_oracle_exhaustively ... ignored, exhaustive: 486_852 probe pairs over 20 positions, ~2.6 s in a debug build — seven times the rest of the suite. Run with `cargo test -- --ignored`.
[INFO] [stdout] test implicit::tests::only_the_tail_offer_stands_when_both_half_moves_offer ... ok
[INFO] [stdout] test natural_state::tests::pre_t0_candidates_are_ignored ... ok
[INFO] [stdout] test natural_state::tests::informed_illegal_is_skipped_leaving_ongoing ... ok
[INFO] [stdout] test natural_state::tests::gap_in_play_order_stops_the_chain ... ok
[INFO] [stdout] test natural_state::tests::re_premove_correction_supersedes_illegal_premove ... ok
[INFO] [stdout] test natural_state::tests::opponent_slot_cannot_be_filled ... ok
[INFO] [stdout] test natural_state::tests::cutoff_inclusivity ... ok
[INFO] [stdout] test natural_state::tests::complete_consecutive_chain ... ok
[INFO] [stdout] test natural_state::tests::played_ply_timeout_terminates_the_chain ... ok
[INFO] [stdout] test natural_state::tests::cutoff_excludes_a_later_ply ... ok
[INFO] [stdout] test race_resolution::tests::attested_timing_ignores_the_events_own_created_at ... ok
[INFO] [stdout] test race_resolution::tests::meta_resolution_ignores_non_timestamper_signer ... ok
[INFO] [stdout] test natural_state::tests::unattested_request_yields_none ... ok
[INFO] [stdout] test race_resolution::tests::a_strangers_attestation_can_never_move_a_timing ... ok
[INFO] [stdout] test race_resolution::tests::extreme_timings_are_compared_not_saturated ... ok
[INFO] [stdout] test natural_state::tests::self_timed_cutoff_excludes_a_later_ply ... ok
[INFO] [stdout] test race_resolution::tests::meta_resolution_ignores_other_attested_event ... ok
[INFO] [stdout] test race_resolution::tests::meta_resolution_tiebreak_by_attestation_id ... ok
[INFO] [stdout] test natural_state::tests::self_timed_chain_uses_event_created_at ... ok
[INFO] [stdout] test natural_state::tests::mating_move_terminates_the_chain ... ok
[INFO] [stdout] test race_resolution::tests::self_timed_never_consults_attestations ... ok
[INFO] [stdout] test natural_state::tests::empty_chain_if_no_first_ply ... ok
[INFO] [stdout] test race_resolution::tests::self_timed_slot_selects_smallest_created_at ... ok
[INFO] [stdout] test race_resolution::tests::slot_excludes_pending_plies ... ok
[INFO] [stdout] test race_resolution::tests::slot_selection_ignores_a_strangers_attestation ... ok
[INFO] [stdout] test event::tests::parse_accepts_uppercase_and_normalizes_to_lowercase ... ok
[INFO] [stdout] test natural_state::tests::the_draw_flag_is_outside_the_identical_content_dedup_key ... ok
[INFO] [stdout] test natural_state::tests::the_play_order_model_presumes_a_first_to_move_founding_position ... ok
[INFO] [stdout] test natural_state::tests::identical_content_duplicates_collapse_to_the_race_canonical ... ok
[INFO] [stdout] test race_resolution::tests::race_resolution_is_independent_of_the_input_order ... ok
[INFO] [stdout] test natural_state::tests::pending_ply_breaks_the_chain ... ok
[INFO] [stdout] test race_resolution::tests::self_timed_timing_uses_event_created_at ... ok
[INFO] [stdout] test race_resolution::tests::slot_smallest_attestation_created_at ... ok
[INFO] [stdout] test race_resolution::tests::meta_resolution_smallest_created_at ... ok
[INFO] [stdout] test race_resolution::tests::slot_tiebreak_by_ply_id ... ok
[INFO] [stdout] test race_resolution::tests::slot_no_attested_ply_yields_none ... ok
[INFO] [stdout] test selection::tests::anterior_latest_legal_wins ... ok
[INFO] [stdout] test selection::tests::all_illegal_both_windows_unfilled ... ok
[INFO] [stdout] test selection::tests::anterior_tie_breaks_by_largest_id_first ... ok
[INFO] [stdout] test selection::tests::anterior_cap_admits_exactly_k_and_no_more ... ok
[INFO] [stdout] test selection::tests::anterior_tie_skips_the_larger_id_when_illegal ... ok
[INFO] [stdout] test selection::tests::anterior_skips_newest_illegal_to_next_legal ... ok
[INFO] [stdout] test race_resolution::tests::self_timed_slot_tiebreak_by_ply_id ... ok
[INFO] [stdout] test selection::tests::first_slot_boundary_t0_is_informed ... ok
[INFO] [stdout] test selection::tests::informed_cap_admits_exactly_k_and_no_more ... ok
[INFO] [stdout] test selection::tests::boundary_is_exclusive_below_and_inclusive_at ... ok
[INFO] [stdout] test selection::tests::informed_earliest_legal_wins ... ok
[INFO] [stdout] test selection::tests::informed_tie_breaks_by_smallest_id_first ... ok
[INFO] [stdout] test selection::tests::legality_probes_are_bounded_by_the_cap ... ok
[INFO] [stdout] test selection::tests::cap_informed_earliest_buries_later_legal ... ok
[INFO] [stdout] test selection::tests::extreme_timestamps_partition_without_saturating ... ok
[INFO] [stdout] test selection::tests::empty_and_zero_cap_inputs_are_unfilled_without_probing ... ok
[INFO] [stdout] test selection::tests::fallthrough_to_informed_when_no_legal_anterior ... ok
[INFO] [stdout] test selection::tests::informed_skips_earliest_illegal_to_next_legal ... ok
[INFO] [stdout] test selection::tests::cap_anterior_most_recent_buries_older_legal ... ok
[INFO] [stdout] test selection::tests::legal_anterior_preferred_over_informed ... ok
[INFO] [stdout] test selection::tests::single_informed_legal_applied ... ok
[INFO] [stdout] test session::tests::maps_side_to_player ... ok
[INFO] [stdout] test session::tests::maps_pubkey_to_side ... ok
[INFO] [stdout] test session::tests::accessors ... ok
[INFO] [stdout] test session::tests::play_order_mapping_is_exact_at_the_u32_extremes ... ok
[INFO] [stdout] test session::tests::recognizes_player_and_timestamper ... ok
[INFO] [stdout] test session::tests::play_order_positions_map_to_slots ... ok
[INFO] [stdout] test session::tests::side_of_maps_only_the_two_players ... ok
[INFO] [stdout] test session::tests::self_timed_session_designates_no_timestamper ... ok
[INFO] [stdout] test verdict::tests::cross_session_request_no_verdict ... ok
[INFO] [stdout] test verdict::tests::empty_chain_abandonment_charges_the_player_who_never_moved ... ok
[INFO] [stdout] test verdict::tests::empty_chain_invocation_is_resignation ... ok
[INFO] [stdout] test session::tests::initial_kernel_state ... ok
[INFO] [stdout] test verdict::tests::illegal_move_in_the_chain_is_skipped_not_a_loss ... ok
[INFO] [stdout] test verdict::tests::abandonment_timeout ... ok
[INFO] [stdout] test verdict::tests::cutoff_before_t0_charges_nothing ... ok
[INFO] [stdout] test selection::tests::single_illegal_unfilled ... ok
[INFO] [stdout] test verdict::tests::mate_by_chain_replay ... ok
[INFO] [stdout] test selection::tests::selection_is_independent_of_the_input_order ... ok
[INFO] [stdout] test verdict::tests::abandonment_span_too_wide_saturates_instead_of_pardoning ... ok
[INFO] [stdout] test verdict::tests::abandonment_spends_the_overtime_periods ... ok
[INFO] [stdout] test session::tests::initial_kernel_state_starts_in_the_first_period ... ok
[INFO] [stdout] test verdict::tests::draw_by_agreement ... ok
[INFO] [stdout] test verdict::tests::differing_contents_are_not_an_equivocation_loss ... ok
[INFO] [stdout] test verdict::tests::abandonment_reads_the_replayed_clock_not_the_initial_one ... ok
[INFO] [stdout] test verdict::tests::non_conforming_requests_never_resolve_as_a_resignation ... ok
[INFO] [stdout] test verdict::tests::non_player_request_no_verdict ... ok
[INFO] [stdout] test verdict::tests::unattested_request_no_verdict ... ok
[INFO] [stdout] test verdict::tests::off_turn_invocation_without_cause_is_resignation ... ok
[INFO] [stdout] test verdict::tests::own_turn_invocation_without_cause_is_resignation ... ok
[INFO] [stdout] test verdict::tests::terminal_outranks_a_standing_draw_offer ... ok
[INFO] [stdout] test verdict::tests::own_expired_clock_is_a_timeout_not_a_resignation ... ok
[INFO] [stdout] test verdict::tests::wrong_arbiter_request_no_verdict ... ok
[INFO] [stdout] test verdict::tests::select_request_never_picks_a_non_conforming_or_untimed_one ... ok
[INFO] [stdout] test verdict::tests::score_matches_the_point_split_for_every_outcome ... ok
[INFO] [stdout] test verdict::tests::self_timed_session_rules_on_the_events_own_created_at ... ok
[INFO] [stdout] test verdict::tests::score_per_side ... ok
[INFO] [stdout] test verdict::tests::select_request_earliest_conforming_timed ... ok
[INFO] [stdout] test verdict::tests::the_engine_turn_tracks_the_arbiter_play_order ... ok
[INFO] [stdout] test natural_state::tests::is_legal_matches_the_kernel_step_oracle ... ok
[INFO] [stdout] test verdict::tests::the_verdict_does_not_depend_on_the_input_order ... ok
[INFO] [stderr]      Running tests/conformance.rs (/opt/rustwide/target/debug/deps/conformance-eb37df2fd9d0b8bb)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 118 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.17s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 2 tests
[INFO] [stdout] test selection_conformance ... ok
[INFO] [stdout] test scenario_conformance ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 2 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.47s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cross_variant.rs (/opt/rustwide/target/debug/deps/cross_variant-079e62821573dc2f)
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test cross_variant_uchifuzume_is_skipped_never_a_loss ... ok
[INFO] [stdout] test each_variant_castles_inside_a_cross_variant_session ... ok
[INFO] [stdout] test cross_variant_capture_feeds_the_ogi_hand_and_the_drop_is_played ... ok
[INFO] [stdout] test xiongqi_sideways_en_passant_is_played_in_a_cross_variant_session ... ok
[INFO] [stdout] test inert_tray_checkmate_binds_the_verdict ... ok
[INFO] [stdout] test inert_tray_checkmate_from_the_standard_mixed_start ... ok
[INFO] [stdout] test every_pairing_is_adjudicated_end_to_end ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sashite_sanki_arbiter
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - (line 86) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.56s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b", kill_on_drop: false }`
[INFO] [stdout] 476c869a160f4f9e77f08f9fca9859ef2b816fba1ae603a9f5a13f4d24561c6b
