[INFO] fetching crate rsshogi-csa 0.1.3...
[INFO] testing rsshogi-csa-0.1.3 against 1.100.0-beta.1 for beta-1.100-2
[INFO] extracting crate rsshogi-csa 0.1.3 into /workspace/builds/worker-7-tc2/source
[INFO] started tweaking crates.io crate rsshogi-csa 0.1.3
[INFO] removed 0 missing tests
[INFO] finished tweaking crates.io crate rsshogi-csa 0.1.3
[INFO] tweaked toml for crates.io crate rsshogi-csa 0.1.3 written to /workspace/builds/worker-7-tc2/source/Cargo.toml
[INFO] validating manifest of crates.io crate rsshogi-csa 0.1.3 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 rsshogi-csa 0.1.3 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-7-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-7-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] 3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686
[INFO] running `Command { std: "docker" "start" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686", 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" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686" "/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" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686", 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" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686" "/opt/rustwide/cargo-home/bin/cargo" "+1.100.0-beta.1" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stderr]    Compiling rsshogi-csa v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 0.55s
[INFO] running `Command { std: "docker" "inspect" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686", 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" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686" "/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 rsshogi-csa v0.1.3 (/opt/rustwide/workdir)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 1.21s
[INFO] running `Command { std: "docker" "inspect" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686", 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" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686" "/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.04s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/build/rsshogi-csa/2758e73164def070/out/rsshogi_csa-2758e73164def070)
[INFO] [stdout] running 41 tests
[INFO] [stdout] test model::tests::move_accessors_read_the_wire_text ... ok
[INFO] [stdout] test parser::tests::end_markers_are_permissive ... ok
[INFO] [stdout] test format::tests::canonical_lines_survive_a_round_trip ... ok
[INFO] [stdout] test error::tests::display_includes_kind_and_line ... ok
[INFO] [stdout] test format::tests::constructed_commands_format_without_a_parse_step ... ok
[INFO] [stdout] test error::tests::site_clamps_out_of_range_spans ... ok
[INFO] [stdout] test error::tests::site_clamps_inverted_spans ... ok
[INFO] [stdout] test model::tests::time_unit_converts_to_millis ... ok
[INFO] [stdout] test parser::tests::blank_line_is_keepalive ... ok
[INFO] [stdout] test model::tests::move_parse_rejects_bad_shapes ... ok
[INFO] [stdout] test parser::tests::login_variants ... ok
[INFO] [stdout] test model::tests::position_split_without_moves_returns_everything_as_board ... ok
[INFO] [stdout] test parser::tests::reject_notice_needs_a_rejector ... ok
[INFO] [stdout] test parser::tests::meta_commands ... ok
[INFO] [stdout] test parser::tests::special_moves_carry_optional_time ... ok
[INFO] [stdout] test parser::tests::move_annotations ... ok
[INFO] [stdout] test parser::tests::strict_mode_rejects_noncanonical_spelling ... ok
[INFO] [stdout] test parser::tests::move_rejects_bad_annotations ... ok
[INFO] [stdout] test format::tests::keepalive_formats_as_an_empty_line ... ok
[INFO] [stdout] test parser::tests::info_lines_keep_their_remainder_verbatim ... ok
[INFO] [stdout] test parser::tests::unknown_meta_commands_round_trip_through_other ... ok
[INFO] [stdout] test parser::tests::client_reject_is_distinct_from_server_notice ... ok
[INFO] [stdout] test summary::tests::malformed_numbers_are_reported ... ok
[INFO] [stdout] test model::tests::position_split_finds_replayed_moves ... ok
[INFO] [stdout] test model::tests::end_marker_classification ... ok
[INFO] [stdout] test parser::tests::strict_mode_reports_the_canonical_spelling ... ok
[INFO] [stdout] test model::tests::direction_is_derived_not_stored ... ok
[INFO] [stdout] test summary::tests::position_block_must_end_before_the_summary ... ok
[INFO] [stdout] test summary::tests::position_body_keeps_significant_trailing_spaces ... ok
[INFO] [stdout] test parser::tests::login_responses ... ok
[INFO] [stdout] test parser::tests::summary_field_lines_are_not_top_level_commands ... ok
[INFO] [stdout] test summary::tests::unknown_fields_are_preserved_in_order ... ok
[INFO] [stdout] test summary::tests::per_color_time_blocks_are_kept_apart ... ok
[INFO] [stdout] test summary::tests::time_blocks_require_matching_terminators ... ok
[INFO] [stdout] test summary::tests::time_unit_variants ... ok
[INFO] [stdout] test summary::tests::proposal_fields_are_typed ... ok
[INFO] [stdout] test summary::tests::reader_rejects_lines_outside_the_block ... ok
[INFO] [stdout] test summary::tests::proposal_round_trips_byte_for_byte ... ok
[INFO] [stdout] test summary::tests::reader_rejects_lines_after_the_block ... ok
[INFO] [stdout] test summary::tests::show_output_round_trips ... ok
[INFO] [stdout] test summary::tests::resumed_position_carries_moves ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 41 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/game_summary.rs (/opt/rustwide/target/debug/build/rsshogi-csa/67e40c2d5e5aeffa/out/game_summary-67e40c2d5e5aeffa)
[INFO] [stdout] 
[INFO] [stdout] running 4 tests
[INFO] [stdout] test show_output_exposes_remaining_time_per_side ... ok
[INFO] [stdout] test buoy_proposal_exposes_replayed_moves ... ok
[INFO] [stdout] test golden_summaries_round_trip_line_for_line ... ok
[INFO] [stdout] test floodgate_proposal_is_typed_and_keeps_the_board_verbatim ... ok
[INFO] [stdout] 
[INFO] [stderr]      Running tests/roundtrip.rs (/opt/rustwide/target/debug/build/rsshogi-csa/ade8c608fa3ad9c2/out/roundtrip-ade8c608fa3ad9c2)
[INFO] [stdout] test result: ok. 4 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 10 tests
[INFO] [stdout] test every_piece_name_is_accepted_and_survives ... ok
[INFO] [stderr]      Running tests/transcripts.rs (/opt/rustwide/target/debug/build/rsshogi-csa/9df271446df94c70/out/transcripts-9df271446df94c70)
[INFO] [stdout] test move_annotations_survive_every_combination ... ok
[INFO] [stdout] test server_commands_survive_a_construct_format_parse_cycle ... ok
[INFO] [stdout] test client_commands_survive_a_construct_format_parse_cycle ... ok
[INFO] [stdout] test every_end_marker_survives_a_round_trip ... ok
[INFO] [stdout] test every_turn_preference_survives_a_round_trip ... ok
[INFO] [stdout] test every_meta_command_survives_a_round_trip ... ok
[INFO] [stdout] test adversarial_input_never_panics ... ok
[INFO] [stdout] test a_long_line_is_handled_without_panicking ... ok
[INFO] [stdout] test every_special_move_survives_a_round_trip ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 10 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.08s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 5 tests
[INFO] [stdout] test valid_transcripts_round_trip_to_canonical_form ... ok
[INFO] [stdout] test strict_valid_transcripts_accept_only_canonical_lines ... ok
[INFO] [stdout] test invalid_transcripts_are_rejected ... ok
[INFO] [stdout] test every_transcript_directory_has_cases ... ok
[INFO] [stdout] test strict_invalid_transcripts_reject_noncanonical_lines ... ok
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests rsshogi_csa
[INFO] [stdout] test result: ok. 5 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 7 tests
[INFO] [stdout] test src/model.rs - model::CsaMove::parse (line 112) ... ok
[INFO] [stdout] test src/format.rs - format::format_command (line 14) ... ok
[INFO] [stdout] test src/lib.rs - (line 7) ... ok
[INFO] [stdout] test src/summary.rs - summary::GameSummaryReader (line 26) ... ok
[INFO] [stdout] test src/parser.rs - parser::parse_line_strict (line 110) ... ok
[INFO] [stdout] test src/model.rs - model::TimeConfig::new (line 699) ... ok
[INFO] [stdout] test src/parser.rs - parser::parse_line (line 27) ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 7 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.47s
[INFO] [stdout] 
[INFO] running `Command { std: "docker" "inspect" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686", kill_on_drop: false }`
[INFO] [stdout] 3a52fdd886852f89aaf135a764bd7ef7907f5661384b610e1779b144af94e686
