[INFO] fetching crate sashite-sanki-session 0.15.0...
[INFO] testing sashite-sanki-session-0.15.0 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate sashite-sanki-session 0.15.0 into /workspace/builds/worker-2-tc2/source
[INFO] removed /workspace/builds/worker-2-tc2/source/rust-toolchain.toml
[INFO] started tweaking crates.io crate sashite-sanki-session 0.15.0
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate sashite-sanki-session 0.15.0
[INFO] tweaked toml for crates.io crate sashite-sanki-session 0.15.0 written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate sashite-sanki-session 0.15.0 on toolchain 1.100.0-beta.1
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate crates.io crate sashite-sanki-session 0.15.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.100.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-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-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:3111399a4047eeb3a02b7a90e478d715f38a8c6669b5c4b49d30a17385265909" "sleep" "infinity", kill_on_drop: false }`
[INFO] [stdout] 4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e
[INFO] running `Command { std: "docker" "start" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e", 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" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e", 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" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling sashite-pin v1.1.0
[INFO] [stderr]    Compiling sashite-qi v0.2.1
[INFO] [stderr]    Compiling sashite-sin v1.1.0
[INFO] [stderr]    Compiling syn v3.0.5
[INFO] [stderr]    Compiling sashite-epin v1.1.0
[INFO] [stderr]    Compiling sashite-feen v0.2.0
[INFO] [stderr]    Compiling serde_derive v1.0.229
[INFO] [stderr]    Compiling serde v1.0.229
[INFO] [stderr]    Compiling sashite-sanki-engine v0.11.1
[INFO] [stderr]    Compiling sashite-sanki-session v0.15.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 9.24s
[INFO] running `Command { std: "docker" "inspect" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e", 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" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling sashite-sanki-session v0.15.0 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 2.03s
[INFO] running `Command { std: "docker" "inspect" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e", 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" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "test" "--frozen", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.09s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/sashite-sanki-session/c5825d39dba886af/out/sashite_sanki_session-c5825d39dba886af)
[INFO] [stdout] 
[INFO] [stdout] running 125 tests
[INFO] [stdout] test event::tests::attestation_carries_the_canonical_timing ... ok
[INFO] [stdout] test event::tests::conclusion_links_session_and_carries_its_claim ... ok
[INFO] [stdout] test event::tests::event_id_ordered_by_bytes ... ok
[INFO] [stdout] test event::tests::parse_accepts_uppercase_and_normalizes_to_lowercase ... ok
[INFO] [stdout] test event::tests::parse_hex_rejects_invalid_inputs ... ok
[INFO] [stdout] test event::tests::parse_measures_bytes_and_decodes_ascii_only ... ok
[INFO] [stdout] test event::tests::event_id_order_is_the_raw_byte_order ... ok
[INFO] [stdout] test event::tests::public_key_equality ... ok
[INFO] [stdout] test implicit::tests::a_reply_after_the_cutoff_does_not_decline_the_offer ... ok
[INFO] [stdout] test implicit::tests::agreement_when_opponent_accepts_the_draw ... ok
[INFO] [stdout] test implicit::tests::an_anterior_premove_may_carry_the_standing_offer ... ok
[INFO] [stdout] test implicit::tests::a_reply_the_selection_skipped_does_not_decline_the_offer ... ok
[INFO] [stdout] test event::tests::event_id_hex_round_trip ... ok
[INFO] [stdout] test implicit::tests::offer_extended_past_by_play_is_declined ... ok
[INFO] [stdout] test implicit::tests::an_offer_on_a_ply_that_lost_its_slot_never_stood ... ok
[INFO] [stdout] test implicit::tests::offerer_cannot_accept_their_own_offer ... ok
[INFO] [stdout] test implicit::tests::an_offer_two_half_moves_back_is_no_longer_standing ... ok
[INFO] [stdout] test event::tests::ply_exposes_its_fields ... ok
[INFO] [stdout] test implicit::tests::empty_chain_has_no_offer ... ok
[INFO] [stdout] test implicit::tests::no_acceptance_without_a_draw_flag ... ok
[INFO] [stdout] test implicit::tests::only_the_tail_offer_stands_when_both_half_moves_offer ... ok
[INFO] [stdout] test natural_state::tests::a_re_premove_back_to_an_earlier_content_supersedes ... ok
[INFO] [stdout] test natural_state::tests::cutoff_excludes_a_later_ply ... ok
[INFO] [stdout] test natural_state::tests::empty_chain_if_no_first_ply ... ok
[INFO] [stdout] test natural_state::tests::complete_consecutive_chain ... ok
[INFO] [stdout] test natural_state::tests::a_founding_position_with_second_to_move_is_refused_at_construction ... ok
[INFO] [stdout] test natural_state::tests::a_pair_straddling_the_boundary_is_two_candidates ... 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 natural_state::tests::informed_illegal_is_skipped_leaving_ongoing ... ok
[INFO] [stdout] test natural_state::tests::pre_t0_candidates_are_ignored ... ok
[INFO] [stdout] test natural_state::tests::opponent_slot_cannot_be_filled ... ok
[INFO] [stdout] test natural_state::tests::a_ply_timed_exactly_at_t0_is_a_candidate ... ok
[INFO] [stdout] test natural_state::tests::pending_ply_breaks_the_chain ... ok
[INFO] [stdout] test natural_state::tests::mating_move_terminates_the_chain ... ok
[INFO] [stdout] test natural_state::tests::re_premove_correction_supersedes_illegal_premove ... ok
[INFO] [stdout] test natural_state::tests::self_timed_cutoff_excludes_a_later_ply ... ok
[INFO] [stdout] test natural_state::tests::cutoff_inclusivity ... ok
[INFO] [stdout] test natural_state::tests::the_cap_the_session_carries_bounds_the_windows ... ok
[INFO] [stdout] test selection::tests::anterior_tie_breaks_by_largest_id_first ... ok
[INFO] [stdout] test natural_state::tests::played_ply_timeout_terminates_the_chain ... ok
[INFO] [stdout] test natural_state::tests::identical_twins_timed_at_the_boundary_are_informed ... ok
[INFO] [stdout] test natural_state::tests::the_draw_flag_is_part_of_the_identity_of_a_candidate ... ok
[INFO] [stdout] test selection::tests::anterior_cap_admits_exactly_k_and_no_more ... ok
[INFO] [stdout] test natural_state::tests::identical_candidates_collapse_to_the_one_the_window_reaches_first ... ok
[INFO] [stdout] test selection::tests::boundary_is_exclusive_below_and_inclusive_at ... ok
[INFO] [stdout] test selection::tests::all_illegal_both_windows_unfilled ... ok
[INFO] [stdout] test selection::tests::cap_informed_earliest_buries_later_legal ... ok
[INFO] [stdout] test selection::tests::anterior_tie_skips_the_larger_id_when_illegal ... ok
[INFO] [stdout] test selection::tests::anterior_latest_legal_wins ... ok
[INFO] [stdout] test selection::tests::cap_anterior_most_recent_buries_older_legal ... ok
[INFO] [stdout] test selection::tests::anterior_skips_newest_illegal_to_next_legal ... ok
[INFO] [stdout] test selection::tests::empty_input_and_the_smallest_cap_are_handled_without_waste ... ok
[INFO] [stdout] test natural_state::tests::deep_premove_activates_by_chain_progression ... ok
[INFO] [stdout] test natural_state::tests::gap_in_play_order_stops_the_chain ... ok
[INFO] [stdout] test selection::tests::fallthrough_to_informed_when_no_legal_anterior ... ok
[INFO] [stdout] test selection::tests::extreme_timestamps_partition_without_saturating ... 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 natural_state::tests::self_timed_chain_uses_event_created_at ... ok
[INFO] [stdout] test selection::tests::informed_earliest_legal_wins ... ok
[INFO] [stdout] test selection::tests::informed_skips_earliest_illegal_to_next_legal ... ok
[INFO] [stdout] test selection::tests::informed_tie_breaks_by_smallest_id_first ... ok
[INFO] [stdout] test selection::tests::legal_anterior_preferred_over_informed ... ok
[INFO] [stdout] test selection::tests::legality_probes_are_bounded_by_the_cap ... ok
[INFO] [stdout] test selection::tests::selection_is_independent_of_the_input_order ... ok
[INFO] [stdout] test selection::tests::single_illegal_unfilled ... ok
[INFO] [stdout] test selection::tests::single_informed_legal_applied ... ok
[INFO] [stdout] test selection::tests::the_window_split_has_one_definition ... ok
[INFO] [stdout] test session::tests::accessors ... ok
[INFO] [stdout] test session::tests::initial_kernel_state_starts_in_the_first_period ... ok
[INFO] [stdout] test session::tests::maps_pubkey_to_side ... ok
[INFO] [stdout] test session::tests::initial_kernel_state ... ok
[INFO] [stdout] test session::tests::maps_side_to_player ... ok
[INFO] [stdout] test session::tests::play_order_mapping_is_exact_at_the_u32_extremes ... ok
[INFO] [stdout] test timing::tests::a_strangers_attestation_can_never_move_a_timing ... ok
[INFO] [stdout] test session::tests::seats_reject_a_player_against_themselves ... ok
[INFO] [stdout] test timing::tests::attested_timing_ignores_the_events_own_created_at ... ok
[INFO] [stdout] test session::tests::scores_map_to_the_seat_axis_outcome_whatever_the_tag_order ... ok
[INFO] [stdout] test timing::tests::self_timed_never_consults_attestations ... ok
[INFO] [stdout] test session::tests::play_order_positions_map_to_slots ... ok
[INFO] [stdout] test session::tests::recognizes_player_and_timestamper ... ok
[INFO] [stdout] test verdict::tests::a_played_ply_timeout_outranks_the_mate_it_delivers ... ok
[INFO] [stdout] test timing::tests::extreme_timings_are_compared_not_saturated ... ok
[INFO] [stdout] test session::tests::self_timed_session_designates_no_timestamper ... ok
[INFO] [stdout] test timing::tests::meta_resolution_ignores_non_timestamper_signer ... ok
[INFO] [stdout] test timing::tests::meta_resolution_ignores_other_attested_event ... ok
[INFO] [stdout] test verdict::tests::a_premature_claim_conforms_only_as_the_claimants_resignation ... ok
[INFO] [stdout] test timing::tests::meta_resolution_is_independent_of_the_input_order ... ok
[INFO] [stdout] test timing::tests::self_timed_timing_uses_event_created_at ... ok
[INFO] [stdout] test verdict::tests::a_conclusion_timed_before_t0_concludes_nothing ... ok
[INFO] [stdout] test session::tests::side_of_maps_only_the_two_players ... ok
[INFO] [stdout] test timing::tests::meta_resolution_smallest_created_at ... ok
[INFO] [stdout] test timing::tests::meta_resolution_tiebreak_by_attestation_id ... ok
[INFO] [stdout] test verdict::tests::a_verdict_is_a_coherent_status_outcome_pair ... ok
[INFO] [stdout] test verdict::tests::abandonment_spends_the_overtime_periods ... ok
[INFO] [stdout] test verdict::tests::abandonment_span_too_wide_saturates_instead_of_pardoning ... 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::cross_session_conclusion_no_result ... ok
[INFO] [stdout] test verdict::tests::abandonment_timeout ... ok
[INFO] [stdout] test verdict::tests::empty_chain_abandonment_charges_the_player_who_never_moved ... ok
[INFO] [stdout] test verdict::tests::cutoff_of_reports_the_first_reason_that_holds ... ok
[INFO] [stdout] test verdict::tests::mate_by_chain_replay ... ok
[INFO] [stdout] test verdict::tests::abandonment_reads_the_replayed_clock_not_the_initial_one ... ok
[INFO] [stdout] test verdict::tests::empty_chain_conclusion_is_resignation ... ok
[INFO] [stdout] test verdict::tests::illegal_move_in_the_chain_is_skipped_not_a_loss ... ok
[INFO] [stdout] test verdict::tests::non_player_conclusion_no_result ... ok
[INFO] [stdout] test verdict::tests::off_turn_conclusion_without_cause_is_resignation ... ok
[INFO] [stdout] test verdict::tests::own_expired_clock_is_a_timeout_not_a_resignation ... ok
[INFO] [stdout] test verdict::tests::out_of_reach_conclusions_never_resolve_as_a_resignation ... ok
[INFO] [stdout] test verdict::tests::own_turn_conclusion_without_cause_is_resignation ... ok
[INFO] [stdout] test verdict::tests::self_timed_session_rules_on_the_events_own_created_at ... ok
[INFO] [stdout] test verdict::tests::select_conclusion_in_self_timed_mode_reads_the_events_own_timing ... ok
[INFO] [stdout] test natural_state::tests::is_legal_matches_the_kernel_step_oracle ... ok
[INFO] [stdout] test verdict::tests::terminal_outranks_a_standing_draw_offer ... ok
[INFO] [stdout] test verdict::tests::score_matches_the_point_split_for_every_outcome ... ok
[INFO] [stdout] test verdict::tests::select_conclusion_earliest_conforming_timed ... ok
[INFO] [stdout] test verdict::tests::score_per_side ... ok
[INFO] [stdout] test verdict::tests::unattested_conclusion_no_result ... ok
[INFO] [stdout] test verdict::tests::two_conforming_conclusions_with_different_verdicts_the_earliest_rules ... ok
[INFO] [stdout] test verdict::tests::the_engine_turn_tracks_the_kernel_play_order ... ok
[INFO] [stdout] test verdict::tests::verdict_at_is_the_invocation_primitive ... ok
[INFO] [stdout] test verdict::tests::abandonment_is_charged_from_the_chains_last_anchor_not_a_tail_premoves_timing ... ok
[INFO] [stdout] test verdict::tests::conforms_iff_the_claim_is_the_expected_verdict ... ok
[INFO] [stdout] test verdict::tests::the_verdict_does_not_depend_on_the_input_order ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 124 passed; 0 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.11s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/conformance.rs (/opt/rustwide/target/debug/build/sashite-sanki-session/ba355e7984818b0b/out/conformance-ba355e7984818b0b)
[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.40s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cross_variant.rs (/opt/rustwide/target/debug/build/sashite-sanki-session/8a3b87a649601ac6/out/cross_variant-8a3b87a649601ac6)
[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 inert_tray_checkmate_binds_the_verdict ... ok
[INFO] [stdout] test inert_tray_checkmate_from_the_standard_mixed_start ... ok
[INFO] [stdout] test xiongqi_sideways_en_passant_is_played_in_a_cross_variant_session ... ok
[INFO] [stdout] test cross_variant_capture_feeds_the_ogi_hand_and_the_drop_is_played ... ok
[INFO] [stdout] test every_pairing_is_evaluated_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.16s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests sashite_sanki_session
[INFO] [stdout] 
[INFO] [stdout] running 1 test
[INFO] [stdout] test src/lib.rs - (line 150) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 1 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.19s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e", kill_on_drop: false }`
[INFO] [stdout] 4415b3beb0973f6bac9e7ac9533e92869aa0840005465726c5a3a5583e0a8c0e
